Hello,
I'm trying to get neatx running on a Gentoo PowerPC 64 bit
installation, an (ex) Mac G5. Neatx and dependencies installed fine,
however when trying to open an NX session via SSH apparently neatx (or
nxagent?) hangs:
Nov 17 12:17:05 gonzo sshd[24357]: Accepted publickey for nx from
131.176.40.75 port 3291 ssh2
Nov 17 12:17:05 gonzo sshd[24357]: pam_unix(sshd:session): session
opened for user nx by (uid=0)
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'HELLO NXSERVER - Version 3.3.0 - GPL\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 105 '
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:227 <<<
'hello NXCLIENT - Version 3.3.0\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'Hello nxclient - version 3.3.0\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG nxserver_login:111
Got client protocol version 3030000 ('3.3.0'), want 3030000
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 134 Accepted protocol: 3.3.0\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 105 '
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:227 <<<
'SET SHELL_MODE SHELL\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'Set SHELL_MODE: SHELL\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 105 '
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:227 <<<
'SET AUTH_MODE PASSWORD\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'Set AUTH_MODE: PASSWORD\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 105 '
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:227 <<<
'login\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'Login\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 101 User: '
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:227 <<<
'biscani\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'NX> 102 Password: '
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:225 <<<
[hidden]
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG protocol:172 >>>
'**********\n'
Nov 17 12:17:05 gonzo nxserver-login[24366]: INFO nxserver_login:249
Trying login for user 'biscani' using auth method 'su'
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG auth:50
Authenticating as 'biscani', running ['/usr/lib64/neatx/nxserver', '--
proto=3030000', '--', 'biscani']
Nov 17 12:17:05 gonzo nxserver-login[24366]: DEBUG auth:53 Auth
command ['/usr/lib64/neatx/ttysetup', '/bin/su', 'biscani', '-c', 'cd
&& /usr/lib64/neatx/nxserver --proto=3030000 -- biscani']
Nov 17 12:17:05 gonzo su[24367]: Successful su for biscani by nx
Nov 17 12:17:05 gonzo su[24367]: + /dev/pts/2 nx:biscani
Nov 17 12:17:05 gonzo su[24367]: pam_unix(su:session): session opened
for user biscani by (uid=105)
Nov 17 12:17:06 gonzo nxserver[24370]: INFO nxserver:689 Starting
nxserver for user biscani
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 103
Welcome to: gonzo user: biscani\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 105
'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:227 <<< '
listsession --user="biscani" --status="suspended,running" --
geometry="1280x1024x32+render" --type="unix-kde"\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>>
'Listsession --user="biscani" --status="suspended,running" --
geometry="1280x1024x32+render" --type="unix-kde"\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG nxserver:315 Looking for
sessions with types=['unix-kde'], state=['suspended', 'running']
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session 032318FA3B77ED9DAECA101C6643C8D1 from /var/lib/neatx/sessions/
032318FA3B77ED9DAECA101C6643C8D1/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session 3978952EF394248A005C31458E9A0E25 from /var/lib/neatx/sessions/
3978952EF394248A005C31458E9A0E25/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session 46D31B0E349404A72E40C276794121D8 from /var/lib/neatx/sessions/
46D31B0E349404A72E40C276794121D8/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session 68D096FCABF7304CCDA75DD63EAAFF46 from /var/lib/neatx/sessions/
68D096FCABF7304CCDA75DD63EAAFF46/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session A7C9501573B6FFDC211A8A96D22D5A2A from /var/lib/neatx/sessions/
A7C9501573B6FFDC211A8A96D22D5A2A/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session BE578D214A44FA1C5DEF19A103A04DA2 from /var/lib/neatx/sessions/
BE578D214A44FA1C5DEF19A103A04DA2/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session C04F4F48AE729AD2FDAD5A099E230DAF from /var/lib/neatx/sessions/
C04F4F48AE729AD2FDAD5A099E230DAF/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session C42C0B72F9AD6E35CE22E30593F54346 from /var/lib/neatx/sessions/
C42C0B72F9AD6E35CE22E30593F54346/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session D795161F1B669CB9A65856DFFC9577B1 from /var/lib/neatx/sessions/
D795161F1B669CB9A65856DFFC9577B1/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session DCCFB5DAF884D41B84AE55FC8A283B1B from /var/lib/neatx/sessions/
DCCFB5DAF884D41B84AE55FC8A283B1B/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG session:248 Loading
session E1DCC424BBD028A46FDA6ABC208EC563 from /var/lib/neatx/sessions/
E1DCC424BBD028A46FDA6ABC208EC563/neatx.data
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> "NX> 127
Session list of user 'biscani':\n"
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'Display
Type Session ID Options Depth
Screen Status Session Name\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> '-------
---------------- -------------------------------- -------- -----
-------------- ----------- ------------------------------\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> '\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 148
Server capacity: not reached for user: biscani\n'
Nov 17 12:17:06 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 105
'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:227 <<<
'startsession --link="wan" --backingstore="1" --encryption="1" --
cache="16M" --images="64M" --shmem="1" --shpix="1" --strict="0" --
composite="1" --media="0" --session="gonzo" --type="unix-kde" --
geometry="1274x969" --client="winnt" --keyboard="pc102/us" --
screeninfo="1274x969x32+render"\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'Start
session with: --link="wan" --backingstore="1" --encryption="1" --
cache="16M" --images="64M" --shmem="1" --shpix="1" --strict="0" --
composite="1" --media="0" --session="gonzo" --type="unix-kde" --
geometry="1274x969" --client="winnt" --keyboard="pc102/us" --
screeninfo="1274x969x32+render"\n'
Nov 17 12:17:09 gonzo nxserver[24370]: INFO nxserver:377 Starting new
session 'F91D195FC56539FDA635C19C09B4BA98'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG nxserver:645 Connecting
to nxnode
Nov 17 12:17:09 gonzo nxserver[24370]: INFO node:514 Connecting to '/
var/lib/neatx/sessions/F91D195FC56539FDA635C19C09B4BA98/nxnode.sock'
Nov 17 12:17:09 gonzo nxnode-wrapper[24373]: Started
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG nxnode:301 Starting
mainloop
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG nxserver:385 Sending
startsession command
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG node:551 Sending request:
{'cmd': 'start', 'args': {'composite': '1', 'encryption': '1',
'cache': '16M', 'geometry': '1274x969', 'client': 'winnt', 'strict':
'0', 'screeninfo': '1274x969x32+render', 'session': 'gonzo', 'link':
'wan', 'shmem': '1', 'media': '0', 'images': '64M', 'keyboard': 'pc102/
us', 'type': 'unix-kde', 'shpix': '1', 'backingstore': '1'}}
Nov 17 12:17:09 gonzo nxnode[24375]: INFO nxnode:266 Connection
established
Nov 17 12:17:09 gonzo nxnode[24375]: INFO nxnode:81 Received request:
'start', {'session': 'gonzo', 'strict': '0', 'composite': '1',
'encryption': '1', 'cache': '16M', 'geometry': '1274x969',
'screeninfo': '1274x969x32+render', 'client': 'winnt', 'link': 'wan',
'shmem': '1', 'media': '0', 'images': '64M', 'keyboard': 'pc102/us',
'type': 'unix-kde', 'shpix': '1', 'backingstore': '1'}
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG node:97 Trying display
number 510
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG node:106 Display number 510
appears to be unused
Nov 17 12:17:09 gonzo nxnode[24375]: INFO node:290 Starting xauth for
[(':510', 'FC602AC6BA52FAB84CA622521D11305E'), ('localhost:510',
'FC602AC6BA52FAB84CA622521D11305E')]
Nov 17 12:17:09 gonzo nxnode[24375]: INFO daemon:491 Starting program,
executable=None, args=['/usr/bin/xauth', '-f', '/var/lib/neatx/
sessions/F91D195FC56539FDA635C19C09B4BA98/authority']
Nov 17 12:17:09 gonzo nxnode[24375]: INFO daemon:519 Child /usr/bin/
xauth[24378] started
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/xauth
[24378] stderr: /usr/bin/xauth: creating new authority file /var/lib/
neatx/sessions/F91D195FC56539FDA635C19C09B4BA98/authority
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG node:558 Received
response: {'result': True, 'success': True}
Nov 17 12:17:09 gonzo nxserver[24370]: INFO nxserver:594 Waiting for
session 'F91D195FC56539FDA635C19C09B4BA98' to achieve waiting status
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG session:248 Loading
session F91D195FC56539FDA635C19C09B4BA98 from /var/lib/neatx/sessions/
F91D195FC56539FDA635C19C09B4BA98/neatx.data
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:560 __CheckExit /usr/
bin/xauth[24378] stdin:True stdout:True stderr:True exit:0
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:580 /usr/bin/xauth
[24378] exited cleanly
Nov 17 12:17:09 gonzo nxnode[24375]: INFO node:325 Starting nxagent
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG agent:233 Display for
nxagent: 'nx/nx,options=/var/lib/neatx/sessions/
F91D195FC56539FDA635C19C09B4BA98/options:510'
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG agent:620 Writing session
options 'nx/nx,product=Neatx-
GPL,shpix=1,clipboard=both,render=1,composite=1,cache=16M,geometry=1274x969,accept=127.0.0.1,client=winnt,strict=0,cleanup=0,cookie=FC602AC6BA52FAB84CA622521D11305E,resize=0,keyboard=pc102/
us,images=64M,link=wan,type=kde,id=gonzo-510-
F91D195FC56539FDA635C19C09B4BA98,backingstore=1,shmem=1:510\n' to /var/
lib/neatx/sessions/F91D195FC56539FDA635C19C09B4BA98/options
Nov 17 12:17:09 gonzo nxnode[24375]: INFO daemon:491 Starting program,
executable=None, args=['/usr/bin/nxagent', '-D', '-name', 'Neatx -
biscani@gonzo:510 - gonzo', '-options', '/var/lib/neatx/sessions/
F91D195FC56539FDA635C19C09B4BA98/options', '-nolisten', 'tcp', ':510']
Nov 17 12:17:09 gonzo nxnode[24375]: INFO daemon:519 Child /usr/bin/
nxagent[24379] started
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr:
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: NXAGENT - Version 3.4.0
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr:
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Copyright (C) 2001, 2007 NoMachine.
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: See
http://www.nomachine.com/ for more information.
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr:
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Info: Agent running with pid '24379'.
Nov 17 12:17:09 gonzo nxnode[24375]: INFO agent:346 Matched info
agent_pid, PID 24379
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Session: Starting session at 'Tue Nov 17 12:17:09
2009'.
Nov 17 12:17:09 gonzo nxnode[24375]: INFO agent:407 Nxagent changed
status from 'created' to 'starting'
Nov 17 12:17:09 gonzo nxnode[24375]: INFO node:366 Starting xrdb
Nov 17 12:17:09 gonzo nxnode[24375]: INFO daemon:491 Starting program,
executable=None, args=['/usr/bin/xrdb', '-merge']
Nov 17 12:17:09 gonzo nxnode[24375]: INFO daemon:519 Child /usr/bin/
xrdb[24381] started
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG session:288 Writing session
'F91D195FC56539FDA635C19C09B4BA98' to '/var/lib/neatx/sessions/
F91D195FC56539FDA635C19C09B4BA98/neatx.data'
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Info: Proxy running in server mode with pid '24379'.
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Info: Waiting for connection from '127.0.0.1' on port
'4510'.
Nov 17 12:17:09 gonzo nxnode[24375]: INFO agent:407 Nxagent changed
status from 'starting' to 'waiting'
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG agent:443 Setting session
port to 4510
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG session:288 Writing session
'F91D195FC56539FDA635C19C09B4BA98' to '/var/lib/neatx/sessions/
F91D195FC56539FDA635C19C09B4BA98/neatx.data'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG session:248 Loading
session F91D195FC56539FDA635C19C09B4BA98 from /var/lib/neatx/sessions/
F91D195FC56539FDA635C19C09B4BA98/neatx.data
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 700
Session id: gonzo-510-F91D195FC56539FDA635C19C09B4BA98\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 705
Session display: 510\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 703
Session type: unix-kde\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 701
Proxy cookie: FC602AC6BA52FAB84CA622521D11305E\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 706
Agent cookie: FC602AC6BA52FAB84CA622521D11305E\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 704
Session cache: unix-kde\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 728
Session caption: Neatx - biscani@gonzo:510 - gonzo\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 707
SSL tunneling: 1\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 708
Subscription: GPL\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 710
Session status: running\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 105
'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:227 <<< 'bye\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'Bye\n'
Nov 17 12:17:09 gonzo nxserver[24370]: DEBUG protocol:172 >>> 'NX> 999
Bye.\n'
Nov 17 12:17:09 gonzo nxserver[24370]: INFO nxserver:714 Starting
netcat (localhost:4510)
Nov 17 12:17:09 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Warning: Refusing connection from '0.0.0.0'.
Nov 17 12:17:09 gonzo nxnode[24375]: WARNING agent:381 Agent warning:
Refusing connection from '0.0.0.0'.
Nov 17 12:17:14 gonzo nxserver[24370]: ERROR utils:865 netcat stderr:
read(net): Connection reset by peer
Nov 17 12:17:14 gonzo nxserver[24370]: ERROR nxserver:731 Netcat
failed (code=1, signal=None)
Nov 17 12:17:14 gonzo su[24367]: pam_unix(su:session): session closed
for user biscani
Nov 17 12:17:59 gonzo nxnode[24375]: DEBUG daemon:461 /usr/bin/nxagent
[24379] stderr: Error: Connection with remote host '127.0.0.1' could
not be established.
In particular, the suspicious line seems to be: "Warning: Refusing
connection from '0.0.0.0'.". I've tried fiddling around a bit with
netcat, however I'm not really sure of what I'm doing :)
Is anyone able to shed some light on this?
Thanks and best regards,
Francesco Biscani.