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