Open GoogleCodeExporter opened 9 years ago
I have what appears to be this issue when trying to connect to neatx on ubuntu
l0.04, from my windows XP box running nomachine's nx client. The client
reports connection timeout and exiting with "signal 15". This error does not
happen when I connect from a windows 7 box.
Jun 16 09:05:53 localhost nxserver-login-wrapper[6481]: Called with args:
Jun 16 09:05:53 localhost nxserver-login-wrapper[6481]: Started
/usr/lib/neatx/nxserver-login
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'HELLO
NXSERVER - Version 3.3.0 - GPL\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 105
'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:227 <<< 'hello
NXCLIENT - Version 3.3.0\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'Hello
nxclient - version 3.3.0\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG nxserver_login:111 Got
client protocol version 3030000 ('3.3.0'), want 3030000
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 134
Accepted protocol: 3.3.0\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 105
'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:227 <<< 'SET
SHELL_MODE SHELL\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'Set
SHELL_MODE: SHELL\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 105
'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:227 <<< 'SET
AUTH_MODE PASSWORD\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'Set
AUTH_MODE: PASSWORD\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 105
'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:227 <<< 'login\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'Login\n'
Jun 16 09:05:53 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 101
User: '
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG protocol:227 <<<
'username\n'
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG protocol:172 >>>
'username\n'
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG protocol:172 >>> 'NX> 102
Password: '
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG protocol:225 <<< [hidden]
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG protocol:172 >>>
'**********\n'
Jun 16 09:05:54 localhost nxserver-login[6486]: INFO nxserver_login:253 Trying
login for user 'username' using auth method 'su'
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG auth:50 Authenticating as
'username', running ['/usr/lib/neatx/nxserver', '--proto=3030000', '--',
'username']
Jun 16 09:05:54 localhost nxserver-login[6486]: DEBUG auth:53 Auth command
['/usr/lib/neatx/ttysetup', '/bin/su', 'username', '-c', 'cd &&
/usr/lib/neatx/nxserver --proto=3030000 -- username']
Jun 16 09:05:54 localhost nxserver[6499]: INFO nxserver:689 Starting nxserver
for user username
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 103
Welcome to: username.domain.com user: username\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 105 '
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:227 <<< ' listsession
--user="username" --status="suspended,running" --geometry="1280x800x32+render"
--type="unix-gnome"\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> 'Listsession
--user="username" --status="suspended,running" --geometry="1280x800x32+render"
--type="unix-gnome"\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG nxserver:315 Looking for
sessions with types=['unix-gnome'], state=['suspended', 'running']
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
0CA06CE0D10B93A8BC9E602435318997 from
/var/lib/neatx/sessions/0CA06CE0D10B93A8BC9E602435318997/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
32D98BE982F96B7D43CAED2E896FE2B0 from
/var/lib/neatx/sessions/32D98BE982F96B7D43CAED2E896FE2B0/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
36F6467FA6E1ECC44A697E3C0C20B3F8 from
/var/lib/neatx/sessions/36F6467FA6E1ECC44A697E3C0C20B3F8/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
6F95ABC5167A797D077371ACB79DA45A from
/var/lib/neatx/sessions/6F95ABC5167A797D077371ACB79DA45A/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
96A8D67EF583832023B6A7A93C929E5F from
/var/lib/neatx/sessions/96A8D67EF583832023B6A7A93C929E5F/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
9824E68D34114C353C0787CD1C33C8DF from
/var/lib/neatx/sessions/9824E68D34114C353C0787CD1C33C8DF/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
B2E11E05D822E4537270D4A6B99584EA from
/var/lib/neatx/sessions/B2E11E05D822E4537270D4A6B99584EA/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
B5BA9BC80080444E69372B57A0BE7488 from
/var/lib/neatx/sessions/B5BA9BC80080444E69372B57A0BE7488/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
C662C89CE28066BF19E9F0A18825BF42 from
/var/lib/neatx/sessions/C662C89CE28066BF19E9F0A18825BF42/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG session:248 Loading session
D39B461D2E8EAF3037F6AF92F5A3E80A from
/var/lib/neatx/sessions/D39B461D2E8EAF3037F6AF92F5A3E80A/neatx.data
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> "NX> 127
Session list of user 'username':\n"
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> 'Display Type
Session ID Options Depth Screen
Status Session Name\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> '-------
---------------- -------------------------------- -------- ----- --------------
----------- ------------------------------\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> '\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 148
Server capacity: not reached for user: username\n'
Jun 16 09:05:54 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 105 '
Jun 16 09:05:57 localhost nxserver[6499]: 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="4username" --type="unix-gnome" --geometry="1274x738"
--client="winnt" --keyboard="pc102/en_US" --screeninfo="1274x738x32+render"\n'
Jun 16 09:05:57 localhost nxserver[6499]: 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="4username" --type="unix-gnome" --geometry="1274x738"
--client="winnt" --keyboard="pc102/en_US" --screeninfo="1274x738x32+render"\n'
Jun 16 09:05:57 localhost nxserver[6499]: INFO nxserver:377 Starting new
session '78BEA60B63A4D6031A37529864A62DEE'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG nxserver:645 Connecting to
nxnode
Jun 16 09:05:57 localhost nxserver[6499]: INFO node:514 Connecting to
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/nxnode.sock'
Jun 16 09:05:57 localhost nxnode-wrapper[6503]: Called with args: username
78BEA60B63A4D6031A37529864A62DEE
Jun 16 09:05:57 localhost nxnode-wrapper[6503]: Started /usr/lib/neatx/nxnode
username 78BEA60B63A4D6031A37529864A62DEE
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG nxnode:301 Starting mainloop
Jun 16 09:05:57 localhost nxnode[6506]: INFO nxnode:266 Connection established
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG nxserver:385 Sending
startsession command
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG node:551 Sending request:
{'cmd': 'start', 'args': {'composite': '1', 'encryption': '1', 'cache': '16M',
'geometry': '1274x738', 'client': 'winnt', 'strict': '0', 'screeninfo':
'1274x738x32+render', 'session': '4username', 'link': 'lan', 'shmem': '1',
'media': '0', 'images': '64M', 'keyboard': 'pc102/en_US', 'type': 'unix-gnome',
'shpix': '1', 'backingstore': '1'}}
Jun 16 09:05:57 localhost nxnode[6506]: INFO nxnode:81 Received request:
'start', {'session': '4username', 'strict': '0', 'composite': '1',
'encryption': '1', 'cache': '16M', 'geometry': '1274x738', 'screeninfo':
'1274x738x32+render', 'client': 'winnt', 'link': 'lan', 'shmem': '1', 'media':
'0', 'images': '64M', 'keyboard': 'pc102/en_US', 'type': 'unix-gnome', 'shpix':
'1', 'backingstore': '1'}
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG node:97 Trying display number 464
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG node:106 Display number 464
appears to be unused
Jun 16 09:05:57 localhost nxnode[6506]: INFO node:290 Starting xauth for
[(':464', '9FA9972BE04D56264AE0CB0AEAD5D434'), ('localhost:464',
'9FA9972BE04D56264AE0CB0AEAD5D434')]
Jun 16 09:05:57 localhost nxnode[6506]: INFO daemon:491 Starting program,
executable=None, args=['/usr/bin/xauth', '-f',
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/authority']
Jun 16 09:05:57 localhost nxnode[6506]: INFO daemon:519 Child
/usr/bin/xauth[6509] started
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG node:558 Received response:
{'result': True, 'success': True}
Jun 16 09:05:57 localhost nxserver[6499]: INFO nxserver:594 Waiting for session
'78BEA60B63A4D6031A37529864A62DEE' to achieve waiting status
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG session:248 Loading session
78BEA60B63A4D6031A37529864A62DEE from
/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/neatx.data
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/xauth[6509]
stderr: /usr/bin/xauth: creating new authority file
/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/authority
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:560 __CheckExit
/usr/bin/xauth[6509] stdin:True stdout:True stderr:True exit:0
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:580 /usr/bin/xauth[6509]
exited cleanly
Jun 16 09:05:57 localhost nxnode[6506]: INFO node:325 Starting nxagent
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG agent:233 Display for nxagent:
'nx/nx,options=/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/options:
464'
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG agent:627 Writing session options
'nx/nx,fullscreen=0,product=Neatx-GPL,shpix=1,clipboard=both,render=1,composite=
1,cache=16M,geometry=1274x738,accept=127.0.0.1,client=winnt,strict=0,cleanup=0,c
ookie=9FA9972BE04D56264AE0CB0AEAD5D434,resize=0,keyboard=pc102/en_US,images=64M,
link=lan,type=gnome,id=username.domain.com-464-78BEA60B63A4D6031A37529864A62DEE,
backingstore=1,shmem=1:464\n' to
/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/options
Jun 16 09:05:57 localhost nxnode[6506]: INFO daemon:491 Starting program,
executable=None, args=['/usr/bin/nxagent', '-D', '-name', 'Neatx -
username@username.domain.com:464 - 4username', '-options',
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/options',
'-nolisten', 'tcp', ':464']
Jun 16 09:05:57 localhost nxnode[6506]: INFO daemon:519 Child
/usr/bin/nxagent[6511] started
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr:
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: NXAGENT - Version 3.4.0
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr:
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Copyright (C) 2001, 2010 NoMachine.
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: See http://www.nomachine.com/ for more information.
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr:
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Info: Agent running with pid '6511'.
Jun 16 09:05:57 localhost nxnode[6506]: INFO agent:346 Matched info agent_pid,
PID 6511
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Session: Starting session at 'Wed Jun 16 09:05:57 2010'.
Jun 16 09:05:57 localhost nxnode[6506]: INFO agent:409 Nxagent changed status
from 'created' to 'starting'
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG session:288 Writing session
'78BEA60B63A4D6031A37529864A62DEE' to
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/neatx.data'
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Info: Proxy running in server mode with pid '6511'.
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Info: Waiting for connection from '127.0.0.1' on port '4464'.
Jun 16 09:05:57 localhost nxnode[6506]: INFO agent:409 Nxagent changed status
from 'starting' to 'waiting'
Jun 16 09:05:57 localhost nxnode[6506]: INFO node:366 Starting xrdb
Jun 16 09:05:57 localhost nxnode[6506]: INFO daemon:491 Starting program,
executable=None, args=['/usr/bin/xrdb', '-merge']
Jun 16 09:05:57 localhost nxnode[6506]: INFO daemon:519 Child
/usr/bin/xrdb[6513] started
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG agent:444 Setting session port to
4464
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG session:288 Writing session
'78BEA60B63A4D6031A37529864A62DEE' to
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/neatx.data'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG session:248 Loading session
78BEA60B63A4D6031A37529864A62DEE from
/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/neatx.data
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 700
Session id: username.domain.com-464-78BEA60B63A4D6031A37529864A62DEE\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 705
Session display: 464\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 703
Session type: unix-gnome\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 701 Proxy
cookie: 9FA9972BE04D56264AE0CB0AEAD5D434\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 706 Agent
cookie: 9FA9972BE04D56264AE0CB0AEAD5D434\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 704
Session cache: unix-gnome\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 728
Session caption: Neatx - username@username.domain.com:464 - 4username\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 707 SSL
tunneling: 1\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 708
Subscription: GPL\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 710
Session status: running\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 105 '
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:227 <<< 'bye\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'Bye\n'
Jun 16 09:05:57 localhost nxserver[6499]: DEBUG protocol:172 >>> 'NX> 999
Bye.\n'
Jun 16 09:05:57 localhost nxserver[6499]: INFO nxserver:714 Starting netcat
(localhost:4464)
Jun 16 09:05:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Info: Accepted connection from '127.0.0.1'.
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Info: Aborting the procedure due to signal '1'.
Jun 16 09:06:57 localhost nxserver[6499]: DEBUG nxserver:729 Netcat exited
cleanly
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Error: Aborting session with 'Unable to open display
'nx/nx,options=/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/options:
464''.
Jun 16 09:06:57 localhost nxnode[6506]: ERROR agent:376 Agent error: Aborting
session with 'Unable to open display
'nx/nx,options=/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/options:
464''.
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Session: Aborting session at 'Wed Jun 16 09:06:57 2010'.
Jun 16 09:06:57 localhost nxnode[6506]: INFO agent:409 Nxagent changed status
from 'waiting' to 'terminating'
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG session:288 Writing session
'78BEA60B63A4D6031A37529864A62DEE' to
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/neatx.data'
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/nxagent[6511]
stderr: Session: Session aborted at 'Wed Jun 16 09:06:57 2010'.
Jun 16 09:06:57 localhost nxnode[6506]: INFO agent:409 Nxagent changed status
from 'terminating' to 'terminated'
Jun 16 09:06:57 localhost nxnode[6506]: INFO agent:457 Nxagent terminated
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG session:288 Writing session
'78BEA60B63A4D6031A37529864A62DEE' to
'/var/lib/neatx/sessions/78BEA60B63A4D6031A37529864A62DEE/neatx.data'
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/xrdb[6513]
stderr: /usr/bin/xrdb: Connection reset by peer
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:461 /usr/bin/xrdb[6513]
stderr: /usr/bin/xrdb: Can't open display ':464.0'
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:560 __CheckExit
/usr/bin/xrdb[6513] stdin:True stdout:True stderr:False exit:256
Jun 16 09:06:57 localhost nxnode[6506]: DEBUG daemon:560 __CheckExit
/usr/bin/nxagent[6511] stdin:True stdout:True stderr:True exit:256
Jun 16 09:06:57 localhost nxnode[6506]: ERROR daemon:583 /usr/bin/nxagent[6511]
failed (status=1, signal=None)
Jun 16 09:06:57 localhost nxnode[6506]: INFO node:345 nxagent terminated
Jun 16 09:06:57 localhost nxnode-wrapper[6503]: EOF on stderr, exiting
Jun 16 09:06:57 localhost nxserver-login[6486]: DEBUG auth:133 fdcopy exited
(exitstatus=0, signum=None)
Jun 16 09:06:57 localhost nxserver-login[6486]: INFO auth:142 Waiting for
authenticated program to finish
Jun 16 09:06:57 localhost nxserver-login[6486]: DEBUG auth:152 Authenticated
program finished (exitstatus=0, signalstatus=None)
Jun 16 09:06:57 localhost nxserver-login-wrapper[6481]: EOF on stderr, exiting
Original comment by eby...@gmail.com
on 16 Jun 2010 at 4:23
Original issue reported on code.google.com by
james.ri...@gmail.com
on 17 May 2010 at 2:47