Neatx errors on PowerPC 64 bit

12 views
Skip to first unread message

bluescarni

unread,
Nov 17, 2009, 6:27:07 AM11/17/09
to neatx
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.

Stephen Shirley

unread,
Dec 1, 2009, 4:14:11 PM12/1/09
to ne...@googlegroups.com
On Tue, Nov 17, 2009 at 12:27, bluescarni <blues...@gmail.com> wrote:
...
> 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 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'.".

Good catch. So, nxagent has been told to only accept connections from
127.0.0.1, but when netcat connects, nxagent sees it as connecting
from 0.0.0.0. This is a networking issue, i'm not sure what the cause
is i'm afraid. As a hack, you could change this line in lib/agent.py:

"accept": "127.0.0.1",

to

"accept": "0.0.0.0",

but that's a hack, and decreases security a bit.

Steve
--
"You are technically correct, the best kind of correct."
- Bureaucrat 1.0, Futurama

bluescarni

unread,
Dec 3, 2009, 6:50:57 AM12/3/09
to neatx
Hi Stephen,

On Dec 1, 10:14 pm, Stephen Shirley <kor...@gmail.com> wrote:
> Good catch. So, nxagent has been told to only accept connections from
> 127.0.0.1, but when netcat connects, nxagent sees it as connecting
> from 0.0.0.0. This is a networking issue, i'm not sure what the cause
> is i'm afraid. As a hack, you could change this line in lib/agent.py:
>
>        "accept": "127.0.0.1",
>
> to
>
>        "accept": "0.0.0.0",
>
> but that's a hack, and decreases security a bit.

I've tried this, but it fails with an "unknown host" error:

Dec 3 12:47:40 devochka nxnode[20349]: INFO daemon:491 Starting
program, executable=None, args=['/usr/bin/xrdb', '-merge']
Dec 3 12:47:40 devochka nxnode[20349]: INFO daemon:519 Child /usr/bin/
xrdb[20356] started
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG session:288 Writing
session 'FE192F2F7AD9D5CA4157D266640B51A7' to '/var/lib/neatx/sessions/
FE192F2F7AD9D5CA4157D266640B51A7/neatx.data'
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/
nxagent[20353] stderr: Info: Proxy running in server mode with pid
'20353'.
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/
nxagent[20353] stderr: Error: Cannot accept connections from unknown
host '0.0.0.0'.
Dec 3 12:47:40 devochka nxnode[20349]: ERROR agent:376 Agent error:
Cannot accept connections from unknown host '0.0.0.0'.
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/
nxagent[20353] stderr: Error: Aborting session with 'Unable to open
display 'nx/nx,options=/var/lib/neatx/sessions/
FE192F2F7AD9D5CA4157D266640B51A7/options:955''.
Dec 3 12:47:40 devochka nxnode[20349]: ERROR agent:376 Agent error:
Aborting session with 'Unable to open display 'nx/nx,options=/var/lib/
neatx/sessions/FE192F2F7AD9D5CA4157D266640B51A7/options:955''.
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/
nxagent[20353] stderr: Session: Aborting session at 'Thu Dec 3
12:47:40 2009'.
Dec 3 12:47:40 devochka nxnode[20349]: INFO agent:407 Nxagent changed
status from 'starting' to 'terminating'
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG session:288 Writing
session 'FE192F2F7AD9D5CA4157D266640B51A7' to '/var/lib/neatx/sessions/
FE192F2F7AD9D5CA4157D266640B51A7/neatx.data'
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/
nxagent[20353] stderr: Session: Session aborted at 'Thu Dec 3
12:47:40 2009'.
Dec 3 12:47:40 devochka nxnode[20349]: INFO agent:407 Nxagent changed
status from 'terminating' to 'terminated'
Dec 3 12:47:40 devochka nxnode[20349]: INFO agent:456 Nxagent
terminated
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG session:288 Writing
session 'FE192F2F7AD9D5CA4157D266640B51A7' to '/var/lib/neatx/sessions/
FE192F2F7AD9D5CA4157D266640B51A7/neatx.data'
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:560 __CheckExit /
usr/bin/nxagent[20353] stdin:True stdout:True stderr:False exit:256
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/xrdb
[20356] stderr: /usr/bin/xrdb: No such file or directory
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:461 /usr/bin/xrdb
[20356] stderr: /usr/bin/xrdb: Can't open display ':955.0'
Dec 3 12:47:40 devochka nxnode[20349]: DEBUG daemon:560 __CheckExit /
usr/bin/xrdb[20356] stdin:False stdout:True stderr:False exit:256
Dec 3 12:47:40 devochka nxnode-wrapper[20347]: Traceback (most recent
call last):
Dec 3 12:47:40 devochka nxnode-wrapper[20347]: File "/usr/lib64/
python2.6/site-packages/neatx/daemon.py", line 230, in __HandleIO
Dec 3 12:47:40 devochka nxnode-wrapper[20347]: ((cond &
gobject.IO_OUT) and self.__Write(channel))))
Dec 3 12:47:40 devochka nxnode-wrapper[20347]: File "/usr/lib64/
python2.6/site-packages/neatx/daemon.py", line 202, in __Write
Dec 3 12:47:40 devochka nxnode-wrapper[20347]: n = channel.write
(data)
Dec 3 12:47:40 devochka nxnode-wrapper[20347]: glib.GError: Broken
pipe
Dec 3 12:47:40 devochka nxserver[20344]: DEBUG session:248 Loading
session FE192F2F7AD9D5CA4157D266640B51A7 from /var/lib/neatx/sessions/
FE192F2F7AD9D5CA4157D266640B51A7/neatx.data
Dec 3 12:47:40 devochka nxserver[20344]: ERROR nxserver:586 Session
'FE192F2F7AD9D5CA4157D266640B51A7' has status 'terminated'
Dec 3 12:47:40 devochka nxserver[20344]: DEBUG protocol:172 >>> "NX>
500 Error: Session 'FE192F2F7AD9D5CA4157D266640B51A7' has status
'terminated', aborting\n"
Dec 3 12:47:40 devochka nxserver[20344]: DEBUG protocol:172 >>> 'NX>
999 Bye.\n'
Dec 3 12:47:40 devochka nxserver[20344]: DEBUG nxserver:701 No
nxagent port, not starting netcat
Dec 3 12:47:40 devochka su[20338]: pam_unix(su:session): session
closed for user biscani
Dec 3 12:47:40 devochka sshd[20327]: pam_unix(sshd:session): session
closed for user nx
Dec 3 12:47:40 devochka nxserver-login[20336]: DEBUG auth:133 fdcopy
exited (exitstatus=0, signum=None)
Dec 3 12:47:40 devochka nxserver-login[20336]: INFO auth:142 Waiting
for authenticated program to finish
Dec 3 12:47:40 devochka nxserver-login[20336]: DEBUG auth:152
Authenticated program finished (exitstatus=0, signalstatus=None)
Dec 3 12:47:41 devochka nxnode[20349]: DEBUG daemon:596
__CloseEndExit /usr/bin/nxagent[20353] stdin:True stdout:True
stderr:True
Dec 3 12:47:41 devochka nxnode[20349]: DEBUG daemon:560 __CheckExit /
usr/bin/nxagent[20353] stdin:True stdout:True stderr:True exit:256
Dec 3 12:47:41 devochka nxnode[20349]: ERROR daemon:583 /usr/bin/
nxagent[20353] failed (status=1, signal=None)
Dec 3 12:47:41 devochka nxnode[20349]: INFO node:345 nxagent
terminated
Dec 3 12:47:41 devochka nxnode-wrapper[20347]: EOF on stderr, exiting


I've tried using "localhost" and the IP address of eth0 in place of
127.0.0.1 but I get the same errors as in the original post.

>
> Steve


Regards,

Francesco.

Stephen Shirley

unread,
Dec 6, 2009, 2:38:25 PM12/6/09
to ne...@googlegroups.com
On Thu, Dec 3, 2009 at 12:50, bluescarni <blues...@gmail.com> wrote:
...
> I've tried this, but it fails with an "unknown host" error:
...
> I've tried using "localhost" and the IP address of eth0 in place of
> 127.0.0.1 but I get the same errors as in the original post.

Damn. Here's something you can test:

1. Run a copy of netcat, listening on a arbitrary local port:
netcat -lvvp 4455
2. Run another copy of netcat that connects to that port, via localhost:
netcat -vvz 127.0.0.1 4455
3. Paste the output of the first netcat.

Here's what i get:

diamond@asher:~(0:0)$ netcat -lvvp 4455
listening on [any] 4455 ...
connect to [127.0.0.1] from localhost [127.0.0.1] 49695
sent 0, rcvd 0

As you can see, the connection appears to come from 127.0.0.1, which
is what we expect.

bluescarni

unread,
Dec 6, 2009, 4:56:07 PM12/6/09
to neatx
Hi Steve,

On Dec 6, 8:38 pm, Stephen Shirley <kor...@gmail.com> wrote:
> Damn. Here's something you can test:
>
> 1. Run a copy of netcat, listening on a arbitrary local port:
> netcat -lvvp 4455
> 2. Run another copy of netcat that connects to that port, via localhost:
> netcat -vvz 127.0.0.1 4455
> 3. Paste the output of the first netcat.
>
> Here's what i get:
>
> diamond@asher:~(0:0)$ netcat -lvvp 4455
> listening on [any] 4455 ...
> connect to [127.0.0.1] from localhost [127.0.0.1] 49695
>  sent 0, rcvd 0
>
> As you can see, the connection appears to come from 127.0.0.1, which
> is what we expect.

This is what I get:

biscani@devochka ~ $ nc -lvvp 4455
listening on [any] 4455 ...
connect to [127.0.0.1] from devochka.local [127.0.0.1] 44281
sent 0, rcvd 0

The only difference seems to be that in your case it says "from
127.0.0.1", while here it says "from devochka.local". Is this
relevant?

> Steve

Thanks,

Francesco

Stephen Shirley

unread,
Dec 11, 2009, 2:35:06 PM12/11/09
to ne...@googlegroups.com
On Sun, Dec 6, 2009 at 22:56, bluescarni <blues...@gmail.com> wrote:
> biscani@devochka ~ $ nc -lvvp 4455
> listening on [any] 4455 ...
> connect to [127.0.0.1] from devochka.local [127.0.0.1] 44281
>  sent 0, rcvd 0
>
> The only difference seems to be that in your case it says "from
> 127.0.0.1", while here it says "from devochka.local". Is this
> relevant?

Ok, that looks fine. Hmm. Can you paste the contents of your
/etc/hosts file please? Also, can you try changing "localhost" to
"127.0.0.1" here:
http://code.google.com/p/neatx/source/browse/trunk/neatx/lib/app/nxserver.py#703
?

bluescarni

unread,
Jan 27, 2010, 6:29:24 AM1/27/10
to neatx
Hi Steve,

sorry for getting back to you so late.

On Dec 11 2009, 8:35 pm, Stephen Shirley <kor...@gmail.com> wrote:
> Ok, that looks fine. Hmm. Can you paste the contents of your
> /etc/hosts file please?

These are the non-commented lines:

127.0.0.1 devochka.local devochka localhost
::1 localhost


"devochka" is the name of the computer.

> Also, can you try changing "localhost" to

> "127.0.0.1" here:http://code.google.com/p/neatx/source/browse/trunk/neatx/lib/app/nxse...
> ?

I tried this, but still no luck. Log:

Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
700 Session id: devochka.local-21-333EBB28D78895B596A1276063A07DF2\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
705 Session display: 21\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>


703 Session type: unix-kde\n'

Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
701 Proxy cookie: A85E27F984184569C3029B6D80E1C8E9\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
706 Agent cookie: A85E27F984184569C3029B6D80E1C8E9\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>


704 Session cache: unix-kde\n'

Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
728 Session caption: Neatx - bis...@devochka.local:21 - devochka\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>


707 SSL tunneling: 1\n'

Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
708 Subscription: GPL\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>


710 Session status: running\n'

Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
105 '
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:227 <<< 'bye
\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'Bye
\n'
Jan 27 12:28:42 devochka nxserver[6636]: DEBUG protocol:172 >>> 'NX>
999 Bye.\n'
Jan 27 12:28:42 devochka nxserver[6636]: INFO nxserver:714 Starting
netcat (127.0.0.1:4021)
Jan 27 12:28:42 devochka nxnode[6641]: DEBUG daemon:461 /usr/bin/
nxagent[6645] stderr: Warning: Refusing connection from '0.0.0.0'.
Jan 27 12:28:42 devochka nxnode[6641]: WARNING agent:381 Agent


warning: Refusing connection from '0.0.0.0'.

Jan 27 12:28:47 devochka nxserver[6636]: DEBUG nxserver:729 Netcat
exited cleanly

Cheers,

Francesco.

Stephen Shirley

unread,
Feb 11, 2010, 4:27:58 PM2/11/10
to ne...@googlegroups.com
On Wed, Jan 27, 2010 at 12:29, bluescarni <blues...@gmail.com> wrote:
> I tried this, but still no luck. Log:

I'm sorry, i can't see how this can happen (or how neatx could cause
it either). Looking at the NX source code, the error is being printed
by WaitForRemote() in nxcomp/Loop.cpp. I can't see why it would get
things wrong either. I'm afraid this is beyond my power to help, i
wish i could do better.

Reply all
Reply to author
Forward
0 new messages