Hello
I encountered a very troublesome issue
ISSUE: https://issues.apache.org/jira/browse/SSHD-700
Environment:
XShell (with XAgent) [Running on Windows] -> MINA SSHD (as SSH server,
password authentication) -- MINA SSHD (as SSH client) [Running on Linux] ->
CentOS Linux (SSH Public Key Authentication)
Code: Attachment SshdTest3.java
Phenomenon:
If I use PuTTY, SecureCRT, or openssh client comes with Linux as SSH
client, the agent forward operation can be completed successfully.
But When I use XShell
(https://www.netsarang.com/products/xsh_overview.html), the authentication
process is blocked, even I forced the use of UnixAgentFactory (uncomment
line 54 and comment out line 55, and use tomcat apr/jni).
Debug log file: attachment log.txt
If anyone can help me out, very grateful.
Best Regards
Li Fangning
2016-10-17 10:22:39.357 [INFO ] [main]
[org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration::call]
Trying to register BouncyCastle as a JCE provider
2016-10-17 10:22:39.458 [INFO ] [main]
[org.apache.sshd.common.util.SecurityUtils$BouncyCastleRegistration::call]
Registration succeeded
2016-10-17 10:22:39.550 [DEBUG] [main]
[org.apache.sshd.common.io.nio2.Nio2Acceptor::bind] Binding Nio2Acceptor to
address 0.0.0.0/0.0.0.0:2022
2016-10-17 10:22:39.553 [DEBUG] [main]
[org.apache.sshd.common.io.nio2.Nio2Service::setOption]
setOption(SO_REUSEADDR)[true] from property=socket-reuseaddr
2016-10-17 10:22:43.400 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::<init>] Creating IoSession on
/10.10.0.12:2022 from /192.168.21.90:59333
2016-10-17 10:22:43.426 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.server.session.ServerSessionImpl::<init>] Server session
created Nio2Session[local=/10.10.0.12:2022, remote=/192.168.21.90:59333]
2016-10-17 10:22:43.426 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener::sessionCreated]
sessionCreated(ServerSessionImpl[null@/192.168.21.90:59333]) trackin
2016-10-17 10:22:43.429 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::sendIdentification]
sendIdentification(ServerSessionImpl[null@/192.168.21.90:59333]): SSH-2
2016-10-17 10:22:43.429 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 34 bytes
2016-10-17 10:22:43.434 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:43.436 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:43.437 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::doReadIdentification]
doReadIdentification(ServerSessionImpl[null@/192.168.21.90:59333]) linc.'
2016-10-17 10:22:43.438 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.server.session.AbstractServerSession::readIdentification]
readIdentification(ServerSessionImpl[null@/192.168.21.90:59333]) client
vComputer, Inc.
2016-10-17 10:22:43.484 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.server.keyprovider.AbstractGeneratorHostKeyProvider::loadFromFile]
resolveKeyPair(/root/java/mysample) loaded key=ssh-dss-SHA256:DE
2016-10-17 10:22:43.486 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::sendKexInit]
sendKexInit(ServerSessionImpl[null@/192.168.21.90:59333]) Send SSH_MSG_KEXINIT
2016-10-17 10:22:43.487 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 680 bytes
2016-10-17 10:22:43.488 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:43.488 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::handleKexInit]
handleKexInit(ServerSessionImpl[null@/192.168.21.90:59333]) SSH_MSG_KEXINIT
2016-10-17 10:22:43.489 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::setNegotiationResult]
setNegotiationResult(ServerSessionImpl[null@/192.168.21.90:59333]) Ke
2016-10-17 10:22:43.490 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::setNegotiationResult]
setNegotiationResult(ServerSessionImpl[null@/192.168.21.90:59333]) Ke
2016-10-17 10:22:43.537 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:43.541 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.server.kex.DHGServer::next]
next(DHGServer[diffie-hellman-group14-sha1])[ServerSessionImpl[null@/192.168.21.90:59333]]
process comm
2016-10-17 10:22:43.578 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.server.kex.DHGServer::next]
next(DHGServer[diffie-hellman-group14-sha1])[ServerSessionImpl[null@/192.168.21.90:59333]]
Send SSH_MSG
2016-10-17 10:22:43.578 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 776 bytes
2016-10-17 10:22:43.579 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:43.579 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::handleKexMessage]
handleKexMessage(ServerSessionImpl[null@/192.168.21.90:59333])[diffie-helcmd=30
2016-10-17 10:22:43.579 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::sendNewKeys]
sendNewKeys(ServerSessionImpl[null@/192.168.21.90:59333]) Send SSH_MSG_NEWKEYS
2016-10-17 10:22:43.580 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 16 bytes
2016-10-17 10:22:43.580 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.749 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.754 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::handleNewKeys]
handleNewKeys(ServerSessionImpl[null@/192.168.21.90:59333]) SSH_MSG_NEWKEYS
2016-10-17 10:22:44.754 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::receiveNewKeys]
receiveNewKeys(ServerSessionImpl[null@/192.168.21.90:59333]) session
ID=53:9:eb:42
2016-10-17 10:22:44.780 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::receiveNewKeys]
receiveNewKeys(ServerSessionImpl[null@/192.168.21.90:59333])
inCipher=BaseCeCipher[AES,16,16,AES/CBC/NoPadding], recommended blocks
limit=4294967296, actual=4294967296
2016-10-17 10:22:44.781 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::handleServiceRequest]
handleServiceRequest(ServerSessionImpl[null@/192.168.21.90:59333]) SS
2016-10-17 10:22:44.783 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.server.session.ServerUserAuthService::<init>]
ServerUserAuthService(ServerSessionImpl[null@/192.168.21.90:59333]) authorized
authenublickey
2016-10-17 10:22:44.783 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::handleServiceRequest]
handleServiceRequest(ServerSessionImpl[null@/192.168.21.90:59333]) Ac
2016-10-17 10:22:44.784 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 68 bytes
2016-10-17 10:22:44.784 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.785 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.785 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Received
SSH_MSG_USERAUTH_REQUe
2016-10-17 10:22:44.786 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Authenticating user
'test' witempt 0 / 20)
2016-10-17 10:22:44.786 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) no authentication factory
for
2016-10-17 10:22:44.786 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(null@ServerSessionImpl[null@/192.168
2016-10-17 10:22:44.786 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(null@ServerSessionImpl[null@/192.168rd-interactive,publickey
2016-10-17 10:22:44.787 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 100 bytes
2016-10-17 10:22:44.787 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.818 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.819 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Received
SSH_MSG_USERAUTH_REQUlickey
2016-10-17 10:22:44.819 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Authenticating user
'test' wit (attempt 1 / 20)
2016-10-17 10:22:44.826 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.auth.pubkey.UserAuthPublicKey::doAuth]
doAuth(test@ServerSessionImpl[null@/192.168.21.90:59333]) verify key
type=ssh-rsa, facdsa-sha2-nistp521,ssh-rsa,ssh-dss,
fingerprint=SHA256:V2crcpXa8WD0l6t1j2ZaqHPDD+ljmiUfGO1U47C5Qpk
2016-10-17 10:22:44.834 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.config.keys.AuthorizedKeysAuthenticator::authenticate]
authenticate(test)[ServerSessionImpl[null@/192.168.21.90:59333]][RSA]_keys
2016-10-17 10:22:44.834 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.auth.pubkey.UserAuthPublicKey::doAuth]
doAuth(test@ServerSessionImpl[null@/192.168.21.90:59333]) key type=ssh-rsa,
fingerpri5Qpk - authentication result: false
2016-10-17 10:22:44.835 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(test@ServerSessionImpl[null@/192.168
2016-10-17 10:22:44.835 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(test@ServerSessionImpl[null@/192.168rd-interactive,publickey
2016-10-17 10:22:44.836 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 100 bytes
2016-10-17 10:22:44.836 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.837 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.838 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Received
SSH_MSG_USERAUTH_REQUlickey
2016-10-17 10:22:44.838 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Authenticating user
'test' wit (attempt 2 / 20)
2016-10-17 10:22:44.839 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.auth.pubkey.UserAuthPublicKey::doAuth]
doAuth(test@ServerSessionImpl[null@/192.168.21.90:59333]) verify key
type=ssh-rsa, facdsa-sha2-nistp521,ssh-rsa,ssh-dss,
fingerprint=SHA256:rzVfPrifyUr1fYaRaUfVqWIAYGZhDDBqWldfc0tKRHM
2016-10-17 10:22:44.840 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.config.keys.AuthorizedKeysAuthenticator::authenticate]
authenticate(test)[ServerSessionImpl[null@/192.168.21.90:59333]][RSA]_keys
2016-10-17 10:22:44.840 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.auth.pubkey.UserAuthPublicKey::doAuth]
doAuth(test@ServerSessionImpl[null@/192.168.21.90:59333]) key type=ssh-rsa,
fingerpriKRHM - authentication result: false
2016-10-17 10:22:44.841 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(test@ServerSessionImpl[null@/192.168
2016-10-17 10:22:44.841 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(test@ServerSessionImpl[null@/192.168rd-interactive,publickey
2016-10-17 10:22:44.842 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 100 bytes
2016-10-17 10:22:44.842 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.843 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.843 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Received
SSH_MSG_USERAUTH_REQUlickey
2016-10-17 10:22:44.844 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Authenticating user
'test' wit (attempt 3 / 20)
2016-10-17 10:22:44.845 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.auth.pubkey.UserAuthPublicKey::doAuth]
doAuth(test@ServerSessionImpl[null@/192.168.21.90:59333]) verify key
type=ssh-rsa, facdsa-sha2-nistp521,ssh-rsa,ssh-dss,
fingerprint=SHA256:hVU6TL0TPDQcrmzA/7/USwb8AXz2/IWHf6AtTMY+gPA
2016-10-17 10:22:44.845 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.config.keys.AuthorizedKeysAuthenticator::authenticate]
authenticate(test)[ServerSessionImpl[null@/192.168.21.90:59333]][RSA]_keys
2016-10-17 10:22:44.846 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.auth.pubkey.UserAuthPublicKey::doAuth]
doAuth(test@ServerSessionImpl[null@/192.168.21.90:59333]) key type=ssh-rsa,
fingerpri+gPA - authentication result: false
2016-10-17 10:22:44.846 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(test@ServerSessionImpl[null@/192.168
2016-10-17 10:22:44.846 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationFailure]
handleAuthenticationFailure(test@ServerSessionImpl[null@/192.168rd-interactive,publickey
2016-10-17 10:22:44.846 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 100 bytes
2016-10-17 10:22:44.847 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.848 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.849 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Received
SSH_MSG_USERAUTH_REQUsword
2016-10-17 10:22:44.849 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.server.session.ServerUserAuthService::process]
process(ServerSessionImpl[null@/192.168.21.90:59333]) Authenticating user
'test' wit(attempt 4 / 20)
2016-10-17 10:22:44.851 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.server.auth.password.UserAuthPassword::checkPassword]
checkPassword(ServerSessionImpl[null@/192.168.21.90:59333]) authentication re
2016-10-17 10:22:44.851 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationSuccess]
handleAuthenticationSuccess(test@ServerSessionImpl[null@/192.168
2016-10-17 10:22:44.852 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 52 bytes
2016-10-17 10:22:44.852 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.857 [INFO ] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.server.session.ServerUserAuthService::handleAuthenticationSuccess]
Session test@/192.168.21.90:59333 authenticated
2016-10-17 10:22:44.858 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.859 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::channelOpen]
channelOpen(ServerConnectionService[ServerSessionImpl[test@/192.168.e=session,
window-size=32768, packet-size=16384
2016-10-17 10:22:44.877 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.channel.AbstractChannel::init] init()
service=ServerConnectionService[ServerSessionImpl[test@/192.168.21.90:59333]]
session=
2016-10-17 10:22:44.878 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.channel.Window::init]
init(Window[server/local](ChannelSession[id=0,
recipient=-1]-ServerSessionImpl[test@/192.168.21.90:593
2016-10-17 10:22:44.878 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::registerChannel]
registerChannel(ServerConnectionService[ServerSessionImpl[test@/
recipient=-1]-ServerSessionImpl[test@/192.168.21.90:59333]
2016-10-17 10:22:44.879 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.channel.AbstractChannel::setRecipient]
setRecipient(ChannelSession[id=0,
recipient=-1]-ServerSessionImpl[test@/192.168.21.90
2016-10-17 10:22:44.879 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.channel.Window::init]
init(Window[server/remote](ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:593
2016-10-17 10:22:44.879 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.channel.Window::init]
init(Window[server/local](ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:5933
2016-10-17 10:22:44.879 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.channel.Window::init]
init(Window[server/local](ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:5933
2016-10-17 10:22:44.882 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::lambda$channelOpen$48]
operationComplete(ChannelSession[id=0,
recipient=0]-ServerMSG_CHANNEL_OPEN_CONFIRMATION recipient=0, sender=0,
window-size=2097152, packet-size=32768
2016-10-17 10:22:44.882 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 68 bytes
2016-10-17 10:22:44.883 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.884 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.885 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::handleChannelRequest]
handleChannelRequest(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test11-req wantReply=true
2016-10-17 10:22:44.886 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.forward.StaticDecisionForwardingFilter::checkAcceptance]
checkAcceptance(x11-req)[ServerSessionImpl[test@/192.168.21.90:5933
2016-10-17 10:22:44.887 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::getX11ForwardSupport]
getX11ForwardSupport(ServerSessionImpl[test@/192.168.21.90:
2016-10-17 10:22:44.888 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Acceptor::bind] Binding Nio2Acceptor to
address /127.0.0.1:6010
2016-10-17 10:22:44.888 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Service::setOption]
setOption(SO_REUSEADDR)[true] from property=socket-reuseaddr
2016-10-17 10:22:44.894 [WARN ] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.x11.DefaultX11ForwardSupport::createDisplay]
createDisplay(unix:10.0) failed (IOException) run xauth: Cannot run program "xa
2016-10-17 10:22:44.894 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.x11.DefaultX11ForwardSupport::createDisplay]
createDisplay(unix:10.0) xauth failure details
java.io.IOException: Cannot run program "xauth": error=2, No such file or
directory
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1048)
~[?:1.8.0_101]
at
org.apache.sshd.server.x11.DefaultX11ForwardSupport.createDisplay(DefaultX11ForwardSupport.java:145)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.server.channel.ChannelSession.handleX11Forwarding(ChannelSession.java:768)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.server.channel.ChannelSession.handleInternalRequest(ChannelSession.java:325)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.channel.AbstractChannel.handleUnknownChannelRequest(AbstractChannel.java:294)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.channel.AbstractChannel.handleChannelRequest(AbstractChannel.java:281)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.channel.AbstractChannel.handleRequest(AbstractChannel.java:243)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractConnectionService.channelRequest(AbstractConnectionService.java:466)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractConnectionService.process(AbstractConnectionService.java:317)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractSession.doHandleMessage(AbstractSession.java:540)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractSession.handleMessage(AbstractSession.java:473)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractSession.decode(AbstractSession.java:1341)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractSession.messageReceived(AbstractSession.java:434)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.session.helpers.AbstractSessionIoHandler.messageReceived(AbstractSessionIoHandler.java:67)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.io.nio2.Nio2Session.handleReadCycleCompletion(Nio2Session.java:286)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.io.nio2.Nio2Session$2.onCompleted(Nio2Session.java:266)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.io.nio2.Nio2Session$2.onCompleted(Nio2Session.java:263)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at
org.apache.sshd.common.io.nio2.Nio2CompletionHandler.lambda$completed$92(Nio2CompletionHandler.java:38)
~[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at java.security.AccessController.doPrivileged(Native Method)
~[?:1.8.0_101]
at
org.apache.sshd.common.io.nio2.Nio2CompletionHandler.completed(Nio2CompletionHandler.java:37)
[sshd-core-1.4.0-SNAPSHOT.jar:1.4.0-SNAPSHOT]
at sun.nio.ch.Invoker.invokeUnchecked(Invoker.java:126) [?:1.8.0_101]
at sun.nio.ch.Invoker$2.run(Invoker.java:218) [?:1.8.0_101]
at
sun.nio.ch.AsynchronousChannelGroupImpl$1.run(AsynchronousChannelGroupImpl.java:112)
[?:1.8.0_101]
at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[?:1.8.0_101]
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[?:1.8.0_101]
at java.lang.Thread.run(Thread.java:745) [?:1.8.0_101]
Caused by: java.io.IOException: error=2, No such file or directory
at java.lang.UNIXProcess.forkAndExec(Native Method) ~[?:1.8.0_101]
at java.lang.UNIXProcess.<init>(UNIXProcess.java:248) ~[?:1.8.0_101]
at java.lang.ProcessImpl.start(ProcessImpl.java:134) ~[?:1.8.0_101]
at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
~[?:1.8.0_101]
... 25 more
2016-10-17 10:22:44.901 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.channel.ChannelSession::handleX11Forwarding]
handleX11Forwarding(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/1AGIC-COOKIE-1,
cookie=828498d78f17134e8ccef33b0c19586a, screen=0 - display='null'
2016-10-17 10:22:44.901 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:-reply=true
2016-10-17 10:22:44.901 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 52 bytes
2016-10-17 10:22:44.902 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.902 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::handleChannelRequest]
handleChannelRequest(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[testuth-agent-req wantReply=true
2016-10-17 10:22:44.903 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.forward.StaticDecisionForwardingFilter::checkAcceptance]
checkAcceptance(auth-agent-req)[ServerSessionImpl[test@/192.168.21.rue
2016-10-17 10:22:44.903 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::getAgentForwardSupport]
getAgentForwardSupport(ServerSessionImpl[test@/192.168.21
2016-10-17 10:22:44.913 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.agent.common.DefaultAgentForwardSupport::initialize]
initialize(ServerSessionImpl[test@/192.168.21.90:59333])
id=/tmp/mina472407043rg.apache.sshd.agent.unix.AgentServerProxy@101bde33
2016-10-17 10:22:44.913 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:s, want-reply=true
2016-10-17 10:22:44.914 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 52 bytes
2016-10-17 10:22:44.914 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:44.914 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.channel.ChannelSession::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:5
2016-10-17 10:22:44.915 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::handleChannelRequest]
handleChannelRequest(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[testty-req wantReply=false
2016-10-17 10:22:44.915 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.channel.ChannelSession::handlePtyReq]
handlePtyReq(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:5),
modes=[{TTY_OP_OSPEED=38400, TTY_OP_ISPEED=38400}]
2016-10-17 10:22:44.915 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:-reply=false
2016-10-17 10:22:44.916 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.channel.ChannelSession::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:5
2016-10-17 10:22:44.917 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::handleChannelRequest]
handleChannelRequest(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[testhell wantReply=false
2016-10-17 10:22:44.922 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.channel.ChannelSession::prepareChannelCommand]
prepareChannelCommand(ChannelSession[id=0, recipient=0]-ServerSessionImpl[tes
2016-10-17 10:22:44.922 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.channel.AbstractChannel::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:eply=false
2016-10-17 10:22:44.922 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.server.channel.ChannelSession::sendResponse]
sendResponse(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:5
2016-10-17 10:22:44.962 [DEBUG] [InAppShell]
[org.apache.sshd.client.config.hosts.ConfigFileHostEntryResolver::resolveEffectiveHost]
resolveEffectiveHost(root@10.10.16.203:8022) => null
2016-10-17 10:22:44.963 [DEBUG] [InAppShell]
[org.apache.sshd.client.SshClient::connect] connect(root@10.10.16.203:8022) no
overrides
2016-10-17 10:22:44.984 [DEBUG] [InAppShell]
[org.apache.sshd.common.io.nio2.Nio2Connector::connect] Connecting to
/10.10.16.203:8022
2016-10-17 10:22:44.986 [DEBUG] [InAppShell]
[org.apache.sshd.common.io.nio2.Nio2Service::setOption]
setOption(SO_REUSEADDR)[true] from property=socket-reuseaddr
2016-10-17 10:22:44.988 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::<init>] Creating IoSession on
/10.10.0.12:47730 from /10.10.16.203:8022
2016-10-17 10:22:44.994 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.client.session.ClientSessionImpl::<init>] Client session
created: Nio2Session[local=/10.10.0.12:47730, remote=/10.10.16.203:8022]
2016-10-17 10:22:44.996 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.client.session.ClientUserAuthService::<init>]
ClientUserAuthService(ClientSessionImpl[null@/10.10.16.203:8022]) client
methods: [pu
2016-10-17 10:22:44.998 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener::sessionCreated]
sessionCreated(ClientSessionImpl[null@/10.10.16.203:8022]) tracking
2016-10-17 10:22:44.999 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::sendIdentification]
sendIdentification(ClientSessionImpl[null@/10.10.16.203:8022]): SSH-2.0
2016-10-17 10:22:44.999 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 34 bytes
2016-10-17 10:22:44.999 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.000 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::sendKexInit]
sendKexInit(ClientSessionImpl[null@/10.10.16.203:8022]) Send SSH_MSG_KEXINIT
2016-10-17 10:22:45.000 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 704 bytes
2016-10-17 10:22:45.001 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.001 [DEBUG] [InAppShell]
[org.apache.sshd.client.future.DefaultConnectFuture::verify] Connected to
/10.10.16.203:8022 after 14365594 nanos
2016-10-17 10:22:45.001 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.002 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::doReadIdentification]
doReadIdentification(ClientSessionImpl[root@/10.10.16.203:8022]) line
2016-10-17 10:22:45.002 [DEBUG] [InAppShell]
[org.apache.sshd.client.session.ClientUserAuthService::auth]
auth(ClientSessionImpl[root@/10.10.16.203:8022])[ssh-connection] send
SSH_MSG_USERAUTH_REQUEST for 'none'
2016-10-17 10:22:45.002 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.client.session.AbstractClientSession::readIdentification]
readIdentification(ClientSessionImpl[root@/10.10.16.203:8022]) Server ver
2016-10-17 10:22:45.002 [DEBUG] [InAppShell]
[org.apache.sshd.common.session.helpers.AbstractSession::writePacket]
writePacket(ClientSessionImpl[root@/10.10.16.203:8022])[SSH_MSG_USERAUTH_REQUEST]
Start flagging packets a
2016-10-17 10:22:45.003 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::handleKexInit]
handleKexInit(ClientSessionImpl[root@/10.10.16.203:8022]) SSH_MSG_KEXINIT
2016-10-17 10:22:45.004 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::setNegotiationResult]
setNegotiationResult(ClientSessionImpl[root@/10.10.16.203:8022]) Kex:
2016-10-17 10:22:45.004 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::setNegotiationResult]
setNegotiationResult(ClientSessionImpl[root@/10.10.16.203:8022]) Kex:
2016-10-17 10:22:45.189 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.client.kex.DHGClient::init]
init(DHGClient[ecdh-sha2-nistp521])[ClientSessionImpl[root@/10.10.16.203:8022]]
Send SSH_MSG_KEXDH_INIT
2016-10-17 10:22:45.189 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 152 bytes
2016-10-17 10:22:45.190 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.196 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.197 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.client.kex.DHGClient::next]
next(DHGClient[ecdh-sha2-nistp521])[ClientSessionImpl[root@/10.10.16.203:8022]]
process command=SSH_MSG
2016-10-17 10:22:45.217 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::handleKexMessage]
handleKexMessage(ClientSessionImpl[root@/10.10.16.203:8022])[ecdh-sha2-ni
2016-10-17 10:22:45.217 [WARN ] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.client.keyverifier.StaticServerKeyVerifier::handleAcceptance]
Server at /10.10.16.203:8022 presented unverified EC key: SHA256:QzUx
2016-10-17 10:22:45.218 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.client.session.AbstractClientSession::checkKeys]
checkKeys(ClientSessionImpl[root@/10.10.16.203:8022])
key=ecdsa-sha2-nistp256-SHA2erified=true
2016-10-17 10:22:45.218 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::sendNewKeys]
sendNewKeys(ClientSessionImpl[root@/10.10.16.203:8022]) Send SSH_MSG_NEWKEYS
2016-10-17 10:22:45.219 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 16 bytes
2016-10-17 10:22:45.219 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.219 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::handleNewKeys]
handleNewKeys(ClientSessionImpl[root@/10.10.16.203:8022]) SSH_MSG_NEWKEYS co
2016-10-17 10:22:45.219 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::receiveNewKeys]
receiveNewKeys(ClientSessionImpl[root@/10.10.16.203:8022]) session
ID=22:c856:d4:32:76:b5:0b:06:94:0f:3d:61:f9:e8:2c:b3:f3:1e:94:f2:74:5e:80:0b:8d:a1:4e:80:24:3d:66:dc:c9:4f:db:ed:7b:88:76:ae:fb:8e:0d:f8:49:d7:8a
2016-10-17 10:22:45.223 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::receiveNewKeys]
receiveNewKeys(ClientSessionImpl[root@/10.10.16.203:8022])
inCipher=BaseCipipher[AES,16,16,AES/CTR/NoPadding], recommended blocks
limit=4294967296, actual=4294967296
2016-10-17 10:22:45.223 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.client.session.ClientSessionImpl::sendInitialServiceRequest]
sendInitialServiceRequest(ClientSessionImpl[root@/10.10.16.203:8022])
2016-10-17 10:22:45.223 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 64 bytes
2016-10-17 10:22:45.224 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.224 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.AbstractSession::handleNewKeys]
handleNewKeys(ClientSessionImpl[root@/10.10.16.203:8022]) Dequeing 1 pending
2016-10-17 10:22:45.225 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 80 bytes
2016-10-17 10:22:45.225 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.260 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.261 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-3]
[org.apache.sshd.common.session.helpers.AbstractSession::handleServiceAccept]
handleServiceAccept(ClientSessionImpl[root@/10.10.16.203:8022]) SSH_MS
2016-10-17 10:22:45.300 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:22:45.301 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.session.ClientUserAuthService::processUserAuth]
processUserAuth(ClientSessionImpl[root@/10.10.16.203:8022]) Received
SSH_MSGkey,gssapi-keyex,gssapi-with-mic,password,keyboard-interactive
2016-10-17 10:22:45.301 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.session.ClientUserAuthService::tryNext]
tryNext(ClientSessionImpl[root@/10.10.16.203:8022]) starting authentication
mechanisword], server=[publickey, gssapi-keyex, gssapi-with-mic, password,
keyboard-interactive]
2016-10-17 10:22:45.303 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.session.ClientUserAuthService::tryNext]
tryNext(ClientSessionImpl[root@/10.10.16.203:8022]) attempting method=publickey
2016-10-17 10:22:45.309 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.config.keys.ClientIdentityFileWatcher::reloadClientIdentity]
reloadClientIdentity(/root/.ssh/id_rsa) ignore due to Parent pe
2016-10-17 10:22:45.309 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.config.keys.ClientIdentitiesWatcher::loadKeys]
loadKeys(/root/.ssh/id_rsa) no key loaded
2016-10-17 10:22:45.310 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.config.keys.ClientIdentitiesWatcher::loadKeys]
loadKeys(/root/.ssh/id_dsa) no key loaded
2016-10-17 10:22:45.310 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.config.keys.ClientIdentitiesWatcher::loadKeys]
loadKeys(/root/.ssh/id_ecdsa) no key loaded
2016-10-17 10:22:45.310 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-4]
[org.apache.sshd.client.config.keys.ClientIdentitiesWatcher::loadKeys]
loadKeys(/root/.ssh/id_ed25519) no key loaded
2016-10-17 10:22:45.321 [DEBUG]
[sshd-sshd-AgentServerProxy-PIPE-/tmp/mina4724070436776756263apr/mina597875869168927040apr-thread-1]
[org.apache.sshd.common.channel.AbstractChannel::init] init()
service=ServerConnectionSe]]
session=ServerSessionImpl[test@/192.168.21.90:59333] id=1
2016-10-17 10:22:45.322 [DEBUG]
[sshd-sshd-AgentServerProxy-PIPE-/tmp/mina4724070436776756263apr/mina597875869168927040apr-thread-1]
[org.apache.sshd.common.channel.Window::init]
init(Window[client/local](AgentForwardedCh/192.168.21.90:59333])) size=2097152,
max=2097152, packet=32768
2016-10-17 10:22:45.322 [DEBUG]
[sshd-sshd-AgentServerProxy-PIPE-/tmp/mina4724070436776756263apr/mina597875869168927040apr-thread-1]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::registerChannel]
regiImpl[test@/192.168.21.90:59333]])[id=1] AgentForwardedChannel[id=1,
recipient=-1]-ServerSessionImpl[test@/192.168.21.90:59333]
2016-10-17 10:22:45.322 [DEBUG]
[sshd-sshd-AgentServerProxy-PIPE-/tmp/mina4724070436776756263apr/mina597875869168927040apr-thread-1]
[org.apache.sshd.client.channel.AbstractClientChannel::open]
open(AgentForwardedChannel[68.21.90:59333]) Send SSH_MSG_CHANNEL_OPEN -
type=auth-ag...@openssh.com
2016-10-17 10:22:45.323 [DEBUG]
[sshd-sshd-AgentServerProxy-PIPE-/tmp/mina4724070436776756263apr/mina597875869168927040apr-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 84 bytes
2016-10-17 10:22:45.323 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-6]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:45.360 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-7]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:22:45.360 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-7]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::channelOpenConfirmation]
channelOpenConfirmation(AgentForwardedChannel[id=1, reci9333])
SSH_MSG_CHANNEL_OPEN_CONFIRMATION sender=1, window-size=32768, packet-size=16384
2016-10-17 10:22:45.360 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-7]
[org.apache.sshd.common.channel.AbstractChannel::setRecipient]
setRecipient(AgentForwardedChannel[id=1,
recipient=-1]-ServerSessionImpl[test@/192.16
2016-10-17 10:22:45.361 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-7]
[org.apache.sshd.common.channel.Window::init]
init(Window[client/remote](AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168.21
2016-10-17 10:23:43.404 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-8]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:23:43.406 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-8]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:24:43.404 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:24:43.405 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:24:45.960 [DEBUG] [sshd-SshClient[2f22baf9]-timer-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::checkForTimeouts]
checkForTimeouts(ClientSessionImpl[root@/10.10.16.203:8022]) disconnect
2016-10-17 10:24:45.962 [INFO ] [sshd-SshClient[2f22baf9]-timer-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::disconnect]
Disconnecting(ClientSessionImpl[root@/10.10.16.203:8022]):
SSH2_DISCONNECT_PROuthentication after 120000 ms.
2016-10-17 10:24:45.963 [DEBUG] [sshd-SshClient[2f22baf9]-timer-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 128 bytes
2016-10-17 10:24:45.964 [DEBUG] [sshd-SshClient[2f22baf9]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:47730, remote=/10.10
2016-10-17 10:24:45.969 [DEBUG] [sshd-SshClient[2f22baf9]-timer-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::lambda$disconnect$6]
disconnect(ClientSessionImpl[root@/10.10.16.203:8022]) operation
succTOCOL_ERROR [Session has timed out waiting for authentication after 120000
ms.]
2016-10-17 10:24:45.969 [DEBUG] [sshd-SshClient[2f22baf9]-timer-thread-1]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ClientSessionImpl[root@/10.10.16.203:8022]) Closing immediately
2016-10-17 10:25:43.407 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:25:43.407 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:26:43.406 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:26:43.406 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-2]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:27:43.406 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:27:43.406 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-3]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:28:43.407 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:28:43.407 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-4]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:29:43.409 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:29:43.410 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-5]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:30:43.410 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-6]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:30:43.411 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-6]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:31:43.412 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-7]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:31:43.413 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-7]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:32:43.412 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-8]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:32:43.413 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-8]
[org.apache.sshd.common.session.helpers.ReservedSessionMessagesHandlerAdapter::handleIgnoreMessage]
handleIgnoreMessage(ServerSessionImpl[test@/192.
2016-10-17 10:32:45.548 [DEBUG] [sshd-SshServer[40a4337a]-timer-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::checkForTimeouts]
checkForTimeouts(ServerSessionImpl[test@/192.168.21.90:59333]) disconnec
2016-10-17 10:32:45.549 [INFO ] [sshd-SshServer[40a4337a]-timer-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::disconnect]
Disconnecting(ServerSessionImpl[test@/192.168.21.90:59333]): SSH2_DISCONNECT_P
after 600000 ms.
2016-10-17 10:32:45.549 [DEBUG] [sshd-SshServer[40a4337a]-timer-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::write] Writing 116 bytes
2016-10-17 10:32:45.550 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.io.nio2.Nio2Session::handleCompletedWriteCycle]
handleCompletedWriteCycle(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:32:45.551 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.io.nio2.Nio2Session::handleReadCycleCompletion]
handleReadCycleCompletion(Nio2Session[local=/10.10.0.12:2022, remote=/192.16
2016-10-17 10:32:45.551 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.session.helpers.AbstractSession::lambda$disconnect$6]
disconnect(ServerSessionImpl[test@/192.168.21.90:59333]) operation
sucOTOCOL_ERROR [User session has timed out idling after 600000 ms.]
2016-10-17 10:32:45.551 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ServerSessionImpl[test@/192.168.21.90:59333]) Closing immediately
2016-10-17 10:32:45.551 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.session.helpers.AbstractSession::handleDisconnect]
handleDisconnect(ServerSessionImpl[test@/192.168.21.90:59333]) SSH_MSG_DI
2016-10-17 10:32:45.551 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-1]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ServerSessionImpl[test@/192.168.21.90:59333])[Immediately] state already
Imme
2016-10-17 10:32:45.551 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.session.helpers.SessionTimeoutListener::sessionClosed]
sessionClosed(ServerSessionImpl[test@/192.168.21.90:59333]) un-tracke
2016-10-17 10:32:45.558 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ServerConnectionService[ServerSessionImpl[test@/192.168.21.90:59333]])
Closin
2016-10-17 10:32:45.559 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(DefaultAgentForwardSupport[ServerSessionImpl[test@/192.168.21.90:59333]])
Clo
2016-10-17 10:32:45.559 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.agent.common.DefaultAgentForwardSupport::close]
close(ServerSessionImpl[test@/192.168.21.90:59333]) closing
server=org.apache.sshd.
2016-10-17 10:32:45.559 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.agent.unix.AgentServerProxy::deleteFile] Deleted PIPE socket
/tmp/mina4724070436776756263apr/mina597875869168927040apr
2016-10-17 10:32:45.560 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.agent.unix.AgentServerProxy::deleteFile] Deleted parent PIPE
socket /tmp/mina4724070436776756263apr
2016-10-17 10:32:45.560 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.agent.unix.AgentServerProxy::close] Shut down runners count=0
2016-10-17 10:32:45.561 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(DefaultAgentForwardSupport[ServerSessionImpl[test@/192.168.21.90:59333]])[Imm
2016-10-17 10:32:45.561 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(DefaultX11ForwardSupport: class
org.apache.sshd.server.session.ServerConnecti
2016-10-17 10:32:45.561 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.io.nio2.Nio2Acceptor::unbind] Unbinding
2016-10-17 10:32:45.562 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(org.apache.sshd.common.io.nio2.Nio2Acceptor@47d7a097) Closing immediately
2016-10-17 10:32:45.562 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(org.apache.sshd.common.io.nio2.Nio2Acceptor@47d7a097)[Immediately] closed
2016-10-17 10:32:45.563 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(DefaultX11ForwardSupport: class
org.apache.sshd.server.session.ServerConnecti
2016-10-17 10:32:45.563 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.AbstractChannel::close]
close(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:59333]) preven
2016-10-17 10:32:45.563 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:59333
2016-10-17 10:32:45.564 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.Window::close] Closing
Window[server/local](ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:
2016-10-17 10:32:45.564 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.Window::close] Closing
Window[server/remote](ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90
2016-10-17 10:32:45.564 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::unregisterChannel]
unregisterChannel(ChannelSession[id=0, recipient=0]-ServerSesslSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:59333]
2016-10-17 10:32:45.567 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.AbstractChannel$GracefulChannelCloseable::close]
close(ChannelSession[id=0, recipient=0]-ServerSessionImpl[test@/192
2016-10-17 10:32:45.567 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.SequentialCloseable$1::operationComplete]
doClose(true) signal close complete
2016-10-17 10:32:45.567 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ChannelSession[id=0,
recipient=0]-ServerSessionImpl[test@/192.168.21.90:59333
2016-10-17 10:32:45.567 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.AbstractChannel::close]
close(AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168.21.90:59333])
2016-10-17 10:32:45.568 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168.21.9
2016-10-17 10:32:45.568 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.Window::close] Closing
Window[client/local](AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168
2016-10-17 10:32:45.568 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.Window::close] Closing
Window[client/remote](AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.16
2016-10-17 10:32:45.569 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.session.helpers.AbstractConnectionService::unregisterChannel]
unregisterChannel(AgentForwardedChannel[id=1,
recipient=1]-Ser=AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168.21.90:59333]
2016-10-17 10:32:45.570 [WARN ]
[sshd-AgentClient[/tmp/mina4724070436776756263apr/mina597875869168927040apr]-thread-1]
[org.apache.sshd.agent.unix.AgentClient::run] IOException while still open:
Connection reset by peer (
2016-10-17 10:32:45.571 [DEBUG]
[sshd-AgentClient[/tmp/mina4724070436776756263apr/mina597875869168927040apr]-thread-1]
[org.apache.sshd.agent.common.AbstractAgentProxy::close] close() - shutdown
runners count=0
2016-10-17 10:32:45.572 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.AbstractChannel::sendEof]
sendEof(AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168.21.90:593
2016-10-17 10:32:45.572 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.channel.AbstractChannel$GracefulChannelCloseable::close]
close(AgentForwardedChannel[id=1, recipient=1]-ServerSessionImpl[tessing
2016-10-17 10:32:45.572 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.SequentialCloseable$1::operationComplete]
doClose(true) signal close complete
2016-10-17 10:32:45.572 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(AgentForwardedChannel[id=1,
recipient=1]-ServerSessionImpl[test@/192.168.21.9
2016-10-17 10:32:45.573 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.SequentialCloseable$1::operationComplete]
doClose(true) signal close complete
2016-10-17 10:32:45.573 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ServerConnectionService[ServerSessionImpl[test@/192.168.21.90:59333]])[Immedi
2016-10-17 10:32:45.573 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(Nio2Session[local=/10.10.0.12:2022, remote=/192.168.21.90:59333]) Closing
imm
2016-10-17 10:32:45.574 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ServerSessionImpl[test@/192.168.21.90:59333])[Immediately] state already
Imme
2016-10-17 10:32:45.574 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(Nio2Session[local=/10.10.0.12:2022,
remote=/192.168.21.90:59333])[Immediately
2016-10-17 10:32:45.574 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.SequentialCloseable$1::operationComplete]
doClose(true) signal close complete
2016-10-17 10:32:45.574 [DEBUG] [sshd-SshServer[40a4337a]-nio2-thread-9]
[org.apache.sshd.common.util.closeable.AbstractCloseable::close]
close(ServerSessionImpl[test@/192.168.21.90:59333])[Immediately] closed
