search

markcox / neatx

Automatically exported from code.google.com/p/neatx
0 stars 1 forks source link

nxagent writes the session as terminated before the session is even open #53

Open GoogleCodeExporter opened 9 years ago

GoogleCodeExporter commented 9 years ago 
What steps will reproduce the problem?
1. Install Ubuntu version of neatx
2. Exit out of the session (the improper way) Ref. 
http://code.google.com/p/neatx/issues/detail?id=19
3. Delete all sessions in the sessions directory. (mine: 
/var/lib/neatx/sessions/

What is the expected output? What do you see instead?
The setup was working fine until I received the same issues as present in 
issue 19.

I now get this output on attempting to connect...

NX> 203 NXSSH running with pid: 3296
NX> 285 Enabling check on switch command
NX> 285 Enabling skip of SSH config files
NX> 285 Setting the preferred NX options
NX> 200 Connected to address: 192.168.1.60 on port: 22
NX> 211 The authenticity of host '192.168.1.60 (192.168.1.60)' can't be 
established.
RSA key fingerprint is 70:d5:96:f3:e1:9e:70:0f:23:7e:2a:96:d2:05:c4:df.
Are you sure you want to continue connecting (yes/no)? 
Warning: Permanently added '192.168.1.60' (RSA) to the list of known hosts.
NX> 202 Authenticating user: nx
NX> 208 Using auth method: publickey
HELLO NXSERVER - Version 3.3.0 - GPL
NX> 105 Hello nxclient - version 3.3.0
NX> 134 Accepted protocol: 3.3.0
NX> 105 Set SHELL_MODE: SHELL
NX> 105 Set AUTH_MODE: PASSWORD
NX> 105 Login
NX> 101 User: nxserv
NX> 102 Password: **********
Could not find ':' in DISPLAY: 
NX> 103 Welcome to: nx1.localhost user: nxserv
NX> 105 Listsession --user="nxserv" --status="suspended,running" --
geometry="1152x864x32+render" --type="unix-gnome"
NX> 127 Session list of user 'nxserv':
Display Type             Session ID                       Options  Depth 
Screen         Status      Session Name
------- ---------------- -------------------------------- -------- ----- --
------------ ----------- ------------------------------

NX> 148 Server capacity: not reached for user: nxserv
NX> 105 Start session with: --link="lan" --backingstore="1" --
encryption="1" --cache="16M" --images="64M" --shmem="1" --shpix="1" --
strict="0" --composite="1" --media="0" --session="NxServer" --type="unix-
gnome" --geometry="1024x768" --client="winnt" --keyboard="pc102/en_US" --
screeninfo="1024x768x32+render"
NX> 500 Error: Session '3DF61F5CB5C14ED7E2B6DA271C327379' has status 
'terminated', aborting
NX> 999 Bye.
NX> 280 Exiting on signal: 15

What version of the product are you using? On what operating system?
Not sure: this is the package from the ubuntu repo. 

neatx-server_0.3.1+svn59-0~ppa1~lucid1_i386.deb

Please provide any additional information below.

I did try removing all files from the installation... did no good... This 
leads me to believe there are other files besides the ones stored in the 
sessions folder that nxagent/neatx references. I've looked... but couldnt 
find them.

If anything else is needed I can provide it. This was a rather quick post.

Original issue reported on code.google.com by vxxz...@gmail.com on 27 May 2010 at 12:25

GoogleCodeExporter commented 9 years ago 
This too happens to me. I have tried the fixes in Issue 19 with no luck.  I am 
running 64bit Ubuntu 10.04. Windows and Linux NoMachine clients are connecting. 
 I can delete the sessions and there is no change, make sure no ssh sessions 
are running that should not be on both the client and server. and have no luck.

Here is the output of my syslog on the failed connection attempt. 

Jul  8 12:21:18 hal9000 nxserver[27144]: Called with args: 
Jul  8 12:21:18 hal9000 nxserver[27144]: Started /usr/lib/neatx/nxserver-login 
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'HELLO 
NXSERVER - Version 3.3.0 - GPL\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 105 '
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:227 <<< 'hello 
NXCLIENT - Version 3.3.0\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'Hello 
nxclient - version 3.3.0\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG nxserver_login:111 Got 
client protocol version 3030000 ('3.3.0'), want 3030000
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 134 
Accepted protocol: 3.3.0\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 105 '
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:227 <<< 'SET 
SHELL_MODE SHELL\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'Set 
SHELL_MODE: SHELL\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 105 '
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:227 <<< 'SET 
AUTH_MODE PASSWORD\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'Set 
AUTH_MODE: PASSWORD\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 105 '
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:227 <<< 'login\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'Login\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 101 
User: '
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:227 <<< 
'testee2\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 
'testee2\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 'NX> 102 
Password: '
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:225 <<< [hidden]
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG protocol:172 >>> 
'**********\n'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: INFO nxserver_login:253 Trying 
login for user 'testee2' using auth method 'su'
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG auth:50 Authenticating as 
'testee2', running ['/usr/lib/neatx/nxserver', '--proto=3030000', '--', 
'testee2']
Jul  8 12:21:18 hal9000 nxserver-login[27149]: DEBUG auth:53 Auth command 
['/usr/lib/neatx/ttysetup', '/bin/su', 'testee2', '-c', 'cd && 
/usr/lib/neatx/nxserver --proto=3030000 -- testee2']
Jul  8 12:21:18 hal9000 nxserver[27155]: INFO nxserver:689 Starting nxserver 
for user testee2
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'NX> 103 
Welcome to: hal9000.ajrs.com user: testee2\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'NX> 105 '
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:227 <<< ' listsession 
--user="testee2" --status="suspended,running" --geometry="1400x1050x16+render" 
--type="unix-kde"\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'Listsession 
--user="testee2" --status="suspended,running" --geometry="1400x1050x16+render" 
--type="unix-kde"\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG nxserver:315 Looking for 
sessions with types=['unix-kde'], state=['suspended', 'running']
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> "NX> 127 
Session list of user 'testee2':\n"
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'Display Type   
          Session ID                       Options  Depth Screen         Status 
     Session Name\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> '------- 
---------------- -------------------------------- -------- ----- -------------- 
----------- ------------------------------\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> '\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'NX> 148 Server 
capacity: not reached for user: testee2\n'
Jul  8 12:21:18 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'NX> 105 '
Jul  8 12:21:21 hal9000 nxserver[27155]: 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="hal9300" --type="unix-kde" --geometry="1400x1024" --client="linux" 
--keyboard="pc105/us" --screeninfo="1400x1024x16+render"\n'
Jul  8 12:21:21 hal9000 nxserver[27155]: 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="hal9300" --type="unix-kde" --geometry="1400x1024" --client="linux" 
--keyboard="pc105/us" --screeninfo="1400x1024x16+render"\n'
Jul  8 12:21:21 hal9000 nxserver[27155]: INFO nxserver:377 Starting new session 
'4B312A0AC2B71AC15B5EEB5334969E22'
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG nxserver:645 Connecting to nxnode
Jul  8 12:21:21 hal9000 nxserver[27155]: INFO node:514 Connecting to 
'/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/nxnode.sock'
Jul  8 12:21:21 hal9000 nxnode-wrapper[27158]: Called with args: testee2 
4B312A0AC2B71AC15B5EEB5334969E22
Jul  8 12:21:21 hal9000 nxnode-wrapper[27158]: Started /usr/lib/neatx/nxnode 
testee2 4B312A0AC2B71AC15B5EEB5334969E22
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG nxnode:301 Starting mainloop
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO nxnode:266 Connection established
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG nxserver:385 Sending 
startsession command
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG node:551 Sending request: 
{'cmd': 'start', 'args': {'composite': '1', 'encryption': '1', 'cache': '16M', 
'geometry': '1400x1024', 'client': 'linux', 'strict': '0', 'screeninfo': 
'1400x1024x16+render', 'session': 'hal9300', 'link': 'lan', 'shmem': '1', 
'media': '0', 'images': '64M', 'keyboard': 'pc105/us', 'type': 'unix-kde', 
'shpix': '1', 'backingstore': '1'}}
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO nxnode:81 Received request: 
'start', {'session': 'hal9300', 'strict': '0', 'composite': '1', 'encryption': 
'1', 'cache': '16M', 'geometry': '1400x1024', 'screeninfo': 
'1400x1024x16+render', 'client': 'linux', 'link': 'lan', 'shmem': '1', 'media': 
'0', 'images': '64M', 'keyboard': 'pc105/us', 'type': 'unix-kde', 'shpix': '1', 
'backingstore': '1'}
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG node:97 Trying display number 601
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG node:106 Display number 601 
appears to be unused
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO node:290 Starting xauth for 
[(':601', '24C00C7D3CA31EA0C9ACAF4687EB66DB'), ('localhost:601', 
'24C00C7D3CA31EA0C9ACAF4687EB66DB')]
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO daemon:491 Starting program, 
executable=None, args=['/usr/bin/xauth', '-f', 
'/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/authority']
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO daemon:519 Child 
/usr/bin/xauth[27164] started
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:461 /usr/bin/xauth[27164] 
stderr: /usr/bin/xauth:  creating new authority file 
/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/authority
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG node:558 Received response: 
{'result': True, 'success': True}
Jul  8 12:21:21 hal9000 nxserver[27155]: INFO nxserver:594 Waiting for session 
'4B312A0AC2B71AC15B5EEB5334969E22' to achieve waiting status
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG session:248 Loading session 
4B312A0AC2B71AC15B5EEB5334969E22 from 
/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/neatx.data
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:560 __CheckExit 
/usr/bin/xauth[27164] stdin:True stdout:True stderr:True exit:0
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:580 /usr/bin/xauth[27164] 
exited cleanly
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO node:325 Starting nxagent
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG agent:233 Display for nxagent: 
'nx/nx,options=/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/options:
601'
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG agent:627 Writing session options 
'nx/nx,fullscreen=0,product=Neatx-GPL,shpix=1,clipboard=both,render=1,composite=
1,cache=16M,geometry=1400x1024,accept=127.0.0.1,client=linux,strict=0,cleanup=0,
cookie=24C00C7D3CA31EA0C9ACAF4687EB66DB,resize=0,keyboard=pc105/us,images=64M,li
nk=lan,type=kde,id=hal9000.ajrs.com-601-4B312A0AC2B71AC15B5EEB5334969E22,backing
store=1,shmem=1:601\n' to 
/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/options
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO daemon:491 Starting program, 
executable=None, args=['/usr/bin/nxagent', '-D', '-name', 'Neatx - 
testee2@hal9000.ajrs.com:601 - hal9300', '-options', 
'/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/options', 
'-nolisten', 'tcp', ':601']
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO daemon:519 Child 
/usr/bin/nxagent[27166] started
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:461 /usr/bin/nxagent[27166] 
stderr: Error: Aborting session with 'Cannot establish any listening sockets - 
Make sure an X server isn't already running'.
Jul  8 12:21:21 hal9000 nxnode[27161]: ERROR agent:376 Agent error: Aborting 
session with 'Cannot establish any listening sockets - Make sure an X server 
isn't already running'.
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:461 /usr/bin/nxagent[27166] 
stderr: Session: Aborting session at 'Thu Jul  8 12:21:21 2010'.
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO agent:409 Nxagent changed status 
from 'created' to 'terminating'
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG session:288 Writing session 
'4B312A0AC2B71AC15B5EEB5334969E22' to 
'/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/neatx.data'
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:461 /usr/bin/nxagent[27166] 
stderr: Session: Session aborted at 'Thu Jul  8 12:21:21 2010'.
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO agent:409 Nxagent changed status 
from 'terminating' to 'terminated'
Jul  8 12:21:21 hal9000 nxnode[27161]: INFO agent:457 Nxagent terminated
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG session:288 Writing session 
'4B312A0AC2B71AC15B5EEB5334969E22' to 
'/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/neatx.data'
Jul  8 12:21:21 hal9000 nxnode[27161]: DEBUG daemon:560 __CheckExit 
/usr/bin/nxagent[27166] stdin:True stdout:True stderr:False exit:256
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG session:248 Loading session 
4B312A0AC2B71AC15B5EEB5334969E22 from 
/var/lib/neatx/sessions/4B312A0AC2B71AC15B5EEB5334969E22/neatx.data
Jul  8 12:21:21 hal9000 nxserver[27155]: ERROR nxserver:586 Session 
'4B312A0AC2B71AC15B5EEB5334969E22' has status 'terminated'
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG protocol:172 >>> "NX> 500 Error: 
Session '4B312A0AC2B71AC15B5EEB5334969E22' has status 'terminated', aborting\n"
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG protocol:172 >>> 'NX> 999 Bye.\n'
Jul  8 12:21:21 hal9000 nxserver[27155]: DEBUG nxserver:701 No nxagent port, 
not starting netcat
Jul  8 12:21:21 hal9000 nxserver-login[27149]: DEBUG auth:133 fdcopy exited 
(exitstatus=0, signum=None)
Jul  8 12:21:21 hal9000 nxserver-login[27149]: INFO auth:142 Waiting for 
authenticated program to finish
Jul  8 12:21:22 hal9000 nxserver-login[27149]: DEBUG auth:152 Authenticated 
program finished (exitstatus=0, signalstatus=None)
Jul  8 12:21:22 hal9000 nxserver[27144]: EOF on stderr, exiting
Jul  8 12:21:22 hal9000 nxnode[27161]: DEBUG daemon:596 __CloseEndExit 
/usr/bin/nxagent[27166] stdin:True stdout:True stderr:True
Jul  8 12:21:22 hal9000 nxnode[27161]: DEBUG daemon:560 __CheckExit 
/usr/bin/nxagent[27166] stdin:True stdout:True stderr:True exit:256
Jul  8 12:21:22 hal9000 nxnode[27161]: ERROR daemon:583 /usr/bin/nxagent[27166] 
failed (status=1, signal=None)
Jul  8 12:21:22 hal9000 nxnode[27161]: INFO node:345 nxagent terminated
Jul  8 12:21:22 hal9000 nxnode-wrapper[27158]: EOF on stderr, exiting

I have this error on multiple servers and can not seem to overcome it. I have 
tried modifying the  LD_LIBRARY_PATH as well and have had no luck with that 
attempted fix.

Original comment by Grant...@gmail.com on 8 Jul 2010 at 5:24

GoogleCodeExporter commented 9 years ago 
I have the same problem, with so fix so far.  The problem occurs for me if I 
shut down the VM (running neatx server) while still viewing the NX session from 
the client.

Original comment by drewbhar...@gmail.com on 15 Sep 2010 at 10:20