[
https://issues.apache.org/jira/browse/SSHD-909?focusedWorklogId=216685&page=com.atlassian.jira.plugin.system.issuetabpanels:worklog-tabpanel#worklog-216685
]
ASF GitHub Bot logged work on SSHD-909:
---------------------------------------
Author: ASF GitHub Bot
Created on: 21/Mar/19 11:32
Start Date: 21/Mar/19 11:32
Worklog Time Spent: 10m
Work Description: 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]
Issue Time Tracking
-------------------
Worklog Id: (was: 216685)
Time Spent: 0.5h (was: 20m)
> Fix SFTP version re-negotiation support
> ---------------------------------------
>
> Key: SSHD-909
> URL: https://issues.apache.org/jira/browse/SSHD-909
> Project: MINA SSHD
> Issue Type: Bug
> Affects Versions: 2.2.0
> Reporter: Goldstein Lyor
> Assignee: Goldstein Lyor
> Priority: Major
> Time Spent: 0.5h
> Remaining Estimate: 0h
>
> According to the
> [standard|[https://tools.ietf.org/html/draft-ietf-secsh-filexfer-11#section-5.5]]
> the versions are *strings* whereas the current code always treats them as
> *integers*.
> {quote}
> string "versions"
> string comma-separated-versions
> 'comma-separated-versions' is a string of comma separated version
> numbers. Defined versions are: "2", "3", "4", "5", "6". Any other
> version advertised by the server must follow the DNS extensibility
> naming convention outlined in [RFC4251].
> For example: "2,3,6,[email protected]".
> {quote}
> Note: the same applies for the client's choice
> {quote}
> string "version-select"
> string version-from-list
> {quote}
--
This message was sent by Atlassian JIRA
(v7.6.3#76005)
