Attachment 1115 Details for Bug 1131 – sshd debug output for hang at byte 10647071

sshd debug output for hang at byte 10647071

hung_at_10647071.txt (text/plain), 8.07 KB, created by Cove Schneider on 2006-04-05 10:20:01 AEST

(hide)

Description:

Filename:

MIME Type:

Creator: Cove Schneider

Created: 2006-04-05 10:20:01 AEST

Size: 8.07 KB

patch

obsolete

>Session hung at 10647071 bytes.
>
>debug2: load_server_config: filename /usr/local/etc/sshd_config
>debug2: load_server_config: done config len = 292
>debug2: parse_server_config: config /usr/local/etc/sshd_config len 292
>debug1: sshd version OpenSSH_4.3p2
>debug1: private host key: #0 type 0 RSA1
>debug3: Not a RSA1 key file /usr/local/etc/ssh_host_rsa_key.
>debug1: read PEM private key done: type RSA
>debug1: private host key: #1 type 1 RSA
>debug3: Not a RSA1 key file /usr/local/etc/ssh_host_dsa_key.
>debug1: read PEM private key done: type DSA
>debug1: private host key: #2 type 2 DSA
>debug1: rexec_argv[0]='/home/employees/cove/openssh-4.3p2/sshd'
>debug1: rexec_argv[1]='-p'
>debug1: rexec_argv[2]='2200'
>debug1: rexec_argv[3]='-ddddde'
>debug2: fd 3 setting O_NONBLOCK
>debug1: Bind to port 2200 on ::.
>Server listening on :: port 2200.
>debug2: fd 4 setting O_NONBLOCK
>debug1: Bind to port 2200 on 0.0.0.0.
>Bind to port 2200 on 0.0.0.0 failed: Address already in use.
>Generating 768 bit RSA key.
>RSA key generation complete.
>debug3: fd 4 is not O_NONBLOCK
>debug1: Server will not fork when running in debugging mode.
>debug3: send_rexec_state: entering fd = 7 config len 292
>debug3: ssh_msg_send: type 0
>debug3: send_rexec_state: done
>debug1: rexec start in 4 out 4 newsock 4 pipe -1 sock 7
>debug3: recv_rexec_state: entering fd = 5
>debug3: ssh_msg_recv entering
>debug3: recv_rexec_state: done
>debug2: parse_server_config: config rexec len 292
>debug1: sshd version OpenSSH_4.3p2
>debug1: private host key: #0 type 0 RSA1
>debug3: Not a RSA1 key file /usr/local/etc/ssh_host_rsa_key.
>debug1: read PEM private key done: type RSA
>debug1: private host key: #1 type 1 RSA
>debug3: Not a RSA1 key file /usr/local/etc/ssh_host_dsa_key.
>debug1: read PEM private key done: type DSA
>debug1: private host key: #2 type 2 DSA
>debug1: inetd sockets after dupping: 3, 3
>debug3: Normalising mapped IPv4 in IPv6 address
>Connection from 10.4.58.3 port 42404
>debug1: Client protocol version 2.0; client software version cryptlib
>debug1: no match: cryptlib
>debug1: Enabling compatibility mode for protocol 2.0
>debug1: Local version string SSH-1.99-OpenSSH_4.3
>debug2: fd 3 setting O_NONBLOCK
>debug1: list_hostkey_types: ssh-rsa,ssh-dss
>debug1: SSH2_MSG_KEXINIT sent
>debug1: SSH2_MSG_KEXINIT received
>debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
>debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
>debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
>debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
>debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
>debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
>debug2: kex_parse_kexinit: none,zlib@openssh.com
>debug2: kex_parse_kexinit: none,zlib@openssh.com
>debug2: kex_parse_kexinit: 
>debug2: kex_parse_kexinit: 
>debug2: kex_parse_kexinit: first_kex_follows 0 
>debug2: kex_parse_kexinit: reserved 0 
>debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha1
>debug2: kex_parse_kexinit: ssh-rsa
>debug2: kex_parse_kexinit: 3des-cbc
>debug2: kex_parse_kexinit: 3des-cbc
>debug2: kex_parse_kexinit: hmac-sha1
>debug2: kex_parse_kexinit: hmac-sha1
>debug2: kex_parse_kexinit: none
>debug2: kex_parse_kexinit: none
>debug2: kex_parse_kexinit: 
>debug2: kex_parse_kexinit: 
>debug2: kex_parse_kexinit: first_kex_follows 0 
>debug2: kex_parse_kexinit: reserved 0 
>debug2: mac_init: found hmac-sha1
>debug1: kex: client->server 3des-cbc hmac-sha1 none
>debug2: mac_init: found hmac-sha1
>debug1: kex: server->client 3des-cbc hmac-sha1 none
>debug1: SSH2_MSG_KEX_DH_GEX_REQUEST_OLD received
>debug1: SSH2_MSG_KEX_DH_GEX_GROUP sent
>debug2: dh_gen_key: priv key bits set: 163/384
>debug2: bits set: 517/1024
>debug1: expecting SSH2_MSG_KEX_DH_GEX_INIT
>debug2: bits set: 524/1024
>debug1: SSH2_MSG_KEX_DH_GEX_REPLY sent
>debug2: kex_derive_keys
>debug2: set_newkeys: mode 1
>debug1: SSH2_MSG_NEWKEYS sent
>debug1: expecting SSH2_MSG_NEWKEYS
>debug2: set_newkeys: mode 0
>debug1: SSH2_MSG_NEWKEYS received
>debug1: KEX done
>debug1: userauth-request for user cove service ssh-connection method password
>debug1: attempt 0 failures 0
>debug2: input_userauth_request: setting up authctxt for cove
>debug1: PAM: initializing for "cove"
>debug3: Normalising mapped IPv4 in IPv6 address
>debug3: Trying to reverse map address 10.4.58.3.
>debug1: PAM: setting PAM_RHOST to "xo.wildpackets.com"
>debug1: PAM: setting PAM_TTY to "ssh"
>debug2: input_userauth_request: try method password
>debug3: PAM: sshpam_passwd_conv called with 1 messages
>debug1: PAM: password authentication accepted for cove
>debug1: do_pam_account: called
>debug3: PAM: do_pam_account pam_acct_mgmt = 0 (Success)
>debug3: Normalising mapped IPv4 in IPv6 address
>Accepted password for cove from 10.4.58.3 port 42404 ssh2
>debug1: Entering interactive session for SSH2.
>debug2: fd 6 setting O_NONBLOCK
>debug2: fd 7 setting O_NONBLOCK
>debug1: server_init_dispatch_20
>debug1: server_input_channel_open: ctype session rchan 0 win 2147483647 max 16384
>debug1: input_session_request
>debug1: channel 0: new [server-session]
>debug1: session_new: init
>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
>debug1: server_input_channel_req: channel 0 request shell reply 0
>debug1: session_by_channel: session 0 channel 0
>debug1: session_input_channel_req: session 0 req shell
>debug1: PAM: establishing credentials
>debug2: fd 9 setting O_NONBLOCK
>debug3: fd 9 is O_NONBLOCK
>debug2: fd 11 setting O_NONBLOCK
>debug2: channel 0: read 30 from efd 11
>debug2: channel 0: rwin 2147483647 elen 30 euse 1
>debug2: channel 0: sent ext data 30
>debug2: channel 0: read 41 from efd 11
>debug2: channel 0: rwin 2147483617 elen 41 euse 1
>debug2: channel 0: sent ext data 41
>debug2: channel 0: read 40 from efd 11
>debug2: channel 0: rwin 2147483576 elen 40 euse 1
>debug2: channel 0: sent ext data 40
>debug2: channel 0: read 49 from efd 11
>debug2: channel 0: rwin 2147483536 elen 49 euse 1
>debug2: channel 0: sent ext data 49
>debug2: channel 0: read 13 from efd 11
>debug2: channel 0: rwin 2147483487 elen 13 euse 1
>debug2: channel 0: sent ext data 13
>debug2: channel 0: read 12 from efd 11
>debug2: channel 0: rwin 2147483474 elen 12 euse 1
>debug2: channel 0: sent ext data 12
>debug2: channel 0: read 15 from efd 11
>debug2: channel 0: rwin 2147483462 elen 15 euse 1
>debug2: channel 0: sent ext data 15
>debug2: channel 0: read 28 from efd 11
>debug2: channel 0: rwin 2147483447 elen 28 euse 1
>debug2: channel 0: sent ext data 28
>debug2: channel 0: read 52 from efd 11
>debug2: channel 0: rwin 2147483419 elen 52 euse 1
>debug2: channel 0: sent ext data 52
>debug2: channel 0: read 22 from efd 11
>debug2: channel 0: rwin 2147483367 elen 22 euse 1
>debug2: channel 0: sent ext data 22
>debug2: channel 0: read 17 from efd 11
>debug2: channel 0: rwin 2147483345 elen 17 euse 1
>debug2: channel 0: sent ext data 17
>debug2: channel 0: read 16 from efd 11
>debug2: channel 0: rwin 2147483328 elen 16 euse 1
>debug2: channel 0: sent ext data 16
>debug2: channel 0: read 34 from efd 11
>debug2: channel 0: rwin 2147483312 elen 34 euse 1
>debug2: channel 0: sent ext data 34
>debug2: channel 0: read 49 from efd 11
>debug2: channel 0: rwin 2147483278 elen 49 euse 1
>debug2: channel 0: sent ext data 49
>debug2: channel 0: read 50 from efd 11
>debug2: channel 0: rwin 2147483229 elen 50 euse 1
>debug2: channel 0: sent ext data 50
> [stopped connection on client]
>Connection closed by 10.4.58.3
>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 9/9 cfd -1)
>
>debug3: channel 0: close_fds r 9 w 9 e 11 c -1
>debug1: Received SIGCHLD.
>debug1: session_close: session 0 pid 17110
>debug1: do_cleanup
>debug1: PAM: cleanup
>debug3: PAM: sshpam_thread_cleanup entering
>Closing connection to 10.4.58.3
>debug1: PAM: cleanup
>
>

Actions: View

Attachments on bug 1131: 1071 | 1072 | 1092 | 1095 | 1100 | 1103 | 1113 | 1114 | 1115 | 1116 | 1117