bangnab commented on issue #98: [SSHD-909] SFTP versions extension handler ignores non-numerical ions when resolving the available ones URL: https://github.com/apache/mina-sshd/pull/98#issuecomment-475195269 Did just a simple test: ` try (SshClient client = SshClient.setUpDefaultClient()) { client.start(); ConnectFuture connect = client.connect(login, host, port); connect.await(); try (ClientSession session = connect.getSession()) { session.addPasswordIdentity(password); session.auth().verify(5L, TimeUnit.SECONDS).isFailure(); DefaultSftpClient sftp = new DefaultSftpClient(session); SftpClient.Attributes lstat = sftp.lstat("."); assertTrue(lstat.isDirectory()); } }` and it went well. Here's the log 12:16:12.191 [main] INFO o.a.s.c.u.s.b.BouncyCastleSecurityProviderRegistrar - getOrCreateProvider(BC) created instance of org.bouncycastle.jce.provider.BouncyCastleProvider 12:16:12.199 [main] DEBUG o.a.s.c.util.security.SecurityUtils - register(EdDSA) not registered - enabled=true, supported=false 12:16:12.432 [main] INFO o.a.s.c.i.DefaultIoServiceFactoryFactory - No detected/configured IoServiceFactoryFactory using Nio2ServiceFactoryFactory 12:16:12.443 [main] DEBUG o.a.s.c.c.h.DefaultConfigFileHostEntryResolver - reloadHostConfigEntries(someuser@localhost:22222) check permissions of /home/ambnic/.ssh/config 12:16:12.463 [main] INFO o.a.s.c.c.h.DefaultConfigFileHostEntryResolver - resolveEffectiveResolver(someuser@localhost:22222) loaded 33 entries from /home/ambnic/.ssh/config 12:16:12.467 [main] DEBUG o.a.s.c.c.h.DefaultConfigFileHostEntryResolver - resolveEffectiveHost(someuser@localhost:22222) => localhost: someuser@localhost:22222 12:16:12.467 [main] DEBUG org.apache.sshd.client.SshClient - connect(someuser@localhost:22222) effective: localhost: someuser@localhost:22222 12:16:12.469 [main] DEBUG o.a.s.common.io.nio2.Nio2Connector - Connecting to localhost/127.0.0.1:22222 12:16:12.475 [main] DEBUG o.a.s.common.io.nio2.Nio2Connector - setOption(SO_REUSEADDR)[true] from property=socket-reuseaddr 12:16:12.481 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - Creating IoSession on /127.0.0.1:44616 from localhost/127.0.0.1:22222 via null 12:16:12.498 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.session.ClientSessionImpl - Client session created: Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222] 12:16:12.500 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.s.ClientUserAuthService - ClientUserAuthService(ClientSessionImpl[null@localhost/127.0.0.1:22222]) client methods: [publickey, keyboard-interactive, password] 12:16:12.510 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.s.h.SessionTimeoutListener - sessionCreated(ClientSessionImpl[null@localhost/127.0.0.1:22222]) tracking 12:16:12.514 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.session.ClientSessionImpl - sendIdentification(ClientSessionImpl[null@localhost/127.0.0.1:22222]): SSH-2.0-APACHE-SSHD-2.3.0-SNAPSHOT 12:16:12.514 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 36 bytes 12:16:12.516 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=36 12:16:12.517 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.session.ClientSessionImpl - sendKexInit(ClientSessionImpl[null@localhost/127.0.0.1:22222]) Send SSH_MSG_KEXINIT 12:16:12.522 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 856 bytes 12:16:12.523 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=856 12:16:12.523 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG org.apache.sshd.client.SshClient - setupDefaultSessionIdentities(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) key identity provider override in session listener 12:16:12.538 [main] DEBUG o.a.s.c.session.ClientSessionImpl - addPasswordIdentity(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) SHA256:07+UP3WR9+B5ct30XOtzrP6wvdLjJ2df/9ngeG5tNn4 12:16:12.538 [main] DEBUG o.a.s.c.s.ClientUserAuthService - auth(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST for 'none' 12:16:12.552 [main] DEBUG o.a.s.c.session.ClientSessionImpl - writePacket(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[SSH_MSG_USERAUTH_REQUEST] Start flagging packets as pending until key exchange is done 12:16:12.608 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 34 bytes 12:16:12.608 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.session.ClientSessionImpl - doReadIdentification(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) line='SSH-2.0-VShell_4_2_1_1051 VShell' 12:16:12.609 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.session.ClientSessionImpl - readIdentification(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Server version string: SSH-2.0-VShell_4_2_1_1051 VShell 12:16:12.668 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 536 bytes 12:16:12.668 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.session.ClientSessionImpl - handleKexInit(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) SSH_MSG_KEXINIT 12:16:12.690 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.session.ClientSessionImpl - setNegotiationResult(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Kex: server->client aes128-ctr hmac-md5 none 12:16:12.691 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.session.ClientSessionImpl - setNegotiationResult(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Kex: client->server aes128-ctr hmac-md5 none 12:16:12.695 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.apache.sshd.client.kex.DHGEXClient - init(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Send SSH_MSG_KEX_DH_GEX_REQUEST 12:16:12.695 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 32 bytes 12:16:12.695 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=32 12:16:12.716 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 1048 bytes 12:16:12.718 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.apache.sshd.client.kex.DHGEXClient - next(DHGEXClient[diffie-hellman-group-exchange-sha1])[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]] process command=SSH_MSG_KEX_DH_GEX_GROUP 12:16:12.884 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.apache.sshd.client.kex.DHGEXClient - next(DHGEXClient[diffie-hellman-group-exchange-sha1])[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]] Send SSH_MSG_KEX_DH_GEX_INIT 12:16:12.884 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 536 bytes 12:16:12.885 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=536 12:16:12.885 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.s.c.session.ClientSessionImpl - handleKexMessage(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[diffie-hellman-group-exchange-sha1] more KEX packets expected after cmd=31 12:16:12.911 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 1040 bytes 12:16:12.911 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.apache.sshd.client.kex.DHGEXClient - next(DHGEXClient[diffie-hellman-group-exchange-sha1])[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]] process command=SSH_MSG_KEX_DH_GEX_REPLY 12:16:13.021 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - handleKexMessage(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[diffie-hellman-group-exchange-sha1] KEX processing complete after cmd=33 12:16:13.022 [sshd-SshClient[1e802ef9]-nio2-thread-5] WARN o.a.s.c.k.AcceptAllServerKeyVerifier - Server at localhost/127.0.0.1:22222 presented unverified DSA key: SHA256:It9yILa9a525xg4ROTxzg9NuH4Iiy0CnU/i+9lviVkM 12:16:13.023 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - checkKeys(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) key=ssh-dss-SHA256:It9yILa9a525xg4ROTxzg9NuH4Iiy0CnU/i+9lviVkM, verified=true 12:16:13.023 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - sendNewKeys(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Send SSH_MSG_NEWKEYS 12:16:13.023 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 16 bytes 12:16:13.023 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=16 12:16:13.024 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - handleNewKeys(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) SSH_MSG_NEWKEYS command=SSH_MSG_NEWKEYS 12:16:13.024 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - receiveNewKeys(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) session ID=4a:41:39:38:94:22:42:94:67:e1:d4:59:53:9c:2d:dc:2b:a4:c7:7d 12:16:13.058 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - receiveNewKeys(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) inCipher=BaseCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16], outCipher=BaseCipher[AES, ivSize=16, kdfSize=16,AES/CTR/NoPadding, blkSize=16], recommended blocks limit=4294967296, actual=4294967296 12:16:13.058 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - sendInitialServiceRequest(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Send SSH_MSG_SERVICE_REQUEST for ssh-userauth 12:16:13.059 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 64 bytes 12:16:13.060 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=64 12:16:13.060 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 80 bytes 12:16:13.060 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=80 12:16:13.061 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.s.c.session.ClientSessionImpl - handleNewKeys(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) sent 1 pending packets 12:16:13.086 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 48 bytes 12:16:13.087 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.session.ClientSessionImpl - handleServiceAccept(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) SSH_MSG_SERVICE_ACCEPT service=ssh-userauth 12:16:13.104 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 64 bytes 12:16:13.105 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.s.ClientUserAuthService - processUserAuth(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password 12:16:13.105 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.s.ClientUserAuthService - tryNext(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) starting authentication mechanisms: client=[publickey, keyboard-interactive, password], server=[publickey, password] 12:16:13.107 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.s.ClientUserAuthService - tryNext(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) attempting method=publickey 12:16:13.145 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.a.pubkey.UserAuthPublicKey - sendAuthDataRequest(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST request publickey type=ssh-rsa - fingerprint=SHA256:++UcWFkGntdRPF/OrnlOpCtS0GwiwNCKzdDke7vg5us 12:16:13.146 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 384 bytes 12:16:13.146 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=384 12:16:13.146 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.s.ClientUserAuthService - tryNext(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) successfully processed initial buffer by method=publickey 12:16:13.167 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 64 bytes 12:16:13.168 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.s.ClientUserAuthService - processUserAuth(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Received SSH_MSG_USERAUTH_FAILURE - partial=false, methods=publickey,password 12:16:13.168 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.c.k.DefaultClientIdentitiesWatcher - loadKeys(/home/ambnic/.ssh/id_dsa) no key loaded 12:16:13.169 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.c.k.DefaultClientIdentitiesWatcher - loadKeys(/home/ambnic/.ssh/id_ecdsa) no key loaded 12:16:13.169 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.c.k.DefaultClientIdentitiesWatcher - loadKeys(/home/ambnic/.ssh/id_ed25519) no key loaded 12:16:13.169 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.a.pubkey.UserAuthPublicKey - sendAuthDataRequest(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[ssh-connection] no more keys to send 12:16:13.169 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.s.ClientUserAuthService - tryNext(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) no initial request sent by method=publickey 12:16:13.170 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.a.pubkey.UserAuthPublicKey - destroy(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[ssh-connection] 12:16:13.171 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.s.ClientUserAuthService - tryNext(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) attempting method=password 12:16:13.174 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.a.password.UserAuthPassword - sendPassword(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[ssh-connection] send SSH_MSG_USERAUTH_REQUEST for password - modified=false 12:16:13.174 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 112 bytes 12:16:13.175 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=112 12:16:13.175 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.s.c.s.ClientUserAuthService - tryNext(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) successfully processed initial buffer by method=password 12:16:13.198 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 32 bytes 12:16:13.198 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.s.c.s.ClientUserAuthService - processUserAuth(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) SSH_MSG_USERAUTH_SUCCESS Succeeded with password 12:16:13.199 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.s.c.a.password.UserAuthPassword - destroy(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[ssh-connection] 12:16:13.199 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.s.c.s.ClientConnectionService - stopHeartBeat(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) no heartbeat to stop 12:16:13.200 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.s.c.s.ClientConnectionService - startHeartbeat(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) heartbeat type=null, interval=0 12:16:13.226 [main] DEBUG o.a.s.c.channel.ChannelSubsystem - init() service=ClientConnectionService[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]] session=ClientSessionImpl[someuser@localhost/127.0.0.1:22222] id=0 12:16:13.227 [main] DEBUG o.apache.sshd.common.channel.Window - init(Window[client/local](ChannelSubsystem[id=0, recipient=-1]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) size=2097152, max=2097152, packet=32768 12:16:13.227 [main] DEBUG o.a.s.c.s.ClientConnectionService - registerChannel(ClientConnectionService[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]])[id=0] ChannelSubsystem[id=0, recipient=-1]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp] 12:16:13.227 [main] DEBUG o.a.s.c.session.ClientSessionImpl - createSubsystemChannel(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[sftp] created id=0 12:16:13.228 [main] DEBUG o.a.s.c.channel.ChannelSubsystem - open(ChannelSubsystem[id=0, recipient=-1]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) Send SSH_MSG_CHANNEL_OPEN - type=session 12:16:13.229 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 64 bytes 12:16:13.229 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=64 12:16:13.250 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 48 bytes 12:16:13.251 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.s.ClientConnectionService - channelOpenConfirmation(ChannelSubsystem[id=0, recipient=-1]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) SSH_MSG_CHANNEL_OPEN_CONFIRMATION sender=0, window-size=0, packet-size=16384 12:16:13.251 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.channel.ChannelSubsystem - setRecipient(ChannelSubsystem[id=0, recipient=-1]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) recipient=0 12:16:13.251 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.apache.sshd.common.channel.Window - init(Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) size=0, max=0, packet=16384 12:16:13.251 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.channel.ChannelSubsystem - doOpen(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) SSH_MSG_CHANNEL_REQUEST subsystem=sftp 12:16:13.251 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.channel.ChannelSubsystem - addPendingRequest(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) request=subsystem, pending=Thu Mar 21 12:16:13 CET 2019 12:16:13.252 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 64 bytes 12:16:13.252 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=64 12:16:13.272 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 80 bytes 12:16:13.273 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.channel.ChannelSubsystem - handleWindowAdjust(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) SSH_MSG_CHANNEL_WINDOW_ADJUST window=32768 12:16:13.273 [main] DEBUG o.apache.sshd.common.channel.Window - waitForSpace(Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) available: 32768 12:16:13.273 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.apache.sshd.common.channel.Window - Increase Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) by 32768 up to 32768 12:16:13.273 [main] DEBUG o.apache.sshd.common.channel.Window - waitForSpace(Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) available: 32768 12:16:13.273 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.channel.ChannelSubsystem - removePendingRequest(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) request=subsystem, pending=Thu Mar 21 12:16:13 CET 2019 12:16:13.273 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.s.c.channel.ChannelSubsystem - handleSuccess(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) subsystem=sftp, pending since=Thu Mar 21 12:16:13 CET 2019 12:16:13.275 [main] DEBUG o.apache.sshd.common.channel.Window - waitAndConsume(Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) - requested=9, available=32768 12:16:13.276 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 64 bytes 12:16:13.276 [sshd-SshClient[1e802ef9]-nio2-thread-3] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=64 12:16:13.299 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 1376 bytes 12:16:13.301 [sshd-SshClient[1e802ef9]-nio2-thread-4] DEBUG o.a.s.c.channel.ChannelSubsystem - handleData(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) SSH_MSG_CHANNEL_DATA len=1342 12:16:13.302 [main] DEBUG o.apache.sshd.common.channel.Window - waitForSpace(Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) available: 32759 12:16:13.302 [main] DEBUG o.apache.sshd.common.channel.Window - waitAndConsume(Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])) - requested=18, available=32759 12:16:13.303 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - writePacket(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Writing 64 bytes 12:16:13.303 [sshd-SshClient[1e802ef9]-nio2-thread-5] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleCompletedWriteCycle(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) finished writing len=64 12:16:13.332 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.sshd.common.io.nio2.Nio2Session - handleReadCycleCompletion(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) read 400 bytes 12:16:13.333 [sshd-SshClient[1e802ef9]-nio2-thread-1] DEBUG o.a.s.c.channel.ChannelSubsystem - handleData(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) SSH_MSG_CHANNEL_DATA len=360 12:16:13.340 [main] DEBUG o.a.s.c.session.ClientSessionImpl - close(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) Closing immediately 12:16:13.341 [main] DEBUG o.a.s.c.session.ClientSessionImpl - signalAuthFailure(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) type=SshException, signalled=false, message="Session is being closed" 12:16:13.343 [main] DEBUG o.a.s.c.s.h.SessionTimeoutListener - sessionClosed(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) un-tracked 12:16:13.359 [main] DEBUG o.a.s.c.s.ClientConnectionService - close(ClientConnectionService[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]]) Closing immediately 12:16:13.359 [main] DEBUG o.a.s.c.s.ClientConnectionService - stopHeartBeat(ClientSessionImpl[someuser@localhost/127.0.0.1:22222]) no heartbeat to stop 12:16:13.360 [main] DEBUG o.a.s.c.channel.ChannelSubsystem - close(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) Closing immediately 12:16:13.360 [main] DEBUG o.a.s.c.channel.ChannelSubsystem - close(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) prevent sending EOF 12:16:13.361 [main] DEBUG o.apache.sshd.common.channel.Window - Closing Window[client/local](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) 12:16:13.361 [main] DEBUG o.apache.sshd.common.channel.Window - Closing Window[client/remote](ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) 12:16:13.385 [main] DEBUG o.a.s.c.channel.ChannelSubsystem - sendEof(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) already closing or closed 12:16:13.386 [main] DEBUG o.a.s.c.c.AbstractChannel$GracefulChannelCloseable - close(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])[immediately=true] processing 12:16:13.387 [main] DEBUG o.a.s.c.s.ClientConnectionService - unregisterChannel(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp]) result=ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp] 12:16:13.388 [main] DEBUG o.a.s.c.u.c.SequentialCloseable - doClose(true) signal close complete 12:16:13.388 [main] DEBUG o.a.s.c.u.c.SequentialCloseable - doClose(true) signal close complete 12:16:13.388 [main] DEBUG o.a.s.c.u.c.SequentialCloseable - doClose(true) signal close complete 12:16:13.395 [main] DEBUG o.a.s.c.channel.ChannelSubsystem - close(ChannelSubsystem[id=0, recipient=0]-ClientSessionImpl[someuser@localhost/127.0.0.1:22222][sftp])[Immediately] closed 12:16:13.395 [main] DEBUG o.a.s.c.s.ClientConnectionService - close(ClientConnectionService[ClientSessionImpl[someuser@localhost/127.0.0.1:22222]])[Immediately] closed 12:16:13.395 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - close(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) Closing immediately 12:16:13.395 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - doCloseImmediately(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) closing socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[connected local=/127.0.0.1:44616 remote=localhost/127.0.0.1:22222] 12:16:13.396 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - doCloseImmediately(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222]) socket=sun.nio.ch.UnixAsynchronousSocketChannelImpl[closed] closed 12:16:13.396 [sshd-SshClient[1e802ef9]-nio2-thread-2] DEBUG o.a.sshd.common.io.nio2.Nio2Session - close(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222])[Immediately] state already Immediate 12:16:13.398 [main] DEBUG o.a.s.common.io.nio2.Nio2Connector - unmapSession(id=101): Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222] 12:16:13.398 [main] DEBUG o.a.s.c.session.ClientSessionImpl - close(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[Immediately] state already Immediate 12:16:13.398 [main] DEBUG o.a.sshd.common.io.nio2.Nio2Session - close(Nio2Session[local=/127.0.0.1:44616, remote=localhost/127.0.0.1:22222])[Immediately] closed 12:16:13.398 [main] DEBUG o.a.s.c.u.c.SequentialCloseable - doClose(true) signal close complete 12:16:13.399 [main] DEBUG o.a.s.c.session.ClientSessionImpl - close(ClientSessionImpl[someuser@localhost/127.0.0.1:22222])[Immediately] closed 12:16:13.399 [main] DEBUG org.apache.sshd.client.SshClient - close(SshClient[1e802ef9]) Closing immediately 12:16:13.400 [main] DEBUG o.a.s.common.io.nio2.Nio2Connector - close(org.apache.sshd.common.io.nio2.Nio2Connector@55182842) Closing immediately 12:16:13.400 [main] DEBUG o.a.s.common.io.nio2.Nio2Connector - close(org.apache.sshd.common.io.nio2.Nio2Connector@55182842)[Immediately] closed 12:16:13.400 [main] DEBUG o.a.s.c.io.nio2.Nio2ServiceFactory - close(org.apache.sshd.common.io.nio2.Nio2ServiceFactory@3270d194) Closing immediately 12:16:13.400 [main] DEBUG o.a.s.c.io.nio2.Nio2ServiceFactory - Shutdown group 12:16:13.401 [main] DEBUG o.a.s.c.io.nio2.Nio2ServiceFactory - Group successfully shut down 12:16:13.401 [main] DEBUG o.a.s.c.io.nio2.Nio2ServiceFactory - Shutdown executor 12:16:13.402 [main] DEBUG o.a.s.c.io.nio2.Nio2ServiceFactory - Shutdown complete 12:16:13.402 [main] DEBUG o.a.s.c.io.nio2.Nio2ServiceFactory - close(org.apache.sshd.common.io.nio2.Nio2ServiceFactory@3270d194)[Immediately] closed 12:16:13.402 [main] DEBUG o.a.s.c.u.c.SequentialCloseable - doClose(true) signal close complete 12:16:13.402 [main] DEBUG org.apache.sshd.client.SshClient - close(SshClient[1e802ef9])[Immediately] closed Process finished with exit code 0
---------------------------------------------------------------- This is an automated message from the Apache Git Service. To respond to the message, please log on to GitHub and use the URL above to go to the specific comment. For queries about this service, please contact Infrastructure at: [email protected] With regards, Apache Git Services
