FileSystemProvider ignores provided environment parameters when creating the default SshClient

[GabriRuflex]

Version

2.16.0

Bug description

I receive a Connection reset error while I'm trying to upload a file via SFTP.

This error appears when I use the FileSystems.newFileSystem routine with environment parameters.

Actual behavior

This is the error log:

[org.apache.sshd.common.util.security.SecurityUtils] register(EdDSA) not registered - enabled=true, supported=false
[org.apache.sshd.common.io.DefaultIoServiceFactoryFactory] No detected/configured IoServiceFactoryFactory; using Nio2ServiceFactoryFactory
[org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver] reloadHostConfigEntries(user@10.0.0.1:22/null) check permissions of /home/test/.ssh/config
[org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver] resolveEffectiveResolver(user@10.0.0.1:22) loaded 1 entries from /home/test/.ssh/config
[org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver] resolveEffectiveHost(user@10.0.0.1:22/null) => 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.client.SshClient] connect(user@10.0.0.1:22) effective: 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] Connecting to /10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] setOption(SO_REUSEADDR)[true] from property=Property[socket-reuseaddr](Boolean]
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) connect(/10.0.0.1:22): waiting for connection (timeout=60000ms)
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) Creating IoSession on /10.100.0.1:38794 from /10.0.0.1:22 via null
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) Client session created: Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) ClientUserAuthService(ClientSessionImpl[null@/10.0.0.1:22]) client methods: [publickey, keyboard-interactive, password]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) sessionCreated(ClientSessionImpl[null@/10.0.0.1:22]) tracking
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) initializeProxyConnector(ClientSessionImpl[null@/10.0.0.1:22]) no proxy to initialize
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) sendIdentification(ClientSessionImpl[null@/10.0.0.1:22]): SSH-2.0-APACHE-SSHD-2.15.0
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 28 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) sendKexInit(ClientSessionImpl[null@/10.0.0.1:22]) Send SSH_MSG_KEXINIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) encode(ClientSessionImpl[null@/10.0.0.1:22]) packet #0 sending command=20[SSH_MSG_KEXINIT] len=1393
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 1408 bytes
[org.apache.sshd.client.SshClient] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider override in session listener
[org.apache.sshd.client.SshClient] (sshd-SshClient[178aeecb]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider enhanced
[org.apache.sshd.client.future.DefaultConnectFuture] Connected to /10.0.0.1:22 after 34357588 nanos
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-3) doReadIdentification(ClientSessionImpl[user@/10.0.0.1:22]) line='SSH-2.0--'
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-3) readIdentification(ClientSessionImpl[user@/10.0.0.1:22]) Server version string: SSH-2.0--
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #0 SSH_MSG_KEXINIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) handleKexInit(ClientSessionImpl[user@/10.0.0.1:22]) SSH_MSG_KEXINIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) negotiate(ClientSessionImpl[user@/10.0.0.1:22]) strict KEX=false client=true server=false
[org.apache.sshd.client.session.ClientSessionImpl] addPasswordIdentity(ClientSessionImpl[user@/10.0.0.1:22]) SHA256:NhNdqVhmUqoL3v7mKAAcTE62kB4nikQjOiPNKBHq3Bk
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: kex algorithms = curve25519-sha256
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: server host key algorithms = ecdsa-sha2-nistp521
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: encryption algorithms (client to server) = aes128-ctr
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: encryption algorithms (server to client) = aes128-ctr
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: mac algorithms (client to server) = hmac-sha2-256
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: mac algorithms (server to client) = hmac-sha2-256
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: compression algorithms (client to server) = none
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) setNegotiationResult(ClientSessionImpl[user@/10.0.0.1:22]) Kex: compression algorithms (server to client) = none
[org.apache.sshd.client.kex.DHGClient] (sshd-SshClient[178aeecb]-nio2-thread-4) init(DHGClient[curve25519-sha256])[ClientSessionImpl[user@/10.0.0.1:22]] Send SSH_MSG_KEXDH_INIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #1 sending command=30[30] len=37
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-4) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 56 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #1 31
[org.apache.sshd.client.kex.DHGClient] (sshd-SshClient[178aeecb]-nio2-thread-5) next(DHGClient[curve25519-sha256])[ClientSessionImpl[user@/10.0.0.1:22]] process command=SSH_MSG_KEXDH_REPLY
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) setServerKey(ClientSessionImpl[user@/10.0.0.1:22]) keyType=ecdsa-sha2-nistp521, digest=SHA256:Stv8rltQHQdzaAAhaowY5sB24iPxTmFUZl1l1ePnzKM
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) handleKexMessage(ClientSessionImpl[user@/10.0.0.1:22])[curve25519-sha256] KEX processing complete after cmd=31
[org.apache.sshd.client.keyverifier.AcceptAllServerKeyVerifier] (sshd-SshClient[178aeecb]-nio2-thread-5) Server at /10.0.0.1:22 presented unverified EC key: SHA256:Stv8rltQHQdzaAAhaowY5sB24iPxTmFUZl1l1ePnzKM
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) checkKeys(ClientSessionImpl[user@/10.0.0.1:22]) key=ecdsa-sha2-nistp521-SHA256:Stv8rltQHQdzaAAhaowY5sB24iPxTmFUZl1l1ePnzKM, verified=true
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) sendNewKeys(ClientSessionImpl[user@/10.0.0.1:22]) Send SSH_MSG_NEWKEYS
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) prepareNewKeys(ClientSessionImpl[user@/10.0.0.1:22]) session ID=8c:3f:ba:9e:59:f4:c3:49:a2:f7:3f:32:2a:78:3b:4c:23:32:7f:db:32:c1:cc:4b:2e:ee:5a:79:b9:27:5b:5d
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #2 sending command=21[SSH_MSG_NEWKEYS] len=1
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-5) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 16 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) setOutputEncoding(ClientSessionImpl[user@/10.0.0.1:22]): cipher BaseCTRCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16]; mac BaseMac[HmacSHA256] -  block=32/32 bytes, encrypt-then-mac=false; compression none[Deflater/-1]; blocks limit 134217728
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) handleNewKeys(ClientSessionImpl[user@/10.0.0.1:22]) No pending packets to flush at end of KEX
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #2 SSH_MSG_NEWKEYS
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) handleNewKeys(ClientSessionImpl[user@/10.0.0.1:22]) SSH_MSG_NEWKEYS command=SSH_MSG_NEWKEYS
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) setInputEncoding(ClientSessionImpl[user@/10.0.0.1:22]): cipher BaseCTRCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16]; mac BaseMac[HmacSHA256] -  block=32/32 bytes, encrypt-then-mac=false; compression none[Inflater/-1]; blocks limit 4294967296
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) sendInitialServiceRequest(ClientSessionImpl[user@/10.0.0.1:22]) Send SSH_MSG_SERVICE_REQUEST for ssh-userauth
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #3 sending command=5[SSH_MSG_SERVICE_REQUEST] len=17
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-5) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 80 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-5) auth(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST for 'none'
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #4 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=44
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-5) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 112 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-1) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #3 SSH_MSG_SERVICE_ACCEPT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-1) handleServiceAccept(ClientSessionImpl[user@/10.0.0.1:22]) SSH_MSG_SERVICE_ACCEPT service=ssh-userauth
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-1) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #4 SSH_MSG_USERAUTH_FAILURE
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-1) processUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-1) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) starting authentication mechanisms: client=[publickey, keyboard-interactive, password], client index=0, server=[publickey, password]
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-1) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) attempting method=publickey
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[178aeecb]-nio2-thread-1) sendAuthDataRequest(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=rsa-sha2-512 - fingerprint=SHA256:WMPCZt1CDm+g0cG2exQ2+MOXuqJIA1YlmwUaG1bTUqQ
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-1) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #5 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=605
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 672 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-1) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=publickey
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #5 SSH_MSG_USERAUTH_FAILURE
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-2) processUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[178aeecb]-nio2-thread-2) sendAuthDataRequest(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=rsa-sha2-256 - fingerprint=SHA256:WMPCZt1CDm+g0cG2exQ2+MOXuqJIA1YlmwUaG1bTUqQ
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #6 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=605
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 672 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-2) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=publickey
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-3) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #6 SSH_MSG_USERAUTH_FAILURE
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-3) processUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[178aeecb]-nio2-thread-3) sendAuthDataRequest(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=ssh-rsa - fingerprint=SHA256:WMPCZt1CDm+g0cG2exQ2+MOXuqJIA1YlmwUaG1bTUqQ
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-3) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #7 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=600
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-3) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 656 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-3) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=publickey
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #7 SSH_MSG_USERAUTH_FAILURE
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-4) processUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
[org.apache.sshd.common.config.keys.loader.openssh.OpenSSHKeyPairResourceParser] (sshd-SshClient[178aeecb]-nio2-thread-4) extractKeyPairs(/home/test/.ssh/id_rsa_git) decode 1 keys using context=OpenSSHParserContext[cipher=none, kdfOptions=none: options=]
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[178aeecb]-nio2-thread-4) sendAuthDataRequest(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=rsa-sha2-512 - fingerprint=SHA256:LJDP6LLpfLiJJenyvRLFjN8CXC7dlTM07rKfVX7dfn8
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-4) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #8 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=605
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-4) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 672 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-4) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=publickey
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #8 SSH_MSG_USERAUTH_FAILURE
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-5) processUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[178aeecb]-nio2-thread-5) sendAuthDataRequest(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=rsa-sha2-256 - fingerprint=SHA256:LJDP6LLpfLiJJenyvRLFjN8CXC7dlTM07rKfVX7dfn8
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-5) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #9 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=605
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-5) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 672 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-5) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=publickey
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-1) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #9 SSH_MSG_USERAUTH_FAILURE
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-1) processUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[178aeecb]-nio2-thread-1) sendAuthDataRequest(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=ssh-rsa - fingerprint=SHA256:LJDP6LLpfLiJJenyvRLFjN8CXC7dlTM07rKfVX7dfn8
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-1) encode(ClientSessionImpl[user@/10.0.0.1:22]) packet #10 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=600
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) writing 656 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-1) tryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=publickey
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #10 SSH_MSG_USERAUTH_BANNER
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[178aeecb]-nio2-thread-2) process(ClientSessionImpl[user@/10.0.0.1:22]) Welcome banner(lang=):
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) doHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #11 SSH_MSG_DISCONNECT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=SshException, signalled=true, first=false: The connection is closed by SSH Server
[org.apache.sshd.client.session.ClientSessionImpl] close(ClientSessionImpl[user@/10.0.0.1:22]) Closing immediately
[org.apache.sshd.client.session.ClientSessionImpl] signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=SshException, signalled=false, first=false: Session is being closed
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) handleDisconnect(ClientSessionImpl[user@/10.0.0.1:22]) SSH_MSG_DISCONNECT reason=SSH2_DISCONNECT_PROTOCOL_ERROR, [lang= ] msg=The connection is closed by SSH Server
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] sessionClosed(ClientSessionImpl[user@/10.0.0.1:22]) un-tracked
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) handleReadCycleFailure(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) IOException after 233906 nanos at read cycle=11: Connection reset
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) exceptionCaught(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) caught IOException[Connection reset] - calling handler
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=IOException, signalled=false, first=false: Connection reset
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) exceptionCaught(ClientSessionImpl[user@/10.0.0.1:22]) ignore IOException due to state=Immediate, message='Connection reset'
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) close(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) Closing immediately
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) doCloseImmediately(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) closing socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/10.100.0.1:38794 remote=/10.0.0.1:22]
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) doCloseImmediately(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]) socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[178aeecb]-nio2-thread-2) unmapSession(id=101): Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[178aeecb]-nio2-thread-2) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[178aeecb]-nio2-thread-2) close(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.client.session.ClientUserAuthService] close(org.apache.sshd.client.session.ClientUserAuthService@1e4ac143) Closing immediately
[org.apache.sshd.client.session.ClientUserAuthService] close(org.apache.sshd.client.session.ClientUserAuthService@1e4ac143)[Immediately] closed
[org.apache.sshd.client.session.ClientConnectionService] close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]]) Closing immediately
[org.apache.sshd.client.session.ClientConnectionService] stopHeartBeat(ClientSessionImpl[user@/10.0.0.1:22]) no heartbeat to stop
[org.apache.sshd.client.session.ClientConnectionService] close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]])[Immediately] closed
[org.apache.sshd.common.io.nio2.Nio2Session] close(Nio2Session[local=/10.100.0.1:38794, remote=/10.0.0.1:22])[Immediately] state already Closed
[org.apache.sshd.common.util.closeable.SequentialCloseable] doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@4de533a6) signal close complete immediately=true
[org.apache.sshd.client.session.ClientSessionImpl] close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver] (MWFM Worker 2) resolveEffectiveHost(user@10.0.0.1:22/null) => 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.client.SshClient] (MWFM Worker 2) connect(user@10.0.0.1:22) effective: 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] (MWFM Worker 2) Connecting to /10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] (MWFM Worker 2) setOption(SO_REUSEADDR)[true] from property=Property[socket-reuseaddr](Boolean]
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) connect(/10.0.0.1:22): waiting for connection (timeout=60000ms)
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) Creating IoSession on /10.100.0.1:52522 from /10.0.0.1:22 via null
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) Client session created: Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) ClientUserAuthService(ClientSessionImpl[null@/10.0.0.1:22]) client methods: [publickey, keyboard-interactive, password]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) sessionCreated(ClientSessionImpl[null@/10.0.0.1:22]) tracking
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) initializeProxyConnector(ClientSessionImpl[null@/10.0.0.1:22]) no proxy to initialize
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) sendIdentification(ClientSessionImpl[null@/10.0.0.1:22]): SSH-2.0-APACHE-SSHD-2.15.0
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) writing 28 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) sendKexInit(ClientSessionImpl[null@/10.0.0.1:22]) Send SSH_MSG_KEXINIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) encode(ClientSessionImpl[null@/10.0.0.1:22]) packet #0 sending command=20[SSH_MSG_KEXINIT] len=1393
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) writing 1408 bytes
[org.apache.sshd.client.SshClient] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider override in session listener
[org.apache.sshd.client.SshClient] (sshd-SshClient[3ca6a47]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider enhanced
[org.apache.sshd.client.future.DefaultConnectFuture] (MWFM Worker 2) Connected to /10.0.0.1:22 after 12137620 nanos
[org.apache.sshd.client.session.ClientSessionImpl] (MWFM Worker 2) addPasswordIdentity(ClientSessionImpl[user@/10.0.0.1:22]) SHA256:NhNdqVhmUqoL3v7mKAAcTE62kB4nikQjOiPNKBHq3Bk
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-thread-3) handleReadCycleFailure(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) IOException after 12088785 nanos at read cycle=1: Connection reset
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-thread-3) exceptionCaught(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) caught IOException[Connection reset] - calling handler
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-thread-3) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=IOException, signalled=true, first=false: Connection reset
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-thread-3) exceptionCaught(ClientSessionImpl[user@/10.0.0.1:22])[state=Opened] IOException: Connection reset: java.io.IOException: Connection reset
[org.apache.sshd.client.session.ClientSessionImpl] (MWFM Worker 2) close(ClientSessionImpl[user@/10.0.0.1:22]) Closing immediately
[org.apache.sshd.client.session.ClientSessionImpl] (MWFM Worker 2) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=SshException, signalled=false, first=false: Session is being closed
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (MWFM Worker 2) sessionClosed(ClientSessionImpl[user@/10.0.0.1:22]) un-tracked
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[3ca6a47]-nio2-thread-3) sessionException(ClientSessionImpl[user@/10.0.0.1:22]) IOException: Connection reset
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) Closing immediately
[org.apache.sshd.client.session.ClientUserAuthService] (MWFM Worker 2) close(org.apache.sshd.client.session.ClientUserAuthService@26e71b15) Closing immediately
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-thread-3) doCloseImmediately(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) closing socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/10.100.0.1:52522 remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientUserAuthService] (MWFM Worker 2) close(org.apache.sshd.client.session.ClientUserAuthService@26e71b15)[Immediately] closed
[org.apache.sshd.client.session.ClientConnectionService] (MWFM Worker 2) close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]]) Closing immediately
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-thread-3) doCloseImmediately(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]) socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed
[org.apache.sshd.client.session.ClientConnectionService] (MWFM Worker 2) stopHeartBeat(ClientSessionImpl[user@/10.0.0.1:22]) no heartbeat to stop
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[3ca6a47]-nio2-thread-3) unmapSession(id=102): Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[3ca6a47]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[3ca6a47]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.client.session.ClientConnectionService] (MWFM Worker 2) close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]])[Immediately] closed
[org.apache.sshd.common.io.nio2.Nio2Session] (MWFM Worker 2) close(Nio2Session[local=/10.100.0.1:52522, remote=/10.0.0.1:22])[Immediately] state already Closed
[org.apache.sshd.common.util.closeable.SequentialCloseable] (MWFM Worker 2) doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@2a7193c2) signal close complete immediately=true
[org.apache.sshd.client.session.ClientSessionImpl] (MWFM Worker 2) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver] (MWFM Worker 1) resolveEffectiveHost(user@10.0.0.1:22/null) => 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.client.SshClient] (MWFM Worker 1) connect(user@10.0.0.1:22) effective: 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] (MWFM Worker 1) Connecting to /10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] (MWFM Worker 1) setOption(SO_REUSEADDR)[true] from property=Property[socket-reuseaddr](Boolean]
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) connect(/10.0.0.1:22): waiting for connection (timeout=60000ms)
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) Creating IoSession on /10.100.0.1:45052 from /10.0.0.1:22 via null
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) Client session created: Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) ClientUserAuthService(ClientSessionImpl[null@/10.0.0.1:22]) client methods: [publickey, keyboard-interactive, password]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) sessionCreated(ClientSessionImpl[null@/10.0.0.1:22]) tracking
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) initializeProxyConnector(ClientSessionImpl[null@/10.0.0.1:22]) no proxy to initialize
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) sendIdentification(ClientSessionImpl[null@/10.0.0.1:22]): SSH-2.0-APACHE-SSHD-2.15.0
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) writing 28 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) sendKexInit(ClientSessionImpl[null@/10.0.0.1:22]) Send SSH_MSG_KEXINIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) encode(ClientSessionImpl[null@/10.0.0.1:22]) packet #0 sending command=20[SSH_MSG_KEXINIT] len=1393
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) writing 1408 bytes
[org.apache.sshd.client.SshClient] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider override in session listener
[org.apache.sshd.client.SshClient] (sshd-SshClient[5833ddff]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider enhanced
[org.apache.sshd.client.future.DefaultConnectFuture] (MWFM Worker 1) Connected to /10.0.0.1:22 after 12742499 nanos
[org.apache.sshd.client.session.ClientSessionImpl] (MWFM Worker 1) addPasswordIdentity(ClientSessionImpl[user@/10.0.0.1:22]) SHA256:NhNdqVhmUqoL3v7mKAAcTE62kB4nikQjOiPNKBHq3Bk
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) handleReadCycleFailure(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) IOException after 14069579 nanos at read cycle=1: Connection reset
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) exceptionCaught(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) caught IOException[Connection reset] - calling handler
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-thread-3) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=IOException, signalled=true, first=false: Connection reset
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-thread-3) exceptionCaught(ClientSessionImpl[user@/10.0.0.1:22])[state=Opened] IOException: Connection reset: java.io.IOException: Connection reset
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[5833ddff]-nio2-thread-3) sessionException(ClientSessionImpl[user@/10.0.0.1:22]) IOException: Connection reset
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[5833ddff]-nio2-thread-3) sessionClosed(ClientSessionImpl[user@/10.0.0.1:22]) un-tracked
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22]) Closing immediately
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-thread-3) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=SshException, signalled=false, first=false: Session is being closed
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[5833ddff]-nio2-thread-3) close(org.apache.sshd.client.session.ClientUserAuthService@3aa65cf2) Closing immediately
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[5833ddff]-nio2-thread-3) close(org.apache.sshd.client.session.ClientUserAuthService@3aa65cf2)[Immediately] closed
[org.apache.sshd.client.session.ClientConnectionService] (sshd-SshClient[5833ddff]-nio2-thread-3) close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]]) Closing immediately
[org.apache.sshd.client.session.ClientConnectionService] (sshd-SshClient[5833ddff]-nio2-thread-3) stopHeartBeat(ClientSessionImpl[user@/10.0.0.1:22]) no heartbeat to stop
[org.apache.sshd.client.session.ClientConnectionService] (sshd-SshClient[5833ddff]-nio2-thread-3) close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]])[Immediately] closed
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) Closing immediately
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) doCloseImmediately(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) closing socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/10.100.0.1:45052 remote=/10.0.0.1:22]
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) doCloseImmediately(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]) socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[5833ddff]-nio2-thread-3) unmapSession(id=103): Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.common.util.closeable.SequentialCloseable] (sshd-SshClient[5833ddff]-nio2-thread-3) doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@3461a5d6) signal close complete immediately=true
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[5833ddff]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[5833ddff]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:45052, remote=/10.0.0.1:22])[Immediately] state already Closed
[org.apache.sshd.client.config.hosts.DefaultConfigFileHostEntryResolver] resolveEffectiveHost(user@10.0.0.1:22/null) => 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.client.SshClient] connect(user@10.0.0.1:22) effective: 10.0.0.1: user@10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] Connecting to /10.0.0.1:22
[org.apache.sshd.common.io.nio2.Nio2Connector] setOption(SO_REUSEADDR)[true] from property=Property[socket-reuseaddr](Boolean]
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) connect(/10.0.0.1:22): waiting for connection (timeout=60000ms)
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) Creating IoSession on /10.100.0.1:45064 from /10.0.0.1:22 via null
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) Client session created: Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) ClientUserAuthService(ClientSessionImpl[null@/10.0.0.1:22]) client methods: [publickey, keyboard-interactive, password]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) sessionCreated(ClientSessionImpl[null@/10.0.0.1:22]) tracking
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) initializeProxyConnector(ClientSessionImpl[null@/10.0.0.1:22]) no proxy to initialize
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) sendIdentification(ClientSessionImpl[null@/10.0.0.1:22]): SSH-2.0-APACHE-SSHD-2.15.0
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) writing 28 bytes
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) sendKexInit(ClientSessionImpl[null@/10.0.0.1:22]) Send SSH_MSG_KEXINIT
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) encode(ClientSessionImpl[null@/10.0.0.1:22]) packet #0 sending command=20[SSH_MSG_KEXINIT] len=1393
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) writeBuffer(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) writing 1408 bytes
[org.apache.sshd.client.SshClient] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider override in session listener
[org.apache.sshd.client.SshClient] (sshd-SshClient[36cadf8]-nio2-resume-thread-1) setupDefaultSessionIdentities(ClientSessionImpl[user@/10.0.0.1:22]) key identity provider enhanced
[org.apache.sshd.client.future.DefaultConnectFuture] Connected to /10.0.0.1:22 after 12534511 nanos
[org.apache.sshd.client.session.ClientSessionImpl] addPasswordIdentity(ClientSessionImpl[user@/10.0.0.1:22]) SHA256:NhNdqVhmUqoL3v7mKAAcTE62kB4nikQjOiPNKBHq3Bk
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-thread-3) handleReadCycleFailure(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) IOException after 14448337 nanos at read cycle=1: Connection reset
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-thread-3) exceptionCaught(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) caught IOException[Connection reset] - calling handler
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-thread-3) signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=IOException, signalled=true, first=false: Connection reset
[org.apache.sshd.client.session.ClientSessionImpl] close(ClientSessionImpl[user@/10.0.0.1:22]) Closing immediately
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-thread-3) exceptionCaught(ClientSessionImpl[user@/10.0.0.1:22])[state=Opened] IOException: Connection reset: java.io.IOException: Connection reset
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[36cadf8]-nio2-thread-3) sessionException(ClientSessionImpl[user@/10.0.0.1:22]) IOException: Connection reset
[org.apache.sshd.common.session.helpers.SessionTimeoutListener] (sshd-SshClient[36cadf8]-nio2-thread-3) sessionClosed(ClientSessionImpl[user@/10.0.0.1:22]) un-tracked
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) Closing immediately
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-thread-3) doCloseImmediately(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) closing socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/10.100.0.1:45064 remote=/10.0.0.1:22]
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-thread-3) doCloseImmediately(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]) socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed
[org.apache.sshd.common.io.nio2.Nio2Connector] (sshd-SshClient[36cadf8]-nio2-thread-3) unmapSession(id=104): Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22]
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-thread-3) close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] state already Immediate
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[36cadf8]-nio2-thread-3) close(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.client.session.ClientSessionImpl] signalAuthFailure(ClientSessionImpl[user@/10.0.0.1:22]) type=SshException, signalled=false, first=false: Session is being closed
[org.apache.sshd.client.session.ClientUserAuthService] close(org.apache.sshd.client.session.ClientUserAuthService@2ac3071) Closing immediately
[org.apache.sshd.client.session.ClientUserAuthService] close(org.apache.sshd.client.session.ClientUserAuthService@2ac3071)[Immediately] closed
[org.apache.sshd.client.session.ClientConnectionService] close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]]) Closing immediately
[org.apache.sshd.client.session.ClientConnectionService] stopHeartBeat(ClientSessionImpl[user@/10.0.0.1:22]) no heartbeat to stop
[org.apache.sshd.client.session.ClientConnectionService] close(ClientConnectionService[ClientSessionImpl[user@/10.0.0.1:22]])[Immediately] closed
[org.apache.sshd.common.io.nio2.Nio2Session] close(Nio2Session[local=/10.100.0.1:45064, remote=/10.0.0.1:22])[Immediately] state already Closed
[org.apache.sshd.common.util.closeable.SequentialCloseable] doClose(org.apache.sshd.common.util.closeable.SequentialCloseable$1@64495eb1) signal close complete immediately=true
[org.apache.sshd.client.session.ClientSessionImpl] close(ClientSessionImpl[user@/10.0.0.1:22])[Immediately] closed
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[36cadf8]-nio2-thread-3) exceptionCaught(ClientSessionImpl[user@/10.0.0.1:22])[state=Opened] IOException: Connection reset: java.io.IOException: Connection reset
        at java.base/sun.nio.ch.UnixAsynchronousSocketChannelImpl.finishRead(UnixAsynchronousSocketChannelImpl.java:425)
        at java.base/sun.nio.ch.UnixAsynchronousSocketChannelImpl.finish(UnixAsynchronousSocketChannelImpl.java:195)
        at java.base/sun.nio.ch.UnixAsynchronousSocketChannelImpl.onEvent(UnixAsynchronousSocketChannelImpl.java:217)
        at java.base/sun.nio.ch.EPollPort$EventHandlerTask.run(EPollPort.java:306)
        at java.base/java.lang.Thread.run(Thread.java:840)

The verbose output of the SSH command returns:

...
debug1: Next authentication method: password
user@10.0.0.1's password:
Info: Password will expire in: 90 days.debug1: Authentication succeeded (password).
Authenticated to 10.0.0.1 ([10.0.0.1]:22).
debug1: channel 0: new [client-session]
debug1: Entering interactive session.
debug1: pledge: network
debug1: Sending environment.
debug1: Sending env LANG = en_US.UTF-8

Expected behavior

In this case it worked as expected:

...
[org.apache.sshd.client.auth.pubkey.UserAuthPublicKey] (sshd-SshClient[1cdfac07]-nio2-thread-8) userresolveAttemptedPublicKeyIdentity(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] no more keys to send
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[1cdfac07]-nio2-thread-8) usertryNext(ClientSessionImpl[user@/10.0.0.1:22]) no initial request sent by method=publickey
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[1cdfac07]-nio2-thread-8) usertryNext(ClientSessionImpl[user@/10.0.0.1:22]) attempting method=password
[org.apache.sshd.client.auth.password.UserAuthPassword] (sshd-SshClient[1cdfac07]-nio2-thread-8) usersendPassword(ClientSessionImpl[user@/10.0.0.1:22])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST for password - modified=false
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[1cdfac07]-nio2-thread-8) userencode(ClientSessionImpl[user@/10.0.0.1:22]) packet #8 sending command=50[SSH_MSG_USERAUTH_REQUEST] len=63
[org.apache.sshd.common.io.nio2.Nio2Session] (sshd-SshClient[1cdfac07]-nio2-thread-8) userwriteBuffer(Nio2Session[local=/10.100.0.1:60698, remote=/10.78.55.130:22]) writing 128 bytes
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[1cdfac07]-nio2-thread-8) usertryNext(ClientSessionImpl[user@/10.0.0.1:22]) successfully processed initial buffer by method=password
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[1cdfac07]-nio2-thread-9) userdoHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #8 SSH_MSG_USERAUTH_BANNER
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[1cdfac07]-nio2-thread-9) userprocess(ClientSessionImpl[user@/10.0.0.1:22]) Welcome banner(lang=): Info: Password will expire in: 90 days.
[org.apache.sshd.client.session.ClientSessionImpl] (sshd-SshClient[1cdfac07]-nio2-thread-9) userdoHandleMessage(ClientSessionImpl[user@/10.0.0.1:22]) process #9 SSH_MSG_USERAUTH_SUCCESS
[org.apache.sshd.client.session.ClientUserAuthService] (sshd-SshClient[1cdfac07]-nio2-thread-9) userprocessUserAuth(ClientSessionImpl[user@/10.0.0.1:22]) SSH_MSG_USERAUTH_SUCCESS Succeeded with password
...

Relevant log output

Other information

No response

[tomaswolf]

The failing log shows 6 attempts to authenticate with two RSA keys (for each key with sa-sha2-512 signature, then rsa-sha2-256 signature, and finally with the deprecated ssh-rsa signature). After the sixth failed authentication attempt, the server closes the connection.

The successful log excerpt shows no public key authentication attempts at all.

Hence configure your Java SSH client to not use publickey authentication with this device, but only password.

[GabriRuflex]

Thanks @tomaswolf for your quick response.

This is my implementation:

Map<String, Object> envParameters = MapEntryUtils.MapBuilder.<String, Object>builder()
      .put(CommonModuleProperties.SESSION_HEARTBEAT_TYPE.getName(), SessionHeartbeatController.HeartbeatType.IGNORE)
      .put(CommonModuleProperties.SESSION_HEARTBEAT_INTERVAL.getName(), HEARTBEAT.toMillis())
      .put(CoreModuleProperties.AUTH_METHODS.getName(), List.of(UserAuthPasswordFactory.INSTANCE)) // tried with List.of(UserAuthPasswordFactory.PASSWORD) and UserAuthPasswordFactory.PASSWORD
      .put(SftpModuleProperties.AUTH_TIME.getName(), SSH_TIMEOUT.toMillis())
      .put(SftpModuleProperties.CONNECT_TIME.getName(), SSH_TIMEOUT.toMillis())
      .put(SftpModuleProperties.SFTP_CHANNEL_OPEN_TIMEOUT.getName(), SFTP_TIMEOUT.toMillis()).build();

...


FileSystem fileSystem = FileSystems.newFileSystem(
      createFileSystemURI(remotehost, remoteport, username, password), envParameters, this.getClass().getClassLoader())

But unfortunately SSHD seems to ignore the selected auth methods.
Is this implementation correct? Am I missing something?

Thanks!

[GabriRuflex]

@tomaswolf I updated the title and the description.

The problem was simple (though not obvious to me, thanks for the help): even though I had defined the auth methods in the environment parameters, the FileSystemProvider ignored them, causing too many connection attempts and the server resetting the connection.

Defining the SshClient separately and then passing it to SftpFileSystemProvider worked for me.

IMHO, it would be much more convenient if the FileSystemProvider could respect the environment parameters directly.
This would avoid additional boilerplate code, making the code more readable and maintainable.

This code worked for me:

try (SshClient client = SshClient.setUpDefaultClient()) {
    client.setUserAuthFactories(List.of(UserAuthPasswordFactory.INSTANCE));
    client.start();

    try (FileSystem fs = new SftpFileSystemProvider(client)
            .newFileSystem(uri, Collections.emptyMap())) {
...
    }
}

[tomaswolf]

Admittedly defining settings for automatically created SftpFileSystems is currently a bit of a mess. We are in the process of developing a new 3.0.0 major release of Apache MINA SSHD, and I plan as part of that effort to improve that area of the SFTP support, too. Keep an eye out for the 3.0.0 milestone pre-releases.

[GabriRuflex]

Thanks a lot @tomaswolf , I will do it!