[FreeNX-kNX] freenx not work with RHEL3 ?
Tom Kavanaugh
tomnaugh at gmail.com
Thu Oct 5 23:28:51 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 following 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:
0 used once, disabling now
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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mail.kde.org/pipermail/freenx-knx/attachments/20061005/5e04a4ca/attachment.html>
More information about the FreeNX-kNX
mailing list