Attempt 1: Oct 30 10:50:02 sshserver sshd[50889]: debug3: fd 4 is not O_NONBLOCK Oct 30 10:50:02 sshserver sshd[50889]: debug1: Forked child 63017. Oct 30 10:50:02 sshserver sshd[50889]: debug3: send_rexec_state: entering fd = 7 config len 638 Oct 30 10:50:02 sshserver sshd[50889]: debug3: ssh_msg_send: type 0 Oct 30 10:50:02 sshserver sshd[50889]: debug3: send_rexec_state: done Oct 30 10:50:02 sshserver sshd[63017]: debug3: oom_adjust_restore Oct 30 10:50:02 sshserver sshd[63017]: debug1: Set /proc/self/oom_score_adj to 0 Oct 30 10:50:02 sshserver sshd[63017]: debug1: rexec start in 4 out 4 newsock 4 pipe 6 sock 7 Oct 30 10:50:02 sshserver sshd[63017]: debug1: inetd sockets after dupping: 3, 3 Oct 30 10:50:02 sshserver sshd[63017]: Connection from 10.10.10.100 port 31264 on 10.20.20.100 port 22 Oct 30 10:50:02 sshserver sshd[63017]: debug1: Client protocol version 2.0; client software version OpenSSH_6.2 PKIX Oct 30 10:50:02 sshserver sshd[63017]: debug1: match: OpenSSH_6.2 PKIX pat OpenSSH* compat 0x04000000 Oct 30 10:50:02 sshserver sshd[63017]: debug1: Local version string SSH-2.0-OpenSSH_7.4p1 Debian-10+deb9u6 Oct 30 10:50:02 sshserver sshd[63017]: debug1: Enabling compatibility mode for protocol 2.0 Oct 30 10:50:02 sshserver sshd[63017]: debug2: fd 3 setting O_NONBLOCK Oct 30 10:50:02 sshserver sshd[63017]: debug3: ssh_sandbox_init: preparing seccomp filter sandbox Oct 30 10:50:02 sshserver sshd[63017]: debug2: Network child is on pid 63018 Oct 30 10:50:02 sshserver sshd[63017]: debug3: preauth child monitor started Oct 30 10:50:02 sshserver sshd[63017]: debug3: privsep user:group 106:65534 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: permanently_set_uid: 106/65534 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: ssh_sandbox_child: setting PR_SET_NO_NEW_PRIVS [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: ssh_sandbox_child: attaching seccomp filter program [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: list_hostkey_types: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: send packet: type 20 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: SSH2_MSG_KEXINIT sent [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: receive packet: type 20 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: SSH2_MSG_KEXINIT received [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: local server KEXINIT proposal [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: compression ctos: none,zlib@openssh.com [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: compression stoc: none,zlib@openssh.com [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: languages ctos: [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: languages stoc: [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: first_kex_follows 0 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: reserved 0 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: peer client KEXINIT proposal [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: KEX algorithms: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: host key algorithms: x509v3-sign-rsa,x509v3-sign-dss,ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ssh-rsa-cert-v00@openssh.com,ssh-dss-cert-v00@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,ssh-rsa,ssh-dss [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: ciphers ctos: aes128-cbc,3des-cbc,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: ciphers stoc: aes128-cbc,3des-cbc,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: MACs ctos: hmac-sha1,hmac-sha1-96 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: MACs stoc: hmac-sha1,hmac-sha1-96 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: compression ctos: none,zlib@openssh.com,zlib [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: compression stoc: none,zlib@openssh.com,zlib [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: languages ctos: [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: languages stoc: [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: first_kex_follows 0 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: reserved 0 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: kex: algorithm: ecdh-sha2-nistp256 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: kex: host key algorithm: ecdsa-sha2-nistp256 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: kex: client->server cipher: aes128-ctr MAC: hmac-sha1 compression: none [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: kex: server->client cipher: aes128-ctr MAC: hmac-sha1 compression: none [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: expecting SSH2_MSG_KEX_ECDH_INIT [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: receive packet: type 30 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_key_sign entering [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_request_send entering: type 6 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_request_receive_expect entering: type 7 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_request_receive entering [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_request_receive entering Oct 30 10:50:02 sshserver sshd[63017]: debug3: monitor_read: checking request 6 Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_answer_sign Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_answer_sign: hostkey proof signature 0x561d3e71b2e0(99) Oct 30 10:50:02 sshserver sshd[63017]: debug3: mm_request_send entering: type 7 Oct 30 10:50:02 sshserver sshd[63017]: debug2: monitor_read: 6 used once, disabling now Oct 30 10:50:02 sshserver sshd[63017]: debug3: send packet: type 31 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug3: send packet: type 21 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug2: set_newkeys: mode 1 [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: rekey after 4294967296 blocks [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: SSH2_MSG_NEWKEYS sent [preauth] Oct 30 10:50:02 sshserver sshd[63017]: debug1: expecting SSH2_MSG_NEWKEYS [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: receive packet: type 21 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug1: SSH2_MSG_NEWKEYS received [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug2: set_newkeys: mode 0 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug1: rekey after 4294967296 blocks [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug1: KEX done [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: receive packet: type 5 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: send packet: type 6 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: receive packet: type 50 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug1: userauth-request for user testuser service ssh-connection method none [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug1: attempt 0 failures 0 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_getpwnamallow entering [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_send entering: type 8 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_receive_expect entering: type 9 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_receive entering [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_receive entering Oct 30 10:50:04 sshserver sshd[63017]: debug3: monitor_read: checking request 8 Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_answer_pwnamallow Oct 30 10:50:04 sshserver sshd[63017]: debug2: parse_server_config: config reprocess config len 638 Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1 Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_send entering: type 9 Oct 30 10:50:04 sshserver sshd[63017]: debug2: monitor_read: 8 used once, disabling now Oct 30 10:50:04 sshserver sshd[63017]: debug2: input_userauth_request: setting up authctxt for testuser [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_start_pam entering [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_send entering: type 100 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_inform_authserv entering [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_send entering: type 4 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug2: input_userauth_request: try method none [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: userauth_finish: failure partial=0 next methods="publickey,password" [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: send packet: type 51 [preauth] Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_receive entering Oct 30 10:50:04 sshserver sshd[63017]: debug3: monitor_read: checking request 100 Oct 30 10:50:04 sshserver sshd[63017]: debug1: PAM: initializing for "testuser" Oct 30 10:50:04 sshserver sshd[63017]: debug1: PAM: setting PAM_RHOST to "10.10.10.100" Oct 30 10:50:04 sshserver sshd[63017]: debug1: PAM: setting PAM_TTY to "ssh" Oct 30 10:50:04 sshserver sshd[63017]: debug2: monitor_read: 100 used once, disabling now Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_request_receive entering Oct 30 10:50:04 sshserver sshd[63017]: debug3: monitor_read: checking request 4 Oct 30 10:50:04 sshserver sshd[63017]: debug3: mm_answer_authserv: service=ssh-connection, style=, role= Oct 30 10:50:04 sshserver sshd[63017]: debug2: monitor_read: 4 used once, disabling now ==================================================================================================================================================== got password prompt now, did not enter but did stop login attempt. Oct 30 10:50:24 sshserver sshd[63017]: Connection closed by 10.10.10.100 port 31264 [preauth] Oct 30 10:50:24 sshserver sshd[63017]: debug1: do_cleanup [preauth] Oct 30 10:50:24 sshserver sshd[63017]: debug3: PAM: sshpam_thread_cleanup entering [preauth] Oct 30 10:50:24 sshserver sshd[63017]: debug1: monitor_read_log: child log fd closed Oct 30 10:50:24 sshserver sshd[63017]: debug3: mm_request_receive entering Oct 30 10:50:24 sshserver sshd[63017]: debug1: do_cleanup Oct 30 10:50:24 sshserver sshd[63017]: debug1: PAM: cleanup Oct 30 10:50:24 sshserver sshd[63017]: debug3: PAM: sshpam_thread_cleanup entering Oct 30 10:50:24 sshserver sshd[63017]: debug1: Killing privsep child 63018 Oct 30 10:50:24 sshserver sshd[63017]: debug1: audit_event: unhandled event 12 ==================================================================================================================================================== Attempt 2: Oct 30 10:52:09 sshserver sshd[50889]: debug3: fd 4 is not O_NONBLOCK Oct 30 10:52:09 sshserver sshd[50889]: debug1: Forked child 63451. Oct 30 10:52:09 sshserver sshd[50889]: debug3: send_rexec_state: entering fd = 7 config len 638 Oct 30 10:52:09 sshserver sshd[50889]: debug3: ssh_msg_send: type 0 Oct 30 10:52:09 sshserver sshd[50889]: debug3: send_rexec_state: done Oct 30 10:52:09 sshserver sshd[63451]: debug3: oom_adjust_restore Oct 30 10:52:09 sshserver sshd[63451]: debug1: Set /proc/self/oom_score_adj to 0 Oct 30 10:52:09 sshserver sshd[63451]: debug1: rexec start in 4 out 4 newsock 4 pipe 6 sock 7 Oct 30 10:52:09 sshserver sshd[63451]: debug1: inetd sockets after dupping: 3, 3 Oct 30 10:52:09 sshserver sshd[63451]: Connection from 10.10.10.100 port 31311 on 10.20.20.100 port 22 Oct 30 10:52:09 sshserver sshd[63451]: debug1: Client protocol version 2.0; client software version OpenSSH_6.2 PKIX Oct 30 10:52:09 sshserver sshd[63451]: debug1: match: OpenSSH_6.2 PKIX pat OpenSSH* compat 0x04000000 Oct 30 10:52:09 sshserver sshd[63451]: debug1: Local version string SSH-2.0-OpenSSH_7.4p1 Debian-10+deb9u6 Oct 30 10:52:09 sshserver sshd[63451]: debug1: Enabling compatibility mode for protocol 2.0 Oct 30 10:52:09 sshserver sshd[63451]: debug2: fd 3 setting O_NONBLOCK Oct 30 10:52:09 sshserver sshd[63451]: debug3: ssh_sandbox_init: preparing seccomp filter sandbox Oct 30 10:52:09 sshserver sshd[63451]: debug2: Network child is on pid 63452 Oct 30 10:52:09 sshserver sshd[63451]: debug3: preauth child monitor started Oct 30 10:52:09 sshserver sshd[63451]: debug3: privsep user:group 106:65534 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: permanently_set_uid: 106/65534 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: ssh_sandbox_child: setting PR_SET_NO_NEW_PRIVS [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: ssh_sandbox_child: attaching seccomp filter program [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: list_hostkey_types: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: send packet: type 20 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: SSH2_MSG_KEXINIT sent [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: receive packet: type 20 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: SSH2_MSG_KEXINIT received [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: local server KEXINIT proposal [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: KEX algorithms: curve25519-sha256,curve25519-sha256@libssh.org,ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group16-sha512,diffie-hellman-group18-sha512,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: MACs ctos: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: MACs stoc: umac-64-etm@openssh.com,umac-128-etm@openssh.com,hmac-sha2-256-etm@openssh.com,hmac-sha2-512-etm@openssh.com,hmac-sha1-etm@openssh.com,umac-64@openssh.com,umac-128@openssh.com,hmac-sha2-256,hmac-sha2-512,hmac-sha1 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: compression ctos: none,zlib@openssh.com [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: compression stoc: none,zlib@openssh.com [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: languages ctos: [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: languages stoc: [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: first_kex_follows 0 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: reserved 0 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: peer client KEXINIT proposal [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: KEX algorithms: ecdh-sha2-nistp256,ecdh-sha2-nistp384,ecdh-sha2-nistp521,diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: host key algorithms: ecdsa-sha2-nistp256-cert-v01@openssh.com,ecdsa-sha2-nistp384-cert-v01@openssh.com,ecdsa-sha2-nistp521-cert-v01@openssh.com,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,x509v3-sign-rsa,x509v3-sign-dss,ssh-rsa-cert-v01@openssh.com,ssh-dss-cert-v01@openssh.com,ssh-rsa-cert-v00@openssh.com,ssh-dss-cert-v00@openssh.com,ssh-rsa,ssh-dss [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: ciphers ctos: aes128-cbc,3des-cbc,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: ciphers stoc: aes128-cbc,3des-cbc,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: MACs ctos: hmac-sha1,hmac-sha1-96 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: MACs stoc: hmac-sha1,hmac-sha1-96 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: compression ctos: none,zlib@openssh.com,zlib [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: compression stoc: none,zlib@openssh.com,zlib [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: languages ctos: [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: languages stoc: [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: first_kex_follows 0 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: reserved 0 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: kex: algorithm: ecdh-sha2-nistp256 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: kex: host key algorithm: ecdsa-sha2-nistp256 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: kex: client->server cipher: aes128-ctr MAC: hmac-sha1 compression: none [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: kex: server->client cipher: aes128-ctr MAC: hmac-sha1 compression: none [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: expecting SSH2_MSG_KEX_ECDH_INIT [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: receive packet: type 30 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_key_sign entering [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_send entering: type 6 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive_expect entering: type 7 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive entering [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:09 sshserver sshd[63451]: debug3: monitor_read: checking request 6 Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_answer_sign Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_answer_sign: hostkey proof signature 0x5652ef0672b0(101) Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_send entering: type 7 Oct 30 10:52:09 sshserver sshd[63451]: debug2: monitor_read: 6 used once, disabling now Oct 30 10:52:09 sshserver sshd[63451]: debug3: send packet: type 31 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: send packet: type 21 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: set_newkeys: mode 1 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: rekey after 4294967296 blocks [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: SSH2_MSG_NEWKEYS sent [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: expecting SSH2_MSG_NEWKEYS [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: receive packet: type 21 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: SSH2_MSG_NEWKEYS received [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: set_newkeys: mode 0 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: rekey after 4294967296 blocks [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: KEX done [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: receive packet: type 5 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: send packet: type 6 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: receive packet: type 50 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: userauth-request for user testuser service ssh-connection method none [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug1: attempt 0 failures 0 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_getpwnamallow entering [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_send entering: type 8 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive_expect entering: type 9 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive entering [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:09 sshserver sshd[63451]: debug3: monitor_read: checking request 8 Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_answer_pwnamallow Oct 30 10:52:09 sshserver sshd[63451]: debug2: parse_server_config: config reprocess config len 638 Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1 Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_send entering: type 9 Oct 30 10:52:09 sshserver sshd[63451]: debug2: monitor_read: 8 used once, disabling now Oct 30 10:52:09 sshserver sshd[63451]: debug2: input_userauth_request: setting up authctxt for testuser [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_start_pam entering [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_send entering: type 100 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_inform_authserv entering [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_send entering: type 4 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug2: input_userauth_request: try method none [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: userauth_finish: failure partial=0 next methods="publickey,password" [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: send packet: type 51 [preauth] Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:09 sshserver sshd[63451]: debug3: monitor_read: checking request 100 Oct 30 10:52:09 sshserver sshd[63451]: debug1: PAM: initializing for "testuser" Oct 30 10:52:09 sshserver sshd[63451]: debug1: PAM: setting PAM_RHOST to "10.10.10.100" Oct 30 10:52:09 sshserver sshd[63451]: debug1: PAM: setting PAM_TTY to "ssh" Oct 30 10:52:09 sshserver sshd[63451]: debug2: monitor_read: 100 used once, disabling now Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:09 sshserver sshd[63451]: debug3: monitor_read: checking request 4 Oct 30 10:52:09 sshserver sshd[63451]: debug3: mm_answer_authserv: service=ssh-connection, style=, role= Oct 30 10:52:09 sshserver sshd[63451]: debug2: monitor_read: 4 used once, disabling now ==================================================================================================================================================== Got Password Prompt and entered password then Oct 30 10:52:25 sshserver sshd[63451]: debug3: receive packet: type 50 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug1: userauth-request for user testuser service ssh-connection method password [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug1: attempt 1 failures 0 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug2: input_userauth_request: try method password [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_auth_password entering [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_send entering: type 12 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_auth_password: waiting for MONITOR_ANS_AUTHPASSWORD [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive_expect entering: type 13 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive entering [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:25 sshserver sshd[63451]: debug3: monitor_read: checking request 12 Oct 30 10:52:25 sshserver sshd[63451]: debug3: PAM: sshpam_passwd_conv called with 1 messages Oct 30 10:52:25 sshserver sshd[63451]: debug1: PAM: password authentication accepted for testuser Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_answer_authpassword: sending result 1 Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_send entering: type 13 Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive_expect entering: type 102 Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:25 sshserver sshd[63451]: debug1: do_pam_account: called Oct 30 10:52:25 sshserver sshd[63451]: debug3: PAM: do_pam_account pam_acct_mgmt = 0 (Success) Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_send entering: type 103 Oct 30 10:52:25 sshserver sshd[63451]: Accepted password for testuser from 10.10.10.100 port 31311 ssh2 Oct 30 10:52:25 sshserver sshd[63451]: debug1: monitor_child_preauth: testuser has been authenticated by privileged process Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_get_keystate: Waiting for new keys Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive_expect entering: type 26 Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_get_keystate: GOT new keys Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_auth_password: user authenticated [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_do_pam_account entering [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_send entering: type 102 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive_expect entering: type 103 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_receive entering [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_do_pam_account returning 1 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: send packet: type 52 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_request_send entering: type 26 [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug3: mm_send_keystate: Finished sending state [preauth] Oct 30 10:52:25 sshserver sshd[63451]: debug1: monitor_read_log: child log fd closed Oct 30 10:52:25 sshserver sshd[63451]: debug3: ssh_sandbox_parent_finish: finished Oct 30 10:52:25 sshserver sshd[63451]: debug1: PAM: establishing credentials Oct 30 10:52:25 sshserver sshd[63451]: debug3: PAM: opening session Oct 30 10:52:25 sshserver sshd[63451]: pam_unix(sshd:session): session opened for user testuser by (uid=0) Oct 30 10:52:27 sshserver systemd-logind[497]: New session 72191 of user testuser. Oct 30 10:52:28 sshserver systemd: pam_unix(systemd-user:session): session opened for user testuser by (uid=0) Oct 30 10:52:28 sshserver sshd[63451]: debug3: PAM: sshpam_store_conv called with 1 messages Oct 30 10:52:28 sshserver sshd[63451]: debug3: PAM: sshpam_store_conv called with 1 messages Oct 30 10:52:28 sshserver sshd[63451]: User child is on pid 63460 Oct 30 10:52:28 sshserver sshd[63460]: debug1: SELinux support disabled Oct 30 10:52:28 sshserver sshd[63460]: debug1: PAM: establishing credentials Oct 30 10:52:28 sshserver sshd[63460]: debug1: permanently_set_uid: 2105/504 Oct 30 10:52:28 sshserver sshd[63460]: debug3: monitor_apply_keystate: packet_set_state Oct 30 10:52:28 sshserver sshd[63460]: debug2: set_newkeys: mode 0 Oct 30 10:52:28 sshserver sshd[63460]: debug1: rekey after 4294967296 blocks Oct 30 10:52:28 sshserver sshd[63460]: debug2: set_newkeys: mode 1 Oct 30 10:52:28 sshserver sshd[63460]: debug1: rekey after 4294967296 blocks Oct 30 10:52:28 sshserver sshd[63460]: debug1: ssh_packet_set_postauth: called Oct 30 10:52:28 sshserver sshd[63460]: debug3: ssh_packet_set_state: done Oct 30 10:52:28 sshserver sshd[63460]: debug3: notify_hostkeys: key 0: ssh-rsa SHA256:ic6UNfJQ1hHg6gXM95WCh6zhrrH+tJ4Ot5l78Vv1lNQ Oct 30 10:52:28 sshserver sshd[63460]: debug3: notify_hostkeys: key 1: ecdsa-sha2-nistp256 SHA256:zGf33LGyonIbL14nFcjO3fL4ncgQv81adQGdGlclLXo Oct 30 10:52:28 sshserver sshd[63460]: debug3: notify_hostkeys: key 2: ssh-ed25519 SHA256:0yaoFgLd+u4Y4ibzbSqs2qONi/v699UHfRxceSkJHkg Oct 30 10:52:28 sshserver sshd[63460]: debug3: notify_hostkeys: sent 3 hostkeys Oct 30 10:52:28 sshserver sshd[63460]: debug3: send packet: type 80 Oct 30 10:52:28 sshserver sshd[63460]: debug1: Entering interactive session for SSH2. Oct 30 10:52:28 sshserver sshd[63460]: debug2: fd 9 setting O_NONBLOCK Oct 30 10:52:28 sshserver sshd[63460]: debug2: fd 10 setting O_NONBLOCK Oct 30 10:52:28 sshserver sshd[63460]: debug1: server_init_dispatch Oct 30 10:52:28 sshserver sshd[63460]: debug3: receive packet: type 90 Oct 30 10:52:28 sshserver sshd[63460]: debug1: server_input_channel_open: ctype session rchan 0 win 2097152 max 32768 Oct 30 10:52:28 sshserver sshd[63460]: debug1: input_session_request Oct 30 10:52:28 sshserver sshd[63460]: debug1: channel 0: new [server-session] Oct 30 10:52:28 sshserver sshd[63460]: debug2: session_new: allocate (allocated 0 max 10) Oct 30 10:52:28 sshserver sshd[63460]: debug3: session_unused: session id 0 unused Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_new: session 0 Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_open: channel 0 Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_open: session 0: link with channel 0 Oct 30 10:52:28 sshserver sshd[63460]: debug1: server_input_channel_open: confirm session Oct 30 10:52:28 sshserver sshd[63460]: debug3: send packet: type 91 Oct 30 10:52:28 sshserver sshd[63460]: debug3: receive packet: type 80 Oct 30 10:52:28 sshserver sshd[63460]: debug1: server_input_global_request: rtype no-more-sessions@openssh.com want_reply 0 Oct 30 10:52:28 sshserver sshd[63460]: debug3: receive packet: type 98 Oct 30 10:52:28 sshserver sshd[63460]: debug1: server_input_channel_req: channel 0 request exec reply 1 Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_by_channel: session 0 channel 0 Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_input_channel_req: session 0 req exec Oct 30 10:52:28 sshserver sshd[63460]: Starting session: command for testuser from 10.10.10.100 port 31311 id 0 Oct 30 10:52:28 sshserver sshd[63460]: debug3: mm_audit_run_command entering command scp -t /testpath/test.txt Oct 30 10:52:28 sshserver sshd[63460]: debug3: mm_request_send entering: type 113 Oct 30 10:52:28 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:28 sshserver sshd[63451]: debug3: monitor_read: checking request 113 Oct 30 10:52:28 sshserver sshd[63451]: debug3: mm_answer_audit_command entering Oct 30 10:52:28 sshserver sshd[63460]: debug2: fd 3 setting TCP_NODELAY Oct 30 10:52:28 sshserver sshd[63460]: debug3: ssh_packet_set_tos: set IP_TOS 0x08 Oct 30 10:52:28 sshserver sshd[63460]: debug2: fd 13 setting O_NONBLOCK Oct 30 10:52:28 sshserver sshd[63460]: debug2: fd 12 setting O_NONBLOCK Oct 30 10:52:28 sshserver sshd[63460]: debug2: fd 15 setting O_NONBLOCK Oct 30 10:52:28 sshserver sshd[63460]: debug3: send packet: type 99 Oct 30 10:52:28 sshserver sshd[63461]: debug3: Copy environment: XDG_SESSION_ID=72191 Oct 30 10:52:28 sshserver sshd[63461]: debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/9999 Oct 30 10:52:28 sshserver sshd[63461]: debug3: Copy environment: LANG=en_US.UTF-8 Oct 30 10:52:28 sshserver sshd[63460]: debug3: receive packet: type 96 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: rcvd eof Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: output open -> drain Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: obuf empty Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: close_write Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: output drain -> closed Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: read<=0 rfd 13 len 0 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: read failed Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: close_read Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: input open -> drain Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: read 0 from efd 15 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: closing read-efd 15 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: ibuf empty Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: send eof Oct 30 10:52:28 sshserver sshd[63460]: debug3: send packet: type 96 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: input drain -> closed Oct 30 10:52:28 sshserver sshd[63460]: debug2: notify_done: reading Oct 30 10:52:28 sshserver sshd[63460]: debug1: Received SIGCHLD. Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_by_pid: pid 63461 Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_exit_message: session 0 channel 0 pid 63461 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: request exit-status confirm 0 Oct 30 10:52:28 sshserver sshd[63460]: debug3: send packet: type 98 Oct 30 10:52:28 sshserver sshd[63460]: debug1: session_exit_message: release channel 0 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: send close Oct 30 10:52:28 sshserver sshd[63460]: debug3: send packet: type 97 Oct 30 10:52:28 sshserver sshd[63460]: debug3: channel 0: will not send data after close Oct 30 10:52:28 sshserver sshd[63460]: debug3: receive packet: type 97 Oct 30 10:52:28 sshserver sshd[63460]: debug2: channel 0: rcvd close Oct 30 10:52:28 sshserver sshd[63460]: debug3: receive packet: type 1 Oct 30 10:52:28 sshserver sshd[63460]: Received disconnect from 10.10.10.100 port 31311:11: disconnected by user Oct 30 10:52:28 sshserver sshd[63460]: Disconnected from 10.10.10.100 port 31311 Oct 30 10:52:28 sshserver sshd[63460]: debug1: do_cleanup Oct 30 10:52:28 sshserver sshd[63460]: debug3: PAM: sshpam_thread_cleanup entering Oct 30 10:52:28 sshserver sshd[63451]: debug3: mm_request_receive entering Oct 30 10:52:28 sshserver sshd[63451]: debug1: do_cleanup Oct 30 10:52:28 sshserver sshd[63451]: debug1: PAM: cleanup Oct 30 10:52:28 sshserver sshd[63451]: debug1: PAM: closing session Oct 30 10:52:28 sshserver sshd[63451]: pam_unix(sshd:session): session closed for user testuser Oct 30 10:52:29 sshserver sshd[63451]: debug1: PAM: deleting credentials Oct 30 10:52:29 sshserver sshd[63451]: debug3: PAM: sshpam_thread_cleanup entering Oct 30 10:52:29 sshserver sshd[63451]: debug1: audit_event: unhandled event 12 Oct 30 10:52:29 sshserver systemd-logind[497]: Removed session 72191. Oct 30 10:52:29 sshserver systemd: pam_unix(systemd-user:session): session closed for user testuser