Ticket #2598: sshd_debug_log

File sshd_debug_log, 17.9 KB (added by anevilyak, 16 years ago)

Log from entire sshd session.

Line 
1~/config/etc> /boot/home/config/bin/sshd -ddd
2debug2: load_server_config: filename /boot/home/config/etc/sshd_config
3debug2: load_server_config: done config len = 176
4debug2: parse_server_config: config /boot/home/config/etc/sshd_config len 176
5debug3: /boot/home/config/etc/sshd_config:21 setting Protocol 2
6debug3: /boot/home/config/etc/sshd_config:111 setting Subsystem sftp /boot/home/config/bin/sftp-server
7debug1: sshd version OpenSSH_5.0p1
8debug3: Not a RSA1 key file /boot/home/config/etc/ssh_host_rsa_key.
9debug1: read PEM private key done: type RSA
10debug1: private host key: #0 type 1 RSA
11debug3: Not a RSA1 key file /boot/home/config/etc/ssh_host_dsa_key.
12debug1: read PEM private key done: type DSA
13debug1: private host key: #1 type 2 DSA
14debug1: rexec_argv[0]='/boot/home/config/bin/sshd'
15debug1: rexec_argv[1]='-ddd'
16debug2: fd 3 setting O_NONBLOCK
17debug1: Bind to port 22 on 0.0.0.0.
18Server listening on 0.0.0.0 port 22.
19
20debug1: fd 4 clearing O_NONBLOCK
21debug1: Server will not fork when running in debugging mode.
22debug3: send_rexec_state: entering fd = 7 config len 176
23debug3: ssh_msg_send: type 0
24debug3: send_rexec_state: done
25debug1: rexec start in 4 out 4 newsock 4 pipe -1 sock 7
26debug1: inetd sockets after dupping: 3, 3
27Connection from 192.168.1.126 port 56647
28debug1: Client protocol version 2.0; client software version OpenSSH_4.5p1 FreeBSD-20061110
29debug1: match: OpenSSH_4.5p1 FreeBSD-20061110 pat OpenSSH*
30debug1: Enabling compatibility mode for protocol 2.0
31debug1: Local version string SSH-2.0-OpenSSH_5.0
32debug2: fd 3 setting O_NONBLOCK
33debug2: Network child is on pid 230
34debug3: privsep user:group 1001:100
35debug1: permanently_set_uid: 1001/100
36debug3: preauth child monitor started
37debug1: list_hostkey_types: ssh-rsa,ssh-dss
38debug3: mm_request_receive entering
39debug1: SSH2_MSG_KEXINIT sent
40debug1: SSH2_MSG_KEXINIT received
41debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
42debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
43debug2: 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
44debug2: 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
45debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
46debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,umac-64@openssh.com,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
47debug2: kex_parse_kexinit: none,zlib@openssh.com
48debug2: kex_parse_kexinit: none,zlib@openssh.com
49debug2: kex_parse_kexinit:
50debug2: kex_parse_kexinit:
51debug2: kex_parse_kexinit: first_kex_follows 0
52debug2: kex_parse_kexinit: reserved 0
53debug2: kex_parse_kexinit: diffie-hellman-group-exchange-sha256,diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
54debug2: kex_parse_kexinit: ssh-dss,ssh-rsa
55debug2: 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
56debug2: 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
57debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
58debug2: kex_parse_kexinit: hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160@openssh.com,hmac-sha1-96,hmac-md5-96
59debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib
60debug2: kex_parse_kexinit: none,zlib@openssh.com,zlib
61debug2: kex_parse_kexinit:
62debug2: kex_parse_kexinit:
63debug2: kex_parse_kexinit: first_kex_follows 0
64debug2: kex_parse_kexinit: reserved 0
65debug2: mac_setup: found hmac-md5
66debug1: kex: client->server aes128-cbc hmac-md5 none
67debug2: mac_setup: found hmac-md5
68debug1: kex: server->client aes128-cbc hmac-md5 none
69debug1: SSH2_MSG_KEX_DH_GEX_REQUEST received
70debug3: mm_request_send entering: type 0
71debug3: mm_choose_dh: waiting for MONITOR_ANS_MODULI
72debug3: mm_request_receive_expect entering: type 1
73debug3: mm_request_receive entering
74debug3: monitor_read: checking request 0
75debug3: mm_answer_moduli: got parameters: 1024 1024 8192
76debug3: mm_request_send entering: type 1
77debug2: monitor_read: 0 used once, disabling now
78debug3: mm_request_receive entering
79debug3: mm_choose_dh: remaining 0
80debug1: SSH2_MSG_KEX_DH_GEX_GROUP sent
81debug2: dh_gen_key: priv key bits set: 129/256
82debug2: bits set: 507/1024
83debug1: expecting SSH2_MSG_KEX_DH_GEX_INIT
84debug2: bits set: 531/1024
85debug3: mm_key_sign entering
86debug3: mm_request_send entering: type 4
87debug3: mm_key_sign: waiting for MONITOR_ANS_SIGN
88debug3: mm_request_receive_expect entering: type 5
89debug3: mm_request_receive entering
90debug3: monitor_read: checking request 4
91debug3: mm_answer_sign
92debug3: mm_answer_sign: signature 0x18026320(55)
93debug3: mm_request_send entering: type 5
94debug2: monitor_read: 4 used once, disabling now
95debug3: mm_request_receive entering
96debug1: SSH2_MSG_KEX_DH_GEX_REPLY sent
97debug2: kex_derive_keys
98debug2: set_newkeys: mode 1
99debug1: SSH2_MSG_NEWKEYS sent
100debug1: expecting SSH2_MSG_NEWKEYS
101debug2: set_newkeys: mode 0
102debug1: SSH2_MSG_NEWKEYS received
103debug1: KEX done
104debug1: userauth-request for user rene service ssh-connection method none
105debug1: attempt 0 failures 0
106debug3: mm_getpwnamallow entering
107debug3: mm_request_send entering: type 6
108debug3: mm_getpwnamallow: waiting for MONITOR_ANS_PWNAM
109debug3: monitor_read: checking request 6
110debug3: mm_answer_pwnamallow
111debug3: Trying to reverse map address 192.168.1.126.
112debug3: mm_request_receive_expect entering: type 7
113debug3: mm_request_receive entering
114debug2: parse_server_config: config reprocess config len 176
115debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1
116debug3: mm_request_send entering: type 7
117debug2: monitor_read: 6 used once, disabling now
118debug3: mm_request_receive entering
119debug2: input_userauth_request: setting up authctxt for rene
120debug3: mm_inform_authserv entering
121debug3: mm_request_send entering: type 3
122debug2: input_userauth_request: try method none
123debug3: mm_auth_password entering
124debug3: mm_request_send entering: type 10
125debug3: mm_auth_password: waiting for MONITOR_ANS_AUTHPASSWORD
126debug3: mm_request_receive_expect entering: type 11
127debug3: mm_request_receive entering
128debug3: monitor_read: checking request 3
129debug3: mm_answer_authserv: service=ssh-connection, style=
130debug2: monitor_read: 3 used once, disabling now
131debug3: mm_request_receive entering
132debug3: monitor_read: checking request 10
133debug3: mm_answer_authpassword: sending result 0
134debug3: mm_request_send entering: type 11
135Failed none for rene from 192.168.1.126 port 56647 ssh2
136debug3: mm_request_receive entering
137debug3: mm_auth_password: user not authenticated
138debug1: userauth-request for user rene service ssh-connection method publickey
139debug1: attempt 1 failures 1
140debug2: input_userauth_request: try method publickey
141debug1: test whether pkalg/pkblob are acceptable
142debug3: mm_key_allowed entering
143debug3: mm_request_send entering: type 20
144debug3: mm_key_allowed: waiting for MONITOR_ANS_KEYALLOWED
145debug3: mm_request_receive_expect entering: type 21
146debug3: mm_request_receive entering
147debug3: monitor_read: checking request 20
148debug3: mm_answer_keyallowed entering
149debug3: mm_answer_keyallowed: key_from_blob: 0x1801bce0
150debug1: temporarily_use_uid: 0/0 (e=0/0)
151debug1: trying public key file /boot/home/.ssh/authorized_keys
152debug1: restore_uid: 0/0
153debug1: temporarily_use_uid: 0/0 (e=0/0)
154debug1: trying public key file /boot/home/.ssh/authorized_keys2
155debug1: restore_uid: 0/0
156Failed publickey for rene from 192.168.1.126 port 56647 ssh2
157debug3: mm_answer_keyallowed: key 0x1801bce0 is disallowed
158debug3: mm_request_send entering: type 21
159debug3: mm_request_receive entering
160debug2: userauth_pubkey: authenticated 0 pkalg ssh-rsa
161debug1: userauth-request for user rene service ssh-connection method keyboard-interactive
162debug1: attempt 2 failures 2
163debug2: input_userauth_request: try method keyboard-interactive
164debug1: keyboard-interactive devs
165debug1: auth2_challenge: user=rene devs=
166debug1: kbdint_alloc: devices ''
167debug2: auth2_challenge_start: devices
168debug1: userauth-request for user rene service ssh-connection method password
169debug1: attempt 3 failures 3
170debug2: input_userauth_request: try method password
171debug3: mm_auth_password entering
172debug3: mm_request_send entering: type 10
173debug3: mm_auth_password: waiting for MONITOR_ANS_AUTHPASSWORD
174debug3: mm_request_receive_expect entering: type 11
175debug3: mm_request_receive entering
176debug3: monitor_read: checking request 10
177debug3: mm_answer_authpassword: sending result 1
178debug3: mm_request_send entering: type 11
179Accepted password for rene from 192.168.1.126 port 56647 ssh2
180debug1: monitor_child_preauth: rene has been authenticated by privileged process
181debug3: mm_get_keystate: Waiting for new keys
182debug3: mm_request_receive_expect entering: type 24
183debug3: mm_request_receive entering
184debug3: mm_auth_password: user authenticated
185debug3: mm_send_keystate: Sending new keys: 0x1808d2c0 0x1808d3e8
186debug3: mm_newkeys_to_blob: converting 0x1808d2c0
187debug3: mm_newkeys_to_blob: converting 0x1808d3e8
188debug3: mm_send_keystate: New keys have been sent
189debug3: mm_send_keystate: Sending compression state
190debug3: mm_request_send entering: type 24
191debug3: mm_send_keystate: Finished sending state
192debug3: mm_newkeys_from_blob: 0x1802a2a0(118)
193debug2: mac_setup: found hmac-md5
194debug3: mm_get_keystate: Waiting for second key
195debug3: mm_newkeys_from_blob: 0x1802a2a0(118)
196debug2: mac_setup: found hmac-md5
197debug3: mm_get_keystate: Getting compression state
198debug3: mm_get_keystate: Getting Network I/O buffers
199debug3: mm_share_sync: Share sync
200debug3: mm_share_sync: Share sync end
201debug2: set_newkeys: mode 0
202debug2: set_newkeys: mode 1
203debug1: Entering interactive session for SSH2.
204debug2: fd 4 setting O_NONBLOCK
205debug2: fd 5 setting O_NONBLOCK
206debug1: server_init_dispatch_20
207debug1: server_input_channel_open: ctype session rchan 0 win 65536 max 16384
208debug1: input_session_request
209debug1: channel 0: new [server-session]
210debug1: session_new: init
211debug1: session_new: session 0
212debug1: session_open: channel 0
213debug1: session_open: session 0: link with channel 0
214debug1: server_input_channel_open: confirm session
215debug1: server_input_channel_req: channel 0 request pty-req reply 0
216debug1: session_by_channel: session 0 channel 0
217debug1: session_input_channel_req: session 0 req pty-req
218debug1: Allocating pty.
219debug1: session_pty_req: session 0 alloc /dev/tt/p1
220debug3: tty_parse_modes: SSH2 n_bytes 251
221debug3: tty_parse_modes: ospeed 38400
222debug3: tty_parse_modes: ispeed 38400
223debug3: tty_parse_modes: 1 3
224debug3: tty_parse_modes: 2 28
225debug3: tty_parse_modes: 3 127
226debug3: tty_parse_modes: 4 21
227debug3: tty_parse_modes: 5 4
228debug3: tty_parse_modes: 6 255
229debug3: tty_parse_modes: 7 255
230debug3: tty_parse_modes: 8 17
231debug3: tty_parse_modes: 9 19
232debug3: tty_parse_modes: 10 26
233debug1: Ignoring unsupported tty mode opcode 11 (0xb)
234debug1: Ignoring unsupported tty mode opcode 12 (0xc)
235debug1: Ignoring unsupported tty mode opcode 13 (0xd)
236debug1: Ignoring unsupported tty mode opcode 14 (0xe)
237debug1: Ignoring unsupported tty mode opcode 17 (0x11)
238debug1: Ignoring unsupported tty mode opcode 18 (0x12)
239debug3: tty_parse_modes: 30 0
240debug3: tty_parse_modes: 31 0
241debug3: tty_parse_modes: 32 0
242debug3: tty_parse_modes: 33 0
243debug3: tty_parse_modes: 34 0
244debug3: tty_parse_modes: 35 0
245debug3: tty_parse_modes: 36 1
246debug3: tty_parse_modes: 38 1
247debug3: tty_parse_modes: 39 1
248debug3: tty_parse_modes: 40 0
249debug1: Ignoring unsupported tty mode opcode 41 (0x29)
250debug3: tty_parse_modes: 50 1
251debug3: tty_parse_modes: 51 1
252debug3: tty_parse_modes: 53 1
253debug3: tty_parse_modes: 54 1
254debug3: tty_parse_modes: 55 0
255debug3: tty_parse_modes: 56 0
256debug3: tty_parse_modes: 57 0
257debug3: tty_parse_modes: 58 0
258debug3: tty_parse_modes: 59 1
259debug1: Ignoring unsupported tty mode opcode 60 (0x3c)
260debug1: Ignoring unsupported tty mode opcode 61 (0x3d)
261debug1: Ignoring unsupported tty mode opcode 62 (0x3e)
262debug3: tty_parse_modes: 70 1
263debug3: tty_parse_modes: 72 1
264debug3: tty_parse_modes: 73 0
265debug3: tty_parse_modes: 74 0
266debug3: tty_parse_modes: 75 0
267debug3: tty_parse_modes: 90 1
268debug3: tty_parse_modes: 91 1
269debug3: tty_parse_modes: 92 0
270debug3: tty_parse_modes: 93 0
271debug1: server_input_channel_req: channel 0 request shell reply 0
272debug1: session_by_channel: session 0 channel 0
273debug1: session_input_channel_req: session 0 req shell
274debug2: fd 3 setting TCP_NODELAY
275debug2: channel 0: rfd 7 isatty
276debug2: fd 7 setting O_NONBLOCK
277debug3: fd 6 is O_NONBLOCK
278debug2: channel 0: rcvd adjust 32775
279debug2: channel 0: rcvd adjust 32773
280debug2: channel 0: rcvd adjust 32845
281debug2: channel 0: rcvd adjust 32796
282debug2: channel 0: rcvd adjust 32934
283debug2: channel 0: rcvd adjust 32850
284debug2: channel 0: rcvd adjust 32777
285debug2: channel 0: rcvd adjust 32815
286debug2: channel 0: rcvd adjust 32810
287debug2: channel 0: rcvd adjust 32772
288debug2: channel 0: rcvd adjust 32769
289debug2: channel 0: rcvd adjust 32772
290debug2: channel 0: rcvd adjust 32789
291debug2: channel 0: rcvd adjust 32801
292debug2: channel 0: rcvd adjust 32816
293debug2: channel 0: rcvd adjust 32783
294debug2: channel 0: rcvd adjust 32834
295debug2: channel 0: rcvd adjust 32778
296debug2: channel 0: rcvd adjust 32771
297debug2: channel 0: rcvd adjust 32785
298debug2: channel 0: rcvd adjust 32806
299debug2: channel 0: rcvd adjust 32826
300debug2: channel 0: rcvd adjust 32813
301debug2: channel 0: rcvd adjust 32786
302debug2: channel 0: rcvd adjust 32787
303debug2: channel 0: rcvd adjust 32802
304debug2: channel 0: rcvd adjust 32788
305debug2: channel 0: rcvd adjust 32793
306debug2: channel 0: rcvd adjust 32808
307debug2: channel 0: rcvd adjust 32777
308debug2: channel 0: rcvd adjust 32774
309debug2: channel 0: rcvd adjust 32795
310debug2: channel 0: rcvd adjust 32813
311debug2: channel 0: rcvd adjust 32819
312debug2: channel 0: rcvd adjust 32812
313debug2: channel 0: rcvd adjust 32815
314debug2: channel 0: rcvd adjust 32803
315debug2: channel 0: rcvd adjust 32805
316debug2: channel 0: rcvd adjust 32805
317debug2: channel 0: rcvd adjust 32798
318debug2: channel 0: rcvd adjust 32787
319debug2: channel 0: rcvd adjust 32775
320debug2: channel 0: rcvd adjust 32785
321debug2: channel 0: rcvd adjust 32797
322debug2: channel 0: rcvd adjust 32838
323debug2: channel 0: rcvd adjust 32784
324debug2: channel 0: rcvd adjust 32859
325debug2: channel 0: rcvd adjust 32779
326debug2: channel 0: rcvd adjust 32775
327debug2: channel 0: rcvd adjust 32769
328debug2: channel 0: rcvd adjust 32787
329debug2: channel 0: rcvd adjust 32796
330debug2: channel 0: rcvd adjust 32802
331debug2: channel 0: rcvd adjust 32770
332debug2: channel 0: rcvd adjust 32772
333debug2: channel 0: rcvd adjust 32806
334debug2: channel 0: rcvd adjust 32786
335debug2: channel 0: rcvd adjust 32806
336debug2: channel 0: rcvd adjust 32798
337debug2: channel 0: rcvd adjust 32802
338debug2: channel 0: rcvd adjust 32812
339debug2: channel 0: rcvd adjust 32838
340debug2: channel 0: rcvd adjust 32835
341debug2: channel 0: rcvd adjust 29822
342debug2: channel 0: rcvd adjust 8192
343debug2: channel 0: rcvd adjust 65536
344debug2: channel 0: rcvd adjust 32791
345debug2: channel 0: rcvd adjust 32794
346debug2: channel 0: rcvd adjust 32774
347debug2: channel 0: rcvd adjust 32796
348debug2: channel 0: rcvd adjust 32885
349debug2: channel 0: rcvd adjust 32808
350debug2: channel 0: rcvd adjust 33284
351debug2: channel 0: rcvd adjust 32903
352debug2: channel 0: rcvd adjust 32880
353debug2: channel 0: rcvd adjust 33069
354debug2: channel 0: rcvd adjust 33216
355debug2: channel 0: rcvd adjust 32820
356debug2: channel 0: rcvd adjust 32778
357debug2: channel 0: rcvd adjust 32822
358debug2: channel 0: rcvd adjust 32796
359debug2: channel 0: rcvd adjust 32805
360debug2: channel 0: rcvd adjust 32838
361debug2: channel 0: rcvd adjust 32779
362debug2: channel 0: rcvd adjust 32986
363debug2: channel 0: rcvd adjust 32780
364debug2: channel 0: rcvd adjust 32781
365debug2: channel 0: rcvd adjust 32805
366debug2: channel 0: rcvd adjust 32788
367debug2: channel 0: rcvd adjust 32844
368debug2: channel 0: rcvd adjust 33284
369debug2: channel 0: rcvd adjust 32833
370debug2: channel 0: rcvd adjust 32843
371debug2: channel 0: rcvd adjust 30300
372debug2: channel 0: rcvd adjust 65536
373debug2: channel 0: rcvd adjust 32778
374debug1: server_input_channel_req: channel 0 request window-change reply 0
375debug1: session_by_channel: session 0 channel 0
376debug1: session_input_channel_req: session 0 req window-change
377debug1: server_input_channel_req: channel 0 request window-change reply 0
378debug1: session_by_channel: session 0 channel 0
379debug1: session_input_channel_req: session 0 req window-change
380debug1: Received SIGCHLD.
381debug1: session_by_pid: pid 231
382debug1: session_exit_message: session 0 channel 0 pid 231
383debug2: channel 0: request exit-status confirm 0
384debug1: session_exit_message: release channel 0
385debug2: channel 0: write failed
386debug2: channel 0: close_write
387debug2: channel 0: output open -> closed
388debug1: session_pty_cleanup: session 0 release /dev/tt/p1
389debug2: channel 0: read<=0 rfd 7 len 0
390debug2: channel 0: read failed
391debug2: channel 0: close_read
392debug2: channel 0: input open -> drain
393debug2: channel 0: ibuf empty
394debug2: channel 0: send eof
395debug2: channel 0: input drain -> closed
396debug2: channel 0: send close
397debug2: notify_done: reading
398debug3: channel 0: will not send data after close
399debug2: channel 0: rcvd close
400debug3: channel 0: will not send data after close
401debug2: channel 0: is dead
402debug2: channel 0: gc: notify user
403debug1: session_by_channel: session 0 channel 0
404debug1: session_close_by_channel: channel 0 child 0
405debug1: session_close: session 0 pid 0
406debug2: channel 0: gc: user detached
407debug2: channel 0: is dead
408debug2: channel 0: garbage collecting
409debug1: channel 0: free: server-session, nchannels 1
410debug3: channel 0: status: The following connections are open:
411 #0 server-session (t4 r0 i3/0 o3/0 fd -1/-1 cfd -1)
412
413debug3: channel 0: close_fds r -1 w -1 e -1 c -1
414Connection closed by 192.168.1.126
415debug1: do_cleanup
416Closing connection to 192.168.1.126