openssh help

From: Billy Mahoney (billy@ozoney.com)
Date: 02/22/03

  • Next message: Leland T. Snyder: "Restricting sftp"
    Date: Fri, 21 Feb 2003 15:28:14 -0800 (PST)
    From: Billy Mahoney <billy@ozoney.com>
    To: secureshell@securityfocus.com
    
    

    seems it authenticates, now i can't login

    it was all working fine til i upgraded openssl

    here's my debug

    Feb 21 15:32:47 vteorlaa sshd[11316]: debug1: Forked child 11320.
    Feb 21 15:32:47 vteorlaa sshd[11320]: Connection from 63.170.198.34 port
    62533
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug1: Client protocol version
    2.0; client software version OpenSSH_3.5p1
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug1: match: OpenSSH_3.5p1 pat
    OpenSSH*
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug1: Enabling compatibility mode
    for protocol 2.0
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug1: Local version string
    SSH-1.99-OpenSSH_3.5p1
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug1: list_hostkey_types: ssh-rsa,ssh-dss
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug1: SSH2_MSG_KEXINIT sent
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug1: SSH2_MSG_KEXINIT received
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug2: kex_parse_kexinit: none
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug2: kex_parse_kexinit: none
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug2: kex_parse_kexinit:
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug2: kex_parse_kexinit:
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: first_kex_follows 0
    Feb 21 15:32:47 vteorlaa sshd[11320]: debug2: kex_parse_kexinit: reserved
    0
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc@lysator.liu.se
    Feb 21 15:32:47 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
    @
    Feb 21 15:32:48 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: kex_parse_kexinit: none,zlib
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: kex_parse_kexinit: none,zlib
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: kex_parse_kexinit:
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: kex_parse_kexinit:
    Feb 21 15:32:48 vteorlaa
    sshd[11320]: debug2: kex_parse_kexinit: first_kex_follows 0
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: kex_parse_kexinit: reserved
    0
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: mac_init: found hmac-md5
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: kex: client->server
    aes128-cbc hmac-md5 none
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: mac_init: found hmac-md5
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: kex: server->client
    aes128-cbc hmac-md5 none
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: SSH2_MSG_KEX_DH_GEX_REQUEST
    received
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: SSH2_MSG_KEX_DH_GEX_GROUP
    sent
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: dh_gen_key: priv key bits
    set: 134/256
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: bits set: 1617/3191
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: expecting
    SSH2_MSG_KEX_DH_GEX_INIT
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: bits set: 1560/3191
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: SSH2_MSG_KEX_DH_GEX_REPLY
    sent
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: kex_derive_keys
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: newkeys: mode 1
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: SSH2_MSG_NEWKEYS sent
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: waiting for SSH2_MSG_NEWKEYS
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: newkeys: mode 0
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: SSH2_MSG_NEWKEYS received
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: KEX done
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: userauth-request for user
    billy service ssh-connection method none
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: attempt 0 failures 0
    Feb 21 15:32:48 vteorlaa
    sshd[11320]: debug2: input_userauth_request: setting up authctxt for billy
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: Starting up PAM with
    username "billy"
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug3: Trying to reverse map
    address 63.170.198.34.
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug1: PAM setting rhost to
    "63.170.198.34"
    Feb 21 15:32:48 vteorlaa sshd[11320]: debug2: input_userauth_request: try
    method none
    Feb 21 15:32:49 vteorlaa sshd[11320]: Failed none for billy from
    63.170.198.34 port 62533 ssh2
    Feb 21 15:32:49 vteorlaa sshd[11320]: debug1: userauth-request for user
    billy service ssh-connection method keyboard-interactive
    Feb 21 15:32:49 vteorlaa sshd[11320]: debug1: attempt 1 failures 1
    Feb 21 15:32:49 vteorlaa sshd[11320]: debug2: input_userauth_request: try
    method keyboard-interactive
    Feb 21 15:32:49 vteorlaa sshd[11320]: debug1: keyboard-interactive devs
    Feb 21 15:32:49 vteorlaa sshd[11320]: debug1: auth2_challenge: user=billy
    devs=
    Feb 21 15:32:49 vteorlaa sshd[11320]: debug1: kbdint_alloc: devices ''
    Feb 21 15:32:49 vteorlaa
    sshd[11320]: debug2: auth2_challenge_start: devices
    Feb 21 15:32:49 vteorlaa sshd[11320]: Failed keyboard-interactive for
    billy from 63.170.198.34 port 62533 ssh2
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: userauth-request for user
    billy service ssh-connection method password
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: attempt 2 failures 2
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug2: input_userauth_request: try
    method password
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: PAM Password authentication
    accepted for user "billy"
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug2: pam_acct_mgmt() = 0
    Feb 21 15:32:51 vteorlaa sshd[11320]: Accepted password for billy from
    63.170.198.34 port 62533 ssh2
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: Entering interactive session
    for SSH2.
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: fd 3 setting O_NONBLOCK
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: fd 5 setting O_NONBLOCK
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: server_init_dispatch_20
    Feb 21 15:32:51 vteorlaa
    sshd[11320]: debug1: server_input_channel_open: ctype session rchan 0 win
    65536 max 16384
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: input_session_request
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: channel 0: new
    [server-session]
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: session_new: init
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: session_new: session 0
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: session_open: channel 0
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: session_open: session
    0: link with channel 0
    Feb 21 15:32:51 vteorlaa
    sshd[11320]: debug1: server_input_channel_open: confirm session
    Feb 21 15:32:51 vteorlaa
    sshd[11320]: debug1: server_input_channel_req: channel 0 request pty-req
    reply 0
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: session_by_channel: session
    0 channel 0
    Feb 21 15:32:51 vteorlaa
    sshd[11320]: debug1: session_input_channel_req: session 0 req pty-req
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: Allocating pty.
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug1: session_pty_req: session 0
    alloc /dev/pts/0
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: SSH2
    n_bytes 256
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: ospeed
    38400
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: ispeed
    38400
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 1 3
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 2 28
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 3 127
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 4 21
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 5 4
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 6 0
    Feb 21 15:32:51 vteorlaa sshd[11320]: debug3: tty_parse_modes: 7 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 8 17
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 9 19
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 10 26
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 12 18
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 13 23
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 14 22
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 18 15
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 30 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 31 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 32 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 33 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 34 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 35 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 36 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 37 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 38 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 39 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 40 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 41 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 50 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 51 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 52 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 53 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 54 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 55 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 56 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 57 0

    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 58 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 59 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 60 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 61 1
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 62 0
    Feb 21 15:32:52 vteorlaa sshd[11320]: debug3: tty_parse_modes: 70 1
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 71 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 72 1
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 73 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 74 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 75 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 90 1
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 91 1
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 92 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug3: tty_parse_modes: 93 0
    Feb 21 15:32:53 vteorlaa
    sshd[11320]: debug1: server_input_channel_req: channel 0 request x11-req
    reply 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: session_by_channel: session
    0 channel 0
    Feb 21 15:32:53 vteorlaa
    sshd[11320]: debug1: session_input_channel_req: session 0 req x11-req
    Feb 21 15:32:53 vteorlaa
    sshd[11320]: debug1: server_input_channel_req: channel 0 request shell
    reply 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: session_by_channel: session
    0 channel 0
    Feb 21 15:32:53 vteorlaa
    sshd[11320]: debug1: session_input_channel_req: session 0 req shell
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: PAM setting tty to
    "/dev/pts/0"
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: PAM establishing creds
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: fd 4 setting TCP_NODELAY
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: channel 0: rfd 7 isatty
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: fd 7 setting O_NONBLOCK
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug2: fd 6 is O_NONBLOCK
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: Received SIGCHLD.
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: session_by_pid: pid 11321
    Feb 21 15:32:53 vteorlaa
    sshd[11320]: debug1: session_exit_message: session 0 channel 0 pid 11321
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: channel request
    0: exit-status
    Feb 21 15:32:53 vteorlaa
    sshd[11320]: debug1: session_exit_message: release channel 0
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: channel 0: write failed
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: channel 0: close_write
    Feb 21 15:32:53 vteorlaa sshd[11320]: debug1: channel 0: output open ->
    closed
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: session_close: session 0 pid
    11321
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: session_pty_cleanup: session
    0 release /dev/pts/0
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: read<=0 rfd 7 len
    -1
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: read failed
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: close_read
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: input open ->
    drain
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: ibuf empty
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: send eof
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: input drain ->
    closed
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: send close
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug2: notify_done: reading
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug3: channel 0: will not send
    data after close
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: rcvd close
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug3: channel 0: will not send
    data after close
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: is dead
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel 0: garbage
    collecting
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug1: channel_free: channel
    0: server-session, nchannels 1
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug3: channel_free: status: The
    following connections are open:^M #0 server-session (t4 r0 i3/0 o3/0 fd
    -1/-1)^M
    Feb 21 15:32:54 vteorlaa sshd[11320]: debug3: channel_close_fds: channel
    0: r -1 w -1 e -1
    Feb 21 15:32:54 vteorlaa sshd[11320]: Connection closed by 63.170.198.34
    Feb 21 15:32:54 vteorlaa sshd[11320]: Closing connection to 63.170.198.34