Attachment 3113 Details for Bug 2819 – scp and sshd logs

scp and sshd logs

scp_bug.txt (text/plain), 28.53 KB, created by Mayank Sharma on 2018-01-09 21:19:38 AEDT

(hide)

Description:

Filename:

MIME Type:

Creator: Mayank Sharma

Created: 2018-01-09 21:19:38 AEDT

Size: 28.53 KB

patch

obsolete

>********************************************************************************************
>scp logs
>********************************************************************************************
>
># scp -vvv -C /usr/lib/libssl.a localhost:/
>Executing: program /usr//bin/ssh host localhost, user (unspecified), command scp -v -t /
>OpenSSH_7.5p1, OpenSSL 1.0.2m  2 Nov 2017
>debug1: Reading configuration data /etc/ssh/ssh_config
>debug2: resolving "localhost" port 22
>debug2: ssh_connect_direct: needpriv 0
>debug1: Connecting to localhost [127.0.0.1] port 22.
>debug1: Connection established.
>debug1: permanently_set_uid: 0/0
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_rsa type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_rsa-cert type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_dsa type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_dsa-cert type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_ecdsa type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_ecdsa-cert type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_ed25519 type -1
>debug1: key_load_public: No such file or directory
>debug1: identity file /.ssh/id_ed25519-cert type -1
>debug1: Enabling compatibility mode for protocol 2.0
>debug1: Local version string SSH-2.0-OpenSSH_7.5
>debug1: Remote protocol version 2.0, remote software version OpenSSH_7.5
>debug1: match: OpenSSH_7.5 pat OpenSSH* compat 0x04000000
>debug2: fd 3 setting O_NONBLOCK
>debug1: Authenticating to localhost:22 as 'root'
>debug3: hostkeys_foreach: reading file "/.ssh/known_hosts"
>debug3: record_hostkey: found key type ECDSA in file /.ssh/known_hosts:1
>debug3: load_hostkeys: loaded 1 keys from localhost
>debug3: order_hostkeyalgs: prefer hostkeyalgs: 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
>debug3: send packet: type 20
>debug1: SSH2_MSG_KEXINIT sent
>debug3: receive packet: type 20
>debug1: SSH2_MSG_KEXINIT received
>debug2: local client KEXINIT proposal
>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-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,ext-info-c
>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,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa
>debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc
>debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc
>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
>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
>debug2: compression ctos: zlib@openssh.com,zlib,none
>debug2: compression stoc: zlib@openssh.com,zlib,none
>debug2: languages ctos:
>debug2: languages stoc:
>debug2: first_kex_follows 0
>debug2: reserved 0
>debug2: peer server KEXINIT proposal
>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
>debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519
>debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com
>debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com
>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
>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
>debug2: compression ctos: none,zlib@openssh.com
>debug2: compression stoc: none,zlib@openssh.com
>debug2: languages ctos:
>debug2: languages stoc:
>debug2: first_kex_follows 0
>debug2: reserved 0
>debug1: kex: algorithm: curve25519-sha256
>debug1: kex: host key algorithm: ecdsa-sha2-nistp256
>debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com
>debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com
>debug3: send packet: type 30
>debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
>debug3: receive packet: type 31
>debug1: Server host key: ecdsa-sha2-nistp256 SHA256:LPYmCuhACtqQJTWxb9gyZD8Lj3MoEg0qEYm8nsTT0Ws
>debug3: hostkeys_foreach: reading file "/.ssh/known_hosts"
>debug3: record_hostkey: found key type ECDSA in file /.ssh/known_hosts:1
>debug3: load_hostkeys: loaded 1 keys from localhost
>debug1: Host 'localhost' is known and matches the ECDSA host key.
>debug1: Found key in /.ssh/known_hosts:1
>debug3: send packet: type 21
>debug2: set_newkeys: mode 1
>debug1: rekey after 134217728 blocks
>debug1: SSH2_MSG_NEWKEYS sent
>debug1: expecting SSH2_MSG_NEWKEYS
>debug3: receive packet: type 21
>debug1: SSH2_MSG_NEWKEYS received
>debug2: set_newkeys: mode 0
>debug1: rekey after 134217728 blocks
>debug2: key: /.ssh/id_rsa (0)
>debug2: key: /.ssh/id_dsa (0)
>debug2: key: /.ssh/id_ecdsa (0)
>debug2: key: /.ssh/id_ed25519 (0)
>debug3: send packet: type 5
>debug3: receive packet: type 7
>debug1: SSH2_MSG_EXT_INFO received
>debug1: kex_input_ext_info: server-sig-algs=<ssh-ed25519,ssh-rsa,rsa-sha2-256,rsa-sha2-512,ssh-dss,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521>
>debug3: receive packet: type 6
>debug2: service_accept: ssh-userauth
>debug1: SSH2_MSG_SERVICE_ACCEPT received
>debug3: send packet: type 50
>debug3: receive packet: type 51
>debug1: Authentications that can continue: publickey,password,keyboard-interactive
>debug3: start over, passed a different list publickey,password,keyboard-interactive
>debug3: preferred publickey,keyboard-interactive,password
>debug3: authmethod_lookup publickey
>debug3: remaining preferred: keyboard-interactive,password
>debug3: authmethod_is_enabled publickey
>debug1: Next authentication method: publickey
>debug1: Trying private key: /.ssh/id_rsa
>debug3: no such identity: /.ssh/id_rsa: No such file or directory
>debug1: Trying private key: /.ssh/id_dsa
>debug3: no such identity: /.ssh/id_dsa: No such file or directory
>debug1: Trying private key: /.ssh/id_ecdsa
>debug3: no such identity: /.ssh/id_ecdsa: No such file or directory
>debug1: Trying private key: /.ssh/id_ed25519
>debug3: no such identity: /.ssh/id_ed25519: No such file or directory
>debug2: we did not send a packet, disable method
>debug3: authmethod_lookup keyboard-interactive
>debug3: remaining preferred: password
>debug3: authmethod_is_enabled keyboard-interactive
>debug1: Next authentication method: keyboard-interactive
>debug2: userauth_kbdint
>debug3: send packet: type 50
>debug2: we sent a keyboard-interactive packet, wait for reply
>debug3: receive packet: type 51
>debug1: Authentications that can continue: publickey,password,keyboard-interactive
>debug3: userauth_kbdint: disable: no info_req_seen
>debug2: we did not send a packet, disable method
>debug3: authmethod_lookup password
>debug3: remaining preferred:
>debug3: authmethod_is_enabled password
>debug1: Next authentication method: password
>root@localhost's password:
>debug3: send packet: type 50
>debug2: we sent a password packet, wait for reply
>debug3: receive packet: type 52
>debug1: Enabling compression at level 6.
>debug1: Authentication succeeded (password).
>Authenticated to localhost ([127.0.0.1]:22).
>debug2: fd 4 setting O_NONBLOCK
>debug2: fd 5 setting O_NONBLOCK
>debug1: channel 0: new [client-session]
>debug3: ssh_session2_open: channel_new: 0
>debug2: channel 0: send open
>debug3: send packet: type 90
>debug1: Requesting no-more-sessions@openssh.com
>debug3: send packet: type 80
>debug1: Entering interactive session.
>debug1: pledge: network
>debug3: receive packet: type 80
>debug1: client_input_global_request: rtype hostkeys-00@openssh.com want_reply 0
>debug3: receive packet: type 91
>debug2: callback start
>debug2: fd 3 setting TCP_NODELAY
>debug3: ssh_packet_set_tos: set IP_TOS 0x08
>debug2: client_session2_setup: id 0
>debug1: Sending command: scp -v -t /
>debug2: channel 0: request exec confirm 1
>debug3: send packet: type 98
>debug2: callback done
>debug2: channel 0: open confirm rwindow 0 rmax 32768
>debug2: channel 0: rcvd adjust 2097152
>debug3: receive packet: type 99
>debug2: channel_input_status_confirm: type 99 id 0
>debug2: exec request accepted on channel 0
>debug2: channel 0: rcvd ext data 604
>debug3: AIX/UsrInfo: set len 23
>debug1: permanently_set_uid: 0/0
>Environment:
>  USER=root
>  LOGNAME=root
>  LOGIN=root
>  HOME=/
>  PATH=/usr/bin:/etc:/usr/sbin:/usr/ucb:/usr/bin/X11:/sbin:/usr/java7_64/jre/bin:/usr/java7_64/bin
>  MAIL=/var/spool/mail/root
>  SHELL=/usr/bin/ksh
>  TZ=CST6CDT
>  SSH_CLIENT=127.0.0.1 50403 22
>  SSH_CONNECTION=127.0.0.1 50403 127.0.0.1 22
>  AUTHSTATE=compat
>  LANG=en_US
>  LOCPATH=/usr/lib/nls/loc
>  NLSPATH=/usr/lib/nls/msg/%L/%N:/usr/lib/nls/msg/%L/%N.cat:/usr/lib/nls/msg/%l.%c/%N:/usr/lib/nls/msg/%l.%c/%N.cat
>  LC__FASTMSG=true
>  ODMDIR=/etc/objrepos
>  CLCMD_PASSTHRU=1
>debug2: channel 0: written 604 to efd 6
>Sending file modes: C0555 4453815 libssl.a
>debug2: channel 0: rcvd ext data 29
>Sink: C0555 4453815 libssl.a
>debug2: channel 0: written 29 to efd 6
>libssl.a                                                                                                                               0%    0     0.0KB/s   --:-- ETAdebug2: channel 0: rcvd adjust 32791
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 32768
>debug2: channel 0: rcvd adjust 81920
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 131072
>debug2: channel 0: rcvd adjust 114688
>ssh_packet_send: invalid format
>lost connection
>
>***************************************************************************
>sshd log
>***************************************************************************
>
># /usr/sbin/sshd -ddd
>debug2: load_server_config: filename /etc/ssh/sshd_config
>debug2: load_server_config: done config len = 216
>debug2: parse_server_config: config /etc/ssh/sshd_config len 216
>debug3: /etc/ssh/sshd_config:33 setting PermitRootLogin yes
>debug3: /etc/ssh/sshd_config:42 setting AuthorizedKeysFile .ssh/authorized_keys
>debug3: /etc/ssh/sshd_config:114 setting Subsystem sftp /usr/sbin/sftp-server
>debug1: sshd version OpenSSH_7.5, OpenSSL 1.0.2m  2 Nov 2017
>debug1: private host key #0: ssh-rsa SHA256:UE2GE4pe2aoYkyYE+ysiarWw06094o2ruCYA7GxEDtE
>debug1: private host key #1: ssh-dss SHA256:mie5GliDaYAzqWS5tjkF4qx5zGd7ygIeAOc9kxvhKDw
>debug1: private host key #2: ecdsa-sha2-nistp256 SHA256:LPYmCuhACtqQJTWxb9gyZD8Lj3MoEg0qEYm8nsTT0Ws
>debug1: private host key #3: ssh-ed25519 SHA256:1za4xpmyum5RpYCwQC6pnqQNE6V99tf4xn4+IxNGvgM
>debug1: rexec_argv[0]='/usr/sbin/sshd'
>debug1: rexec_argv[1]='-ddd'
>debug2: fd 3 setting O_NONBLOCK
>debug1: Bind to port 22 on 0.0.0.0.
>Server listening on 0.0.0.0 port 22.
>debug2: fd 4 setting O_NONBLOCK
>debug3: sock_set_v6only: set socket 4 IPV6_V6ONLY
>debug1: Bind to port 22 on ::.
>Server listening on :: port 22.
>debug1: fd 5 clearing O_NONBLOCK
>debug1: Server will not fork when running in debugging mode.
>debug3: send_rexec_state: entering fd = 8 config len 216
>debug3: ssh_msg_send: type 0
>debug3: send_rexec_state: done
>debug1: rexec start in 5 out 5 newsock 5 pipe -1 sock 8
>debug1: inetd sockets after dupping: 3, 3
>Connection from 127.0.0.1 port 50403 on 127.0.0.1 port 22
>debug1: Client protocol version 2.0; client software version OpenSSH_7.5
>debug1: match: OpenSSH_7.5 pat OpenSSH* compat 0x04000000
>debug1: Local version string SSH-2.0-OpenSSH_7.5
>debug1: Enabling compatibility mode for protocol 2.0
>debug2: fd 3 setting O_NONBLOCK
>debug3: ssh_sandbox_init: preparing rlimit sandbox
>debug2: Network child is on pid 18481472
>debug3: preauth child monitor started
>debug3: privsep user:group 212:201 [preauth]
>debug1: permanently_set_uid: 212/201 [preauth]
>debug3: list_hostkey_types: ssh-dss key not permitted by HostkeyAlgorithms [preauth]
>debug1: list_hostkey_types: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth]
>debug3: send packet: type 20 [preauth]
>debug1: SSH2_MSG_KEXINIT sent [preauth]
>debug3: receive packet: type 20 [preauth]
>debug1: SSH2_MSG_KEXINIT received [preauth]
>debug2: local server KEXINIT proposal [preauth]
>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]
>debug2: host key algorithms: ssh-rsa,rsa-sha2-512,rsa-sha2-256,ecdsa-sha2-nistp256,ssh-ed25519 [preauth]
>debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com [preauth]
>debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com [preauth]
>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]
>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]
>debug2: compression ctos: none,zlib@openssh.com [preauth]
>debug2: compression stoc: none,zlib@openssh.com [preauth]
>debug2: languages ctos:  [preauth]
>debug2: languages stoc:  [preauth]
>debug2: first_kex_follows 0  [preauth]
>debug2: reserved 0  [preauth]
>debug2: peer client KEXINIT proposal [preauth]
>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-group-exchange-sha1,diffie-hellman-group14-sha256,diffie-hellman-group14-sha1,ext-info-c [preauth]
>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,ssh-ed25519-cert-v01@openssh.com,ssh-rsa-cert-v01@openssh.com,ssh-ed25519,rsa-sha2-512,rsa-sha2-256,ssh-rsa [preauth]
>debug2: ciphers ctos: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc [preauth]
>debug2: ciphers stoc: chacha20-poly1305@openssh.com,aes128-ctr,aes192-ctr,aes256-ctr,aes128-gcm@openssh.com,aes256-gcm@openssh.com,aes128-cbc,aes192-cbc,aes256-cbc [preauth]
>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]
>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]
>debug2: compression ctos: zlib@openssh.com,zlib,none [preauth]
>debug2: compression stoc: zlib@openssh.com,zlib,none [preauth]
>debug2: languages ctos:  [preauth]
>debug2: languages stoc:  [preauth]
>debug2: first_kex_follows 0  [preauth]
>debug2: reserved 0  [preauth]
>debug1: kex: algorithm: curve25519-sha256 [preauth]
>debug1: kex: host key algorithm: ecdsa-sha2-nistp256 [preauth]
>debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com [preauth]
>debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: zlib@openssh.com [preauth]
>debug1: expecting SSH2_MSG_KEX_ECDH_INIT [preauth]
>debug3: receive packet: type 30 [preauth]
>debug3: mm_key_sign entering [preauth]
>debug3: mm_request_send entering: type 6 [preauth]
>debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN [preauth]
>debug3: mm_request_receive_expect entering: type 7 [preauth]
>debug3: mm_request_receive entering [preauth]
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 6
>debug3: mm_answer_sign
>debug3: mm_answer_sign: hostkey proof signature 200630d8(101)
>debug3: mm_request_send entering: type 7
>debug2: monitor_read: 6 used once, disabling now
>debug3: send packet: type 31 [preauth]
>debug3: send packet: type 21 [preauth]
>debug2: set_newkeys: mode 1 [preauth]
>debug1: rekey after 134217728 blocks [preauth]
>debug1: SSH2_MSG_NEWKEYS sent [preauth]
>debug1: expecting SSH2_MSG_NEWKEYS [preauth]
>debug3: send packet: type 7 [preauth]
>debug3: receive packet: type 21 [preauth]
>debug1: SSH2_MSG_NEWKEYS received [preauth]
>debug2: set_newkeys: mode 0 [preauth]
>debug1: rekey after 134217728 blocks [preauth]
>debug1: KEX done [preauth]
>debug3: receive packet: type 5 [preauth]
>debug3: send packet: type 6 [preauth]
>debug3: receive packet: type 50 [preauth]
>debug1: userauth-request for user root service ssh-connection method none [preauth]
>debug1: attempt 0 failures 0 [preauth]
>debug3: mm_getpwnamallow entering [preauth]
>debug3: mm_request_send entering: type 8 [preauth]
>debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM [preauth]
>debug3: mm_request_receive_expect entering: type 9 [preauth]
>debug3: mm_request_receive entering [preauth]
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 8
>debug3: mm_answer_pwnamallow
>debug2: parse_server_config: config reprocess config len 216
>debug3: AIX/setauthdb set registry 'files'
>debug3: aix_restoreauthdb: restoring old registry ''
>debug3: sys_auth_allowed_user: not checking
>debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1
>debug3: mm_request_send entering: type 9
>debug2: monitor_read: 8 used once, disabling now
>debug2: input_userauth_request: setting up authctxt for root [preauth]
>debug3: mm_inform_authserv entering [preauth]
>debug3: mm_request_send entering: type 4 [preauth]
>debug2: input_userauth_request: try method none [preauth]
>debug3: userauth_finish: failure partial=0 next methods="publickey,password,keyboard-interactive" [preauth]
>debug3: send packet: type 51 [preauth]
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 4
>debug3: mm_answer_authserv: service=ssh-connection, style=
>debug2: monitor_read: 4 used once, disabling now
>debug3: receive packet: type 50 [preauth]
>debug1: userauth-request for user root service ssh-connection method keyboard-interactive [preauth]
>debug1: attempt 1 failures 0 [preauth]
>debug2: input_userauth_request: try method keyboard-interactive [preauth]
>debug1: keyboard-interactive devs  [preauth]
>debug1: auth2_challenge: user=root devs= [preauth]
>debug1: kbdint_alloc: devices '' [preauth]
>debug2: auth2_challenge_start: devices  [preauth]
>debug3: userauth_finish: failure partial=0 next methods="publickey,password,keyboard-interactive" [preauth]
>debug3: send packet: type 51 [preauth]
>debug3: receive packet: type 50 [preauth]
>debug1: userauth-request for user root service ssh-connection method password [preauth]
>debug1: attempt 2 failures 1 [preauth]
>debug2: input_userauth_request: try method password [preauth]
>debug3: mm_auth_password entering [preauth]
>debug3: mm_request_send entering: type 12 [preauth]
>debug3: mm_auth_password: waiting for MONITOR_ANS_AUTHPASSWORD [preauth]
>debug3: mm_request_receive_expect entering: type 13 [preauth]
>debug3: mm_request_receive entering [preauth]
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 12
>debug3: AIX/authenticate result 0, authmsg
>debug3: AIX SYSTEM attribute compat
>debug3: AIX/setauthdb set registry 'files'
>debug3: AIX/passwdexpired returned 0 msg
>debug3: aix_restoreauthdb: restoring old registry ''
>debug3: mm_answer_authpassword: sending result 1
>debug3: mm_request_send entering: type 13
>Accepted password for root from 127.0.0.1 port 50403 ssh2
>debug3: AIX/setauthdb set registry 'files'
>debug1: AIX/loginsuccess: msg Last unsuccessful login: Tue Jan  9 04:18:55 CST 2018 on /dev/pts/3 from 9.199.153.71
>Last login: Tue Jan  9 04:19:03 CST 2018 on /dev/pts/4 from 9.199.153.71
>
>debug3: aix_restoreauthdb: restoring old registry ''
>debug1: monitor_child_preauth: root has been authenticated by privileged process
>debug3: mm_get_keystate: Waiting for new keys
>debug3: mm_request_receive_expect entering: type 26
>debug3: mm_request_receive entering
>debug3: mm_get_keystate: GOT new keys
>debug3: mm_auth_password: user authenticated [preauth]
>debug3: send packet: type 52 [preauth]
>debug1: Enabling compression at level 6. [preauth]
>debug3: mm_request_send entering: type 26 [preauth]
>debug3: mm_send_keystate: Finished sending state [preauth]
>debug1: monitor_read_log: child log fd closed
>debug3: ssh_sandbox_parent_finish: finished
>debug3: monitor_apply_keystate: packet_set_state
>debug2: set_newkeys: mode 0
>debug1: rekey after 134217728 blocks
>debug2: set_newkeys: mode 1
>debug1: rekey after 134217728 blocks
>debug1: ssh_packet_set_postauth: called
>debug1: Enabling compression at level 6.
>debug3: ssh_packet_set_state: done
>debug3: notify_hostkeys: key 0: ssh-rsa SHA256:UE2GE4pe2aoYkyYE+ysiarWw06094o2ruCYA7GxEDtE
>debug3: notify_hostkeys: key 1: ssh-dss SHA256:mie5GliDaYAzqWS5tjkF4qx5zGd7ygIeAOc9kxvhKDw
>debug3: notify_hostkeys: key 2: ecdsa-sha2-nistp256 SHA256:LPYmCuhACtqQJTWxb9gyZD8Lj3MoEg0qEYm8nsTT0Ws
>debug3: notify_hostkeys: key 3: ssh-ed25519 SHA256:1za4xpmyum5RpYCwQC6pnqQNE6V99tf4xn4+IxNGvgM
>debug3: notify_hostkeys: sent 4 hostkeys
>debug3: send packet: type 80
>debug1: Entering interactive session for SSH2.
>debug2: fd 5 setting O_NONBLOCK
>debug2: fd 6 setting O_NONBLOCK
>debug1: server_init_dispatch
>debug3: receive packet: type 90
>debug1: server_input_channel_open: ctype session rchan 0 win 2097152 max 32768
>debug1: input_session_request
>debug1: channel 0: new [server-session]
>debug2: session_new: allocate (allocated 0 max 10)
>debug3: session_unused: session id 0 unused
>debug1: session_new: session 0
>debug1: session_open: channel 0
>debug1: session_open: session 0: link with channel 0
>debug1: server_input_channel_open: confirm session
>debug3: send packet: type 91
>debug3: receive packet: type 80
>debug1: server_input_global_request: rtype no-more-sessions@openssh.com want_reply 0
>debug3: receive packet: type 98
>debug1: server_input_channel_req: channel 0 request exec reply 1
>debug1: session_by_channel: session 0 channel 0
>debug1: session_input_channel_req: session 0 req exec
>Starting session: command for root from 127.0.0.1 port 50403 id 0
>debug2: fd 3 setting TCP_NODELAY
>debug3: ssh_packet_set_tos: set IP_TOS 0x08
>debug2: fd 10 setting O_NONBLOCK
>debug2: fd 9 setting O_NONBLOCK
>debug2: fd 12 setting O_NONBLOCK
>debug3: send packet: type 99
>debug2: channel 0: read 604 from efd 12
>debug2: channel 0: rwin 2097151 elen 604 euse 1
>debug2: channel 0: sent ext data 604
>debug2: channel 0: read 29 from efd 12
>debug2: channel 0: rwin 2096546 elen 29 euse 1
>debug2: channel 0: sent ext data 29
>debug2: channel 0: window 1998825 sent adjust 32791
>debug2: channel 0: window 1867776 sent adjust 32768
>debug2: channel 0: window 1818624 sent adjust 32768
>debug2: channel 0: window 1720320 sent adjust 32768
>debug2: channel 0: window 1671168 sent adjust 32768
>debug2: channel 0: window 1458176 sent adjust 32768
>debug2: channel 0: window 1458176 sent adjust 32768
>debug2: channel 0: window 1490944 sent adjust 32768
>debug2: channel 0: window 1523712 sent adjust 32768
>debug2: channel 0: window 1556480 sent adjust 32768
>debug2: channel 0: window 1589248 sent adjust 32768
>debug2: channel 0: window 1622016 sent adjust 32768
>debug2: channel 0: window 1654784 sent adjust 32768
>debug2: channel 0: window 1687552 sent adjust 32768
>debug2: channel 0: window 1720320 sent adjust 32768
>debug2: channel 0: window 1753088 sent adjust 32768
>debug2: channel 0: window 1785856 sent adjust 32768
>debug2: channel 0: window 1818624 sent adjust 32768
>debug2: channel 0: window 1851392 sent adjust 32768
>debug2: channel 0: window 1884160 sent adjust 32768
>debug2: channel 0: window 1916928 sent adjust 32768
>debug2: channel 0: window 1949696 sent adjust 32768
>debug2: channel 0: window 1982464 sent adjust 32768
>debug2: channel 0: window 1982464 sent adjust 81920
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1966080 sent adjust 131072
>debug2: channel 0: window 1982464 sent adjust 114688
>Connection closed by 127.0.0.1 port 50403
>debug1: channel 0: free: server-session, nchannels 1
>debug3: channel 0: status: The following connections are open:
>  #0 server-session (t4 r0 i0/0 o0/0 fd 10/9 cc -1)
>
>Close session: user root from 127.0.0.1 port 50403 id 0
>debug3: session_unused: session id 0 unused
>debug1: do_cleanup
>Transferred: sent 4004, received 1190592 bytes
>Closing connection to 127.0.0.1 port 50403
>debug1: compress outgoing: raw data 2018, compressed 1542, factor 0.76
>debug1: compress incoming: raw data 1542, compressed 2018, factor 1.31

Actions: View

Attachments on bug 2819: 3113