[FreeNX-kNX] Authentication failure
Yang
g07dgk502 at sneakemail.com
Tue Aug 29 04:23:02 UTC 2006
Hi all,
I yum-installed freenx on an up-to-date FC5, and I can ssh in as user
nx from my client machine just fine, but the NX client fails to
authenticate. The client is from
http://www.nomachine.com/download-client-windows.php. It simply says
"Authentication failed for user yang" (the Details button is grayed
out).
My nxserver.log is empty and my /var/log/secure recorded:
Aug 10 01:49:13 myhost sshd[27262]: Accepted publickey for nx from
123.123.123.123 port 4224 ssh2
Aug 9 21:49:13 myhost sshd[27261]: Accepted publickey for nx from
123.123.123.123 port 4224 ssh2
Aug 9 21:49:13 myhost sshd[27263]: pam_unix(sshd:session): session
opened for user nx by (uid=0)
Aug 9 21:49:14 myhost sshd[27310]: refused connect from
::ffff:127.0.0.1 (::ffff:127.0.0.1)
Aug 9 21:49:19 myhost sshd[27263]: pam_unix(sshd:session): session
closed for user nx
Can anyone tell me what's going wrong? Thanks in advance. Here is the
full debug output from my /var/log/secure:
Aug 29 00:09:29 myhost sshd[1974]: debug3: fd 4 is not O_NONBLOCK
Aug 29 00:09:29 myhost sshd[6863]: debug1: rexec start in 4 out 4
newsock 4 pipe 6 sock 7
Aug 29 00:09:29 myhost sshd[1974]: debug1: Forked child 6863.
Aug 29 00:09:29 myhost sshd[1974]: debug3: send_rexec_state: entering
fd = 7 config len 531
Aug 29 00:09:29 myhost sshd[1974]: debug3: ssh_msg_send: type 0
Aug 29 00:09:29 myhost sshd[1974]: debug3: send_rexec_state: done
Aug 29 00:09:29 myhost sshd[6863]: debug1: inetd sockets after dupping: 3, 3
Aug 29 00:09:29 myhost sshd[6863]: debug3: Normalising mapped IPv4 in
IPv6 address
Aug 29 00:09:29 myhost sshd[6863]: Connection from 123.123.123.123 port 1374
Aug 29 00:09:29 myhost sshd[6863]: debug1: Client protocol version
2.0; client software version OpenSSH_3.9p1
Aug 29 00:09:29 myhost sshd[6863]: debug1: match: OpenSSH_3.9p1 pat OpenSSH_3.*
Aug 29 00:09:29 myhost sshd[6863]: debug1: Enabling compatibility mode
for protocol 2.0
Aug 29 00:09:29 myhost sshd[6863]: debug1: Local version string
SSH-2.0-OpenSSH_4.3
Aug 29 00:09:29 myhost sshd[6863]: debug2: fd 3 setting O_NONBLOCK
Aug 29 00:09:29 myhost sshd[6864]: debug3: privsep user:group 74:74
Aug 29 00:09:29 myhost sshd[6863]: debug2: Network child is on pid 6864
Aug 29 04:09:29 myhost sshd[6864]: debug1: permanently_set_uid: 74/74
Aug 29 00:09:29 myhost sshd[6863]: debug3: preauth child monitor started
Aug 29 04:09:29 myhost sshd[6864]: debug1: list_hostkey_types: ssh-rsa,ssh-dss
Aug 29 00:09:29 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:29 myhost sshd[6864]: debug1: SSH2_MSG_KEXINIT sent
Aug 29 04:09:29 myhost sshd[6864]: debug1: SSH2_MSG_KEXINIT received
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc at lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour128,arcfour256,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc at lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160 at openssh.com,hmac-sha1-96,hmac-md5-96
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160 at openssh.com,hmac-sha1-96,hmac-md5-96
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
none,zlib at openssh.com
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
none,zlib at openssh.com
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
first_kex_follows 0
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit: reserved 0
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
diffie-hellman-group-exchange-sha1,diffie-hellman-group14-sha1,diffie-hellman-group1-sha1
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit: ssh-rsa,ssh-dss
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc at lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
aes128-cbc,3des-cbc,blowfish-cbc,cast128-cbc,arcfour,aes192-cbc,aes256-cbc,rijndael-cbc at lysator.liu.se,aes128-ctr,aes192-ctr,aes256-ctr
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160 at openssh.com,hmac-sha1-96,hmac-md5-96
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
hmac-md5,hmac-sha1,hmac-ripemd160,hmac-ripemd160 at openssh.com,hmac-sha1-96,hmac-md5-96
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit: none,zlib
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit: none,zlib
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit:
first_kex_follows 0
Aug 29 04:09:29 myhost sshd[6864]: debug2: kex_parse_kexinit: reserved 0
Aug 29 04:09:29 myhost sshd[6864]: debug2: mac_init: found hmac-md5
Aug 29 04:09:29 myhost sshd[6864]: debug1: kex: client->server
aes128-cbc hmac-md5 none
Aug 29 04:09:29 myhost sshd[6864]: debug2: mac_init: found hmac-md5
Aug 29 04:09:29 myhost sshd[6864]: debug1: kex: server->client
aes128-cbc hmac-md5 none
Aug 29 04:09:29 myhost sshd[6864]: debug1: SSH2_MSG_KEX_DH_GEX_REQUEST received
Aug 29 04:09:29 myhost sshd[6864]: debug3: mm_request_send entering: type 0
Aug 29 04:09:29 myhost sshd[6864]: debug3: mm_choose_dh: waiting for
MONITOR_ANS_MODULI
Aug 29 04:09:29 myhost sshd[6864]: debug3: mm_request_receive_expect
entering: type 1
Aug 29 04:09:29 myhost sshd[6864]: debug3: mm_request_receive entering
Aug 29 00:09:29 myhost sshd[6863]: debug3: monitor_read: checking request 0
Aug 29 00:09:29 myhost sshd[6863]: debug3: mm_answer_moduli: got
parameters: 1024 1024 8192
Aug 29 00:09:29 myhost sshd[6863]: debug3: mm_request_send entering: type 1
Aug 29 00:09:30 myhost sshd[6863]: debug2: monitor_read: 0 used once,
disabling now
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_choose_dh: remaining 0
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:30 myhost sshd[6864]: debug1: SSH2_MSG_KEX_DH_GEX_GROUP sent
Aug 29 04:09:30 myhost sshd[6864]: debug2: dh_gen_key: priv key bits
set: 133/256
Aug 29 04:09:30 myhost sshd[6864]: debug2: bits set: 485/1024
Aug 29 04:09:30 myhost sshd[6864]: debug1: expecting SSH2_MSG_KEX_DH_GEX_INIT
Aug 29 04:09:30 myhost sshd[6864]: debug2: bits set: 524/1024
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_key_sign entering
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_send entering: type 5
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_key_sign: waiting for
MONITOR_ANS_SIGN
Aug 29 00:09:30 myhost sshd[6863]: debug3: monitor_read: checking request 5
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_receive_expect
entering: type 6
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_sign
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_receive entering
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_sign: signature
0x9830390(143)
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_send entering: type 6
Aug 29 00:09:30 myhost sshd[6863]: debug2: monitor_read: 5 used once,
disabling now
Aug 29 04:09:30 myhost sshd[6864]: debug1: SSH2_MSG_KEX_DH_GEX_REPLY sent
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:30 myhost sshd[6864]: debug2: kex_derive_keys
Aug 29 04:09:30 myhost sshd[6864]: debug2: set_newkeys: mode 1
Aug 29 04:09:30 myhost sshd[6864]: debug1: SSH2_MSG_NEWKEYS sent
Aug 29 04:09:30 myhost sshd[6864]: debug1: expecting SSH2_MSG_NEWKEYS
Aug 29 04:09:30 myhost sshd[6864]: debug2: set_newkeys: mode 0
Aug 29 04:09:30 myhost sshd[6864]: debug1: SSH2_MSG_NEWKEYS received
Aug 29 04:09:30 myhost sshd[6864]: debug1: KEX done
Aug 29 04:09:30 myhost sshd[6864]: debug1: userauth-request for user
nx service ssh-connection method none
Aug 29 04:09:30 myhost sshd[6864]: debug1: attempt 0 failures 0
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_getpwnamallow entering
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_send entering: type 7
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_getpwnamallow: waiting
for MONITOR_ANS_PWNAM
Aug 29 00:09:30 myhost sshd[6863]: debug3: monitor_read: checking request 7
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_receive_expect
entering: type 8
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_pwnamallow
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_receive entering
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_pwnamallow:
sending MONITOR_ANS_PWNAM: 1
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_send entering: type 8
Aug 29 00:09:30 myhost sshd[6863]: debug2: monitor_read: 7 used once,
disabling now
Aug 29 04:09:30 myhost sshd[6864]: debug2: input_userauth_request:
setting up authctxt for nx
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_start_pam entering
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_send entering: type 46
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_inform_authserv entering
Aug 29 00:09:30 myhost sshd[6863]: debug3: monitor_read: checking request 46
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_send entering: type 3
Aug 29 00:09:30 myhost sshd[6863]: debug1: PAM: initializing for "nx"
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_inform_authrole entering
Aug 29 00:09:30 myhost sshd[6863]: debug3: Normalising mapped IPv4 in
IPv6 address
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_send entering: type 4
Aug 29 00:09:30 myhost sshd[6863]: debug3: Trying to reverse map
address 123.123.123.123.
Aug 29 04:09:30 myhost sshd[6864]: debug2: input_userauth_request: try
method none
Aug 29 00:09:30 myhost sshd[6863]: debug1: PAM: setting PAM_RHOST to
"myclienthost"
Aug 29 04:09:30 myhost sshd[6864]: debug3: Normalising mapped IPv4 in
IPv6 address
Aug 29 00:09:30 myhost sshd[6863]: debug1: PAM: setting PAM_TTY to "ssh"
Aug 29 04:09:30 myhost sshd[6864]: Failed none for nx from
123.123.123.123 port 1374 ssh2
Aug 29 00:09:30 myhost sshd[6863]: debug2: monitor_read: 46 used once,
disabling now
Aug 29 04:09:30 myhost sshd[6864]: debug1: userauth-request for user
nx service ssh-connection method publickey
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:30 myhost sshd[6864]: debug1: attempt 1 failures 1
Aug 29 00:09:30 myhost sshd[6863]: debug3: monitor_read: checking request 3
Aug 29 04:09:30 myhost sshd[6864]: debug2: input_userauth_request: try
method publickey
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_authserv:
service=ssh-connection, style=
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_key_allowed entering
Aug 29 00:09:30 myhost sshd[6863]: debug2: monitor_read: 3 used once,
disabling now
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_send entering: type 21
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_key_allowed: waiting for
MONITOR_ANS_KEYALLOWED
Aug 29 00:09:30 myhost sshd[6863]: debug3: monitor_read: checking request 4
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_receive_expect
entering: type 22
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_authrole: role=
Aug 29 04:09:30 myhost sshd[6864]: debug3: mm_request_receive entering
Aug 29 00:09:30 myhost sshd[6863]: debug2: monitor_read: 4 used once,
disabling now
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 00:09:30 myhost sshd[6863]: debug3: monitor_read: checking request 21
Aug 29 00:09:30 myhost sshd[6863]: debug3: mm_answer_keyallowed entering
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_answer_keyallowed:
key_from_blob: 0x983a8e8
Aug 29 00:09:31 myhost sshd[6306]: debug2: channel 0: rcvd adjust 8252
Aug 29 00:09:31 myhost sshd[6863]: debug1: temporarily_use_uid: 102/104 (e=0/0)
Aug 29 00:09:31 myhost sshd[6863]: debug1: trying public key file
/var/lib/nxserver/home/.ssh/authorized_keys
Aug 29 00:09:31 myhost sshd[6863]: debug1: restore_uid: 0/0
Aug 29 00:09:31 myhost sshd[6863]: debug1: temporarily_use_uid: 102/104 (e=0/0)
Aug 29 00:09:31 myhost sshd[6863]: debug1: trying public key file
/var/lib/nxserver/home/.ssh/authorized_keys2
Aug 29 00:09:31 myhost sshd[6863]: debug3: secure_filename: checking
'/var/lib/nxserver/home/.ssh'
Aug 29 00:09:31 myhost sshd[6863]: debug3: secure_filename: checking
'/var/lib/nxserver/home'
Aug 29 00:09:31 myhost sshd[6863]: debug3: secure_filename:
terminating check at '/var/lib/nxserver/home'
Aug 29 00:09:31 myhost sshd[6863]: debug2: key_type_from_name: unknown
key type 'no-port-forwarding,no-X11-forwarding,no-agent-forwarding,command="/usr/bin/nxserver"'
Aug 29 00:09:31 myhost sshd[6863]: debug3: key_read: missing keytype
Aug 29 00:09:31 myhost sshd[6863]: debug2: user_key_allowed: check
options: 'no-port-forwarding,no-X11-forwarding,no-agent-forwarding,command="/usr/bin/nxserver"
ssh-dss AAAAB3NzaC1kc3MAAACBAN3JGrYnoG2vS8zKY+eueCu+AY+gutXrUY46EucM5+BNDyHF8JwOd8I1xgv3l76c8Q1QCuX3MOKym3xdQfv4v8AdxcOpJzJCfQS6CzYDPwU9QxnZzY62bEzDiuzV1nU7QhKwgJYUBZtTIil++zYtnXR6IVd6kHMZ2C0Y3VG+b/JBAAAAFQD5qr1bKA8T9FAxBqDG+UNdK8kukQAAAIA/q3qcZFirgHHPM4gMKSgsi/9GywXpZZDCP/oP6r4RT78r0anR+Mlanr9Xd9ihdhE30Qh97XCFSXOfBIbDNpF0yJSWNgl/MRuN0xvvnhqJdZVWSVyuD30NsXs3CRZXgDy6sM0FX
Aug 29 00:09:31 myhost sshd[6863]: debug1: matching key found: file
/var/lib/nxserver/home/.ssh/authorized_keys2, line 1
Aug 29 00:09:31 myhost sshd[6863]: Found matching DSA key:
68:7f:e0:98:06:57:6b:e7:a3:0d:ca:9f:25:0f:6d:94
Aug 29 00:09:31 myhost sshd[6863]: debug1: restore_uid: 0/0
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_answer_keyallowed: key
0x983a8e8 is allowed
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_append_debug: Appending
debug messages for child
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_send entering: type 22
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_debug: Sending
debug: Port forwarding disabled.
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_debug: Sending
debug: X11 forwarding disabled.
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_debug: Sending
debug: Agent forwarding disabled.
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_debug: Sending
debug: Forced command: /usr/bin/nxserver
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_key_verify entering
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_send entering: type 23
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_key_verify: waiting for
MONITOR_ANS_KEYVERIFY
Aug 29 00:09:31 myhost sshd[6863]: debug3: monitor_read: checking request 23
Aug 29 00:09:31 myhost sshd[6863]: debug1: ssh_dss_verify: signature correct
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_answer_keyverify: key
0x983ad38 signature verified
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_receive_expect
entering: type 24
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_send entering: type 24
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_receive entering
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_receive_expect
entering: type 47
Aug 29 04:09:31 myhost sshd[6864]: debug2: userauth_pubkey:
authenticated 1 pkalg ssh-dss
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_do_pam_account entering
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_send entering: type 47
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_receive_expect
entering: type 48
Aug 29 00:09:31 myhost sshd[6863]: debug1: do_pam_account: called
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_receive entering
Aug 29 00:09:31 myhost sshd[6863]: debug3: PAM: do_pam_account
pam_acct_mgmt = 0 (Success)
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_send entering: type 48
Aug 29 00:09:31 myhost sshd[6863]: debug3: Normalising mapped IPv4 in
IPv6 address
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_do_pam_account returning 1
Aug 29 00:09:31 myhost sshd[6863]: Accepted publickey for nx from
123.123.123.123 port 1374 ssh2
Aug 29 04:09:31 myhost sshd[6864]: Accepted publickey for nx from
123.123.123.123 port 1374 ssh2
Aug 29 00:09:31 myhost sshd[6863]: debug1: monitor_child_preauth: nx
has been authenticated by privileged process
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_keystate: Sending
new keys: 0x98305f0 0x98304c8
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_get_keystate: Waiting for new keys
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_newkeys_to_blob:
converting 0x98305f0
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_receive_expect
entering: type 25
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_newkeys_to_blob:
converting 0x98304c8
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_keystate: New keys
have been sent
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_keystate: Sending
compression state
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_request_send entering: type 25
Aug 29 04:09:31 myhost sshd[6864]: debug3: mm_send_keystate: Finished
sending state
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_newkeys_from_blob: 0x9839cc8(118)
Aug 29 00:09:31 myhost sshd[6863]: debug2: mac_init: found hmac-md5
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_get_keystate: Waiting
for second key
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_newkeys_from_blob: 0x9839cc8(118)
Aug 29 00:09:31 myhost sshd[6863]: debug2: mac_init: found hmac-md5
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_get_keystate: Getting
compression state
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_get_keystate: Getting
Network I/O buffers
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_share_sync: Share sync
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_share_sync: Share sync end
Aug 29 00:09:31 myhost sshd[6863]: debug2: User child is on pid 6865
Aug 29 00:09:31 myhost sshd[6865]: debug1: temporarily_use_uid:
102/104 (e=0/104)
Aug 29 00:09:31 myhost sshd[6863]: debug3: mm_request_receive entering
Aug 29 00:09:31 myhost sshd[6865]: debug1: ssh_gssapi_storecreds: Not
a GSSAPI mechanism
Aug 29 00:09:31 myhost sshd[6865]: debug1: restore_uid: 0/104
Aug 29 00:09:31 myhost sshd[6865]: debug3: PAM: opening session
Aug 29 00:09:31 myhost sshd[6865]: pam_unix(sshd:session): session
opened for user nx by (uid=0)
Aug 29 00:09:32 myhost sshd[6865]: debug1: PAM: reinitializing credentials
Aug 29 00:09:32 myhost sshd[6865]: debug1: permanently_set_uid: 102/104
Aug 29 00:09:32 myhost sshd[6306]: debug2: channel 0: rcvd adjust 8296
Aug 29 00:09:32 myhost sshd[6865]: debug2: set_newkeys: mode 0
Aug 29 00:09:32 myhost sshd[6865]: debug2: set_newkeys: mode 1
Aug 29 00:09:32 myhost sshd[6865]: debug1: Entering interactive
session for SSH2.
Aug 29 00:09:32 myhost sshd[6865]: debug2: fd 5 setting O_NONBLOCK
Aug 29 00:09:32 myhost sshd[6865]: debug2: fd 6 setting O_NONBLOCK
Aug 29 00:09:32 myhost sshd[6865]: debug1: server_init_dispatch_20
Aug 29 00:09:32 myhost sshd[6865]: debug1: server_input_channel_open:
ctype session rchan 0 win 131072 max 32768
Aug 29 00:09:32 myhost sshd[6865]: debug1: input_session_request
Aug 29 00:09:32 myhost sshd[6865]: debug1: channel 0: new [server-session]
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_new: init
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_new: session 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_open: channel 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_open: session 0:
link with channel 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: server_input_channel_open:
confirm session
Aug 29 00:09:32 myhost sshd[6865]: debug1: server_input_channel_req:
channel 0 request x11-req reply 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_by_channel: session
0 channel 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_input_channel_req:
session 0 req x11-req
Aug 29 00:09:32 myhost sshd[6865]: debug1: server_input_channel_req:
channel 0 request shell reply 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_by_channel: session
0 channel 0
Aug 29 00:09:32 myhost sshd[6865]: debug1: session_input_channel_req:
session 0 req shell
Aug 29 00:09:32 myhost sshd[6865]: debug1: Forced command '/usr/bin/nxserver'
Aug 29 00:09:32 myhost sshd[6866]: debug3: Normalising mapped IPv4 in
IPv6 address
Aug 29 00:09:32 myhost sshd[6866]: debug3: channel 0: close_fds r -1 w
-1 e -1 c -1
Aug 29 00:09:32 myhost sshd[6865]: debug2: fd 8 setting O_NONBLOCK
Aug 29 00:09:32 myhost sshd[6865]: debug3: fd 8 is O_NONBLOCK
Aug 29 00:09:32 myhost sshd[6865]: debug2: fd 10 setting O_NONBLOCK
Aug 29 00:09:33 myhost sshd[1974]: debug3: fd 4 is not O_NONBLOCK
Aug 29 00:09:33 myhost sshd[6912]: debug1: rexec start in 4 out 4
newsock 4 pipe 6 sock 7
Aug 29 00:09:33 myhost sshd[1974]: debug1: Forked child 6912.
Aug 29 00:09:33 myhost sshd[1974]: debug3: send_rexec_state: entering
fd = 7 config len 531
Aug 29 00:09:33 myhost sshd[1974]: debug3: ssh_msg_send: type 0
Aug 29 00:09:33 myhost sshd[1974]: debug3: send_rexec_state: done
Aug 29 00:09:33 myhost sshd[6912]: debug1: inetd sockets after dupping: 3, 3
Aug 29 00:09:33 myhost sshd[6912]: debug3: Normalising mapped IPv4 in
IPv6 address
Aug 29 00:09:33 myhost sshd[6912]: debug1: Connection refused by tcp wrapper
Aug 29 00:09:33 myhost sshd[6912]: refused connect from
::ffff:127.0.0.1 (::ffff:127.0.0.1)
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: read<=0 rfd 8 len 0
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: read failed
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: close_read
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: input open -> drain
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: ibuf_empty
delayed efd 10/(0)
Aug 29 00:09:38 myhost sshd[6865]: debug2: notify_done: reading
Aug 29 00:09:38 myhost sshd[6865]: debug1: Received SIGCHLD.
Aug 29 00:09:38 myhost sshd[6865]: debug1: session_by_pid: pid 6866
Aug 29 00:09:38 myhost sshd[6865]: debug1: session_exit_message:
session 0 channel 0 pid 6866
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: request
exit-status confirm 0
Aug 29 00:09:38 myhost sshd[6865]: debug1: session_exit_message:
release channel 0
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: write failed
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: close_write
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: output open -> closed
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: read 0 from efd 10
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: closing read-efd 10
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: ibuf empty
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: send eof
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: input drain -> closed
Aug 29 00:09:38 myhost sshd[6865]: debug2: channel 0: send close
Aug 29 00:09:38 myhost sshd[6865]: debug3: channel 0: will not send
data after close
Aug 29 00:09:38 myhost sshd[6865]: Connection closed by 123.123.123.123
Aug 29 00:09:38 myhost sshd[6865]: debug1: channel 0: free:
server-session, nchannels 1
Aug 29 00:09:38 myhost sshd[6865]: debug3: channel 0: status: The
following connections are open:\r\n #0 server-session (t4 r0 i3/0
o3/0 fd 8/8 cfd -1)\r\n
Aug 29 00:09:38 myhost sshd[6865]: debug3: channel 0: close_fds r 8 w
8 e -1 c -1
Aug 29 00:09:38 myhost sshd[6865]: debug1: session_close: session 0 pid 0
Aug 29 00:09:38 myhost sshd[6865]: debug1: do_cleanup
Aug 29 00:09:38 myhost sshd[6865]: debug1: PAM: cleanup
Aug 29 00:09:38 myhost sshd[6865]: pam_unix(sshd:session): session
closed for user nx
Aug 29 00:09:38 myhost sshd[6865]: debug3: PAM: sshpam_thread_cleanup entering
Aug 29 00:09:38 myhost sshd[6865]: Closing connection to 123.123.123.123
Aug 29 00:09:38 myhost sshd[6865]: debug1: PAM: cleanup
Aug 29 00:09:38 myhost sshd[6865]: debug3: mm_request_send entering: type 59
Aug 29 00:09:38 myhost sshd[6863]: debug3: monitor_read: checking request 59
Aug 29 00:09:38 myhost sshd[6863]: debug3: mm_answer_term: tearing down sessions
Aug 29 00:09:40 myhost sshd[6306]: debug2: channel 0: rcvd adjust 8247
More information about the FreeNX-kNX
mailing list