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