Oct  5 16:25:07 nat5 sshd[7104]: debug1: Enabling compatibility mode for protocol 2.0
Oct  5 16:25:07 nat5 sshd[7104]: debug1: Local version string SSH-2.0-OpenSSH_3.8.1p1 Debian-8.sarge.4
Oct  5 16:25:08 nat5 sshd[7104]: debug2: Network child is on pid 7105
Oct  5 16:25:08 nat5 sshd[7104]: debug3: preauth child monitor started
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 0
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_moduli: got parameters: 1024 1024 8192
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_send entering: type 1
Oct  5 16:25:08 nat5 sshd[7104]: debug2: monitor_read: 0 used once, disabling now
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 4
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_sign
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_sign: signature 0x809dbb0(143)
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_send entering: type 5
Oct  5 16:25:08 nat5 sshd[7104]: debug2: monitor_read: 4 used once, disabling now
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 6
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_pwnamallow
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_send entering: type 7
Oct  5 16:25:08 nat5 sshd[7104]: debug2: monitor_read: 6 used once, disabling now
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 45
Oct  5 16:25:08 nat5 sshd[7104]: debug1: PAM: initializing for "tclements"
Oct  5 16:25:08 nat5 sshd[7104]: debug3: Trying to reverse map address 66.115.179.14.
Oct  5 16:25:08 nat5 sshd[7104]: debug1: PAM: setting PAM_RHOST to "maxiez.natnet.com"
Oct  5 16:25:08 nat5 sshd[7104]: debug1: PAM: setting PAM_TTY to "ssh"
Oct  5 16:25:08 nat5 sshd[7104]: debug2: monitor_read: 45 used once, disabling now
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 3
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_authserv: service=ssh-connection, style=
Oct  5 16:25:08 nat5 sshd[7104]: debug2: monitor_read: 3 used once, disabling now
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 10
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_authpassword: sending result 0
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_send entering: type 11
Oct  5 16:25:08 nat5 sshd[7104]: Failed none for tclements from 66.115.179.14 port 34867 ssh2
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: monitor_read: checking request 20
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_keyallowed entering
Oct  5 16:25:08 nat5 sshd[7104]: debug3: mm_answer_keyallowed: key_from_blob: 0x80a2160
Oct  5 16:25:08 nat5 sshd[7104]: debug1: temporarily_use_uid: 1006/102 (e=0/0)
Oct  5 16:25:08 nat5 sshd[7104]: debug1: trying public key file /web/sites/tclements/.ssh/authorized_keys
Oct  5 16:25:08 nat5 sshd[7104]: debug1: restore_uid: 0/0
Oct  5 16:25:08 nat5 sshd[7104]: debug1: temporarily_use_uid: 1006/102 (e=0/0)
Oct  5 16:25:08 nat5 sshd[7104]: debug1: trying public key file /web/sites/tclements/.ssh/authorized_keys2
Oct  5 16:25:09 nat5 sshd[7104]: debug3: secure_filename: checking '/web/sites/tclements/.ssh'
Oct  5 16:25:09 nat5 sshd[7104]: debug3: secure_filename: checking '/web/sites/tclements'
Oct  5 16:25:09 nat5 sshd[7104]: debug3: secure_filename: terminating check at '/web/sites/tclements'
Oct  5 16:25:09 nat5 sshd[7104]: debug1: matching key found: file /web/sites/tclements/.ssh/authorized_keys2, line 1
Oct  5 16:25:09 nat5 sshd[7104]: Found matching DSA key: cc:64:f7:57:dc:a7:5f:cb:40:40:1e:37:19:8c:57:c7
Oct  5 16:25:09 nat5 sshd[7104]: debug1: restore_uid: 0/0
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_answer_keyallowed: key 0x80a2160 is allowed
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_send entering: type 21
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:09 nat5 sshd[7104]: debug3: monitor_read: checking request 20
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_answer_keyallowed entering
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_answer_keyallowed: key_from_blob: 0x80a22b8
Oct  5 16:25:09 nat5 sshd[7104]: debug1: temporarily_use_uid: 1006/102 (e=0/0)
Oct  5 16:25:09 nat5 sshd[7104]: debug1: trying public key file /web/sites/tclements/.ssh/authorized_keys
Oct  5 16:25:09 nat5 sshd[7104]: debug1: restore_uid: 0/0
Oct  5 16:25:09 nat5 sshd[7104]: debug1: temporarily_use_uid: 1006/102 (e=0/0)
Oct  5 16:25:09 nat5 sshd[7104]: debug1: trying public key file /web/sites/tclements/.ssh/authorized_keys2
Oct  5 16:25:09 nat5 sshd[7104]: debug3: secure_filename: checking '/web/sites/tclements/.ssh'
Oct  5 16:25:09 nat5 sshd[7104]: debug3: secure_filename: checking '/web/sites/tclements'
Oct  5 16:25:09 nat5 sshd[7104]: debug3: secure_filename: terminating check at '/web/sites/tclements'
Oct  5 16:25:09 nat5 sshd[7104]: debug1: matching key found: file /web/sites/tclements/.ssh/authorized_keys2, line 1
Oct  5 16:25:09 nat5 sshd[7104]: Found matching DSA key: cc:64:f7:57:dc:a7:5f:cb:40:40:1e:37:19:8c:57:c7
Oct  5 16:25:09 nat5 sshd[7104]: debug1: restore_uid: 0/0
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_answer_keyallowed: key 0x80a22b8 is allowed
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_send entering: type 21
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:09 nat5 sshd[7104]: debug3: monitor_read: checking request 22
Oct  5 16:25:09 nat5 sshd[7104]: debug1: ssh_dss_verify: signature correct
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_answer_keyverify: key 0x80a22e8 signature verified
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_send entering: type 23
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_receive_expect entering: type 46
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:09 nat5 sshd[7104]: debug3: PAM: do_pam_account pam_acct_mgmt = 0
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_send entering: type 47
Oct  5 16:25:09 nat5 sshd[7104]: Accepted publickey for tclements from 66.115.179.14 port 34867 ssh2
Oct  5 16:25:09 nat5 sshd[7104]: debug1: monitor_child_preauth: tclements has been authenticated by privileged process
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_get_keystate: Waiting for new keys
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_receive_expect entering: type 24
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_newkeys_from_blob: 0x80a0f80(118)
Oct  5 16:25:09 nat5 sshd[7104]: debug2: mac_init: found hmac-md5
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_get_keystate: Waiting for second key
Oct  5 16:25:09 nat5 sshd[7104]: debug3: mm_newkeys_from_blob: 0x80a0f80(118)
Oct  5 16:25:09 nat5 sshd[7104]: debug2: mac_init: found hmac-md5
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_get_keystate: Getting compression state
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_get_keystate: Getting Network I/O buffers
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_share_sync: Share sync
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_share_sync: Share sync end
Oct  5 16:25:10 nat5 sshd[7104]: debug2: User child is on pid 7106
Oct  5 16:25:10 nat5 sshd[7106]: debug3: PAM: opening session
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:10 nat5 sshd[7106]: (pam_unix) session opened for user tclements by (uid=0)
Oct  5 16:25:10 nat5 sshd[7106]: debug3: PAM: pam_store_conv called with 1 messages
Oct  5 16:25:10 nat5 sshd[7106]: debug3: PAM: pam_store_conv called with 1 messages
Oct  5 16:25:10 nat5 sshd[7106]: debug1: PAM: reinitializing credentials
Oct  5 16:25:10 nat5 sshd[7106]: debug1: permanently_set_uid: 1006/102
Oct  5 16:25:10 nat5 sshd[7106]: debug2: set_newkeys: mode 0
Oct  5 16:25:10 nat5 sshd[7106]: debug2: set_newkeys: mode 1
Oct  5 16:25:10 nat5 sshd[7106]: debug1: Entering interactive session for SSH2.
Oct  5 16:25:10 nat5 sshd[7106]: debug2: fd 5 setting O_NONBLOCK
Oct  5 16:25:10 nat5 sshd[7106]: debug2: fd 6 setting O_NONBLOCK
Oct  5 16:25:10 nat5 sshd[7106]: debug1: server_init_dispatch_20
Oct  5 16:25:10 nat5 sshd[7106]: debug1: server_input_channel_open: ctype session rchan 0 win 65536 max 16384
Oct  5 16:25:10 nat5 sshd[7106]: debug1: input_session_request
Oct  5 16:25:10 nat5 sshd[7106]: debug1: channel 0: new [server-session]
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_new: init
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_new: session 0
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_open: channel 0
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_open: session 0: link with channel 0
Oct  5 16:25:10 nat5 sshd[7106]: debug1: server_input_channel_open: confirm session
Oct  5 16:25:10 nat5 sshd[7106]: debug1: server_input_channel_req: channel 0 request pty-req reply 0
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_by_channel: session 0 channel 0
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_input_channel_req: session 0 req pty-req
Oct  5 16:25:10 nat5 sshd[7106]: debug1: Allocating pty.
Oct  5 16:25:10 nat5 sshd[7106]: debug3: mm_request_send entering: type 25
Oct  5 16:25:10 nat5 sshd[7106]: debug3: mm_pty_allocate: waiting for MONITOR_ANS_PT
Y
Oct  5 16:25:10 nat5 sshd[7104]: debug3: monitor_read: checking request 25
Oct  5 16:25:10 nat5 sshd[7106]: debug3: mm_request_receive_expect entering: type 26
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_answer_pty entering
Oct  5 16:25:10 nat5 sshd[7106]: debug3: mm_request_receive entering
Oct  5 16:25:10 nat5 sshd[7104]: debug1: session_new: init
Oct  5 16:25:10 nat5 sshd[7104]: debug1: session_new: session 0
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_request_send entering: type 26
Oct  5 16:25:10 nat5 sshd[7106]: debug1: session_pty_req: session 0 alloc /dev/pts/20
Oct  5 16:25:10 nat5 sshd[7104]: debug3: mm_answer_pty: tty /dev/pts/20 ptyfd 3
Oct  5 16:25:10 nat5 sshd[7106]: debug3: tty_parse_modes: SSH2 n_bytes 256
Oct  5 16:25:11 nat5 sshd[7104]: debug3: mm_request_receive entering
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: ospeed 38400
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: ispeed 38400
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 1 3
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 2 28
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 3 127
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 4 21
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 5 4
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 6 255
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 7 255
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 8 17
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 9 19
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 10 26
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 12 18
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 13 23
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 14 22
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 18 15
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 30 1
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 31 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 32 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 33 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 34 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 35 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 36 1
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 37 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 38 1
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 39 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 40 0
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 41 1
Oct  5 16:25:11 nat5 sshd[7106]: debug3: tty_parse_modes: 50 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 51 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 52 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 53 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 54 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 55 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 56 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 57 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 58 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 59 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 60 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 61 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 62 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 70 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 71 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 72 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 73 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 74 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 75 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 90 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 91 1
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 92 0
Oct  5 16:25:12 nat5 sshd[7106]: debug3: tty_parse_modes: 93 0
Oct  5 16:25:12 nat5 sshd[7106]: debug1: server_input_channel_req: channel 0 request shell reply 0
Oct  5 16:25:12 nat5 sshd[7106]: debug1: session_by_channel: session 0 channel 0
Oct  5 16:25:12 nat5 sshd[7106]: debug1: session_input_channel_req: session 0 req shell
Oct  5 16:25:12 nat5 sshd[7106]: debug1: PAM: setting PAM_TTY to "/dev/pts/20"
Oct  5 16:25:12 nat5 sshd[7107]: debug1: Setting controlling tty using TIOCSCTTY.
Oct  5 16:25:12 nat5 sshd[7106]: debug2: fd 4 setting TCP_NODELAY
Oct  5 16:25:12 nat5 sshd[7107]: debug3: channel 0: close_fds r -1 w -1 e -1
Oct  5 16:25:12 nat5 sshd[7106]: debug2: channel 0: rfd 8 isatty
Oct  5 16:25:12 nat5 sshd[7106]: debug2: fd 8 setting O_NONBLOCK
Oct  5 16:25:12 nat5 sshd[7106]: debug2: fd 7 is O_NONBLOCK