Export to GitHub

neatx - issue #4

Unable to open display nx/nx,product=Neatx-GPL...


Posted on Jul 8, 2009 by Helpful Elephant

What steps will reproduce the problem? 1. install svn revision 9 2. install nxclient, nxnode from nomachine 3. try to connect

What is the expected output? What do you see instead? Jul 9 00:07:28 descent nxserver[23036]: INFO Starting nxserver for user descent Jul 9 00:07:31 descent nxserver[23036]: INFO Starting new session '1962CC9F82030674C46E7BA9C3CE54DD' Jul 9 00:07:31 descent nxserver[23036]: INFO Connecting to '/usr/local/ var/lib/neatx/sessions/1962CC9F82030674C46E7BA9C3CE54DD/nxnode.sock' Jul 9 00:07:31 descent nxnode-wrapper[23045]: Started Jul 9 00:07:31 descent nxnode-wrapper[23045]: /usr/lib/python2.6/dist- packages/neatx/session.py:27: DeprecationWarning: the md5 module is deprecated; use hashlib instead Jul 9 00:07:31 descent nxnode-wrapper[23045]: import md5 Jul 9 00:07:31 descent nxnode[23047]: INFO Connection established Jul 9 00:07:31 descent nxnode[23047]: INFO Received request: u'start', {u'geometry': u'1024x768+328+98', u'encryption': u'1', u'cache': u'16M', u'kbtype': u'pc105/us', u'screeninfo': u'1024x768x24+render', u'session': u'local', u'link': u'adsl', u'media': u'0', u'images': u'64M', u'type': u'unix-gnome', u'backingstore': u'1'} Jul 9 00:07:31 descent nxnode[23047]: INFO Starting xauth for [(':849', '5F87DF55524FB95E7BD9D9B2BCAFD505'), ('localhost:849', '5F87DF55524FB95E7BD9D9B2BCAFD505')] Jul 9 00:07:31 descent nxnode[23047]: INFO Starting program, executable=None, args=['/usr/bin/xauth', '-f', '/usr/local/var/lib/neatx/ sessions/1962CC9F82030674C46E7BA9C3CE54DD/authority'] Jul 9 00:07:31 descent nxserver[23036]: INFO Waiting for session '1962CC9F82030674C46E7BA9C3CE54DD' to achieve waiting status Jul 9 00:07:31 descent nxnode[23047]: INFO Starting nxagent Jul 9 00:07:31 descent nxnode[23047]: INFO Starting program, executable=None, args=['/usr/lib/nx/nxagent', '-D', '-name', u'Neatx - descent@descent.temple.stunet.ru:849 - local', '-options', '/usr/local/var/ lib/neatx/sessions/1962CC9F82030674C46E7BA9C3CE54DD/options', '-nolisten', 'tcp', ':849'] Jul 9 00:07:31 descent nxnode[23047]: INFO Matched info agent_pid, PID 23053 Jul 9 00:07:31 descent nxnode[23047]: INFO Nxagent changed status from 'created' to 'starting' Jul 9 00:07:31 descent nxnode[23047]: INFO Starting xrdb Jul 9 00:07:31 descent nxnode[23047]: INFO Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] Jul 9 00:07:31 descent nxnode[23047]: ERROR Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=5F87DF55524FB95E7BD9D9B2BCAFD505,id=descent.temple.stunet.ru-849-1962CC9F82030674C46E7BA9C3CE54DD,shpix=1,strict=0,clipboard=both,shmem=1,type=gnome,cleanup=0,backingstore=1:849''. Jul 9 00:07:31 descent nxnode[23047]: INFO Nxagent changed status from 'starting' to 'terminating' Jul 9 00:07:31 descent nxnode[23047]: INFO Nxagent changed status from 'terminating' to 'terminated' Jul 9 00:07:31 descent nxnode[23047]: INFO Nxagent terminated Jul 9 00:07:31 descent nxnode[23047]: ERROR /usr/lib/nx/nxagent failed (status=1, signal=None) Jul 9 00:07:31 descent nxnode[23047]: INFO nxagent terminated Jul 9 00:07:31 descent nxnode-wrapper[23045]: EOF on stderr, exiting Jul 9 00:07:31 descent nxserver[23036]: ERROR Session u'1962CC9F82030674C46E7BA9C3CE54DD' has status u'terminated' Jul 9 00:07:31 descent nxserver-login[23021]: INFO Waiting for authenticated program to finish Jul 9 00:07:31 descent nxserver-login-wrapper[23017]: EOF on stderr, exiting

What version of the product are you using? On what operating system? ubuntu 9.04 x86_64

Please provide any additional information below. I haven't found the way to fix it yet. Waiting for comments.

Comment #1

Posted on Jul 8, 2009 by Helpful Elephant

Maybe this will help:

In the dir /usr/local/var/lib/neatx/sessions/00EAD27D7090C8E308A5160C1620CA12/C- descent.temple.stunet.ru-294-00EAD27D7090C8E308A5160C1620CA12 i have found a file which says: Couldn't open RGB_DB '/usr/X11R6/lib/X11/rgb'

P.S. Forgot to tell, that I should have made the dir /usr/local/var/lib/neatx/ sessions/ writable by everyone (made it rwxrwxrwt) to bypass the corresponding error.

Comment #2

Posted on Jul 9, 2009 by Happy Rhino

Can you enable debug logging for neatx please? If you look in the neatx config file (only recently documented in INSTALL), you'll find a '# logging = debug' line that needs to be uncommented. Thanks.

Comment #3

Posted on Jul 9, 2009 by Helpful Elephant

this is what I got with debug: Jul 10 02:02:24 descent nxnode[12541]: INFO agent:376 Nxagent changed status from 'created' to 'starting' Jul 10 02:02:24 descent nxnode[12541]: INFO node:366 Starting xrdb Jul 10 02:02:24 descent nxnode[12541]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] Jul 10 02:02:24 descent nxnode[12541]: DEBUG session:283 Writing session 'A4175EA36091E63680A96786E0ED87A2' to '/usr/local/var/lib/neatx/sessions/ A4175EA36091E63680A96786E0ED87A2/neatx.data' Jul 10 02:02:24 descent nxnode[12541]: DEBUG daemon:456 /usr/NX/bin/nxagent stderr: Error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=C7DCD3A422BC111789F5221F04945F5D,id=descent.temple.stunet.ru-854- A4175EA36091E63680A96786E0ED87A2,shpix=1,strict=0,clipboard=both,shmem=1,type=gnome,cleanup=0,backingstore=1:854''. Jul 10 02:02:24 descent nxnode[12541]: ERROR agent:345 Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=C7DCD3A422BC111789F5221F04945F5D,id=descent.temple.stunet.ru-854- A4175EA36091E63680A96786E0ED87A2,shpix=1,strict=0,clipboard=both,shmem=1,type=gnome,cleanup=0,backingstore=1:854''. Jul 10 02:02:24 descent nxnode[12541]: DEBUG daemon:456 /usr/NX/bin/nxagent stderr: Session: Aborting session at 'Fri Jul 10 02:02:24 2009'. Jul 10 02:02:24 descent nxnode[12541]: INFO agent:376 Nxagent changed status from 'starting' to 'terminating' Jul 10 02:02:24 descent nxnode[12541]: DEBUG session:283 Writing session 'A4175EA36091E63680A96786E0ED87A2' to '/usr/local/var/lib/neatx/sessions/ A4175EA36091E63680A96786E0ED87A2/neatx.data' Jul 10 02:02:24 descent nxnode[12541]: DEBUG daemon:456 /usr/NX/bin/nxagent stderr: Session: Session aborted at 'Fri Jul 10 02:02:24 2009'. Jul 10 02:02:24 descent nxnode[12541]: INFO agent:376 Nxagent changed status from 'terminating' to 'terminated' Jul 10 02:02:24 descent nxnode[12541]: INFO agent:425 Nxagent terminated Jul 10 02:02:24 descent nxnode[12541]: DEBUG session:283 Writing session 'A4175EA36091E63680A96786E0ED87A2' to '/usr/local/var/lib/neatx/sessions/ A4175EA36091E63680A96786E0ED87A2/neatx.data' Jul 10 02:02:24 descent nxnode[12541]: DEBUG daemon:456 /usr/bin/xrdb stderr: /usr/ bin/xrdb: Connection reset by peer Jul 10 02:02:24 descent nxnode[12541]: DEBUG daemon:456 /usr/bin/xrdb stderr: /usr/ bin/xrdb: Can't open display ':854.0' Jul 10 02:02:24 descent nxnode-wrapper[12539]: Traceback (most recent call last): Jul 10 02:02:24 descent nxnode[12541]: ERROR daemon:573 /usr/NX/bin/nxagent failed (status=1, signal=None) Jul 10 02:02:24 descent nxnode[12541]: INFO node:345 nxagent terminated Jul 10 02:02:24 descent nxnode-wrapper[12539]: File "/usr/lib/python2.6/dist- packages/neatx/daemon.py", line 225, in __HandleIO Jul 10 02:02:24 descent nxnode-wrapper[12539]: ((cond & gobject.IO_OUT) and self.__Write(channel)))) Jul 10 02:02:24 descent nxnode-wrapper[12539]: File "/usr/lib/python2.6/dist- packages/neatx/daemon.py", line 197, in __Write Jul 10 02:02:24 descent nxnode-wrapper[12539]: n = channel.write(data) Jul 10 02:02:24 descent nxnode-wrapper[12539]: glib.GError: Broken pipe Jul 10 02:02:24 descent nxnode-wrapper[12539]: EOF on stderr, exiting Jul 10 02:02:24 descent nxserver[12520]: DEBUG session:243 Loading session A4175EA36091E63680A96786E0ED87A2 from /usr/local/var/lib/neatx/sessions/ A4175EA36091E63680A96786E0ED87A2/neatx.data Jul 10 02:02:24 descent nxserver[12520]: ERROR nxserver:586 Session u'A4175EA36091E63680A96786E0ED87A2' has status u'terminated' Jul 10 02:02:24 descent nxserver[12520]: DEBUG protocol:172 >>> "NX> 500 Error: Session u'A4175EA36091E63680A96786E0ED87A2' has status u'terminated', aborting\n" Jul 10 02:02:24 descent nxserver[12520]: DEBUG protocol:172 >>> 'NX> 999 Bye.\n' Jul 10 02:02:24 descent nxserver[12520]: DEBUG nxserver:701 No nxagent port, not starting netcat Jul 10 02:02:25 descent nxserver-login[12505]: DEBUG auth:132 fdcopy exited (exitstatus=0, signum=None) Jul 10 02:02:25 descent nxserver-login[12505]: INFO auth:141 Waiting for authenticated program to finish Jul 10 02:02:25 descent nxserver-login[12505]: DEBUG auth:151 Authenticated program finished (exitstatus=0, signalstatus=None) Jul 10 02:02:25 descent nxserver-login-wrapper[12501]: EOF on stderr, exiting

Comment #4

Posted on Jul 14, 2009 by Helpful Elephant

kormat, do you have any comments? It still does not work

Comment #5

Posted on Jul 14, 2009 by Happy Rhino

I'm afraid i'm rather lost as to why nxagent is dying. Can you confirm that /usr/local/var/lib/neatx/sessions/A4175EA36091E63680A96786E0ED87A2/neatx.data exists etc?

Comment #6

Posted on Jul 15, 2009 by Happy Rhino

One thing i just noticed is the DeprecationWarning from your first log. I've sent a patch out for review to fix that, not sure whether it'll make things work for you though.

Comment #7

Posted on Jul 15, 2009 by Helpful Panda

FWIW I appear to be having much the same problem (rvn 15):

Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: INFO agent:377 Nxagent changed status from 'created' to 'starting' Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: INFO node:366 Starting xrdb Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: DEBUG session:283 Writing session '178B1214DCD07718251CD2AB98AE8472' to '/usr/local/var/lib/neatx/sessions/178B1214DCD07718251CD2AB98AE8472/neatx.data' Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: DEBUG daemon:456 /usr/bin/nxagent stderr: Error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx-GPL,render=1,composite=1,accept=127.0.0.1,cookie=0E51F8E213CD5320E8FDAFB0884AFE48,id=sorberd-dev-box-106-178B1214DCD07718251CD2AB98AE8472,shpix=1,strict=0,clipboard=both,shmem=1,type=gnome,cleanup=0,backingstore=1:106''. Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: ERROR agent:346 Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx-GPL,render=1,composite=1,accept=127.0.0.1,cookie=0E51F8E213CD5320E8FDAFB0884AFE48,id=sorberd-dev-box-106-178B1214DCD07718251CD2AB98AE8472,shpix=1,strict=0,clipboard=both,shmem=1,type=gnome,cleanup=0,backingstore=1:106''. Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: DEBUG daemon:456 /usr/bin/nxagent stderr: Session: Aborting session at 'Wed Jul 15 17:04:14 2009'. Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: INFO agent:377 Nxagent changed status from 'starting' to 'terminating' Jul 15 17:04:14 sorberd-dev-box nxnode[32763]: DEBUG session:283 Writing session '178B1214DCD07718251CD2AB98AE8472' to '/usr/local/var/lib/neatx/sessions/178B1214DCD07718251CD2AB98AE8472/neatx.data' Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: DEBUG daemon:456 /usr/bin/nxagent stderr: Session: Session aborted at 'Wed Jul 15 17:04:14 2009'. Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: INFO agent:377 Nxagent changed status from 'terminating' to 'terminated' Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: INFO agent:426 Nxagent terminated Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: DEBUG session:283 Writing session '178B1214DCD07718251CD2AB98AE8472' to '/usr/local/var/lib/neatx/sessions/178B1214DCD07718251CD2AB98AE8472/neatx.data' Jul 15 17:04:15 sorberd-dev-box nxserver[32685]: DEBUG session:243 Loading session 178B1214DCD07718251CD2AB98AE8472 from /usr/local/var/lib/neatx/sessions/178B1214DCD07718251CD2AB98AE8472/neatx.data Jul 15 17:04:15 sorberd-dev-box nxserver[32685]: ERROR nxserver:586 Session u'178B1214DCD07718251CD2AB98AE8472' has status u'terminating' Jul 15 17:04:15 sorberd-dev-box nxserver[32685]: DEBUG protocol:172 >>> "NX> 500 Error: Session u'178B1214DCD07718251CD2AB98AE8472' has status u'terminating', aborting\n" Jul 15 17:04:15 sorberd-dev-box nxserver[32685]: DEBUG protocol:172 >>> 'NX> 999 Bye.\n' Jul 15 17:04:15 sorberd-dev-box nxserver[32685]: DEBUG nxserver:701 No nxagent port, not starting netcat Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: Traceback (most recent call last): Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: DEBUG daemon:456 /usr/bin/xrdb stderr: /usr/bin/xrdb: No such file or directory Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: DEBUG daemon:456 /usr/bin/xrdb stderr: /usr/bin/xrdb: Can't open display ':106.0' Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: ERROR daemon:573 /usr/bin/nxagent failed (status=1, signal=None) Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: INFO node:345 nxagent terminated Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: File "/usr/local/lib/python2.6/dist-packages/neatx/daemon.py", line 225, in __HandleIO Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: ((cond & gobject.IO_OUT) and self.__Write(channel)))) Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: File "/usr/local/lib/python2.6/dist-packages/neatx/daemon.py", line 197, in __Write Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: n = channel.write(data) Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: glib.GError: Broken pipe Jul 15 17:04:15 sorberd-dev-box nxnode-wrapper[32761]: EOF on stderr, exiting Jul 15 17:04:15 sorberd-dev-box nxserver-login[32672]: DEBUG auth:132 fdcopy exited (exitstatus=0, signum=None) Jul 15 17:04:15 sorberd-dev-box nxserver-login[32672]: INFO auth:141 Waiting for authenticated program to finish Jul 15 17:04:15 sorberd-dev-box nxserver-login[32672]: DEBUG auth:151 Authenticated program finished (exitstatus=0, signalstatus=None) Jul 15 17:04:15 sorberd-dev-box nxserver-login-wrapper[32668]: EOF on stderr, exiting

Also, I can confirm that /usr/local/var/lib/neatx/sessions/178B1214DCD07718251CD2AB98AE8472/neatx.data exists and looks reasonable.

Comment #8

Posted on Jul 16, 2009 by Helpful Elephant

I'm pretty sure the md5 Deprecationwarning has nothing to do with the problem. The neatx.data files exist. Don't know where too look at next.

Comment #9

Posted on Jul 16, 2009 by Happy Rhino

@baranovich

That does look like the same problem alright, though you have an additional one:

Jul 15 17:04:15 sorberd-dev-box nxnode[32763]: DEBUG daemon:456 /usr/bin/xrdb stderr: /usr/bin/xrdb: No such file or directory

xrdb is not installed, or the path is wrong.

Are all you folks running into this issue on ubuntu 9.04?

Comment #10

Posted on Jul 16, 2009 by Helpful Panda

Hmmm... I do (at least now) have xrdb installed and I'm getting the same error. I think I might have installed it since yesterday, as I have been fooling with neatx a bit.

I am running Ubuntu 9.04 amd64.

I also noticed that my client (which is running on win xp in a VM on the same physical box) is giving me this: "Could not find ':' in DISPLAY:"

even though the DISPLAY environment variable looks like it contains a ':' to me... $ echo $DISPLAY :0.0

Not sure if the above is related, but I figure it might help someone discover what's going on.

Comment #11

Posted on Jul 17, 2009 by Quick Camel

Somehow same problem here, Version 0.3.1, with this little patch: http://aur.archlinux.org/packages/neatx/neatx/constants.archlinux.patch .

nxnode[2140]: INFO node:366 Starting xrdb nxnode[2140]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] nxnode[2140]: DEBUG session:283 Writing session 'E37FC58796CA5B6830D2670DF10C6EAB' to '/var/lib/neatx/sessions/E37FC58796CA5B6830D2670DF10C6EAB/neatx.data' nxnode[2140]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=93C1E56B5B498C90B16A9C28685AA6F0,id= umbriel-673- E37FC58796CA5B6830D2670DF10C6EAB,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:673''. nxnode[2140]: ERROR agent:345 Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=93C1E56B5B498C90B16A9C28685AA6F0,id= umbriel-673- E37FC58796CA5B6830D2670DF10C6EAB,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:673''. nxnode[2140]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Session: Aborting session at 'Fri Jul 17 12:03:42 2009'. nxnode[2140]: INFO agent:376 Nxagent changed status from 'starting' to 'terminating' nxnode[2140]: DEBUG session:283 Writing session 'E37FC58796CA5B6830D2670DF10C6EAB' to '/var/lib/neatx/sessions/E37FC58796CA5B6830D2670DF10C6EAB/neatx.data' nxnode[2140]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Session: Session aborted at 'Fri Jul 17 12:03:42 2009'. nxnode[2140]: INFO agent:376 Nxagent changed status from 'terminating' to 'terminated' nxnode[2140]: INFO agent:425 Nxagent terminated

the .data-File exists. Do you need any more infos?

Comment #12

Posted on Jul 20, 2009 by Happy Rhino

I've tried to reproduce this on ubuntu 9.04 32bit and 64bit, no luck so far. Can you guys confirm that you're running the latest revision from svn (currently r19), just in case any fixes have gone in since that make it work now?

Comment #13

Posted on Jul 20, 2009 by Helpful Elephant

With the latest svn, which probably added some more debugging output to syslog, I think I figured out what the problem was. Though I don't know how to solve that normally.

nxagent was saying: refusing connection from 192.168.69.130, which is one of my local IP's, and in it's cmd arguments was only accept=127.0.0.1.

If I launch 2 python programs like these:

s = socket.socket() s.bind(('localhost', 10000)) s.listen(1) s.accept()

import socket s = socket.socket() s.connect(('localhost', 10000))

The first one outputs (, ('192.168.69.130', 59935))

So the "client" address is always this one 192.168.69.130, and not 127.0.0.1

I don't know how to fix it, and why this happens. Adding a route to 127.0.0.0/8 through loopback interface did not help.

I hacked the agent.py file, and changed the "accept" line there to this address and finally I had the client run and connect successfully, though freezing after several seconds, but that is definitely another story.

Comment #14

Posted on Jul 20, 2009 by Quick Camel

still the "old" version, hacking the agent.py did not change anything, nither did some hacking via /etc/hosts, moving the localhost or hostname around (letting "localhost" resolve to the external or lo-IP).

might be able to update the next days.

Comment #15

Posted on Jul 21, 2009 by Quick Camel

still the same error on latest SVN-Revion (r21):

Jul 21 12:19:41 umbriel nxnode[23984]: INFO node:366 Starting xrdb Jul 21 12:19:41 umbriel nxnode[23984]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] Jul 21 12:19:41 umbriel nxnode[23984]: DEBUG session:288 Writing session '79AB7CBC3959A75409B076F58333F61C' to '/var/lib/neatx/sessions/79AB7CBC3959A75409B076F58333F61C/neatx.data' Jul 21 12:19:42 umbriel nxnode[23984]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=F524468B0F5E0C22B09EDEDCA37FA1DF,id= umbriel-824- 79AB7CBC3959A75409B076F58333F61C,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:824''. Jul 21 12:19:42 umbriel nxnode[23984]: ERROR agent:346 Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=F524468B0F5E0C22B09EDEDCA37FA1DF,id= umbriel-824- 79AB7CBC3959A75409B076F58333F61C,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:824''. Jul 21 12:19:42 umbriel nxnode[23984]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Session: Aborting session at 'Tue Jul 21 12:19:41 2009'. Jul 21 12:19:42 umbriel nxnode[23984]: INFO agent:377 Nxagent changed status from 'starting' to 'terminating' Jul 21 12:19:42 umbriel nxnode[23984]: DEBUG session:288 Writing session '79AB7CBC3959A75409B076F58333F61C' to '/var/lib/neatx/sessions/79AB7CBC3959A75409B076F58333F61C/neatx.data' Jul 21 12:19:42 umbriel nxnode[23984]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Session: Session aborted at 'Tue Jul 21 12:19:41 2009'. Jul 21 12:19:42 umbriel nxnode[23984]: INFO agent:377 Nxagent changed status from 'terminating' to 'terminated' Jul 21 12:19:42 umbriel nxnode[23984]: INFO agent:426 Nxagent terminated

"ping localhost" pings 127.0.0.1 "ping hostname" pings the external IP

running 2.6.30-ARCH, Archlinux.

Comment #16

Posted on Jul 21, 2009 by Swift Giraffe

Try to install pygobject and netcat, desoxyrebonukleinsaure.

Comment #17

Posted on Jul 22, 2009 by Quick Camel

netcat was not installed ;)

the error did not really change ;)

Jul 22 18:11:00 umbriel nxnode[27924]: INFO agent:377 Nxagent changed status from 'created' to 'starting' Jul 22 18:11:00 umbriel nxnode[27924]: INFO node:366 Starting xrdb Jul 22 18:11:00 umbriel nxnode[27924]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] Jul 22 18:11:00 umbriel nxnode[27924]: DEBUG session:288 Writing session '3FC9C82240E7F90AB70033AE03BABC4B' to '/var/lib/neatx/sessions/3FC9C82240E7F90AB70033AE03BABC4B/neatx.data' Jul 22 18:11:00 umbriel nxnode[27924]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=F58C17C48AB06357939AC9EDA71390CC,id= umbriel-823- 3FC9C82240E7F90AB70033AE03BABC4B,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:823''. Jul 22 18:11:00 umbriel nxnode[27924]: ERROR agent:346 Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=F58C17C48AB06357939AC9EDA71390CC,id= umbriel-823- 3FC9C82240E7F90AB70033AE03BABC4B,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:823''. Jul 22 18:11:00 umbriel nxnode[27924]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Session: Aborting session at 'Wed Jul 22 18:11:00 2009'. Jul 22 18:11:00 umbriel nxnode[27924]: INFO agent:377 Nxagent changed status from 'starting' to 'terminating' Jul 22 18:11:00 umbriel nxnode[27924]: DEBUG session:288 Writing session '3FC9C82240E7F90AB70033AE03BABC4B' to '/var/lib/neatx/sessions/3FC9C82240E7F90AB70033AE03BABC4B/neatx.data' Jul 22 18:11:00 umbriel nxnode[27924]: DEBUG daemon:456 /opt/NX/bin/nxagent stderr: Session: Session aborted at 'Wed Jul 22 18:11:00 2009'. Jul 22 18:11:00 umbriel nxnode[27924]: INFO agent:377 Nxagent changed status from 'terminating' to 'terminated'

pacman -Qi gnu-netcat|grep Ver

Version : 0.7.1-2

pacman -Qi pygobject|grep Ver

Version : 2.18.0-1

Comment #18

Posted on Jul 22, 2009 by Grumpy Ox

It's the kernel that's filling in the network interface's IP address when accept(2) returns the peer's address instead of the 127.0.0.1 that you'd expect from your sample Python scripts. Does the output of ifconfig -a' andnetstat -rn' give any clues?

Comment #19

Posted on Jul 22, 2009 by Happy Rhino

This is really weird. Can you guys paste the entire logs for one attempt, please? (The first entry is something like this: nxserver-login-wrapper[3501]: Started). There might be some context in the logs that i'm missing. Thanks.

Comment #20

Posted on Jul 22, 2009 by Quick Camel

Log: http://finkregh.pastebin.com/f7d6dff89

/etc/hosts: 192.168.0.25 umbriel 127.0.0.1 localhost.localdomain localhost

neatx.data: { "username": "finkregh", "fullscreen": false, "rootless": true, "name": "umbriel-twm", "virtualdesktop": false, "geometry": "640x480", "hostname": "umbriel", "state": "terminated", "id": "XX", "port": null, "ssl": true, "screeninfo": "800x600x32+render", "options": null, "cookie": "XX", "_updated": 1248297219.553695, "type": "unix-application", "display": 244, "subscription": "GPL" }

ip a

1: lo: mtu 16436 qdisc noqueue state UNKNOWN link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 inet 127.0.0.1/8 scope host lo inet6 ::1/128 scope host valid_lft forever preferred_lft forever 2: eth0: mtu 1500 qdisc pfifo_fast state UNKNOWN qlen 1000 link/ether 00:0c:29:6b:24:fa brd ff:ff:ff:ff:ff:ff inet 192.168.0.25/24 brd 192.168.0.255 scope global eth0 inet6 fe80::20c:29ff:fe6b:24fa/64 scope link valid_lft forever preferred_lft forever

:)

Comment #21

Posted on Jul 23, 2009 by Helpful Panda

kormat, here is an entire log from just now (rvsn 24):

Jul 23 10:34:57 sorberd-dev-box nxserver-login-wrapper[3531]: Started Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'HELLO NXSERVER - Version 3.3.0 - GPL\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'NX> 105 ' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:227 <<< 'hello NXCLIENT - Version 3.3.0\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'Hello nxclient - version 3.3.0\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG nxserver_login:111 Got client protocol version 3030000 ('3.3.0'), want 3030000 Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'NX> 134 Accepted protocol: 3.3.0\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'NX> 105 ' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:227 <<< 'SET AUTH_MODE PASSWORD\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'Set AUTH_MODE: PASSWORD\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'NX> 105 ' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:227 <<< 'login\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'Login\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'NX> 101 User: ' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:227 <<< 'sorberd\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> '\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> 'NX> 102 Password: ' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:225 <<< [hidden] Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG protocol:172 >>> '******\n' Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: INFO nxserver_login:248 Trying login for user sorberd Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG auth:50 Authenticating as 'sorberd', running ['/usr/local/lib/neatx/nxserver', '--proto=3030000', '--', 'sorberd'] Jul 23 10:34:58 sorberd-dev-box nxserver-login[3535]: DEBUG auth:53 Auth command ['/usr/local/lib/neatx/ttysetup', '/bin/su', 'sorberd', '-c', 'cd && /usr/local/lib/neatx/nxserver --proto=3030000 -- sorberd'] Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: INFO nxserver:689 Starting nxserver for user sorberd Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'NX> 103 Welcome to: sorberd-dev-box user: sorberd\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'NX> 105 ' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:227 <<< ' listsession --user="sorberd" --status="suspended,running" --geometry="1280x1024x32+render" --type="unix-gnome"\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'Listsession --user="sorberd" --status="suspended,running" --geometry="1280x1024x32+render" --type="unix-gnome"\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG nxserver:315 Looking for sessions with types=['unix-gnome'], state=['suspended', 'running'] Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> "NX> 127 Session list of user 'sorberd':\n" Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'Display Type Session ID Options Depth Screen Status
Session Name\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> '-------


----------- ------------------------------\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> '\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'NX> 148 Server capacity: not reached for user: sorberd\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'NX> 105 ' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:227 <<< 'startsession --link="lan" --backingstore="1" --encryption="1" --cache="16M" --images="64M" --shmem="1" --shpix="1" --strict="0" --composite="1" --media="0" --session="sorberd-dev-box" --type="unix-gnome" --geometry="1280x996" --client="winnt" --keyboard="pc102/en_US" --screeninfo="1280x996x32+render"\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'Start session with: --link="lan" --backingstore="1" --encryption="1" --cache="16M" --images="64M" --shmem="1" --shpix="1" --strict="0" --composite="1" --media="0" --session="sorberd-dev-box" --type="unix-gnome" --geometry="1280x996" --client="winnt" --keyboard="pc102/en_US" --screeninfo="1280x996x32+render"\n' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: INFO nxserver:377 Starting new session '68CCB3D0518CDC831F47D0D417F928AA' Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG nxserver:645 Connecting to nxnode Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: INFO node:514 Connecting to '/usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/nxnode.sock' Jul 23 10:34:58 sorberd-dev-box nxnode-wrapper[3553]: Started Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG nxnode:301 Starting mainloop Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG nxserver:385 Sending startsession command Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG node:551 Sending request: {'cmd': 'start', 'args': {'composite': '1', 'encryption': '1', 'cache': '16M', 'geometry': '1280x996', 'client': 'winnt', 'strict': '0', 'screeninfo': '1280x996x32+render', 'session': 'sorberd-dev-box', 'link': 'lan', 'shmem': '1', 'media': '0', 'images': '64M', 'keyboard': 'pc102/en_US', 'type': 'unix-gnome', 'shpix': '1', 'backingstore': '1'}} Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO nxnode:266 Connection established Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO nxnode:81 Received request: u'start', {u'session': u'sorberd-dev-box', u'strict': u'0', u'composite': u'1', u'encryption': u'1', u'cache': u'16M', u'geometry': u'1280x996', u'screeninfo': u'1280x996x32+render', u'client': u'winnt', u'link': u'lan', u'shmem': u'1', u'media': u'0', u'images': u'64M', u'keyboard': u'pc102/en_US', u'type': u'unix-gnome', u'shpix': u'1', u'backingstore': u'1'} Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG node:97 Trying display number 60 Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG node:106 Display number 60 appears to be unused Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO node:290 Starting xauth for [(':60', '553E12FFF735C3628750EEAD0A1227DC'), ('localhost:60', '553E12FFF735C3628750EEAD0A1227DC')] Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xauth', '-f', '/usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/authority'] Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG daemon:456 /usr/bin/xauth stderr: /usr/bin/xauth: creating new authority file /usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/authority Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG node:558 Received response: {u'result': True, u'success': True} Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: INFO nxserver:594 Waiting for session '68CCB3D0518CDC831F47D0D417F928AA' to achieve waiting status Jul 23 10:34:58 sorberd-dev-box nxserver[3548]: DEBUG session:248 Loading session 68CCB3D0518CDC831F47D0D417F928AA from /usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/neatx.data Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG daemon:570 /usr/bin/xauth exited cleanly Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO node:325 Starting nxagent Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG agent:203 Display for nxagent: u'nx/nx,product=Neatx-GPL,render=1,composite=1,accept=127.0.0.1,cookie=553E12FFF735C3628750EEAD0A1227DC,id=sorberd-dev-box-60-68CCB3D0518CDC831F47D0D417F928AA,shpix=1,strict=0,clipboard=both,shmem=1,type=gnome,cleanup=0,backingstore=1:60' Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG agent:599 Writing session options u'fullscreen=0,client=winnt,link=lan,keyboard=pc102/en_US,geometry=1280x996,images=64M,cache=16M,resize=0:60' to /usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/options Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/nxagent', '-D', '-name', u'Neatx - sorberd@sorberd-dev-box:60 - sorberd-dev-box', '-options', '/usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/options', '-nolisten', 'tcp', ':60'] Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG daemon:456 /usr/bin/nxagent stderr: /usr/bin/nxagent: error while loading shared libraries: libXcompext.so.3: cannot open shared object file: No such file or directory Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: ERROR daemon:573 /usr/bin/nxagent failed (status=127, signal=None) Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: INFO node:345 nxagent terminated Jul 23 10:34:58 sorberd-dev-box nxnode-wrapper[3553]: EOF on stderr, exiting Jul 23 10:34:59 sorberd-dev-box nxserver[3548]: DEBUG session:248 Loading session 68CCB3D0518CDC831F47D0D417F928AA from /usr/local/var/lib/neatx/sessions/68CCB3D0518CDC831F47D0D417F928AA/neatx.data Jul 23 10:35:29 sorberd-dev-box last message repeated 30 times Jul 23 10:35:29 sorberd-dev-box nxserver[3548]: ERROR nxserver:600 Session 68CCB3D0518CDC831F47D0D417F928AA has not achieved waiting status within 30 seconds Jul 23 10:35:29 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> "NX> 500 Session didn't become ready in time\n" Jul 23 10:35:29 sorberd-dev-box nxserver[3548]: DEBUG protocol:172 >>> 'NX> 999 Bye.\n' Jul 23 10:35:29 sorberd-dev-box nxserver[3548]: DEBUG nxserver:701 No nxagent port, not starting netcat Jul 23 10:35:29 sorberd-dev-box nxserver-login[3535]: DEBUG auth:132 fdcopy exited (exitstatus=0, signum=None) Jul 23 10:35:29 sorberd-dev-box nxserver-login[3535]: INFO auth:141 Waiting for authenticated program to finish

Comment #22

Posted on Jul 23, 2009 by Happy Rhino

Ok, i think we have a number of different issues here.

@baranvich: Your session failed because of this:

Jul 23 10:34:58 sorberd-dev-box nxnode[3555]: DEBUG daemon:456 /usr/bin/nxagent stderr: /usr/bin/nxagent: error while loading shared libraries: libXcompext.so.3: cannot open shared object file: No such file or directory

That means nxagent is missing one of it's main libraries, and isn't able to start. This is a different error to what you had before, have you maybe changed something?

@descentspb Nxagent refusing a connection from a wrong IP is a different issue to the original one you were having. I'm rather baffled by it. The connection nxagent sees comes from netcat, run by nxserver. You could try telling nxserver to always run netcat against localhost, to see if that changes anything. In lib/app/nxserver.py, change line 719 from:

args = [constants.NETCAT, "--", host, str(port)]

to:

args = [constants.NETCAT, "--", "localhost", str(port)]

and see if that makes any difference.

@desoxyrebonukleinsaure

Thanks for the log, i don't immediately see what could be causing the problem i'm afraid. I'll go back over it most closely when i get a chance. Can you try running this please, and tell me what happens:

nxagent -R -display nx/nx,link=modem:8 :2

Thanks.

Steve

Comment #23

Posted on Jul 23, 2009 by Quick Camel

@Steve:

$ nxagent -R -display nx/nx,link=modem:8 :2

NXAGENT - Version 3.3.0

Copyright (C) 2001, 2007 NoMachine. See http://www.nomachine.com/ for more information.

Info: Agent running with pid '22240'. Session: Starting session at 'Thu Jul 23 21:59:36 2009'. Error: Aborting session with 'Unable to open display 'nx/nx,link=modem:8''. Session: Aborting session at 'Thu Jul 23 21:59:36 2009'. Session: Session aborted at 'Thu Jul 23 21:59:36 2009'.

just to make that clear, i connect via Windows Vista, and i ran that command via putty w/o X-Server in windows-side...

Comment #24

Posted on Jul 23, 2009 by Happy Rhino

@desoxyrebonukleinsaure

Ah hah. Ok. I'm afraid the problem so is with your version of nxagent, as far as i can tell. For comparison, here's what should happen when you run that:

diamond@asher:~(0:0)$ nxagent -R -display nx/nx,link=modem:8 :2

NXAGENT - Version 3.3.0

Copyright (C) 2001, 2007 NoMachine. See http://www.nomachine.com/ for more information.

Info: Agent running with pid '13057'. Session: Starting session at 'Thu Jul 23 21:57:00 2009'. Info: Proxy running in server mode with pid '13057'. Info: Waiting for connection from any host on port '4008'.

In your case, nxagent is exiting before it even gets to the waiting stage.

Comment #25

Posted on Jul 23, 2009 by Happy Rhino

@desoxyrebonukleinsaure

Does /tmp/.X11-unix exist, does it have these perms?

drwxrwxrwt 2 root root 40 2009-07-23 21:58 /tmp/.X11-unix

Comment #26

Posted on Jul 23, 2009 by Helpful Elephant

about libXcompext.so.3:

i also had these problems with the libraries, but through

ldd /usr/NX/bin/nxagent

I figured out, which ones exactly it could not find, and made links to corresponding libraries from /usr/NX/lib in /usr/local. I tried also to put /usr/NX/lib into /etc/ld.so.conf, but some system binaries failed to start after that

Comment #27

Posted on Jul 23, 2009 by Helpful Elephant

@kormat:

sorry, mate, I am willing to help solve that issue, but I should leave for a couple of weeks, and I will be left without any internet connection for that time.

As about library linking, I suggest that you should start nxagent with LD_LIBRARY_PATH environment variable set to e.g. /usr/NX/lib

Comment #28

Posted on Jul 26, 2009 by Quick Camel

@kormat: drwxrwxrwt 2 root root 4096 2009-07-27 00:04 .X11-unix

log from svn-r29: Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG nxserver:645 Connecting to nxnode Jul 27 00:04:42 umbriel nxserver[5621]: INFO node:514 Connecting to '/var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/nxnode.sock' Jul 27 00:04:42 umbriel nxnode-wrapper[5624]: Started Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG nxnode:301 Starting mainloop Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG nxserver:385 Sending startsession command Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG node:551 Sending request: {'cmd': 'start', 'args': {'kbload': ' --kbload=pc102/de', 'client': 'winnt', 'rootless': '1', 'strict': '0', 'composite': '1', 'encryption': '1', 'cache': '16M', 'keymap': ' -- keymap=de', 'shpix': '1', 'application': 'xterm', 'screeninfo': '1600x1124x32+render', 'session': 'umbriel', 'link': 'adsl', 'shmem': '1', 'media': '0', 'images': '64M', 'keyboard': 'pc102/de', 'aux': '1', 'type': 'unix-application', 'virtualdesktop': '0', 'backingstore': '1'}} Jul 27 00:04:42 umbriel nxnode[5626]: INFO nxnode:266 Connection established Jul 27 00:04:42 umbriel nxnode[5626]: INFO nxnode:81 Received request: 'start', {'session': 'umbriel', 'kbload': ' --kbload=pc102/de', 'rootless': '1', 'keymap': ' - -keymap=de', 'composite': '1', 'encryption': '1', 'images': '64M', 'cache': '16M', 'virtualdesktop': '0', 'strict': '0', 'screeninfo': '1600x1124x32+render', 'client': 'winnt', 'link': 'adsl', 'shmem': '1', 'media': '0', 'application': 'xterm', 'keyboard': 'pc102/de', 'aux': '1', 'type': 'unix-application', 'shpix': '1', 'backingstore': '1'} Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG node:97 Trying display number 169 Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG node:106 Display number 169 appears to be unused Jul 27 00:04:42 umbriel nxnode[5626]: INFO node:290 Starting xauth for [(':169', '750FF8E879A2AB59D656F7EAF530B182'), ('localhost:169', '750FF8E879A2AB59D656F7EAF530B182')] Jul 27 00:04:42 umbriel nxnode[5626]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xauth', '-f', '/var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/authority'] Jul 27 00:04:42 umbriel nxnode[5626]: INFO daemon:514 Child pid 5629 Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/xauth stderr: /usr/bin/xauth: creating new authority file /var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/authority Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG node:558 Received response: {'result': True, 'success': True} Jul 27 00:04:42 umbriel nxserver[5621]: INFO nxserver:594 Waiting for session 'E20E94D4A008691A310018B30B40AC1E' to achieve waiting status Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG session:248 Loading session E20E94D4A008691A310018B30B40AC1E from /var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/neatx.data Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:571 /usr/bin/xauth exited cleanly Jul 27 00:04:42 umbriel nxnode[5626]: INFO node:325 Starting nxagent Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG agent:203 Display for nxagent: 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=750FF8E879A2AB59D656F7EAF530B182,id= umbriel-169- E20E94D4A008691A310018B30B40AC1E,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:169' Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG agent:599 Writing session options 'fullscreen=0,client=winnt,link=adsl,keyboard=pc102/de,geometry=640x480,images=64M,ca che=16M,resize=0:169' to /var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/options Jul 27 00:04:42 umbriel nxnode[5626]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/nxagent', '-R', '-name', 'Neatx - finkregh@umbriel:169 - umbriel', '-options', '/var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/options', '-nolisten', 'tcp', ':169'] Jul 27 00:04:42 umbriel nxnode[5626]: INFO daemon:514 Child pid 5630 Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: NXAGENT - Version 3.3.0 Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Copyright (C) 2001, 2007 NoMachine. Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: See http://www.nomachine.com/ for more information. Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Info: Agent running with pid '5630'. Jul 27 00:04:42 umbriel nxnode[5626]: INFO agent:316 Matched info agent_pid, PID 5630 Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Session: Starting session at 'Mon Jul 27 00:04:42 2009'. Jul 27 00:04:42 umbriel nxnode[5626]: INFO agent:377 Nxagent changed status from 'created' to 'starting' Jul 27 00:04:42 umbriel nxnode[5626]: INFO node:366 Starting xrdb Jul 27 00:04:42 umbriel nxnode[5626]: INFO daemon:486 Starting program, executable=None, args=['/usr/bin/xrdb', '-merge'] Jul 27 00:04:42 umbriel nxnode[5626]: INFO daemon:514 Child pid 5631 Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG session:288 Writing session 'E20E94D4A008691A310018B30B40AC1E' to '/var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/neatx.data' Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=750FF8E879A2AB59D656F7EAF530B182,id= umbriel-169- E20E94D4A008691A310018B30B40AC1E,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:169''. Jul 27 00:04:42 umbriel nxnode[5626]: ERROR agent:346 Agent error: Aborting session with 'Unable to open display 'nx/nx,product=Neatx- GPL,render=1,composite=1,accept=127.0.0.1,cookie=750FF8E879A2AB59D656F7EAF530B182,id= umbriel-169- E20E94D4A008691A310018B30B40AC1E,shpix=1,strict=0,clipboard=both,shmem=1,type=applica tion,cleanup=0,backingstore=1:169''. Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Session: Aborting session at 'Mon Jul 27 00:04:42 2009'. Jul 27 00:04:42 umbriel nxnode[5626]: INFO agent:377 Nxagent changed status from 'starting' to 'terminating' Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG session:288 Writing session 'E20E94D4A008691A310018B30B40AC1E' to '/var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/neatx.data' Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG daemon:456 /usr/bin/nxagent stderr: Session: Session aborted at 'Mon Jul 27 00:04:42 2009'. Jul 27 00:04:42 umbriel nxnode[5626]: INFO agent:377 Nxagent changed status from 'terminating' to 'terminated' Jul 27 00:04:42 umbriel nxnode[5626]: INFO agent:426 Nxagent terminated Jul 27 00:04:42 umbriel nxnode[5626]: DEBUG session:288 Writing session 'E20E94D4A008691A310018B30B40AC1E' to '/var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/neatx.data' Jul 27 00:04:42 umbriel nxnode[5626]: ERROR daemon:574 /usr/bin/nxagent failed (status=1, signal=None) Jul 27 00:04:42 umbriel nxnode[5626]: INFO node:345 nxagent terminated Jul 27 00:04:42 umbriel nxnode-wrapper[5624]: EOF on stderr, exiting Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG session:248 Loading session E20E94D4A008691A310018B30B40AC1E from /var/lib/neatx/sessions/E20E94D4A008691A310018B30B40AC1E/neatx.data Jul 27 00:04:42 umbriel nxserver[5621]: ERROR nxserver:586 Session 'E20E94D4A008691A310018B30B40AC1E' has status 'terminated' Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG protocol:172 >>> "NX> 500 Error: Session 'E20E94D4A008691A310018B30B40AC1E' has status 'terminated', aborting\n" Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG protocol:172 >>> 'NX> 999 Bye.\n' Jul 27 00:04:42 umbriel nxserver[5621]: DEBUG nxserver:701 No nxagent port, not starting netcat Jul 27 00:04:42 umbriel nxserver-login[5615]: DEBUG auth:132 fdcopy exited (exitstatus=0, signum=None) Jul 27 00:04:42 umbriel nxserver-login[5615]: INFO auth:141 Waiting for authenticated program to finish Jul 27 00:04:42 umbriel nxserver-login[5615]: DEBUG auth:151 Authenticated program finished (exitstatus=0, signalstatus=None) Jul 27 00:04:42 umbriel nxserver-login-wrapper[5612]: EOF on stderr, exiting

Comment #29

Posted on Jul 27, 2009 by Happy Kangaroo

It looks like this or an closely related issue was present in Fedora with freenx: https://bugzilla.redhat.com/show_bug.cgi?format=multiple&id=503822

I don't quite understand yet how they solved it and a quick try of the suggested workaround didn't show any result.

Comment #30

Posted on Jul 29, 2009 by Happy Rhino

If 'nxagent -R -display nx/nx,link=modem:8 :2' doesn't work for you (i.e. exits immediately with an error), then there is a problem with your nxagent, and neatx cannot work. I'm afraid i've no idea what that problem is, or how to fix it, and as it isn't related to neatx, i don't have enough time to try and dig into it (nor can i reproduce it myself).

@descentspb

If you use NoMachine's nxagent binaries, then yes, you'll need to do some fiddling with LD_LIBRARY_PATH. That's not explicitly supported by neatx, but should be trivial to do. The simplest thing would be to use a small shell script wrapper around nxagent, that sets the LD_LIBRARY_PATH, and then exec's the real nxagent with the arguments it was passed.

Comment #31

Posted on Aug 5, 2009 by Grumpy Lion

i am experiencing the same problem - nxagent -R -display nx/nx,link=modem:8 :2 - fails with "Error: Aborting session with 'Unable to open display 'nx/ nx,link=lan:8''.".

this is 3.3.0 nxagent, compiled from the sources. error message is extremely uninformative, and i've been unable to find any more debugging - any hints on that, maybe ?

at http://www.nabble.com/nxagent---unable-to-open-display-:-(-td11321268.html i've found a hint to "Replace all occurrences of "DISPLAY=unix:$display" in nxnode with "DISPLAY=:$display"." that didn't help, because i couldn't find anything like that in neatx code :)

on the redhat report at https://bugzilla.redhat.com/show_bug.cgi? format=multiple&id=503822 the workaround suggested was "run /usr/libexec/nx/nxnode -- agent as root", which, i guess, also isn't applicable.

i'd appreciate some bright ideas on this issue :)

Comment #32

Posted on Aug 6, 2009 by Happy Rhino

The biggest problem is that i can't reproduce this issue myself, making it very difficult to figure out what's going wrong. Can you maybe try this:

strace -o nxagent.strace -F nxagent -R -display nx/nx,link=modem:8 :2

The nxagent.strace file generated might point out something going wrong (it'd be best to attach it to the bug, rather than pasting, it's likely to be very long)

Comment #33

Posted on Aug 7, 2009 by Grumpy Lion

damn. it took me some time until i found out that 'attach a file' link only appears when i place cursor in this box...

strace output attached. i looked at it, but i didn't see anything interesting myself, which means nothing :)

Attachments

Comment #34

Posted on Aug 27, 2009 by Grumpy Lion

with some time passed, i decided to try some more things. one command i issued that spectacularly failed :

nxagent :1000

NXAGENT - Version 3.3.0

Copyright (C) 2001, 2007 NoMachine. See http://www.nomachine.com/ for more information.

Info: Agent running with pid '28817'. Session: Starting session at 'Thu Aug 27 15:29:53 2009'. Info: Using alpha channel in render extension. nxagent: xcb_io.c:378: _XAllocID: Assertion `ret != inval_id' failed. ./nxagent-wrapper: line 5: 28817 Aborted /usr/NX/bin/nxagent "$@"

is this causing "unable to open display" problem ? if so, any ideas what and why happens ?

Comment #35

Posted on Aug 27, 2009 by Grumpy Lion

although my xorg is compiled without debug symbols, i also got 'bt full' from gdb, just in case it sheds some more light on this. the only line that seemed new to me (but there might be other useful info) :

8 0x08096315 in main (argc=2, argv=0xbf89a264, envp=Cannot access memory at

address 0x5fd1

Attachments

Comment #36

Posted on Sep 7, 2009 by Grumpy Lion

arrgh. i have solved this particular problem.

i did not have nx-X11/lib/X11/libX11.so in LD_LIBRARY_PATH for nxagent, so it was using system libs and crashing in such a mysterious way.

Comment #37

Posted on Sep 8, 2009 by Happy Rhino

Oh! I'm sorry to hear the fix was so simple, but delighted to hear that it's now working for you. Thank you for reporting back!

Steve

Comment #38

Posted on Jan 11, 2010 by Helpful Giraffe

I think I'm seeing the same error as descentspb and baranovich - which I think is due to a broken pipe involving xrdb. I have attached the server log file - NXLoginAttempt3.log - and the message from the Nomachine NXClient on the client machine.
I am running Debian Squeeze on both machines, and the 3.4.0 series of Nomachine files. I followed the descriptions in http://people.binf.ku.dk/~hanne/b2evolution/blogs/index.php/2009/09/01/neatx-is-the-new-black to install.
When I try "nxagent -R -display nx/nx,link=modem:8 :2" on the server, the session aborts with Xlib: connection to "localhost:10.0" refused by server Xlib: Invalid MIT-MAGIC-COOKIE-1 key According to your comment #30, this is an nxagent problem, and I understand that you don't support nxagent. Any suggestions where to turn? I've searched the Nomachine knowledge database without success.

Attachments

Comment #39

Posted on Jan 24, 2010 by Happy Rhino

@Jown_WPA: i think you have a different issue. The error you get from running nxagent manually sounds like an xauth issue, not an nxagent issue. Looking at your logs, i'm wondering if we're trying to run xrdb too soon after nxagent startup. I'm attaching a patch which will fix that, if it's the problem.

Steve

Attachments

Comment #40

Posted on Jan 27, 2010 by Grumpy Bear

Comment deleted

Comment #41

Posted on Jan 27, 2010 by Grumpy Bear

Comment deleted

Comment #42

Posted on Jan 27, 2010 by Grumpy Bear

Comment deleted

Comment #43

Posted on Feb 1, 2010 by Helpful Giraffe

Kormat:

I tried your patch, and things progressed a bit further, but I was still unable to get Neatx running. The problem seems to have more to do with nxagent than anything about Neatx, insofar as I can judge. I dropped back to Debian Lenny and version 3.3 of the Nomachine codes, and now have Neatx running on my server. This more than satisfies me, at least until the end of this semester. Thank you for your help, sincerely.

John

Comment #44

Posted on Feb 9, 2010 by Happy Rhino

@John_WPA: Ok, i'm glad to hear things are running for you now at least. I've sent the xrdb-later patch out for review.

Comment #45

Posted on Mar 17, 2010 by Happy Kangaroo

For the record. The solution for this is to have the libraries in the LD_LIBRARY_PATH, yes, but how to do that is explained nicely here: http://people.binf.ku.dk/~hanne/b2evolution/blogs/index.php/2009/09/01/neatx-is-the-new-black

essentially this line: export LD_LIBRARY_PATH="/path/where/you/put/the/libs/" goes into /usr/local/lib/neatx/nxnode-wrapper and /usr/local/lib/neatx/nxserver-login-wrapper

btw: the libs I put into that directory were: libX11.so libX11.so.6 libX11.so.6.2 libXcompext.a libXcompext.so libXcompext.so.3 libXcompext.so.3.4.0 libXcompshad.a libXcompshad.so libXcompshad.so.3 libXcompshad.so.3.4.0 libXcomp.so libXcomp.so.3 libXcomp.so.3.4.0 libXext.so libXext.so.6 libXext.so.6.4 libXrender.so libXrender.so.1 libXrender.so.1.2.2

Especially the lack of documentation within neatx as to which sources you need to get from nomachine and then build and which libraries/binaries to extract made this a non-trivial task for me.

Comment #46

Posted on Jul 5, 2010 by Grumpy Bear

45 solved it for me on archlinux

Comment #47

Posted on Jul 6, 2010 by Massive Giraffe

An alternative solution is to write a simply script and point nxagent-path (variable inside of neatx.conf) to your script instead of the executable. I did this because there were some issues setting LD_LIBRARY_PATH in the wrapper scripts themselves.

!/bin/bash

export LD_LIBRARY_PATH="/usr/NX/lib"

exec /usr/NX/bin/nxagent "$@"

Status: Accepted

Labels:
Type-Defect Priority-Medium