May 19 11:42:57 x220 sshd[3009]: debug3: fd 5 is not O_NONBLOCK May 19 11:42:57 x220 sshd[3009]: debug1: Forked child 3541. May 19 11:42:57 x220 sshd[3009]: debug3: send_rexec_state: entering fd = 8 config len 523 May 19 11:42:57 x220 sshd[3009]: debug3: ssh_msg_send: type 0 May 19 11:42:57 x220 sshd[3009]: debug3: send_rexec_state: done May 19 11:42:57 x220 sshd[3541]: debug3: oom_adjust_restore May 19 11:42:57 x220 sshd[3541]: Set /proc/self/oom_score_adj to 0 May 19 11:42:57 x220 sshd[3541]: debug1: rexec start in 5 out 5 newsock 5 pipe 7 sock 8 May 19 11:42:57 x220 sshd[3541]: debug1: inetd sockets after dupping: 3, 3 May 19 11:42:57 x220 sshd[3541]: Connection from 192.168.178.21 port 49854 May 19 11:42:57 x220 sshd[3541]: debug1: Client protocol version 2.0; client software version OpenSSH_6.0 May 19 11:42:57 x220 sshd[3541]: debug1: match: OpenSSH_6.0 pat OpenSSH* May 19 11:42:57 x220 sshd[3541]: debug1: Enabling compatibility mode for protocol 2.0 May 19 11:42:57 x220 sshd[3541]: debug1: Local version string SSH-1.99-OpenSSH_6.0 May 19 11:42:57 x220 sshd[3541]: debug2: fd 3 setting O_NONBLOCK May 19 11:42:57 x220 sshd[3541]: debug2: Network child is on pid 3542 May 19 11:42:57 x220 sshd[3541]: debug3: preauth child monitor started May 19 11:42:57 x220 sshd[3541]: debug3: privsep user:group 101:102 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: permanently_set_uid: 101/102 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: list_hostkey_types: ssh-rsa,ssh-dss,ecdsa-sha2-nistp256 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: SSH2_MSG_KEXINIT sent [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: SSH2_MSG_KEXINIT received [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: 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] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: ssh-rsa,ssh-dss,ecdsa-sha2-nistp256 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: first_kex_follows 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: reserved 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: 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] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: 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,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] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: aes128-ctr,aes192-ctr,aes256-ctr,arcfour256,arcfour128,aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,aes192-cbc,aes256-cbc,arcfour,rijndael-cbc@lysator.liu.se [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-sha2-256,hmac-sha2-256-96,hmac-sha2-512,hmac-sha2-512-96,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: first_kex_follows 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kex_parse_kexinit: reserved 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: mac_setup: found hmac-md5 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: kex: client->server aes128-ctr hmac-md5 none [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: mac_setup: found hmac-md5 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: kex: server->client aes128-ctr hmac-md5 none [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: expecting SSH2_MSG_KEX_ECDH_INIT [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_key_sign entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 4 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 5 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:42:57 x220 sshd[3541]: debug3: monitor_read: checking request 4 May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_sign May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_sign: signature 0x7fe873ce8ed0(100) May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 5 May 19 11:42:57 x220 sshd[3541]: debug2: monitor_read: 4 used once, disabling now May 19 11:42:57 x220 sshd[3541]: debug2: kex_derive_keys [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: set_newkeys: mode 1 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: SSH2_MSG_NEWKEYS sent [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: expecting SSH2_MSG_NEWKEYS [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: set_newkeys: mode 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: SSH2_MSG_NEWKEYS received [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: KEX done [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: userauth-request for user hk service ssh-connection method none [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: attempt 0 failures 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_getpwnamallow entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 6 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 7 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:42:57 x220 sshd[3541]: debug3: monitor_read: checking request 6 May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_pwnamallow May 19 11:42:57 x220 sshd[3541]: debug3: Trying to reverse map address 192.168.178.21. May 19 11:42:57 x220 sshd[3541]: debug2: parse_server_config: config reprocess config len 523 May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1 May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 7 May 19 11:42:57 x220 sshd[3541]: debug2: monitor_read: 6 used once, disabling now May 19 11:42:57 x220 sshd[3541]: debug2: input_userauth_request: setting up authctxt for hk [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_start_pam entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 45 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_inform_authserv entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 3 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: input_userauth_request: try method none [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:42:57 x220 sshd[3541]: debug3: monitor_read: checking request 45 May 19 11:42:57 x220 sshd[3541]: debug1: PAM: initializing for "hk" May 19 11:42:57 x220 sshd[3541]: debug1: PAM: setting PAM_RHOST to "d600.fritz.box" May 19 11:42:57 x220 sshd[3541]: debug1: PAM: setting PAM_TTY to "ssh" May 19 11:42:57 x220 sshd[3541]: debug2: monitor_read: 45 used once, disabling now May 19 11:42:57 x220 sshd[3541]: debug1: userauth-request for user hk service ssh-connection method keyboard-interactive [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: attempt 1 failures 0 [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: input_userauth_request: try method keyboard-interactive [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: keyboard-interactive devs [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: auth2_challenge: user=hk devs= [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: kbdint_alloc: devices 'pam' [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: auth2_challenge_start: devices pam [preauth] May 19 11:42:57 x220 sshd[3541]: debug2: kbdint_next_device: devices [preauth] May 19 11:42:57 x220 sshd[3541]: debug1: auth2_challenge_start: trying authentication method 'pam' [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_sshpam_init_ctx [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 48 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_sshpam_init_ctx: waiting for MONITOR_ANS_PAM_INIT_CTX [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 49 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:42:57 x220 sshd[3541]: debug3: monitor_read: checking request 3 May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_authserv: service=ssh-connection, style= May 19 11:42:57 x220 sshd[3541]: debug2: monitor_read: 3 used once, disabling now May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:42:57 x220 sshd[3541]: debug3: monitor_read: checking request 48 May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_pam_init_ctx May 19 11:42:57 x220 sshd[3541]: debug3: PAM: sshpam_init_ctx entering May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 49 May 19 11:42:57 x220 sshd[3541]: debug3: mm_sshpam_query [preauth] May 19 11:42:57 x220 sshd[3543]: debug3: PAM: sshpam_thread_conv entering, 1 messages May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 50 [preauth] May 19 11:42:57 x220 sshd[3543]: debug3: ssh_msg_send: type 1 May 19 11:42:57 x220 sshd[3541]: debug3: mm_sshpam_query: waiting for MONITOR_ANS_PAM_QUERY [preauth] May 19 11:42:57 x220 sshd[3543]: debug3: ssh_msg_recv entering May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 51 [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:42:57 x220 sshd[3541]: debug3: monitor_read: checking request 50 May 19 11:42:57 x220 sshd[3541]: debug3: mm_answer_pam_query May 19 11:42:57 x220 sshd[3541]: debug3: PAM: sshpam_query entering May 19 11:42:57 x220 sshd[3541]: debug3: ssh_msg_recv entering May 19 11:42:57 x220 sshd[3541]: debug3: mm_request_send entering: type 51 May 19 11:42:57 x220 sshd[3541]: debug3: mm_sshpam_query: pam_query returned 0 [preauth] May 19 11:42:57 x220 sshd[3541]: Postponed keyboard-interactive for hk from 192.168.178.21 port 49854 ssh2 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_respond [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 52 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_respond: waiting for MONITOR_ANS_PAM_RESPOND [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 53 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:02 x220 sshd[3541]: debug3: monitor_read: checking request 52 May 19 11:43:02 x220 sshd[3541]: debug3: mm_answer_pam_respond May 19 11:43:02 x220 sshd[3541]: debug2: PAM: sshpam_respond entering, 1 responses May 19 11:43:02 x220 sshd[3541]: debug3: ssh_msg_send: type 6 May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 53 May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_respond: pam_respond returned 1 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_query [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 50 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_query: waiting for MONITOR_ANS_PAM_QUERY [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 51 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:43:02 x220 sshd[3543]: debug1: do_pam_account: called May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:02 x220 sshd[3541]: debug3: monitor_read: checking request 50 May 19 11:43:02 x220 sshd[3541]: debug3: mm_answer_pam_query May 19 11:43:02 x220 sshd[3543]: debug3: PAM: do_pam_account pam_acct_mgmt = 0 (Success) May 19 11:43:02 x220 sshd[3541]: debug3: PAM: sshpam_query entering May 19 11:43:02 x220 sshd[3543]: debug3: ssh_msg_send: type 0 May 19 11:43:02 x220 sshd[3541]: debug3: ssh_msg_recv entering May 19 11:43:02 x220 sshd[3541]: debug3: PAM: import_environments entering May 19 11:43:02 x220 sshd[3541]: debug3: sshpam_password_change_required 0 May 19 11:43:02 x220 sshd[3541]: debug3: PAM: num env strings 0 May 19 11:43:02 x220 sshd[3541]: debug1: PAM: num PAM env strings 0 May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 51 May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_query: pam_query returned 0 [preauth] May 19 11:43:02 x220 sshd[3541]: Postponed keyboard-interactive/pam for hk from 192.168.178.21 port 49854 ssh2 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_respond [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 52 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_respond: waiting for MONITOR_ANS_PAM_RESPOND [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 53 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:02 x220 sshd[3541]: debug3: monitor_read: checking request 52 May 19 11:43:02 x220 sshd[3541]: debug3: mm_answer_pam_respond May 19 11:43:02 x220 sshd[3541]: debug2: PAM: sshpam_respond entering, 0 responses May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 53 May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_respond: pam_respond returned 0 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_free_ctx [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 54 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_sshpam_free_ctx: waiting for MONITOR_ANS_PAM_FREE_CTX [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 55 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:02 x220 sshd[3541]: debug3: monitor_read: checking request 54 May 19 11:43:02 x220 sshd[3541]: debug3: mm_answer_pam_free_ctx May 19 11:43:02 x220 sshd[3541]: debug3: PAM: sshpam_free_ctx entering May 19 11:43:02 x220 sshd[3541]: debug3: PAM: sshpam_thread_cleanup entering May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 55 May 19 11:43:02 x220 sshd[3541]: debug2: monitor_read: 54 used once, disabling now May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 46 May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:02 x220 sshd[3541]: debug1: do_pam_account: called May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 47 May 19 11:43:02 x220 sshd[3541]: Accepted keyboard-interactive/pam for hk from 192.168.178.21 port 49854 ssh2 May 19 11:43:02 x220 sshd[3541]: debug3: mm_do_pam_account entering [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 46 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 47 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_do_pam_account returning 1 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_send_keystate: Sending new keys: 0x7fe873ce8300 0x7fe873ce8160 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_newkeys_to_blob: converting 0x7fe873ce8300 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_newkeys_to_blob: converting 0x7fe873ce8160 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_send_keystate: New keys have been sent [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_send_keystate: Sending compression state [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_send entering: type 24 [preauth] May 19 11:43:02 x220 sshd[3541]: debug3: mm_send_keystate: Finished sending state [preauth] May 19 11:43:02 x220 sshd[3541]: debug1: monitor_read_log: child log fd closed May 19 11:43:02 x220 sshd[3541]: debug1: monitor_child_preauth: hk has been authenticated by privileged process May 19 11:43:02 x220 sshd[3541]: debug3: mm_get_keystate: Waiting for new keys May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive_expect entering: type 24 May 19 11:43:02 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:02 x220 sshd[3541]: debug3: mm_newkeys_from_blob: 0x7fe873cf2810(122) May 19 11:43:02 x220 sshd[3541]: debug2: mac_setup: found hmac-md5 May 19 11:43:02 x220 sshd[3541]: debug3: mm_get_keystate: Waiting for second key May 19 11:43:02 x220 sshd[3541]: debug3: mm_newkeys_from_blob: 0x7fe873cf2810(122) May 19 11:43:02 x220 sshd[3541]: debug2: mac_setup: found hmac-md5 May 19 11:43:02 x220 sshd[3541]: debug3: mm_get_keystate: Getting compression state May 19 11:43:02 x220 sshd[3541]: debug3: mm_get_keystate: Getting Network I/O buffers May 19 11:43:02 x220 sshd[3541]: debug3: mm_share_sync: Share sync May 19 11:43:02 x220 sshd[3541]: debug3: mm_share_sync: Share sync end May 19 11:43:02 x220 sshd[3541]: debug1: PAM: establishing credentials May 19 11:43:02 x220 sshd[3541]: debug3: PAM: opening session May 19 11:43:02 x220 systemd-logind[700]: New session 20 of user hk. May 19 11:43:02 x220 sshd[3541]: User child is on pid 3544 May 19 11:43:02 x220 sshd[3544]: debug1: SELinux support disabled May 19 11:43:02 x220 sshd[3544]: debug1: PAM: establishing credentials May 19 11:43:02 x220 sshd[3544]: debug1: permanently_set_uid: 1500/1107 May 19 11:43:02 x220 sshd[3544]: debug2: set_newkeys: mode 0 May 19 11:43:02 x220 sshd[3544]: debug2: set_newkeys: mode 1 May 19 11:43:02 x220 sshd[3544]: debug1: Entering interactive session for SSH2. May 19 11:43:02 x220 sshd[3544]: debug2: fd 5 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug2: fd 7 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug1: server_init_dispatch_20 May 19 11:43:02 x220 sshd[3544]: debug1: server_input_channel_open: ctype session rchan 0 win 2097152 max 32768 May 19 11:43:02 x220 sshd[3544]: debug1: input_session_request May 19 11:43:02 x220 sshd[3544]: debug1: channel 0: new [server-session] May 19 11:43:02 x220 sshd[3544]: debug2: session_new: allocate (allocated 0 max 10) May 19 11:43:02 x220 sshd[3544]: debug3: session_unused: session id 0 unused May 19 11:43:02 x220 sshd[3544]: debug1: session_new: session 0 May 19 11:43:02 x220 sshd[3544]: debug1: session_open: channel 0 May 19 11:43:02 x220 sshd[3544]: debug1: session_open: session 0: link with channel 0 May 19 11:43:02 x220 sshd[3544]: debug1: server_input_channel_open: confirm session May 19 11:43:02 x220 sshd[3544]: debug1: server_input_global_request: rtype no-more-sessions@openssh.com want_reply 0 May 19 11:43:02 x220 sshd[3544]: debug1: server_input_channel_req: channel 0 request x11-req reply 1 May 19 11:43:02 x220 sshd[3544]: debug1: session_by_channel: session 0 channel 0 May 19 11:43:02 x220 sshd[3544]: debug1: session_input_channel_req: session 0 req x11-req May 19 11:43:02 x220 sshd[3544]: debug3: sock_set_v6only: set socket 8 IPV6_V6ONLY May 19 11:43:02 x220 sshd[3544]: debug2: bind port 6010: Address already in use May 19 11:43:02 x220 sshd[3544]: debug3: sock_set_v6only: set socket 8 IPV6_V6ONLY May 19 11:43:02 x220 sshd[3544]: debug2: bind port 6011: Address already in use May 19 11:43:02 x220 sshd[3544]: debug3: sock_set_v6only: set socket 8 IPV6_V6ONLY May 19 11:43:02 x220 sshd[3544]: debug2: bind port 6012: Address already in use May 19 11:43:02 x220 sshd[3544]: debug3: sock_set_v6only: set socket 8 IPV6_V6ONLY May 19 11:43:02 x220 sshd[3544]: debug2: fd 8 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug3: fd 8 is O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug1: channel 1: new [X11 inet listener] May 19 11:43:02 x220 sshd[3544]: debug2: fd 9 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug3: fd 9 is O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug1: channel 2: new [X11 inet listener] May 19 11:43:02 x220 sshd[3544]: debug1: server_input_channel_req: channel 0 request env reply 0 May 19 11:43:02 x220 sshd[3544]: debug1: session_by_channel: session 0 channel 0 May 19 11:43:02 x220 sshd[3544]: debug1: session_input_channel_req: session 0 req env May 19 11:43:02 x220 sshd[3544]: debug2: Setting env 0: LANG=de_DE.UTF-8 May 19 11:43:02 x220 sshd[3544]: debug1: server_input_channel_req: channel 0 request exec reply 1 May 19 11:43:02 x220 sshd[3544]: debug1: session_by_channel: session 0 channel 0 May 19 11:43:02 x220 sshd[3544]: debug1: session_input_channel_req: session 0 req exec May 19 11:43:02 x220 sshd[3544]: debug2: fd 3 setting TCP_NODELAY May 19 11:43:02 x220 sshd[3545]: debug3: Copy environment: XDG_SESSION_ID=20 May 19 11:43:02 x220 sshd[3544]: debug3: packet_set_tos: set IP_TOS 0x10 May 19 11:43:02 x220 sshd[3545]: debug3: Copy environment: XDG_RUNTIME_DIR=/run/user/hk May 19 11:43:02 x220 sshd[3544]: debug2: fd 12 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug2: fd 11 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug2: fd 14 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug1: X11 connection requested. May 19 11:43:02 x220 sshd[3544]: debug2: fd 10 setting TCP_NODELAY May 19 11:43:02 x220 sshd[3544]: debug2: fd 10 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug3: fd 10 is O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug1: channel 3: new [X11 connection from ::1 port 40742] May 19 11:43:02 x220 sshd[3544]: debug2: channel 3: open confirm rwindow 2097152 rmax 16384 May 19 11:43:02 x220 sshd[3544]: debug1: X11 connection requested. May 19 11:43:02 x220 sshd[3544]: debug2: fd 13 setting TCP_NODELAY May 19 11:43:02 x220 sshd[3544]: debug2: fd 13 setting O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug3: fd 13 is O_NONBLOCK May 19 11:43:02 x220 sshd[3544]: debug1: channel 4: new [X11 connection from ::1 port 40743] May 19 11:43:02 x220 sshd[3544]: debug2: channel 4: open confirm rwindow 2097152 rmax 16384 May 19 11:43:02 x220 sshd[3544]: debug2: channel 3: window 22700 sent adjust 42836 May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: window 27668 sent adjust 37868 May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: read<=0 rfd 10 len 0 May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: read failed May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: close_read May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: input open -> drain May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: ibuf empty May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: send eof May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: input drain -> closed May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: rcvd eof May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: output open -> drain May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: obuf empty May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: close_write May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: output drain -> closed May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: rcvd close May 19 11:43:03 x220 sshd[3544]: debug3: channel 3: will not send data after close May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: send close May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: is dead May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: garbage collecting May 19 11:43:03 x220 sshd[3544]: debug1: channel 3: free: X11 connection from ::1 port 40742, nchannels 5 May 19 11:43:03 x220 sshd[3544]: debug3: channel 3: status: The following connections are open:\r\n #0 server-session (t4 r0 i0/0 o0/0 fd 12/11 cc -1)\r\n #3 X11 connection from ::1 port 40742 (t4 r1 i3/0 o3/0 fd 10/10 cc -1)\r\n #4 X11 connection from ::1 port 40743 (t4 r2 i0/0 o0/0 fd 13/13 cc -1)\r\n May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: read<=0 rfd 13 len 0 May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: read failed May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: close_read May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: input open -> drain May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: ibuf empty May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: send eof May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: input drain -> closed May 19 11:43:03 x220 sshd[3544]: debug1: X11 connection requested. May 19 11:43:03 x220 sshd[3544]: debug2: fd 10 setting TCP_NODELAY May 19 11:43:03 x220 sshd[3544]: debug2: fd 10 setting O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug3: fd 10 is O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug1: channel 3: new [X11 connection from ::1 port 40744] May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: rcvd eof May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: output open -> drain May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: obuf empty May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: close_write May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: output drain -> closed May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: rcvd close May 19 11:43:03 x220 sshd[3544]: debug3: channel 4: will not send data after close May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: send close May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: is dead May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: garbage collecting May 19 11:43:03 x220 sshd[3544]: debug1: channel 4: free: X11 connection from ::1 port 40743, nchannels 5 May 19 11:43:03 x220 sshd[3544]: debug3: channel 4: status: The following connections are open:\r\n #0 server-session (t4 r0 i0/0 o0/0 fd 12/11 cc -1)\r\n #3 X11 connection from ::1 port 40744 (t3 r-1 i0/0 o0/0 fd 10/10 cc -1)\r\n #4 X11 connection from ::1 port 40743 (t4 r2 i3/0 o3/0 fd 13/13 cc -1)\r\n May 19 11:43:03 x220 sshd[3544]: debug2: channel 3: open confirm rwindow 2097152 rmax 16384 May 19 11:43:03 x220 sshd[3544]: debug1: X11 connection requested. May 19 11:43:03 x220 sshd[3544]: debug2: fd 13 setting TCP_NODELAY May 19 11:43:03 x220 sshd[3544]: debug2: fd 13 setting O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug3: fd 13 is O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug1: channel 4: new [X11 connection from ::1 port 40745] May 19 11:43:03 x220 sshd[3544]: debug2: channel 4: open confirm rwindow 2097152 rmax 16384 May 19 11:43:03 x220 sshd[3544]: debug1: X11 connection requested. May 19 11:43:03 x220 sshd[3544]: debug2: fd 15 setting TCP_NODELAY May 19 11:43:03 x220 sshd[3544]: debug2: fd 15 setting O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug3: fd 15 is O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug1: channel 5: new [X11 connection from ::1 port 40746] May 19 11:43:03 x220 sshd[3544]: debug2: channel 5: open confirm rwindow 2097152 rmax 16384 May 19 11:43:03 x220 sshd[3544]: debug1: X11 connection requested. May 19 11:43:03 x220 sshd[3544]: debug2: fd 16 setting TCP_NODELAY May 19 11:43:03 x220 sshd[3544]: debug2: fd 16 setting O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug3: fd 16 is O_NONBLOCK May 19 11:43:03 x220 sshd[3544]: debug1: channel 6: new [X11 connection from ::1 port 40747] May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: open confirm rwindow 2097152 rmax 16384 May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: read<=0 rfd 16 len 0 May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: read failed May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: close_read May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: input open -> drain May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: ibuf empty May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: send eof May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: input drain -> closed May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: rcvd eof May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: output open -> drain May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: obuf empty May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: close_write May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: output drain -> closed May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: rcvd close May 19 11:43:03 x220 sshd[3544]: debug3: channel 6: will not send data after close May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: send close May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: is dead May 19 11:43:03 x220 sshd[3544]: debug2: channel 6: garbage collecting May 19 11:43:03 x220 sshd[3544]: debug1: channel 6: free: X11 connection from ::1 port 40747, nchannels 7 May 19 11:43:03 x220 sshd[3544]: debug3: channel 6: status: The following connections are open:\r\n #0 server-session (t4 r0 i0/0 o0/0 fd 12/11 cc -1)\r\n #3 X11 connection from ::1 port 40744 (t4 r1 i0/0 o0/0 fd 10/10 cc -1)\r\n #4 X11 connection from ::1 port 40745 (t4 r2 i0/0 o0/0 fd 13/13 cc -1)\r\n #5 X11 connection from ::1 port 40746 (t4 r3 i0/0 o0/0 fd 15/15 cc -1)\r\n #6 X11 connection from ::1 port 40747 (t4 r4 i3/0 o3/0 fd 16/16 cc -1)\r\n May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 58892 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 65536 May 19 11:43:04 x220 sshd[3544]: last message repeated 2 times May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 53160 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 32768 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 161920 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 53640 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 65536 May 19 11:43:04 x220 sshd[3544]: last message repeated 4 times May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 16384 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 68592 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 65536 May 19 11:43:04 x220 sshd[3544]: debug2: channel 3: rcvd adjust 32768 May 19 11:43:06 x220 sshd[3544]: debug2: channel 3: rcvd adjust 64800 May 19 11:43:06 x220 sshd[3544]: debug2: channel 3: rcvd adjust 61892 May 19 11:43:06 x220 sshd[3544]: debug2: channel 3: window 32428 sent adjust 33108 May 19 11:43:06 x220 sshd[3544]: debug2: channel 3: rcvd adjust 50356 May 19 11:43:07 x220 sshd[3544]: debug2: channel 3: rcvd adjust 49640 May 19 11:43:07 x220 sshd[3544]: debug2: channel 3: rcvd adjust 50532 May 19 11:43:07 x220 sshd[3544]: debug2: channel 3: rcvd adjust 53484 May 19 11:43:07 x220 sshd[3544]: debug2: channel 3: rcvd adjust 55888 May 19 11:43:08 x220 sshd[3544]: debug2: channel 3: rcvd adjust 53536 May 19 11:43:08 x220 sshd[3544]: debug2: channel 3: window 19312 sent adjust 46224 May 19 11:43:08 x220 sshd[3544]: debug2: channel 3: window 16384 sent adjust 49152 May 19 11:43:08 x220 sshd[3544]: last message repeated 4 times May 19 11:43:08 x220 sshd[3544]: debug2: channel 3: window 18400 sent adjust 47136 May 19 11:43:08 x220 sshd[3544]: debug2: channel 3: window 25600 sent adjust 39936 May 19 11:43:12 x220 sshd[3544]: debug2: channel 3: rcvd adjust 57796 May 19 11:43:13 x220 sshd[3544]: Corrupted MAC on input. May 19 11:43:13 x220 sshd[3544]: Disconnecting: Packet corrupt May 19 11:43:13 x220 sshd[3544]: debug1: do_cleanup May 19 11:43:13 x220 sshd[3544]: debug3: PAM: sshpam_thread_cleanup entering May 19 11:43:13 x220 sshd[3541]: debug3: mm_request_receive entering May 19 11:43:13 x220 sshd[3541]: debug1: do_cleanup May 19 11:43:13 x220 sshd[3541]: debug1: PAM: cleanup May 19 11:43:13 x220 sshd[3541]: debug1: PAM: closing session May 19 11:43:13 x220 sshd[3541]: debug1: PAM: deleting credentials May 19 11:43:13 x220 systemd-logind[700]: Removed session 20. May 19 11:43:13 x220 sshd[3541]: debug3: PAM: sshpam_thread_cleanup entering