<br>
I dd a fresh install of the following two RPM's on a RHEL3 server:<br>
nx-1.5.0-4.FC1.1.i386.rpm<br>
freenx-0.4.4-2.rh.0.noarch.rpm<br>
<br>
I turned on Loglevel DEBUG3 to sshd_config file and restared the sshd daemon. <br>
<br>
The /etc/ssh/sshd_config had the follwoing commented entry:<br>
#AuthorizedKeysFile .ssh/authorized_keys2<br>
<br>
I imported the /etc/nxserver/client.id_dsa.key from the server to the NX client on WinXP.<br>
<br>
And then I attempted to connect.<br>
The client timed out with the following detail:<br>
NX> 203 NXSSH running with pid: 4088<br>
NX> 285 Enabling check on switch command<br>
NX> 285 Enabling skip of SSH config files<br>
NX> 285 Setting the preferred NX options<br>
NX> 200 Connected to address: xx.xx.115.94 on port: 22<br>
NX> 202 Authenticating user: nx<br>
NX> 208 Using auth method: publickey<br>
HELLO NXSERVER - Version 1.4.0-44 OS (GPL)<br>
NX> 105 hello NXCLIENT - Version 1.4.0<br>
NX> 134 Accepted protocol: 1.4.0<br>
NX> 105 SET SHELL_MODE SHELL<br>
NX> 105 SET AUTH_MODE PASSWORD<br>
NX> 105 login<br>
NX> 101 User: joeuser<br>
NX> 102 Password: <br>
NX> 103 Welcome to: server3 user: joeuser<br>
NX> 105 listsession --user="joeuser" --status="suspended,running" --geometry="1024x768x32+render" --type="unix-gnome"<br>
NX> 127 Sessions list of user 'joeuser' for reconnect:<br>
<br>
Display
Type
Session
ID
Options Depth
Screen
Status Session Name<br>
------- ---------------- -------------------------------- --------
----- -------------- ----------- ------------------------------<br>
<br>
<br>
NX> 148 Server capacity: not reached for user: joeuser<br>
NX> 105 startsession --link="lan" --backingstore="1"
--nodelay="1" --cache="8M" --images="32M" --media="0"
--session="efernandes" --type="unix-gnome" --cookie="******"
--geometry="fullscreen" --kbtype="pc102/en_US"
--screeninfo="1024x738x32+render" <br>
<br>
NX> 1000 NXNODE - Version 1.4.0-44 OS (GPL)<br>
NX> 700 Session id: server3-1000-A0B1EAEBB4A33EC3BE0946C6D43D73D6<br>
NX> 705 Session display: 1000<br>
NX> 703 Session type: unix-gnome<br>
NX> 701 Proxy cookie: dfd15830e072991f70aa234e39cfa361<br>
NX> 702 Proxy IP: xx.xx.111.212<br>
NX> 706 Agent cookie: 8abd0e15d7faa9d521df5125100ac05b<br>
NX> 704 Session cache: unix-gnome<br>
NX> 707 SSL tunneling: 0<br>
/usr/bin/nxserver: line 1041: 17346
Terminated
( sleep $AGENT_STARTUP_TIMEOUT; exit 1 )<br>
NX> 105 NX> 504 Session startup failed.<br>
NX> 1004 Error: nxagent failed to start with: Unrecognized option: 1<br>
NX> 1001 Bye.<br>
Killed by signal 15.<br>
<br>
<br>
And, the /var/log/secure output:<br>
Oct 4 18:08:47 lvnc3 xinetd[25107]: START: telnet pid=15912 from=xx.xx.92.40<br>
Oct 4 18:10:01 lvnc3 sshd[1966]: Received signal 15; terminating.<br>
Oct 4 18:10:04 lvnc3 sshd[16150]: Server listening on <a href="http://0.0.0.0">0.0.0.0</a> port 22.<br>
Oct 4 18:10:05 lvnc3 useradd[16162]: new group: name=nx, gid=499 <br>
Oct 4 18:10:05 lvnc3 useradd[16162]: new user: name=nx, uid=499,
gid=499, home=/var/lib/nxserver/home, shell=/usr/bin/nxserver<br>
Oct 4 18:25:27 lvnc3 sshd[16150]: Received signal 15; terminating.<br>
Oct 4 18:25:27 lvnc3 sshd[16980]: socket: Address family not supported by protocol<br>
Oct 4 18:25:27 lvnc3 sshd[16980]: debug1: Bind to port 22 on <a href="http://0.0.0.0">0.0.0.0</a>.<br>
Oct 4 18:25:27 lvnc3 sshd[16980]: Server listening on <a href="http://0.0.0.0">0.0.0.0</a> port 22.<br>
Oct 4 18:25:27 lvnc3 sshd[16980]: Generating 768 bit RSA key.<br>
Oct 4 18:25:27 lvnc3 sshd[16980]: RSA key generation complete.<br>
Oct 4 18:28:31 lvnc3 sshd[16980]: debug1: Forked child 17122.<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: Connection from xx.xx.111.212 port 1719<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug1: Client protocol version 2.0; client software version OpenSSH_3.9p1<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug1: match: OpenSSH_3.9p1 pat OpenSSH*<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug1: Enabling compatibility mode for protocol 2.0<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug1: Local version string SSH-1.99-OpenSSH_3.6.1p2<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug2: Network child is on pid 17123<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: preauth child monitor started<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: monitor_read: checking request 0<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_answer_moduli: got parameters: 1024 1024 8192<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_send entering: type 1<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug2: monitor_read: 0 used once, disabling now<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: monitor_read: checking request 4<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_answer_sign<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_answer_sign: signature 0x5576d0(143)<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_send entering: type 5<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug2: monitor_read: 4 used once, disabling now<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: monitor_read: checking request 6<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_answer_pwnamallow<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_send entering: type 7<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug2: monitor_read: 6 used once, disabling now<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: monitor_read: checking request 41<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug1: Starting up PAM with username "nx"<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: Trying to reverse map address xx.xx.111.212.<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug1: PAM setting rhost to "xx.xx.111.212"<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug2: monitor_read: 41 used once, disabling now<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: monitor_read: checking request 3<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug3: mm_answer_authserv: service=ssh-connection, style=<br>
Oct 4 18:28:31 lvnc3 sshd[17122]: debug2: monitor_read: 3 used once, disabling now<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: monitor_read: checking request 20<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_answer_keyallowed entering<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_answer_keyallowed: key_from_blob: 0x558a80<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: temporarily_use_uid: 499/499 (e=0/0)<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: trying public key file /var/lib/nxserver/home/.ssh/authorized_keys<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: restore_uid: 0/0<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: temporarily_use_uid: 499/499 (e=0/0)<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: trying public key file /var/lib/nxserver/home/.ssh/authorized_keys2<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: secure_filename: checking '/var/lib/nxserver/home/.ssh'<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: secure_filename: checking '/var/lib/nxserver/home'<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: secure_filename: terminating check at '/var/lib/nxserver/home'<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug2: key_type_from_name:
unknown key type
'no-port-forwarding,no-X11-forwarding,no-agent-forwarding,command="/usr/bin/nxserver"'<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: key_read: missing keytype<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug2: user_key_allowed: check
options:
'no-port-forwarding,no-X11-forwarding,no-agent-forwarding,command="/usr/bin/nxserver"
ssh-dss
AAAAB3NzaC1kc3MAAACBALZ9TTZB1jYT/Ebu9CVekLgsnW9k1RRshqz8scX1vSXlbgEnL1CInwlzgqCu1OT+zEsWFkxZn8Fd/GsE8TBjcgWE3DfqZA2l7RMV2w48p5Lg3EGypvW690uKiCl/6nYORSipIMz1vdlHyB1OdDa9HwhrsZmOr5c+s9ADpDXJTbIZAAAAFQC0QFLQvoHHeQbeB5oBxIapltmrKQAAAIAqgsAKKm5RlAnZNje04d8zx4tziHC8Q1gTPmG9Qto3EZSSDtCgKYz0HdXEibj8oo4Y++L42KC953TFAmKHQubT3uYeXzhTFfFU48GFlcPUKMidnUpFUBg/4QhouKjhfZ/5Doa1I
<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: matching key found: file /var/lib/nxserver/home/.ssh/authorized_keys2, line 1<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: Found matching DSA key: ba:11:8f:99:b5:08:c9:0d:9e:38:7a:74:08:f8:86:e9<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: restore_uid: 0/0<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_answer_keyallowed: key 0x558a80 is allowed<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_append_debug: Appending debug messages for child<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_send entering: type 21<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: monitor_read: checking request 22<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: ssh_dss_verify: signature correct<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_answer_keyverify: key 0x5628e0 signature verified<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_send entering: type 23<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug2: pam_acct_mgmt() = 0<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: Accepted publickey for nx from <a href="http://143.103.111.212">143.103.111.212</a> port 1719 ssh2<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug1: monitor_child_preauth: nx has been authenticated by privileged process<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_get_keystate: Waiting for new keys<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_receive_expect entering: type 24<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_newkeys_from_blob: 0x562a60(122)<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug2: mac_init: found hmac-md5<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_get_keystate: Waiting for second key<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_newkeys_from_blob: 0x562a60(122)<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug2: mac_init: found hmac-md5<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_get_keystate: Getting compression state<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_get_keystate: Getting Network I/O buffers<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_share_sync: Share sync<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_share_sync: Share sync end<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug2: User child is on pid 17125<br>
Oct 4 18:28:32 lvnc3 sshd[17122]: debug3: mm_request_receive entering<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: PAM establishing creds<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: PAM establishing creds<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: permanently_set_uid: 499/499<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug2: set_newkeys: mode 0<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug2: set_newkeys: mode 1<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: Entering interactive session for SSH2.<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: fd 5 setting O_NONBLOCK<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: fd 6 setting O_NONBLOCK<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: server_init_dispatch_20<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: server_input_channel_open: ctype session rchan 0 win 131072 max 32768<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: input_session_request<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: channel 0: new [server-session]<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: session_new: init<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: session_new: session 0<br>
Oct 4 18:28:32 lvnc3 sshd[17125]: debug1: session_open: channel 0<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: session_open: session 0: link with channel 0<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: server_input_channel_open: confirm session<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: server_input_channel_req: channel 0 request x11-req reply 0<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: session_by_channel: session 0 channel 0<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: session_input_channel_req: session 0 req x11-req<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: server_input_channel_req: channel 0 request shell reply 0<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: session_by_channel: session 0 channel 0<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: session_input_channel_req: session 0 req shell<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: Forced command '/usr/bin/nxserver'<br>
Oct 4 18:28:33 lvnc3 sshd[17127]: debug3: channel_close_fds: channel 0: r -1 w -1 e -1<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: fd 8 setting O_NONBLOCK<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug2: fd 8 is O_NONBLOCK<br>
Oct 4 18:28:33 lvnc3 sshd[17125]: debug1: fd 10 setting O_NONBLOCK<br>
Oct 4 18:28:33 lvnc3 sshd[16980]: debug1: Forked child 17160.<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: Connection from <a href="http://127.0.0.1">127.0.0.1</a> port 53744<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug1: Client protocol version 2.0; client software version OpenSSH_3.6.1p2<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug1: match: OpenSSH_3.6.1p2 pat OpenSSH*<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug1: Enabling compatibility mode for protocol 2.0<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug1: Local version string SSH-1.99-OpenSSH_3.6.1p2<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug2: Network child is on pid 17161<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: preauth child monitor started<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: monitor_read: checking request 0<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_answer_moduli: got parameters: 1024 2048 8192<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_request_send entering: type 1<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug2: monitor_read: 0 used once, disabling now<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: monitor_read: checking request 4<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_answer_sign<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_answer_sign: signature 0x556430(143)<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_request_send entering: type 5<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug2: monitor_read: 4 used once, disabling now<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:33 lvnc3 sshd[17160]: debug3: monitor_read: checking request 6<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_answer_pwnamallow<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_send entering: type 7<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: monitor_read: 6 used once, disabling now<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: monitor_read: checking request 41<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug1: Starting up PAM with username "efernandes"<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: Trying to reverse map address <a href="http://127.0.0.1">127.0.0.1</a>.<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug1: PAM setting rhost to "localhost.localdomain"<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: monitor_read: 41 used once, disabling now<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: monitor_read: checking request 3<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_answer_authserv: service=ssh-connection, style=<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: monitor_read: 3 used once, disabling now<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: monitor_read: checking request 10<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug1: PAM password authentication accepted for joeuser<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_answer_authpassword: sending result 1<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_send entering: type 11<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: pam_acct_mgmt() = 0<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: Accepted password for joeuser from <a href="http://127.0.0.1">127.0.0.1</a> port 53744 ssh2<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug1: monitor_child_preauth: joeuser has been authenticated by privileged process<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_get_keystate: Waiting for new keys<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_receive_expect entering: type 24<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_newkeys_from_blob: 0x564fa0(122)<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: mac_init: found hmac-md5<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_get_keystate: Waiting for second key<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_newkeys_from_blob: 0x564fa0(122)<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: mac_init: found hmac-md5<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_get_keystate: Getting compression state<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_get_keystate: Getting Network I/O buffers<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_share_sync: Share sync<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_share_sync: Share sync end<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug2: User child is on pid 17164<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: PAM establishing creds<br>
Oct 4 18:28:34 lvnc3 sshd[17160]: debug3: mm_request_receive entering<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: PAM establishing creds<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: permanently_set_uid: 11338/100<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug2: set_newkeys: mode 0<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug2: set_newkeys: mode 1<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: Entering interactive session for SSH2.<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: fd 5 setting O_NONBLOCK<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: fd 6 setting O_NONBLOCK<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: server_init_dispatch_20<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: server_input_channel_open: ctype session rchan 0 win 131072 max 32768<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: input_session_request<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: channel 0: new [server-session]<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: session_new: init<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: session_new: session 0<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: session_open: channel 0<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: session_open: session 0: link with channel 0<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: server_input_channel_open: confirm session<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: server_input_channel_req: channel 0 request exec reply 0<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: session_by_channel: session 0 channel 0<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: session_input_channel_req: session 0 req exec<br>
Oct 4 18:28:34 lvnc3 sshd[17165]: debug3: channel_close_fds: channel 0: r -1 w -1 e -1<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug1: fd 8 setting O_NONBLOCK<br>
Oct 4 18:28:34 lvnc3 sshd[17164]: debug2: fd 8 is O_NONBLOCK<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: fd 10 setting O_NONBLOCK<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: Received SIGCHLD.<br>
Oct 4 18:28:35 lvnc3 sshd[17164]Connection closed by <a href="http://127.0.0.1">127.0.0.1</a><br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: session_by_pid: pid 17165<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: session_exit_message: session 0 channel 0 pid 17165<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: channel 0: request exit-status<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: session_exit_message: release channel 0<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: channel 0: write failed<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: channel 0: close_write<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: channel 0: output open -> closed<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: session_close: session 0 pid 17165<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: channel_free: channel 0: server-session, nchannels 1<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug3: channel_free: status:
The following connections are open:\015 #0 server-session
(t4 r0 i0/0 o3/0 fd 8/8)\015 <br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug3: channel_close_fds: channel 0: r 8 w 8 e 10<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug1: krb5_cleanup_proc called<br>
Oct 4 18:28:35 lvnc3 sshd[17164]: Closing connection to <a href="http://127.0.0.1">127.0.0.1</a><br>
Oct 4 18:28:35 lvnc3 sshd[17164]: debug3: mm_request_send entering: type 42<br>
Oct 4 18:28:35 lvnc3 sshd[17160]: debug3: monitor_read: checking request 42<br>
Oct 4 18:28:35 lvnc3 sshd[17160]: debug3: mm_answer_term: tearing down sessions<br>
Oct 4 18:28:35 lvnc3 sshd[16980]: debug1: Forked child 17359.<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: Connection from <a href="http://127.0.0.1">127.0.0.1</a> port 53748<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug1: Client protocol version 2.0; client software version OpenSSH_3.6.1p2<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug1: match: OpenSSH_3.6.1p2 pat OpenSSH*<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug1: Enabling compatibility mode for protocol 2.0<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug1: Local version string SSH-1.99-OpenSSH_3.6.1p2<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug2: Network child is on pid 17360<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: preauth child monitor started<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: monitor_read: checking request 0<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_answer_moduli: got parameters: 1024 2048 8192<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_request_send entering: type 1<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug2: monitor_read: 0 used once, disabling now<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: monitor_read: checking request 4<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_answer_sign<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_answer_sign: signature 0x556430(143)<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_request_send entering: type 5<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug2: monitor_read: 4 used once, disabling now<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: monitor_read: checking request 6<br>
Oct 4 18:28:35 lvnc3 sshd[17359]: debug3: mm_answer_pwnamallow<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_answer_pwnamallow: sending MONITOR_ANS_PWNAM: 1<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_send entering: type 7<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: monitor_read: 6 used once, disabling now<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: monitor_read: checking request 41<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug1: Starting up PAM with username "joeuser"<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: Trying to reverse map address <a href="http://127.0.0.1">127.0.0.1</a>.<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug1: PAM setting rhost to "localhost.localdomain"<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: monitor_read: 41 used once, disabling now<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: monitor_read: checking request 3<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_answer_authserv: service=ssh-connection, style=<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: monitor_read: 3 used once, disabling now<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: monitor_read: checking request 10<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug1: PAM password authentication accepted for joeuser<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_answer_authpassword: sending result 1<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_send entering: type 11<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: pam_acct_mgmt() = 0<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: Accepted password for efernandes from <a href="http://127.0.0.1">127.0.0.1</a> port 53748 ssh2<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug1: monitor_child_preauth: joeuser has been authenticated by privileged process<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_get_keystate: Waiting for new keys<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_receive_expect entering: type 24<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_newkeys_from_blob: 0x564fa0(122)<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: mac_init: found hmac-md5<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_get_keystate: Waiting for second key<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_newkeys_from_blob: 0x564fa0(122)<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: mac_init: found hmac-md5<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_get_keystate: Getting compression state<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_get_keystate: Getting Network I/O buffers<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_share_sync: Share sync<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_share_sync: Share sync end<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug2: User child is on pid 17363<br>
Oct 4 18:28:36 lvnc3 sshd[17359]: debug3: mm_request_receive entering<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: PAM establishing creds<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: PAM establishing creds<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: permanently_set_uid: 11338/100<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug2: set_newkeys: mode 0<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug2: set_newkeys: mode 1<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: Entering interactive session for SSH2.<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: fd 5 setting O_NONBLOCK<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: fd 6 setting O_NONBLOCK<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: server_init_dispatch_20<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: server_input_channel_open: ctype session rchan 0 win 131072 max 32768<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: input_session_request<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: channel 0: new [server-session]<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: session_new: init<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: session_new: session 0<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: session_open: channel 0<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: session_open: session 0: link with channel 0<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: server_input_channel_open: confirm session<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: server_input_channel_req: channel 0 request exec reply 0<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: session_by_channel: session 0 channel 0<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: session_input_channel_req: session 0 req exec<br>
Oct 4 18:28:36 lvnc3 sshd[17364]: debug3: channel_close_fds: channel 0: r -1 w -1 e -1<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: fd 8 setting O_NONBLOCK<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug2: fd 8 is O_NONBLOCK<br>
Oct 4 18:28:36 lvnc3 sshd[17363]: debug1: fd 10 setting O_NONBLOCK<br>
Oct 4 18:28:38 lvnc3 sshd[17125]: debug2: channel 0: read 101 from efd 10<br>
Oct 4 18:28:38 lvnc3 sshd[17125]: debug2: channel 0: rwin 129686 elen 101 euse 1<br>
Oct 4 18:28:38 lvnc3 sshd[17125]: debug2: channel 0: sent ext data 101<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: Received SIGCHLD.<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: Connection closed by <a href="http://127.0.0.1">127.0.0.1</a><br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: session_by_pid: pid 17364<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: session_exit_message: session 0 channel 0 pid 17364<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: channel 0: request exit-status<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: session_exit_message: release channel 0<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: channel 0: write failed<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: channel 0: close_write<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: channel 0: output open -> closed<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: session_close: session 0 pid 17364<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: channel_free: channel 0: server-session, nchannels 1<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug3: channel_free: status:
The following connections are open:\015 #0 server-session
(t4 r0 i0/0 o3/0 fd 8/8)\015 <br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug3: channel_close_fds: channel 0: r 8 w 8 e 10<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug1: krb5_cleanup_proc called<br>
Oct 4 18:28:38 lvnc3 sshd[17363]: Closing connection to <a href="http://127.0.0.1">127.0.0.1</a><br>
Oct 4 18:28:38 lvnc3 sshd[17363]: debug3: mm_request_send entering: type 42<br>
Oct 4 18:28:38 lvnc3 sshd[17359]: debug3: monitor_read: checking request 42<br>
Oct 4 18:28:38 lvnc3 sshd[17359]: debug3: mm_answer_term: tearing down sessions<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: Connection closed by xx.xx.111.212<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug1: channel_free: channel 0: server-session, nchannels 1<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug3: channel_free: status:
The following connections are open:\015 #0 server-session
(t4 r0 i0/0 o0/0 fd 8/8)\015 <br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug3: channel_close_fds: channel 0: r 8 w 8 e 10<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug1: session_close: session 0 pid 17127<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug1: krb5_cleanup_proc called<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: Closing connection to xx.xx.111.212<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug1: Received SIGCHLD.<br>
Oct 4 18:29:37 lvnc3 sshd[17125]: debug3: mm_request_send entering: type 42<br>
Oct 4 18:29:37 lvnc3 sshd[17122]: debug3: monitor_read: checking request 42<br>
Oct 4 18:29:37 lvnc3 sshd[17122]: debug3: mm_answer_term: tearing down sessions<br>
<br>
<br>
Is there somthing wrong with the RPM's that I installed?<br>
<br>
Thanks<br>
\Tom<br>
<br>
<br><br><div><span class="gmail_quote">On 9/29/06, <b class="gmail_sendername">Rick Stout</b> <<a href="mailto:zipsonic@gmail.com" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">zipsonic@gmail.com
</a>> wrote:</span><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;">
<br>> The client is a WinXP box.<br>> I imported the /etc/nxserver/client.id_dsa.key to the NX client, and<br>> tried connecting.<br>> Still no success. The same error:<br>><br><br>On the client, you clicked Configure > Key... > Import and selected the
<br>client.id_dsa.key that you copied from your server?<br><br>> "Server not installed or client access diabled"<br>><br>> And when I click on details I see:<br>> NX> 203 NXSSH running with pid: 924
<br>> NX> 285 Enabling check on switch command<br>> NX> 285 Enabling skip of SSH config files<br>> NX> 285 Setting the preferred NX options<br>> NX> 200 Connected to address: xx.xx.xx.xx on port: 22
<br>> NX> 202 Authenticating user: nx<br>> NX> 208 Using auth method: publickey<br>> NX> 204 Authentication failed.<br>><br>I would look into adding Loglevel DEBUG3 to your sshd_config file and<br>restarting the sshd daemon. Try connecting again and then examine
<br>/var/log/secure.log to find out what is failing. This error means that<br>sshd is not accepting the key pair. I have also seen that some<br>sshd_config's may have this line which can screw things up:<br><br>Authorizedkeysfile .ssh/authorized_keys
<br><br>Since Freenx uses authorized_keys2, try either commenting out that line<br>or adding a 2 to the end.<br><br>> The instructions at the location below is for a ver 1.50 client. The<br>> current downlod available from nomachine is
2.0 which I am using.<br>> <a href="http://fedoranews.org/contributors/rick_stout/freenx" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">http://fedoranews.org/contributors/rick_stout/freenx</a>
<br><br>If you look at the screen shots, the 1.5 client is almost identical to
<br>the 2.0 client, and the setup *is* the same.<br><br>><br>> Could this be a problem?<br>> There is no logs getting updated in /var/log/nxserver.log<br>> What else could be so wrong in my install?<br>><br>
<br>nxserver.log will only fill if you have set the NX_LOG_LEVEL log setting<br>in /etc/nxserver/node.conf.<br><br>Regards,<br><br>Rick Sout<br><br>________________________________________________________________<br> Were you helped on this list with your FreeNX problem?
<br> Then please write up the solution in the FreeNX Wiki/FAQ:<br> <a href="http://openfacts.berlios.de/index-en.phtml?title=FreeNX_FAQ" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">http://openfacts.berlios.de/index-en.phtml?title=FreeNX_FAQ
</a><br> Don't forget to check the NX Knowledge Base:
<br> <a href="http://www.nomachine.com/kb/" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">http://www.nomachine.com/kb/</a><br><br>________________________________________________________________
<br> FreeNX-kNX mailing list --- <a href="mailto:FreeNX-kNX@kde.org" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">
FreeNX-kNX@kde.org</a><br> <a href="https://mail.kde.org/mailman/listinfo/freenx-knx" target="_blank" onclick="return top.js.OpenExtLink(window,event,this)">https://mail.kde.org/mailman/listinfo/freenx-knx</a><br>________________________________________________________________
<br></blockquote>
</div><br>