[FreeNX-kNX] Authentication failed with NX client ... MORE TESTS ..

[M Hoeller]

Hi

I have put the sshd part completely in this mail, hope you can find the 
issue. When you look for "failed" you find three occurences - I just do 
not find out what's the problem.

Thanks a lot
Michael

On Donnerstag, 30. August 2007, Fabian Franz wrote:
> We need the server log from when the NX client tries to connect.

Aug 30 18:03:24 omicron ipppd[32754]: local  IP address 192.168.55.100
Aug 30 18:03:24 omicron ipppd[32754]: remote IP address 192.168.55.200
Aug 30 18:03:26 omicron ip-up: SuSEfirewall2: Firewall rules 
successfully set from /etc/sysconfig/SuSEfirewall2
Aug 30 18:03:26 omicron SuSEfirewall2: Firewall rules successfully set 
from /etc/sysconfig/SuSEfirewall2
Aug 30 18:03:31 omicron sshd[2234]: Accepted keyboard-interactive/pam 
for kfo from ::ffff:192.168.55.200 port 60003 ssh2
Aug 30 18:03:38 omicron su: (to root) kfo on /dev/pts/1
Aug 30 18:03:38 omicron su: pam_unix2: session started for user root, 
service su 
Aug 30 18:04:04 omicron sshd[5558]: Received signal 15; terminating.
Aug 30 18:04:04 omicron sshd[2305]: debug2: fd 3 setting O_NONBLOCK
Aug 30 18:04:04 omicron sshd[2305]: debug1: Bind to port 22 on ::.
Aug 30 18:04:04 omicron sshd[2305]: Server listening on :: port 22.
Aug 30 18:04:04 omicron sshd[2305]: Generating 768 bit RSA key.
Aug 30 18:04:04 omicron sshd[2305]: RSA key generation complete.
Aug 30 18:04:06 omicron su: pam_unix2: session finished for user root, 
service su 
Aug 30 18:04:27 omicron sshd[2305]: debug3: fd 4 is not O_NONBLOCK
Aug 30 18:04:27 omicron sshd[2308]: debug1: rexec start in 4 out 4 
newsock 4 pipe 6 sock 7
Aug 30 18:04:27 omicron sshd[2305]: debug1: Forked child 2308.
Aug 30 18:04:27 omicron sshd[2305]: debug3: send_rexec_state: entering 
fd = 7 config len 257
Aug 30 18:04:27 omicron sshd[2305]: debug3: ssh_msg_send: type 0
Aug 30 18:04:27 omicron sshd[2305]: debug3: send_rexec_state: done
Aug 30 18:04:27 omicron sshd[2308]: debug1: inetd sockets after dupping: 
3, 3
Aug 30 18:04:27 omicron sshd[2308]: Connection 
from ::ffff:192.168.55.200 port 60004
Aug 30 18:04:27 omicron sshd[2308]: debug1: Client protocol version 2.0; 
client software version OpenSSH_4.4
Aug 30 18:04:27 omicron sshd[2308]: debug1: match: OpenSSH_4.4 pat 
OpenSSH*
Aug 30 18:04:27 omicron sshd[2308]: debug1: Enabling compatibility mode 
for protocol 2.0
Aug 30 18:04:27 omicron sshd[2308]: debug1: Local version string 
SSH-1.99-OpenSSH_3.9p1
Aug 30 18:04:27 omicron sshd[2308]: debug2: fd 3 setting O_NONBLOCK
Aug 30 18:04:27 omicron sshd[2308]: debug2: Network child is on pid 2309
Aug 30 18:04:27 omicron sshd[2308]: debug3: preauth child monitor 
started
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:27 omicron sshd[2308]: debug3: monitor_read: checking 
request 0
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_answer_moduli: got 
parameters: 1024 1024 8192
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_request_send entering: 
type 1
Aug 30 18:04:27 omicron sshd[2308]: debug2: monitor_read: 0 used once, 
disabling now
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:27 omicron sshd[2308]: debug3: monitor_read: checking 
request 4
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_answer_sign
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_answer_sign: signature 
0x809a9f8(143)
Aug 30 18:04:27 omicron sshd[2308]: debug3: mm_request_send entering: 
type 5
Aug 30 18:04:27 omicron sshd[2308]: debug2: monitor_read: 4 used once, 
disabling now
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:28 omicron sshd[2308]: debug3: monitor_read: checking 
request 6
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_pwnamallow
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_pwnamallow: 
sending MONITOR_ANS_PWNAM: 1
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_send entering: 
type 7
Aug 30 18:04:28 omicron sshd[2308]: debug2: monitor_read: 6 used once, 
disabling now
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:28 omicron sshd[2308]: debug3: monitor_read: checking 
request 45
Aug 30 18:04:28 omicron sshd[2308]: debug1: PAM: initializing for "nx"
Aug 30 18:04:28 omicron sshd[2308]: debug3: Normalising mapped IPv4 in 
IPv6 address
Aug 30 18:04:28 omicron sshd[2308]: debug3: Trying to reverse map 
address 192.168.55.200.
Aug 30 18:04:28 omicron sshd[2308]: debug1: PAM: setting PAM_RHOST 
to "192.168.55.200"
Aug 30 18:04:28 omicron sshd[2308]: debug1: PAM: setting PAM_TTY 
to "ssh"
Aug 30 18:04:28 omicron sshd[2308]: debug2: monitor_read: 45 used once, 
disabling now
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:28 omicron sshd[2308]: debug3: monitor_read: checking 
request 3
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_authserv: 
service=ssh-connection, style=
Aug 30 18:04:28 omicron sshd[2308]: debug2: monitor_read: 3 used once, 
disabling now
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:28 omicron sshd[2308]: debug3: monitor_read: checking 
request 10
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_authpassword: 
sending result 0
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_send entering: 
type 11
Aug 30 18:04:28 omicron sshd[2308]: Failed none for nx 
from ::ffff:192.168.55.200 port 60004 ssh2
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:28 omicron sshd[2308]: debug3: monitor_read: checking 
request 20
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_keyallowed 
entering
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_keyallowed: 
key_from_blob: 0x80a6bc8
Aug 30 18:04:28 omicron sshd[2308]: debug1: temporarily_use_uid: 
1005/100 (e=0/0)
Aug 30 18:04:28 omicron sshd[2308]: debug1: trying public key 
file /home/.nx//.ssh/authorized_keys
Aug 30 18:04:28 omicron sshd[2308]: debug1: restore_uid: 0/0
Aug 30 18:04:28 omicron sshd[2308]: debug1: temporarily_use_uid: 
1005/100 (e=0/0)
Aug 30 18:04:28 omicron sshd[2308]: debug1: trying public key 
file /home/.nx//.ssh/authorized_keys
Aug 30 18:04:28 omicron sshd[2308]: debug1: restore_uid: 0/0
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_answer_keyallowed: key 
0x80a6bc8 is disallowed
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_send entering: 
type 21
Aug 30 18:04:28 omicron sshd[2308]: debug3: mm_request_receive entering
Aug 30 18:04:28 omicron sshd[2308]: debug1: do_cleanup
Aug 30 18:04:28 omicron sshd[2308]: debug1: PAM: cleanup
Aug 30 18:04:28 omicron sshd[2308]: debug3: PAM: sshpam_thread_cleanup 
entering
Aug 30 18:04:37 omicron sshd[2305]: debug3: fd 4 is not O_NONBLOCK
Aug 30 18:04:37 omicron sshd[2310]: debug1: rexec start in 4 out 4 
newsock 4 pipe 6 sock 7
Aug 30 18:04:37 omicron sshd[2305]: debug1: Forked child 2310.
Aug 30 18:04:37 omicron sshd[2305]: debug3: send_rexec_state: entering 
fd = 7 config len 257
Aug 30 18:04:37 omicron sshd[2305]: debug3: ssh_msg_send: type 0
Aug 30 18:04:37 omicron sshd[2305]: debug3: send_rexec_state: done
Aug 30 18:04:37 omicron sshd[2310]: debug1: inetd sockets after dupping: 
3, 3
Aug 30 18:04:37 omicron sshd[2310]: Connection 
from ::ffff:192.168.55.200 port 60005
Aug 30 18:04:37 omicron sshd[2310]: debug1: Client protocol version 2.0; 
client software version OpenSSH_4.4
Aug 30 18:04:37 omicron sshd[2310]: debug1: match: OpenSSH_4.4 pat 
OpenSSH*
Aug 30 18:04:37 omicron sshd[2310]: debug1: Enabling compatibility mode 
for protocol 2.0
Aug 30 18:04:37 omicron sshd[2310]: debug1: Local version string 
SSH-1.99-OpenSSH_3.9p1
Aug 30 18:04:37 omicron sshd[2310]: debug2: fd 3 setting O_NONBLOCK
Aug 30 18:04:37 omicron sshd[2310]: debug2: Network child is on pid 2311
Aug 30 18:04:37 omicron sshd[2310]: debug3: preauth child monitor 
started
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:37 omicron sshd[2310]: debug3: monitor_read: checking 
request 0
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_answer_moduli: got 
parameters: 1024 1024 8192
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_request_send entering: 
type 1
Aug 30 18:04:37 omicron sshd[2310]: debug2: monitor_read: 0 used once, 
disabling now
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:37 omicron sshd[2310]: debug3: monitor_read: checking 
request 4
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_answer_sign
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_answer_sign: signature 
0x809a280(143)
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_request_send entering: 
type 5
Aug 30 18:04:37 omicron sshd[2310]: debug2: monitor_read: 4 used once, 
disabling now
Aug 30 18:04:37 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: monitor_read: checking 
request 6
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_answer_pwnamallow
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_answer_pwnamallow: 
sending MONITOR_ANS_PWNAM: 1
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_send entering: 
type 7
Aug 30 18:04:38 omicron sshd[2310]: debug2: monitor_read: 6 used once, 
disabling now
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: monitor_read: checking 
request 45
Aug 30 18:04:38 omicron sshd[2310]: debug1: PAM: initializing for "kfo"
Aug 30 18:04:38 omicron sshd[2310]: debug3: Normalising mapped IPv4 in 
IPv6 address
Aug 30 18:04:38 omicron sshd[2310]: debug3: Trying to reverse map 
address 192.168.55.200.
Aug 30 18:04:38 omicron sshd[2310]: debug1: PAM: setting PAM_RHOST 
to "192.168.55.200"
Aug 30 18:04:38 omicron sshd[2310]: debug1: PAM: setting PAM_TTY 
to "ssh"
Aug 30 18:04:38 omicron sshd[2310]: debug2: monitor_read: 45 used once, 
disabling now
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: monitor_read: checking 
request 3
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_answer_authserv: 
service=ssh-connection, style=
Aug 30 18:04:38 omicron sshd[2310]: debug2: monitor_read: 3 used once, 
disabling now
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: monitor_read: checking 
request 10
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_answer_authpassword: 
sending result 0
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_send entering: 
type 11
Aug 30 18:04:38 omicron sshd[2310]: Failed none for kfo 
from ::ffff:192.168.55.200 port 60005 ssh2
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: monitor_read: checking 
request 48
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_answer_pam_init_ctx
Aug 30 18:04:38 omicron sshd[2310]: debug3: PAM: sshpam_init_ctx 
entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_send entering: 
type 49
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: monitor_read: checking 
request 50
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_answer_pam_query
Aug 30 18:04:38 omicron sshd[2310]: debug3: PAM: sshpam_query entering
Aug 30 18:04:38 omicron sshd[2310]: debug3: ssh_msg_recv entering
Aug 30 18:04:38 omicron sshd[2312]: debug3: PAM: sshpam_thread_conv 
entering, 1 messages
Aug 30 18:04:38 omicron sshd[2312]: debug3: ssh_msg_send: type 1
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_send entering: 
type 51
Aug 30 18:04:38 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:38 omicron sshd[2312]: debug3: ssh_msg_recv entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: monitor_read: checking 
request 52
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_answer_pam_respond
Aug 30 18:04:40 omicron sshd[2310]: debug2: PAM: sshpam_respond 
entering, 1 responses
Aug 30 18:04:40 omicron sshd[2310]: debug3: ssh_msg_send: type 6
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_send entering: 
type 53
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: monitor_read: checking 
request 50
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_answer_pam_query
Aug 30 18:04:40 omicron sshd[2310]: debug3: PAM: sshpam_query entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: ssh_msg_recv entering
Aug 30 18:04:40 omicron sshd[2312]: debug3: PAM: do_pam_account 
pam_acct_mgmt = 0
Aug 30 18:04:40 omicron sshd[2312]: debug3: ssh_msg_send: type 0
Aug 30 18:04:40 omicron sshd[2310]: debug3: PAM: import_environments 
entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: 
sshpam_password_change_required 0
Aug 30 18:04:40 omicron sshd[2310]: debug3: PAM: num env strings 0
Aug 30 18:04:40 omicron sshd[2310]: debug1: PAM: num PAM env strings 0
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_send entering: 
type 51
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: monitor_read: checking 
request 52
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_answer_pam_respond
Aug 30 18:04:40 omicron sshd[2310]: debug2: PAM: sshpam_respond 
entering, 0 responses
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_send entering: 
type 53
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: monitor_read: checking 
request 54
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_answer_pam_free_ctx
Aug 30 18:04:40 omicron sshd[2310]: debug3: PAM: sshpam_free_ctx 
entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: PAM: sshpam_thread_cleanup 
entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_send entering: 
type 55
Aug 30 18:04:40 omicron sshd[2310]: debug2: monitor_read: 54 used once, 
disabling now
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive_expect 
entering: type 46
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_send entering: 
type 47
Aug 30 18:04:40 omicron sshd[2310]: Accepted keyboard-interactive/pam 
for kfo from ::ffff:192.168.55.200 port 60005 ssh2
Aug 30 18:04:40 omicron sshd[2310]: debug1: monitor_child_preauth: kfo 
has been authenticated by privileged process
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_get_keystate: Waiting for 
new keys
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive_expect 
entering: type 24
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_newkeys_from_blob: 
0x80a7ae8(118)
Aug 30 18:04:40 omicron sshd[2310]: debug2: mac_init: found hmac-md5
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_get_keystate: Waiting for 
second key
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_newkeys_from_blob: 
0x80a7ae8(118)
Aug 30 18:04:40 omicron sshd[2310]: debug2: mac_init: found hmac-md5
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_get_keystate: Getting 
compression state
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_get_keystate: Getting 
Network I/O buffers
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_share_sync: Share sync
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_share_sync: Share sync 
end
Aug 30 18:04:40 omicron sshd[2310]: debug2: User child is on pid 2313
Aug 30 18:04:40 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:40 omicron sshd[2313]: debug3: PAM: opening session
Aug 30 18:04:40 omicron sshd[2313]: debug1: PAM: reinitializing 
credentials
Aug 30 18:04:40 omicron sshd[2313]: debug1: permanently_set_uid: 505/100
Aug 30 18:04:40 omicron sshd[2313]: debug2: set_newkeys: mode 0
Aug 30 18:04:40 omicron sshd[2313]: debug2: set_newkeys: mode 1
Aug 30 18:04:40 omicron sshd[2313]: debug1: Entering interactive session 
for SSH2.
Aug 30 18:04:40 omicron sshd[2313]: debug2: fd 5 setting O_NONBLOCK
Aug 30 18:04:40 omicron sshd[2313]: debug2: fd 6 setting O_NONBLOCK
Aug 30 18:04:40 omicron sshd[2313]: debug1: server_init_dispatch_20
Aug 30 18:04:40 omicron sshd[2313]: debug1: server_input_channel_open: 
ctype session rchan 0 win 65536 max 16384
Aug 30 18:04:40 omicron sshd[2313]: debug1: input_session_request
Aug 30 18:04:40 omicron sshd[2313]: debug1: channel 0: new 
[server-session]
Aug 30 18:04:40 omicron sshd[2313]: debug1: session_new: init
Aug 30 18:04:40 omicron sshd[2313]: debug1: session_new: session 0
Aug 30 18:04:40 omicron sshd[2313]: debug1: session_open: channel 0
Aug 30 18:04:40 omicron sshd[2313]: debug1: session_open: session 0: 
link with channel 0
Aug 30 18:04:40 omicron sshd[2313]: debug1: server_input_channel_open: 
confirm session
Aug 30 18:04:41 omicron sshd[2313]: debug1: server_input_channel_req: 
channel 0 request x11-req reply 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_by_channel: session 
0 channel 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_input_channel_req: 
session 0 req x11-req
Aug 30 18:04:41 omicron sshd[2313]: debug2: fd 7 setting O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug3: fd 7 is O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug1: channel 1: new [X11 inet 
listener]
Aug 30 18:04:41 omicron sshd[2313]: debug2: fd 8 setting O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug3: fd 8 is O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug1: channel 2: new [X11 inet 
listener]
Aug 30 18:04:41 omicron sshd[2313]: debug1: server_input_channel_req: 
channel 0 request pty-req reply 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_by_channel: session 
0 channel 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_input_channel_req: 
session 0 req pty-req
Aug 30 18:04:41 omicron sshd[2313]: debug1: Allocating pty.
Aug 30 18:04:41 omicron sshd[2313]: debug3: mm_request_send entering: 
type 25
Aug 30 18:04:41 omicron sshd[2313]: debug3: mm_pty_allocate: waiting for 
MONITOR_ANS_PTY
Aug 30 18:04:41 omicron sshd[2313]: debug3: mm_request_receive_expect 
entering: type 26
Aug 30 18:04:41 omicron sshd[2313]: debug3: mm_request_receive entering
Aug 30 18:04:41 omicron sshd[2310]: debug3: monitor_read: checking 
request 25
Aug 30 18:04:41 omicron sshd[2310]: debug3: mm_answer_pty entering
Aug 30 18:04:41 omicron sshd[2310]: debug1: session_new: init
Aug 30 18:04:41 omicron sshd[2310]: debug1: session_new: session 0
Aug 30 18:04:41 omicron sshd[2310]: debug3: mm_request_send entering: 
type 26
Aug 30 18:04:41 omicron sshd[2310]: debug3: mm_answer_pty: 
tty /dev/pts/1 ptyfd 4
Aug 30 18:04:41 omicron sshd[2310]: debug3: mm_request_receive entering
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_pty_req: session 0 
alloc /dev/pts/1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: SSH2 
n_bytes 256
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: ospeed 
38400
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: ispeed 
38400
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 1 3
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 2 28
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 3 127
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 4 21
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 5 4
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 6 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 7 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 8 17
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 9 19
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 10 26
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 12 18
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 13 23
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 14 22
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 18 15
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 30 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 31 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 32 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 33 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 34 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 35 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 36 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 37 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 38 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 39 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 40 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 41 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 50 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 51 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 52 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 53 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 54 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 55 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 56 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 57 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 58 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 59 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 60 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 61 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 62 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 70 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 71 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 72 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 73 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 74 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 75 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 90 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 91 1
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 92 0
Aug 30 18:04:41 omicron sshd[2313]: debug3: tty_parse_modes: 93 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: server_input_channel_req: 
channel 0 request env reply 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_by_channel: session 
0 channel 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_input_channel_req: 
session 0 req env
Aug 30 18:04:41 omicron sshd[2313]: debug2: Ignoring env request LANG: 
disallowed name
Aug 30 18:04:41 omicron sshd[2313]: debug1: server_input_channel_req: 
channel 0 request shell reply 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_by_channel: session 
0 channel 0
Aug 30 18:04:41 omicron sshd[2313]: debug1: session_input_channel_req: 
session 0 req shell
Aug 30 18:04:41 omicron sshd[2313]: debug1: PAM: setting PAM_TTY 
to "/dev/pts/1"
Aug 30 18:04:41 omicron sshd[2314]: debug1: Setting controlling tty 
using TIOCSCTTY.
Aug 30 18:04:41 omicron sshd[2313]: debug2: fd 3 setting TCP_NODELAY
Aug 30 18:04:41 omicron sshd[2313]: debug2: channel 0: rfd 10 isatty
Aug 30 18:04:41 omicron sshd[2313]: debug2: fd 10 setting O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug3: fd 9 is O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2314]: debug3: channel 0: close_fds r -1 
w -1 e -1 c -1
Aug 30 18:04:41 omicron sshd[2314]: debug3: channel 1: close_fds r 7 w 7 
e -1 c -1
Aug 30 18:04:41 omicron sshd[2314]: debug3: channel 2: close_fds r 8 w 8 
e -1 c -1
Aug 30 18:04:41 omicron sshd[2314]: debug1: Received SIGCHLD.
Aug 30 18:04:41 omicron sshd[2313]: debug1: X11 connection requested.
Aug 30 18:04:41 omicron sshd[2313]: debug2: fd 12 setting TCP_NODELAY
Aug 30 18:04:41 omicron sshd[2313]: debug2: fd 12 setting O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug3: fd 12 is O_NONBLOCK
Aug 30 18:04:41 omicron sshd[2313]: debug1: channel 3: new [X11 
connection from 127.0.0.1 port 1529]
Aug 30 18:04:41 omicron sshd[2313]: debug2: channel 3: open confirm 
rwindow 131072 rmax 16384
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: read<=0 rfd 12 
len 0
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: read failed
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: close_read
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: input open -> 
drain
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: ibuf empty
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: send eof
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: input drain -> 
closed
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: rcvd eof
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: output open -> 
drain
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: obuf empty
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: close_write
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: output drain -> 
closed
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: rcvd close
Aug 30 18:04:43 omicron sshd[2313]: debug3: channel 3: will not send 
data after close
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: send close
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: is dead
Aug 30 18:04:43 omicron sshd[2313]: debug2: channel 3: garbage 
collecting
Aug 30 18:04:43 omicron sshd[2313]: debug1: channel 3: free: X11 
connection from 127.0.0.1 port 1529, nchannels 4
Aug 30 18:04:43 omicron sshd[2313]: debug3: channel 3: status: The 
following connections are open:\r\n  #0 server-session (t4 r0 i0/0 o0/0 
fd 10/9 cfd -1)\r\n  #3 X11 connection from 127.0.0.1 port 1529 (t4 r1 
i3/0 o3/0 fd 12/12 cfd -1)\r\n
Aug 30 18:04:43 omicron sshd[2313]: debug3: channel 3: close_fds r 12 w 
12 e -1 c -1