NX> 500 Internal error (just after user logs in)

[GoogleCodeExporter]

What steps will reproduce the problem?
1. install neatx on Centos 5.4 with nomachine agent.
2. using nomachine linux nxclient from ubuntu hardy, with default key
3. start login with username, pass, -> failure 


What is the expected output? What do you see instead?
I expect to see a CentOS gnome desktop, but instead see this message from the 
client:

NX> 203 NXSSH running with pid: 21827
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: 128.200.15.20 on port: 22
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: hmangala
NX> 102 Password: **********
NX> 500 Internal error
NX> 999 Bye.
NX> 280 Exiting on signal: 15

and this on the CentOS 5.4 server (/var/log/messages):
Jun 28 14:50:55 bduc-login nxserver-login[4158]: INFO nxserver_login:253 Trying 
login for user 'hmangala' using auth method 'su'
Jun 28 14:50:55 bduc-login nxserver-login[4158]: ERROR protocol:165 Error while 
handling line 'login'
Jun 28 14:50:55 bduc-login nxserver-login[4158]: Traceback (most recent call 
last):
Jun 28 14:50:55 bduc-login nxserver-login[4158]:   File 
"/usr/local/lib/python2.5/site-packages/neatx/protocol.py", line 161, in 
_HandleLine
Jun 28 14:50:55 bduc-login nxserver-login[4158]:     self._handler(line)
Jun 28 14:50:55 bduc-login nxserver-login[4158]:   File 
"/usr/local/lib/python2.5/site-packages/neatx/app/nxserver_login.py", line 76, 
in __call__
Jun 28 14:50:55 bduc-login nxserver-login[4158]:     return self._Login(args)
Jun 28 14:50:55 bduc-login nxserver-login[4158]:   File 
"/usr/local/lib/python2.5/site-packages/neatx/app/nxserver_login.py", line 173, 
in _Login
Jun 28 14:50:55 bduc-login nxserver-login[4158]:     self._TryLogin(username, 
password)
Jun 28 14:50:55 bduc-login nxserver-login[4158]:   File 
"/usr/local/lib/python2.5/site-packages/neatx/app/nxserver_login.py", line 263, 
in _TryLogin
Jun 28 14:50:55 bduc-login nxserver-login[4158]:     
authenticator.AuthenticateAndRun(username, password, args)
Jun 28 14:50:55 bduc-login nxserver-login[4158]:   File 
"/usr/local/lib/python2.5/site-packages/neatx/auth.py", line 71, in 
AuthenticateAndRun
Jun 28 14:50:55 bduc-login nxserver-login[4158]:     timeout=30)
Jun 28 14:50:55 bduc-login nxserver-login[4158]: TypeError: __init__() got an 
unexpected keyword argument 'env'
Jun 28 14:50:55 bduc-login nxserver-login-wrapper[4154]: Exception 
exceptions.AttributeError: "spawn instance has no attribute 'closed'" in <bound 
method spawn.__del__ of <pexpect.spawn instance at 0x2b9dfe05e050>> ignored


What version of the product are you using? On what operating system?
svn version 59 on linux; CentOS5.4 on the server; Ubuntu hardy on client (with 
nomachine nxclient)

The 'details' say:

HELLO NXSERVER - Version 3.3.0 - GPL
NX> 105 Hello nxclient - version 3.3.0
NX> 134 Accepted protocol: 3.3.0
Please provide any additional information below.

/etc/passwd entry:
nx:*:102:107::/usr/local/var/lib/neatx/home:/usr/local/lib/neatx/nxserver-login-
wrapper

Original issue reported on code.google.com by hjmanga...@gmail.com on 28 Jun 2010 at 10:06

[GoogleCodeExporter]

And some more info:
if I edited out the offending 'env=env' param, I get further, but it times out, 
even if I extend the timeout to 60s:
ie change
   child = pexpect.spawn(all_args[0], args=all_args[1:], env=env, timeout=30)
to
   child = pexpect.spawn(all_args[0], args=all_args[1:], timeout=60)

Then the client reports:
NX> 203 NXSSH running with pid: 24354
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: 128.200.15.20 on port: 22
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: hmangala
NX> 102 Password: **********
NX> 103 Welcome to: bduc-login.nacs.uci.edu user: hmangala
NX> 105 Listsession --user="hmangala" --status="suspended,running" 
--geometry="1680x1050x24+render" --type="unix-gnome"
NX> 127 Session list of user 'hmangala':
Display Type             Session ID                       Options  Depth Screen 
        Status      Session Name
------- ---------------- -------------------------------- -------- ----- 
-------------- ----------- ------------------------------

NX> 148 Server capacity: not reached for user: hmangala
NX> 105 Start session with: --link="lan" --backingstore="1" --encryption="1" 
--cache="16M" --images="64M" --shmem="1" --shpix="1" --strict="0" 
--composite="1" --media="1" --mediahelper="esd" --imagecompressionmethod="3" 
--imagecompressionlevel="-1" --render="1" --session="BDUC-login" 
--type="unix-gnome" --geometry="1024x768+328+141" --client="linux" 
--keyboard="pc105/us" --screeninfo="1024x768x24+render"
NX> 500 Session didn't become ready in time
NX> 999 Bye.
NX> 280 Exiting on signal: 15

The server says:
Jun 28 15:57:12 bduc-login nxserver-login[10293]: INFO nxserver_login:253 
Trying login for user 'hmangala' using auth method 'su'
Jun 28 15:57:12 bduc-login nxserver[10304]: INFO nxserver:689 Starting nxserver 
for user hmangala
Jun 28 15:57:16 bduc-login nxserver[10304]: INFO nxserver:377 Starting new 
session '98B7666476B6AB90AB332BD1687D65E5'
Jun 28 15:57:16 bduc-login nxserver[10304]: INFO node:514 Connecting to 
'/usr/local/var/lib/neatx/sessions/98B7666476B6AB90AB332BD1687D65E5/nxnode.sock'
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO nxnode:266 Connection established
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO nxnode:81 Received request: 
'start', {'backingstore': '1', 'imagecompressionlevel': '-1', 'render': '1', 
'strict': '0', 'composite': '1', 'encryption': '1', 'cache': '16M', 
'mediahelper': 'esd', 'geometry': '1024x768+328+141', 'screeninfo': 
'1024x768x24+render', 'session': 'BDUC-login', 'link': 'lan', 'shmem': '1', 
'media': '1', 'images': '64M', 'keyboard': 'pc105/us', 'type': 'unix-gnome', 
'shpix': '1', 'imagecompressionmethod': '3', 'client': 'linux'}
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO node:290 Starting xauth for 
[(':207', 'C3F4CB0563BBA2679CE2F0BB8457AB3B'), ('localhost:207', 
'C3F4CB0563BBA2679CE2F0BB8457AB3B')]
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO daemon:491 Starting program, 
executable=None, args=['/usr/bin/xauth', '-f', 
'/usr/local/var/lib/neatx/sessions/98B7666476B6AB90AB332BD1687D65E5/authority']
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO daemon:519 Child 
/usr/bin/xauth[10313] started
Jun 28 15:57:16 bduc-login nxserver[10304]: INFO nxserver:594 Waiting for 
session '98B7666476B6AB90AB332BD1687D65E5' to achieve waiting status
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO node:325 Starting nxagent
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO daemon:491 Starting program, 
executable=None, args=['/usr/local/bin/nxagent', '-D', '-name', 'Neatx - 
hmangala@bduc-login.nacs.uci.edu:207 - BDUC-login', '-options', 
'/usr/local/var/lib/neatx/sessions/98B7666476B6AB90AB332BD1687D65E5/options', 
'-nolisten', 'tcp', ':207']
Jun 28 15:57:16 bduc-login nxnode[10310]: INFO daemon:519 Child 
/usr/local/bin/nxagent[10314] started
Jun 28 15:57:17 bduc-login nxnode[10310]: ERROR daemon:583 
/usr/local/bin/nxagent[10314] failed (status=127, signal=None)
Jun 28 15:57:17 bduc-login nxnode[10310]: INFO node:345 nxagent terminated
Jun 28 15:57:46 bduc-login nxserver[10304]: ERROR nxserver:600 Session 
98B7666476B6AB90AB332BD1687D65E5 has not achieved waiting status within 30 
seconds
Jun 28 15:57:46 bduc-login nxserver-login[10293]: INFO auth:142 Waiting for 
authenticated program to finish

The server says that the nxagent failed, tho not why.  (possibly bc it didn't 
get passed the env?  

Original comment by hjmanga...@gmail.com on 28 Jun 2010 at 11:23