Attachment 476 Details for Bug 717 – output of sshd -d -d -d

output of sshd -d -d -d

ssh-pam-bugsy.txt (text/plain), 14.93 KB, created by Sean on 2003-10-01 23:13:46 AEST

(hide)

Description:

Filename:

MIME Type:

Creator: Sean

Created: 2003-10-01 23:13:46 AEST

Size: 14.93 KB

patch

obsolete

>
>-------
>Server Side
>(note this crashed after I logged out of the machine from the client side.) 
> 
>bash-2.03# 
>bash-2.03# ./sshd -d -d -d
>debug3: Seeding PRNG from /usr/local/openssh/libexec/ssh-rand-helper
>debug2: read_server_config: filename /usr/local/openssh/etc/sshd_config
>debug1: sshd version OpenSSH_3.7.1p2
>debug1: private host key: #0 type 0 RSA1
>debug3: Not a RSA1 key file /usr/local/openssh/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/openssh/etc/ssh_host_dsa_key.
>debug1: read PEM private key done: type DSA
>debug1: private host key: #2 type 2 DSA
>debug1: Bind to port 24 on ::.
>Server listening on :: port 24.
>debug1: Bind to port 24 on 0.0.0.0.
>Server listening on 0.0.0.0 port 24.
>Generating 768 bit RSA key.
>RSA key generation complete.
>debug1: Server will not fork when running in debugging mode.
>Connection from 35.8.1.153 port 34895
>debug1: Client protocol version 2.0; client software version Sun_SSH_1.0
>debug1: match: Sun_SSH_1.0 pat Sun_SSH_1.0*
>debug1: Enabling compatibility mode for protocol 2.0
>debug1: Local version string SSH-1.99-OpenSSH_3.7.1p2
>debug3: privsep user:group 60003:60003
>debug1: permanently_set_uid: 60003/60003
>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-group1-sha1
>debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
>debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,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,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
>debug2: kex_parse_kexinit: none,zlib
>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-group1-sha1
>debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
>debug2: kex_parse_kexinit: aes128-cbc,blowfish-cbc,3des-cbc,rijndael128-cbc
>debug2: kex_parse_kexinit: aes128-cbc,blowfish-cbc,3des-cbc,rijndael128-cbc
>debug2: kex_parse_kexinit: hmac-sha1,hmac-md5
>debug2: kex_parse_kexinit: hmac-sha1,hmac-md5
>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 aes128-cbc hmac-sha1 none
>debug2: mac_init: found hmac-sha1
>debug1: kex: server->client aes128-cbc hmac-sha1 none
>debug2: dh_gen_key: priv key bits set: 147/320
>debug2: bits set: 498/1024
>debug1: expecting SSH2_MSG_KEXDH_INIT
>debug2: Network child is on pid 23326
>debug3: preauth child monitor started
>debug3: mm_request_receive entering
>debug2: bits set: 518/1024
>debug3: mm_key_sign entering
>debug3: mm_request_send entering: type 4
>debug3: monitor_read: checking request 4
>debug3: mm_answer_sign
>debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN
>debug3: mm_request_receive_expect entering: type 5
>debug3: mm_request_receive entering
>debug3: mm_answer_sign: signature 7bf28(143)
>debug3: mm_request_send entering: type 5
>debug2: monitor_read: 4 used once, disabling now
>debug3: mm_request_receive entering
>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 omalleys service ssh-connection method none
>debug1: attempt 0 failures 0
>debug3: mm_getpwnamallow entering
>debug3: mm_request_send entering: type 6
>debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM
>debug3: mm_request_receive_expect entering: type 7
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 6
>debug3: mm_answer_pwnamallow
>debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1
>debug3: mm_request_send entering: type 7
>debug2: monitor_read: 6 used once, disabling now
>debug3: mm_request_receive entering
>debug2: input_userauth_request: setting up authctxt for omalleys
>debug3: mm_start_pam entering
>debug3: mm_request_send entering: type 43
>debug3: mm_inform_authserv entering
>debug3: mm_request_send entering: type 3
>debug2: input_userauth_request: try method none
>debug3: mm_auth_password entering
>debug3: mm_request_send entering: type 10
>debug3: mm_auth_password: waiting for MONITOR_ANS_AUTHPASSWORD
>debug3: mm_request_receive_expect entering: type 11
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 43
>debug1: PAM: initializing for "omalleys"
>debug3: Trying to reverse map address 35.8.1.153.
>debug1: PAM: setting PAM_RHOST to "eclipse.cl.msu.edu"
>debug1: PAM: setting PAM_TTY to "ssh"
>debug2: monitor_read: 43 used once, disabling now
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 3
>debug3: mm_answer_authserv: service=ssh-connection, style=
>debug2: monitor_read: 3 used once, disabling now
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 10
>debug3: mm_answer_authpassword: sending result 0
>debug3: mm_request_send entering: type 11
>Failed none for omalleys from 35.8.1.153 port 34895 ssh2
>debug3: mm_request_receive entering
>debug3: mm_auth_password: user not authenticated
>Failed none for omalleys from 35.8.1.153 port 34895 ssh2
>debug1: userauth-request for user omalleys service ssh-connection method keyboard-interactive
>debug1: attempt 1 failures 1
>debug2: input_userauth_request: try method keyboard-interactive
>debug1: keyboard-interactive devs 
>debug1: auth2_challenge: user=omalleys devs=
>debug1: kbdint_alloc: devices 'pam'
>debug2: auth2_challenge_start: devices pam
>debug2: kbdint_next_device: devices <empty>
>debug1: auth2_challenge_start: trying authentication method 'pam'
>debug3: mm_sshpam_init_ctx
>debug3: mm_request_send entering: type 46
>debug3: mm_sshpam_init_ctx: waiting for MONITOR_ANS_PAM_INIT_CTX
>debug3: mm_request_receive_expect entering: type 47
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 46
>debug3: mm_answer_pam_init_ctx
>debug3: mm_request_send entering: type 47
>debug3: mm_sshpam_query
>debug3: mm_request_send entering: type 48
>debug3: mm_sshpam_query: waiting for MONITOR_ANS_PAM_QUERY
>debug3: mm_request_receive_expect entering: type 49
>debug3: mm_request_receive entering
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 48
>debug3: mm_answer_pam_query
>debug3: ssh_msg_recv entering
>debug3: ssh_msg_send: type 1
>debug3: ssh_msg_recv entering
>debug3: mm_request_send entering: type 49
>debug3: mm_sshpam_query: pam_query returned 0
>Postponed keyboard-interactive for omalleys from 35.8.1.153 port 34895 ssh2
>debug3: mm_request_receive entering
>debug3: mm_sshpam_respond
>debug3: mm_request_send entering: type 50
>debug3: mm_sshpam_respond: waiting for MONITOR_ANS_PAM_RESPOND
>debug3: mm_request_receive_expect entering: type 51
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 50
>debug3: mm_answer_pam_respond
>debug2: PAM: sshpam_respond
>debug3: ssh_msg_send: type 6
>debug3: mm_request_send entering: type 51
>debug3: mm_sshpam_respond: pam_respond returned 1
>debug3: mm_sshpam_query
>debug3: mm_request_send entering: type 48
>debug3: mm_sshpam_query: waiting for MONITOR_ANS_PAM_QUERY
>debug3: mm_request_receive_expect entering: type 49
>debug3: mm_request_receive entering
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 48
>debug3: mm_answer_pam_query
>debug3: ssh_msg_recv entering
>debug3: ssh_msg_send: type 0
>debug1: XXX PAM: num env strings 0
>debug1: XXX PAM: num PAM env strings 0
>debug3: mm_request_send entering: type 49
>debug3: mm_sshpam_query: pam_query returned 0
>Postponed keyboard-interactive/pam for omalleys from 35.8.1.153 port 34895 ssh2
>debug3: mm_request_receive entering
>debug3: mm_sshpam_respond
>debug3: mm_request_send entering: type 50
>debug3: mm_sshpam_respond: waiting for MONITOR_ANS_PAM_RESPOND
>debug3: mm_request_receive_expect entering: type 51
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 50
>debug3: mm_answer_pam_respond
>debug2: PAM: sshpam_respond
>debug3: mm_request_send entering: type 51
>debug3: mm_sshpam_respond: pam_respond returned 0
>debug3: mm_sshpam_free_ctx
>debug3: mm_request_send entering: type 52
>debug3: mm_sshpam_free_ctx: waiting for MONITOR_ANS_PAM_FREE_CTX
>debug3: mm_request_receive_expect entering: type 53
>debug3: mm_request_receive entering
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 52
>debug3: mm_answer_pam_free_ctx
>debug3: mm_request_send entering: type 53
>debug3: mm_do_pam_account entering
>debug3: mm_request_send entering: type 44
>debug3: mm_request_receive_expect entering: type 45
>debug3: mm_request_receive entering
>debug2: monitor_read: 52 used once, disabling now
>debug3: mm_request_receive_expect entering: type 44
>debug3: mm_request_receive entering
>debug3: do_pam_account: pam_acct_mgmt = 0
>debug3: mm_request_send entering: type 45
>debug3: mm_do_pam_account returning 1
>Accepted keyboard-interactive/pam for omalleys from 35.8.1.153 port 34895 ssh2
>debug3: mm_send_keystate: Sending new keys: 78c88 82090
>debug3: mm_newkeys_to_blob: converting 78c88
>debug3: mm_newkeys_to_blob: converting 82090
>debug3: mm_send_keystate: New keys have been sent
>debug3: mm_send_keystate: Sending compression state
>debug3: mm_request_send entering: type 24
>debug3: mm_send_keystate: Finished sending state
>Accepted keyboard-interactive/pam for omalleys from 35.8.1.153 port 34895 ssh2
>debug1: monitor_child_preauth: omalleys has been authenticated by privileged process
>debug3: mm_get_keystate: Waiting for new keys
>debug3: mm_request_receive_expect entering: type 24
>debug3: mm_request_receive entering
>debug3: mm_newkeys_from_blob: 79b68(123)
>debug2: mac_init: found hmac-sha1
>debug3: mm_get_keystate: Waiting for second key
>debug3: mm_newkeys_from_blob: 79b68(123)
>debug2: mac_init: found hmac-sha1
>debug3: mm_get_keystate: Getting compression state
>debug3: mm_get_keystate: Getting Network I/O buffers
>debug3: mm_share_sync: Share sync
>debug3: mm_share_sync: Share sync end
>debug1: PAM: reinitializing credentials
>debug1: permanently_set_uid: 332841/1
>debug2: set_newkeys: mode 0
>debug2: set_newkeys: mode 1
>debug1: Entering interactive session for SSH2.
>debug2: fd 4 setting O_NONBLOCK
>debug2: fd 5 setting O_NONBLOCK
>debug1: server_init_dispatch_20
>debug1: server_input_channel_open: ctype session rchan 0 win 32768 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
>debug2: User child is on pid 23329
>debug3: mm_request_receive entering
>debug1: server_input_channel_req: channel 0 request pty-req reply 0
>debug1: session_by_channel: session 0 channel 0
>debug1: session_input_channel_req: session 0 req pty-req
>debug1: Allocating pty.
>debug3: mm_request_send entering: type 25
>debug3: mm_pty_allocate: waiting for MONITOR_ANS_PTY
>debug3: mm_request_receive_expect entering: type 26
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 25
>debug3: mm_answer_pty entering
>debug1: session_new: init
>debug1: session_new: session 0
>debug3: mm_request_send entering: type 26
>debug1: session_pty_req: session 0 alloc /dev/pts/4
>debug3: tty_parse_modes: SSH2 n_bytes 0
>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: setting PAM_TTY to "/dev/pts/4"
>debug1: PAM: establishing credentials
>debug2: fd 6 setting TCP_NODELAY
>debug2: fd 11 setting O_NONBLOCK
>debug2: fd 10 is O_NONBLOCK
>debug3: mm_answer_pty: tty /dev/pts/4 ptyfd 4
>debug3: mm_request_receive entering
>debug1: Received SIGCHLD.
>debug1: session_by_pid: pid 23331
>debug1: session_exit_message: session 0 channel 0 pid 23331
>debug2: channel 0: request exit-status
>debug1: session_exit_message: release channel 0
>debug2: channel 0: write failed
>debug2: channel 0: close_write
>debug2: channel 0: output open -> closed
>debug1: session_close: session 0 pid 23331
>debug3: mm_request_send entering: type 27
>debug2: notify_done: reading
>debug2: channel 0: read<=0 rfd 11 len 0
>debug2: channel 0: read failed
>debug2: channel 0: close_read
>debug2: channel 0: input open -> drain
>debug2: channel 0: ibuf empty
>debug2: channel 0: send eof
>debug2: channel 0: input drain -> closed
>debug2: channel 0: send close
>debug3: channel 0: will not send data after close
>debug3: monitor_read: checking request 27
>debug3: mm_answer_pty_cleanup entering
>debug1: session_by_tty: session 0 tty /dev/pts/4
>debug2: channel 0: rcvd close
>debug3: channel 0: will not send data after close
>debug2: channel 0: is dead
>debug2: channel 0: garbage collecting
>debug1: channel 0: free: server-session, nchannels 1
>debug3: channel 0: status: The following connections are open:
>  #0 server-session (t4 r0 i3/0 o3/0 fd -1/-1)
>
>debug3: channel 0: close_fds r -1 w -1 e -1
>Connection closed by 35.8.1.153
>Closing connection to 35.8.1.153
>debug1: PAM: cleanup
>debug3: mm_request_send entering: type 54
>debug3: mm_session_close: session 0 pid 23329
>debug3: mm_session_close: tty /dev/pts/4 ptyfd 4
>debug1: session_pty_cleanup: session 0 release /dev/pts/4
>debug3: mm_request_receive entering
>debug3: monitor_read: checking request 54
>debug3: mm_answer_term: tearing down sessions
>bash-2.03# 
>------------
>Client side
>
>picasso@eclipse vice/bin]$ssh -lomalleys -p24 gis
>@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
>@    WARNING: REMOTE HOST IDENTIFICATION HAS CHANGED!     @
>@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
>Someone could be eavesdropping on you right now(man-in-the-middle attack)
>It is also possible that the RSA host key has been changed.
>The fingerprint for the RSA key sent by the remote host is
>md5 c3:ca:ce:62:e0:32:5c:25:14:6f:53:bc:11:c6:ce:30.
>Please contact your system administrator.
>Add correct host key in /home/picasso/.ssh/known_hosts
>Offending key is entry 192 in /home/picasso/.ssh/known_hosts
>Password authentication is disabled to avoid trojan horses.
>X11 forwarding is disabled to avoid trojan horses.
>Password: 
>Last login: Wed Oct  1 00:10:03 2003 from eclipse.cl.msu.
>Environment:
>  USER=omalleys
>  LOGNAME=omalleys
>  HOME=/afs/msu/user/o/m/omalleys
>  PATH=/usr/bin:/bin:/usr/sbin:/sbin:/usr/local/openssh/bin
>  MAIL=/var/mail//omalleys
>  SHELL=/bin/tcsh
>  TZ=US/Michigan
>  SSH_CLIENT=35.8.1.153 34895 24
>  SSH_CONNECTION=35.8.1.153 34895 35.8.2.236 24
>  SSH_TTY=/dev/pts/4
>  TERM=vt100
>debug3: channel 0: close_fds r -1 w -1 e -1
>Sun Microsystems Inc.   SunOS 5.8       Generic February 2000
>> exit
>logout
>Connection to gis closed.
>
>
>

Actions: View

Attachments on bug 717: 472 | 476