2018-12-17 14:42:20 274.157 29913 NXSERVER libnxh::NXGetNetworkInterfaces start. 2018-12-17 14:42:20 274.950 29913 NXSERVER libnxh::NXGetNetworkInterfaces returned: lo AF_INET 00:00:00:00:00:00 127.0.0.1 255.0.0.0 10.111.2.1\nbond0 AF_INET 0c:c4:7a:4b:f3:d2 10.111.2.14 255.255.255.0 10.111.2.1\n. 2018-12-17 14:42:20 275.122 29913 NXSERVER Retrieved server ipv4: 10.111.2.14. 2018-12-17 14:42:20 275.224 29913 NXSERVER Retrieved server ipv6: . 2018-12-17 14:42:20 275.304 29913 NXSERVER Retrieved server ip: 10.111.2.14,. 2018-12-17 14:42:20 275.422 29913 NXSERVER Cluster feature disabled. 2018-12-17 14:42:20 275.523 29913 NXSERVER Setting new read timeout to 30000. 2018-12-17 14:42:46 753.695 16623 NXSERVER Process initialized with arguments '-c /etc/NX/nxserver --login -H 4'. 2018-12-17 14:42:46 754.353 16623 NXSERVER userInfo: get dir for username: nx return /var/NX/nx. 2018-12-17 14:42:46 755.006 16623 NXSERVER NXOpen FD#8 - file /usr/NX/etc/uuid with mode 0 and permissions 0 from Server::getMyUUID. 2018-12-17 14:42:46 755.209 16623 NXSERVER NXClose FD#8 from Server::getMyUUID. 2018-12-17 14:42:46 755.321 16623 NXSERVER Got uuid from file eddb935b-0874-4384-a9a8-89c115d8795f. 2018-12-17 14:42:46 791.946 16623 NXSERVER Effective uid is: 597. 2018-12-17 14:42:46 792.186 16623 NXSERVER userInfo: get username for uid: 597 return nx. 2018-12-17 14:42:46 792.269 16623 NXSERVER getEffectiveUsername: username nx. 2018-12-17 14:42:46 792.428 16623 NXSERVER NXOpen FD#8 - file /usr/NX/etc/nodes.db.lock with mode 1089 and permissions 420 from main::lockFile. 2018-12-17 14:42:46 792.538 16623 NXSERVER nxflock: libnxh::NXFileLock(8, 1) returned '1'. 2018-12-17 14:42:46 792.644 16623 NXSERVER NXOpen FD#9 - file /usr/NX/etc/nodes.db with mode 0 and permissions 0 from NXNodes::reloadNodesDB. 2018-12-17 14:42:46 792.908 16623 NXSERVER NXClose FD#9 from NXNodes::reloadNodesDB. 2018-12-17 14:42:46 793.026 16623 NXSERVER NXClose FD#8 from main::unLockFile. 2018-12-17 14:42:46 793.762 16623 NXSERVER NXOpen FD#8 - file /usr/NX/etc/server.lic with mode 0 and permissions 0 from NXLicense::readLicenseFile. 2018-12-17 14:42:46 794.009 16623 NXSERVER NXClose FD#8 from NXLicense::readLicenseFile. 2018-12-17 14:42:46 794.432 16623 NXSERVER NXEvent: creating new event licenseExpired. 2018-12-17 14:42:46 794.518 16623 NXSERVER NXEvent: adding action NXLicense::setLicenseExpired for event licenseExpired. 2018-12-17 14:42:46 794.622 16623 NXSERVER NXSelector:addTimestampCallback: adding callback 'licenseExpired' with timestamp '1547639851' and handler 'defaultEventHandler'. 2018-12-17 14:42:46 795.021 16623 NXSERVER NXProfilesDB: Loading profiles. 2018-12-17 14:42:46 795.157 16623 NXSERVER NXOpen FD#8 - file /usr/NX/etc/profiles.db.lock with mode 1089 and permissions 420 from main::lockFile. 2018-12-17 14:42:46 795.244 16623 NXSERVER nxflock: libnxh::NXFileLock(8, 1) returned '1'. 2018-12-17 14:42:46 795.332 16623 NXSERVER NXOpen FD#9 - file /usr/NX/etc/profiles.db with mode 0 and permissions 0 from NXProfilesDB::__load. 2018-12-17 14:42:46 795.444 16623 NXSERVER NXClose FD#9 from NXProfilesDB::__load. 2018-12-17 14:42:46 795.532 16623 NXSERVER NXClose FD#8 from main::unLockFile. 2018-12-17 14:42:46 795.614 16623 NXSERVER NXProfilesDB: Converting usernames to SIDs. 2018-12-17 14:42:46 795.842 16623 NXSERVER Client connected through NXD. 2018-12-17 14:42:46 796.004 16623 NXSERVER NXPipeCreate(FD#8, FD#9, 1) returned 0. 2018-12-17 14:42:46 796.110 16623 NXSERVER NXPipeCreate(FD#10, FD#11, 1) returned 0. 2018-12-17 14:42:46 796.179 16623 NXSERVER Created encryptorCreateIn 10, encryptorCreateOut 11 with 1. 2018-12-17 14:42:46 796.286 16623 NXSERVER NXOpen FD#12 - file /usr/NX/lib/perl/nxserver/NXCluster.pm with mode 0 and permissions 0 from main::nxrequire. 2018-12-17 14:42:46 800.153 16623 NXSERVER NXClose FD#12 from main::nxrequire. 2018-12-17 14:42:46 800.294 16623 NXSERVER NXOpen FD#12 - file /usr/NX/lib/perl/nxserver/NXClusterTools.pm with mode 0 and permissions 0 from main::nxrequire. 2018-12-17 14:42:46 810.085 16623 NXSERVER NXClose FD#12 from main::nxrequire. 2018-12-17 14:42:46 810.208 16623 NXSERVER Cluster database doesn't exist. 2018-12-17 14:42:46 810.280 16623 NXSERVER Creating encryptor. 2018-12-17 14:42:46 810.351 16623 NXSERVER libnxh::NXEncryptorCreate(4, 4, 9, 9) 2018-12-17 14:42:46 810.785 16623 NXSERVER Mode -H got: 8 2018-12-17 14:42:46 810.886 16623 NXSERVER setInheritConnectionDescriptor set to: no 2018-12-17 14:42:46 810.956 16623 NXSERVER Connection to client open or nulled. 2018-12-17 14:42:46 829.434 16623 NXSERVER Something to read from encryptor create FD#10. 2018-12-17 14:42:46 829.581 16623 NXSERVER Read from encryptor create: 1\n. 2018-12-17 14:42:46 829.691 16623 NXSERVER NXClose FD#10 from Server::getNXEncryptorContext. 2018-12-17 14:42:46 829.802 16623 NXSERVER libnxh::NXEncryptorGetContext 'MHoCAQECAgMDBALALwQg/kSeZLalhuorHuBOjnhgew2REI11pHhvha3vKCdQn1EEMAXgWo018WZ2lHtbDfv+EppCzjoZDRIBNblW8rx8+w364JMx/cUbtDhSIJdb0h8q96EGAgRcF7XmogQCAhwgpAs\n' after 0 seconds. 2018-12-17 14:42:46 830.243 16623 NXSERVER Get command state machine: Setting state: sendCommandWelcome. 2018-12-17 14:42:46 830.316 16623 NXSERVER Registering state machine GetCommandStateMachine: GetCommandStateMachine::run, GetCommandStateMachine::getTimeout. 2018-12-17 14:42:46 830.403 16623 NXSERVER Check state machines. 2018-12-17 14:42:46 830.469 16623 NXSERVER Get command state machine: Start in state: sendCommandWelcome 2018-12-17 14:42:46 830.544 16623 NXSERVER Get command state machine: Handling state: sendCommandWelcome. 2018-12-17 14:42:46 830.608 16623 NXSERVER handling command 'welcome' '' 2018-12-17 14:42:46 830.767 16623 NXSERVER Connection to client still open. 2018-12-17 14:42:46 830.832 16623 NXSERVER NXMsg: Sent response without number 'HELLO NXSERVER - Version 6.4.6 - NoMachine Enterprise Terminal Server Evaluation' 2018-12-17 14:42:46 830.920 16623 NXSERVER Get command state machine: Transition from sendCommandWelcome with event sentWelcome is sendPrompt. 2018-12-17 14:42:46 830.976 16623 NXSERVER Get command state machine: Setting state: sendPrompt. 2018-12-17 14:42:46 831.045 16623 NXSERVER Get command state machine: Handling state: sendPrompt. 2018-12-17 14:42:46 831.103 16623 NXSERVER Connection to client still open. 2018-12-17 14:42:46 831.172 16623 NXSERVER Sent message: NX> 105 2018-12-17 14:42:46 831.232 16623 NXSERVER Get command state machine: Transition from sendPrompt with event emptyBuffer is waitingForCommand. 2018-12-17 14:42:46 831.298 16623 NXSERVER Get command state machine: Setting state: waitingForCommand. 2018-12-17 14:42:46 831.370 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackFromStdin (GetCommandStateMachine). 2018-12-17 14:42:46 831.440 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackStdinClosed (GetCommandStateMachine). 2018-12-17 14:42:46 831.513 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackFromStdin (GetCommandStateMachine). 2018-12-17 14:42:46 831.576 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackStdinClosed (GetCommandStateMachine). 2018-12-17 14:42:46 831.641 16623 NXSERVER Get command state machine: Stop in state: waitingForCommand 2018-12-17 14:42:46 831.712 16623 NXSERVER Start sleep in select. 2018-12-17 14:42:46 831.954 16623 NXSERVER NXParser: adding handle '6' FD#6 to the selector. 2018-12-17 14:42:46 832.058 16623 NXSERVER Adding callback for FD#8: GetCommandStateMachine::callbackFromStdin. 2018-12-17 14:42:46 832.141 16623 NXSERVER NXParser: adding handle '8' FD#8 to the selector. 2018-12-17 14:42:46 832.227 16623 NXSERVER Adding callback with type 'Close' for FD#8: GetCommandStateMachine::callbackStdinClosed. 2018-12-17 14:42:46 832.294 16623 NXSERVER NXParser: setting callback type Close for handler FD#8 to GetCommandStateMachine::callbackStdinClosed. 2018-12-17 14:42:46 832.384 16623 NXSERVER NXParser: main loop started with timeout inf. 2018-12-17 14:42:46 832.479 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:46 834.372 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:46 834.547 16623 NXSERVER Get command state machine: Read from the stdin: hello NXCLIENT - Version 6.0.62 - Language en_US - UUID d581ade2-cefe-4df8-8e83-459394d6f1b7\n. 2018-12-17 14:42:46 834.632 16623 NXSERVER Get command state machine: Transition from waitingForCommand with event receivedInput is parseBuffer. 2018-12-17 14:42:46 834.704 16623 NXSERVER Get command state machine: Setting state: parseBuffer. 2018-12-17 14:42:46 834.774 16623 NXSERVER NXParser: exiting from main loop with code [0] and result [0]. 2018-12-17 14:42:46 834.865 16623 NXSERVER Check state machines. 2018-12-17 14:42:46 834.928 16623 NXSERVER Get command state machine: Start in state: parseBuffer 2018-12-17 14:42:46 834.998 16623 NXSERVER Get command state machine: Handling state: parseBuffer. 2018-12-17 14:42:46 835.082 16623 NXSERVER Get command state machine: Transition from parseBuffer with event receivedCommand is parseCommand. 2018-12-17 14:42:46 835.140 16623 NXSERVER Get command state machine: Setting state: parseCommand. 2018-12-17 14:42:46 835.202 16623 NXSERVER Get command state machine: Handling state: parseCommand. 2018-12-17 14:42:46 835.269 16623 NXSERVER received response 'hello NXCLIENT - Version 6.0.62 - Language en_US - UUID d581ade2-cefe-4df8-8e83-459394d6f1b7' 2018-12-17 14:42:46 835.331 16623 NXSERVER Get command state machine: Transition from parseCommand with event commandReady is echoCommand. 2018-12-17 14:42:46 835.383 16623 NXSERVER Get command state machine: Setting state: echoCommand. 2018-12-17 14:42:46 835.444 16623 NXSERVER Get command state machine: Handling state: echoCommand. 2018-12-17 14:42:46 835.561 16623 NXSERVER Get command state machine: Transition from echoCommand with event commandEchoed is checkCommand. 2018-12-17 14:42:46 835.624 16623 NXSERVER Get command state machine: Setting state: checkCommand. 2018-12-17 14:42:46 835.692 16623 NXSERVER Get command state machine: Handling state: checkCommand. 2018-12-17 14:42:46 835.762 16623 NXSERVER Command hello is assigned to -2. 2018-12-17 14:42:46 835.863 16623 NXSERVER no user currently logged in 2018-12-17 14:42:46 835.924 16623 NXSERVER Get command state machine: Transition from checkCommand with event allowedCommand is handleCommand. 2018-12-17 14:42:46 835.976 16623 NXSERVER Get command state machine: Setting state: handleCommand. 2018-12-17 14:42:46 836.044 16623 NXSERVER Get command state machine: Handling state: handleCommand. 2018-12-17 14:42:46 836.101 16623 NXSERVER Get command state machine: Handling command: hello. 2018-12-17 14:42:46 836.196 16623 NXSERVER handling command 'hello' 'NXCLIENT - Version 6.0.62 - Language en_US - UUID d581ade2-cefe-4df8-8e83-459394d6f1b7' 2018-12-17 14:42:46 836.288 16623 NXSERVER Server::setClientBrowser: . 2018-12-17 14:42:46 836.380 16623 NXSERVER Client set to nxclient - 6.0.62 2018-12-17 14:42:46 836.655 16623 NXSERVER NXShell: bye means silent exit. 2018-12-17 14:42:46 836.784 16623 NXSERVER Language chosen by client en_US. 2018-12-17 14:42:46 836.916 16623 NXSERVER NXOpen FD#10 - file /usr/NX/lib/perl/nxserver/NXScripts.pm with mode 0 and permissions 0 from main::nxrequire. 2018-12-17 14:42:46 841.135 16623 NXSERVER NXClose FD#10 from main::nxrequire. 2018-12-17 14:42:46 841.342 16623 NXSERVER NXOpen FD#10 - file /usr/NX/etc/hosts.db with mode 0 and permissions 0 from main::getClientRedirect. 2018-12-17 14:42:46 841.440 16623 NXSERVER NXClose FD#10 from main::getClientRedirect. 2018-12-17 14:42:46 841.510 16623 NXSERVER Cannot be found client IP: '10.111.2.31' in hosts.db. 2018-12-17 14:42:46 841.637 16623 NXSERVER Connection to client still open. 2018-12-17 14:42:46 841.708 16623 NXSERVER NXMsg: Sent response message 'NX> 134 Accepted protocol: 6.0.62' 2018-12-17 14:42:46 841.820 16623 NXSERVER Timestamp 'Command hello' time passed '0.005' seconds. 2018-12-17 14:42:46 841.876 16623 NXSERVER Finished handling command 'hello' after 0.005 seconds. 2018-12-17 14:42:46 841.943 16623 NXSERVER Get command state machine: Transition from handleCommand with event commandFinished is sendPrompt. 2018-12-17 14:42:46 841.993 16623 NXSERVER Get command state machine: Setting state: sendPrompt. 2018-12-17 14:42:46 842.056 16623 NXSERVER Get command state machine: Handling state: sendPrompt. 2018-12-17 14:42:46 842.113 16623 NXSERVER Connection to client still open. 2018-12-17 14:42:46 842.187 16623 NXSERVER Sent message: NX> 105 2018-12-17 14:42:46 842.248 16623 NXSERVER Get command state machine: Transition from sendPrompt with event emptyBuffer is waitingForCommand. 2018-12-17 14:42:46 842.297 16623 NXSERVER Get command state machine: Setting state: waitingForCommand. 2018-12-17 14:42:46 842.360 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackFromStdin (GetCommandStateMachine). 2018-12-17 14:42:46 842.425 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackStdinClosed (GetCommandStateMachine). 2018-12-17 14:42:46 842.491 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackFromStdin (GetCommandStateMachine). 2018-12-17 14:42:46 842.548 16623 NXSERVER Registering descriptor FD#8 with callback GetCommandStateMachine::callbackStdinClosed (GetCommandStateMachine). 2018-12-17 14:42:46 842.617 16623 NXSERVER Get command state machine: Stop in state: waitingForCommand 2018-12-17 14:42:46 842.678 16623 NXSERVER Start sleep in select. 2018-12-17 14:42:46 842.885 16623 NXSERVER NXParser: adding handle '6' FD#6 to the selector. 2018-12-17 14:42:46 842.983 16623 NXSERVER Adding callback for FD#8: GetCommandStateMachine::callbackFromStdin. 2018-12-17 14:42:46 843.058 16623 NXSERVER NXParser: adding handle '8' FD#8 to the selector. 2018-12-17 14:42:46 843.136 16623 NXSERVER Adding callback with type 'Close' for FD#8: GetCommandStateMachine::callbackStdinClosed. 2018-12-17 14:42:46 843.200 16623 NXSERVER NXParser: setting callback type Close for handler FD#8 to GetCommandStateMachine::callbackStdinClosed. 2018-12-17 14:42:46 843.286 16623 NXSERVER NXParser: main loop started with timeout inf. 2018-12-17 14:42:46 843.373 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:46 845.418 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:46 845.585 16623 NXSERVER Get command state machine: Read from the stdin: SET SHELL_MODE SHELL\nSET AUTH_MODE PASSWORD\nlogin\n. 2018-12-17 14:42:46 845.683 16623 NXSERVER Get command state machine: Transition from waitingForCommand with event receivedInput is parseBuffer. 2018-12-17 14:42:46 845.744 16623 NXSERVER Get command state machine: Setting state: parseBuffer. 2018-12-17 14:42:46 845.813 16623 NXSERVER NXParser: exiting from main loop with code [0] and result [0]. 2018-12-17 14:42:46 845.905 16623 NXSERVER Check state machines. 2018-12-17 14:42:46 845.978 16623 NXSERVER Get command state machine: Start in state: parseBuffer 2018-12-17 14:42:46 846.038 16623 NXSERVER Get command state machine: Handling state: parseBuffer. 2018-12-17 14:42:46 846.119 16623 NXSERVER Get command state machine: Transition from parseBuffer with event receivedCommand is parseCommand. 2018-12-17 14:42:46 846.170 16623 NXSERVER Get command state machine: Setting state: parseCommand. 2018-12-17 14:42:46 846.229 16623 NXSERVER Get command state machine: Handling state: parseCommand. 2018-12-17 14:42:46 846.292 16623 NXSERVER received response 'SET SHELL_MODE SHELL' 2018-12-17 14:42:46 846.351 16623 NXSERVER Get command state machine: Transition from parseCommand with event commandReady is echoCommand. 2018-12-17 14:42:46 846.400 16623 NXSERVER Get command state machine: Setting state: echoCommand. 2018-12-17 14:42:46 846.458 16623 NXSERVER Get command state machine: Handling state: echoCommand. 2018-12-17 14:42:46 846.572 16623 NXSERVER Get command state machine: Transition from echoCommand with event commandEchoed is checkCommand. 2018-12-17 14:42:46 846.624 16623 NXSERVER Get command state machine: Setting state: checkCommand. 2018-12-17 14:42:46 846.702 16623 NXSERVER Get command state machine: Handling state: checkCommand. 2018-12-17 14:42:46 846.765 16623 NXSERVER Command set is assigned to -5. 2018-12-17 14:42:46 846.854 16623 NXSERVER no user currently logged in 2018-12-17 14:42:46 846.912 16623 NXSERVER Get command state machine: Transition from checkCommand with event allowedCommand is handleCommand. 2018-12-17 14:42:46 846.966 16623 NXSERVER Get command state machine: Setting state: handleCommand. 2018-12-17 14:42:46 847.024 16623 NXSERVER Get command state machine: Handling state: handleCommand. 2018-12-17 14:42:46 847.077 16623 NXSERVER Get command state machine: Handling command: set. 2018-12-17 14:42:46 847.166 16623 NXSERVER handling command 'set' 'SHELL_MODE SHELL' 2018-12-17 14:42:46 847.270 16623 NXSERVER Timestamp 'Command set' time passed '0.0' seconds. 2018-12-17 14:42:46 847.322 16623 NXSERVER Finished handling command 'set' after 0.0 seconds. 2018-12-17 14:42:46 847.379 16623 NXSERVER Get command state machine: Transition from handleCommand with event commandFinished is sendPrompt. 2018-12-17 14:42:46 847.429 16623 NXSERVER Get command state machine: Setting state: sendPrompt. 2018-12-17 14:42:46 847.488 16623 NXSERVER Get command state machine: Handling state: sendPrompt. 2018-12-17 14:42:46 847.541 16623 NXSERVER Connection to client still open. 2018-12-17 14:42:46 847.605 16623 NXSERVER Sent message: NX> 105 2018-12-17 14:42:46 847.670 16623 NXSERVER Get command state machine: Transition from sendPrompt with event notEmptyBuffer is parseBuffer. 2018-12-17 14:42:46 847.725 16623 NXSERVER Get command state machine: Setting state: parseBuffer. 2018-12-17 14:42:46 847.784 16623 NXSERVER Get command state machine: Handling state: parseBuffer. 2018-12-17 14:42:46 847.847 16623 NXSERVER Get command state machine: Transition from parseBuffer with event receivedCommand is parseCommand. 2018-12-17 14:42:46 847.898 16623 NXSERVER Get command state machine: Setting state: parseCommand. 2018-12-17 14:42:46 847.956 16623 NXSERVER Get command state machine: Handling state: parseCommand. 2018-12-17 14:42:46 848.017 16623 NXSERVER received response 'SET AUTH_MODE PASSWORD' 2018-12-17 14:42:46 848.071 16623 NXSERVER Get command state machine: Transition from parseCommand with event commandReady is echoCommand. 2018-12-17 14:42:46 848.120 16623 NXSERVER Get command state machine: Setting state: echoCommand. 2018-12-17 14:42:46 848.177 16623 NXSERVER Get command state machine: Handling state: echoCommand. 2018-12-17 14:42:46 848.270 16623 NXSERVER Get command state machine: Transition from echoCommand with event commandEchoed is checkCommand. 2018-12-17 14:42:46 848.323 16623 NXSERVER Get command state machine: Setting state: checkCommand. 2018-12-17 14:42:46 848.382 16623 NXSERVER Get command state machine: Handling state: checkCommand. 2018-12-17 14:42:46 848.437 16623 NXSERVER Command set is assigned to -5. 2018-12-17 14:42:46 848.501 16623 NXSERVER no user currently logged in 2018-12-17 14:42:46 848.555 16623 NXSERVER Get command state machine: Transition from checkCommand with event allowedCommand is handleCommand. 2018-12-17 14:42:46 848.605 16623 NXSERVER Get command state machine: Setting state: handleCommand. 2018-12-17 14:42:46 848.675 16623 NXSERVER Get command state machine: Handling state: handleCommand. 2018-12-17 14:42:46 848.730 16623 NXSERVER Get command state machine: Handling command: set. 2018-12-17 14:42:46 848.802 16623 NXSERVER handling command 'set' 'AUTH_MODE PASSWORD' 2018-12-17 14:42:46 848.916 16623 NXSERVER Timestamp 'Command set' time passed '0.0' seconds. 2018-12-17 14:42:46 848.975 16623 NXSERVER Finished handling command 'set' after 0.0 seconds. 2018-12-17 14:42:46 849.032 16623 NXSERVER Get command state machine: Transition from handleCommand with event commandFinished is sendPrompt. 2018-12-17 14:42:46 849.082 16623 NXSERVER Get command state machine: Setting state: sendPrompt. 2018-12-17 14:42:46 849.142 16623 NXSERVER Get command state machine: Handling state: sendPrompt. 2018-12-17 14:42:46 849.194 16623 NXSERVER Connection to client still open. 2018-12-17 14:42:46 849.257 16623 NXSERVER Sent message: NX> 105 2018-12-17 14:42:46 849.313 16623 NXSERVER Get command state machine: Transition from sendPrompt with event notEmptyBuffer is parseBuffer. 2018-12-17 14:42:46 849.366 16623 NXSERVER Get command state machine: Setting state: parseBuffer. 2018-12-17 14:42:46 849.423 16623 NXSERVER Get command state machine: Handling state: parseBuffer. 2018-12-17 14:42:46 849.486 16623 NXSERVER Get command state machine: Transition from parseBuffer with event receivedCommand is parseCommand. 2018-12-17 14:42:46 849.543 16623 NXSERVER Get command state machine: Setting state: parseCommand. 2018-12-17 14:42:46 849.602 16623 NXSERVER Get command state machine: Handling state: parseCommand. 2018-12-17 14:42:46 849.660 16623 NXSERVER received response 'login ' 2018-12-17 14:42:46 849.735 16623 NXSERVER Get command state machine: Transition from parseCommand with event commandReady is echoCommand. 2018-12-17 14:42:46 849.785 16623 NXSERVER Get command state machine: Setting state: echoCommand. 2018-12-17 14:42:46 849.843 16623 NXSERVER Get command state machine: Handling state: echoCommand. 2018-12-17 14:42:46 849.929 16623 NXSERVER Get command state machine: Transition from echoCommand with event commandEchoed is checkCommand. 2018-12-17 14:42:46 849.986 16623 NXSERVER Get command state machine: Setting state: checkCommand. 2018-12-17 14:42:46 850.052 16623 NXSERVER Get command state machine: Handling state: checkCommand. 2018-12-17 14:42:46 850.105 16623 NXSERVER Command login is assigned to -1. 2018-12-17 14:42:46 850.164 16623 NXSERVER no user currently logged in 2018-12-17 14:42:46 850.216 16623 NXSERVER Get command state machine: Transition from checkCommand with event allowedCommand is handleCommand. 2018-12-17 14:42:46 850.264 16623 NXSERVER Get command state machine: Setting state: handleCommand. 2018-12-17 14:42:46 850.319 16623 NXSERVER Get command state machine: Handling state: handleCommand. 2018-12-17 14:42:46 850.367 16623 NXSERVER Get command state machine: Handling command: login. 2018-12-17 14:42:46 850.433 16623 NXSERVER handling command 'login' '' 2018-12-17 14:42:46 850.538 16623 NXSERVER Local IP determined from NX_CONNECTION: 10.111.2.31 46870 10.111.2.14 4000 2018-12-17 14:42:46 850.777 16623 NXSERVER NXMsg: Sent request message 'NX> 250 Properties: username required for freia004 port: 4000 service login: ' 2018-12-17 14:42:46 850.929 16623 NXSERVER NXParser: adding handle '6' FD#6 to the selector. 2018-12-17 14:42:46 851.032 16623 NXSERVER NXParser: adding handle '8' FD#8 to the selector. 2018-12-17 14:42:46 851.140 16623 NXSERVER NXParser: main loop started with timeout inf. 2018-12-17 14:42:46 851.217 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:50 306.034 29913 NXSERVER libnxh::NXGetNetworkInterfaces start. 2018-12-17 14:42:50 306.609 29913 NXSERVER libnxh::NXGetNetworkInterfaces returned: lo AF_INET 00:00:00:00:00:00 127.0.0.1 255.0.0.0 10.111.2.1\nbond0 AF_INET 0c:c4:7a:4b:f3:d2 10.111.2.14 255.255.255.0 10.111.2.1\n. 2018-12-17 14:42:50 306.738 29913 NXSERVER Retrieved server ipv4: 10.111.2.14. 2018-12-17 14:42:50 306.810 29913 NXSERVER Retrieved server ipv6: . 2018-12-17 14:42:50 306.891 29913 NXSERVER Retrieved server ip: 10.111.2.14,. 2018-12-17 14:42:50 306.986 29913 NXSERVER Cluster feature disabled. 2018-12-17 14:42:50 307.080 29913 NXSERVER Setting new read timeout to 30000. 2018-12-17 14:42:53 592.930 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 593.176 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 593.353 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 593.469 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 593.594 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 593.717 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 593.845 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 593.954 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 594.072 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 594.238 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 594.362 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 594.467 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 594.589 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 594.721 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 594.847 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 595.059 16623 NXSERVER NXMsg: Received response 'drobson' 2018-12-17 14:42:53 595.495 16623 NXSERVER NXParser: adding handle '6' FD#6 to the selector. 2018-12-17 14:42:53 595.615 16623 NXSERVER NXParser: adding handle '8' FD#8 to the selector. 2018-12-17 14:42:53 595.727 16623 NXSERVER NXMsg: We are not connected to console. 2018-12-17 14:42:53 595.809 16623 NXSERVER NXMsg: Sent request message 'NX> 250 Properties: password required for freia004 port: 4000 service login: ' 2018-12-17 14:42:53 595.889 16623 NXSERVER NXParser: main loop started with timeout inf. 2018-12-17 14:42:53 595.984 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 596.794 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 596.936 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 597.091 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 597.201 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 597.323 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 597.429 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 597.548 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 597.652 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 597.787 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 597.892 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 598.027 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 598.136 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 598.254 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 598.357 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 598.482 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 598.585 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 598.716 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 598.858 16623 NXSERVER NXMsg: We are not connected to console. 2018-12-17 14:42:53 598.933 16623 NXSERVER NXMsg: Received response '********' 2018-12-17 14:42:53 599.280 16623 NXSERVER userInfo: get uid for username: nx return 597. 2018-12-17 14:42:53 599.359 16623 NXSERVER userInfo: get uid for username: drobson return 301. 2018-12-17 14:42:53 599.546 16623 NXSERVER userInfo: get uid for username: nx return 597. 2018-12-17 14:42:53 599.617 16623 NXSERVER userInfo: get uid for username: drobson return 301. 2018-12-17 14:42:53 599.769 16623 NXSERVER checking user drobson credentials 2018-12-17 14:42:53 599.922 16623 NXSERVER NXRunCommand: setting command to '/usr/NX/bin/nxexec --auth'. 2018-12-17 14:42:53 600.034 16623 NXSERVER NXRunCommand: setting to save pid to 'SCALAR(0x43da060)'. 2018-12-17 14:42:53 600.105 16623 NXSERVER NXRunCommand: setting CMD_IN not to be closed. 2018-12-17 14:42:53 600.189 16623 NXSERVER NXRunCommand: setChildDescriptors started 2018-12-17 14:42:53 600.247 16623 NXSERVER NXRunCommand: __setupParentChildStdin started 2018-12-17 14:42:53 600.356 16623 NXSERVER NXPipeCreate(FD#10, FD#12, 0) returned '1' 2018-12-17 14:42:53 600.440 16623 NXSERVER NXRunCommand: __setupParentChildStdout started 2018-12-17 14:42:53 600.515 16623 NXSERVER NXPipeCreate(FD#13, FD#14, 0) returned '1' 2018-12-17 14:42:53 600.578 16623 NXSERVER NXRunCommand: open FD#13 in SCALAR(0x43da020) 2018-12-17 14:42:53 600.699 16623 NXSERVER NXOpen FD#15 - file /usr/NX/var/log/nxerror.log with mode 1089 and permissions 384 from Common::NXRunCommand::__setupParentChildStderr. 2018-12-17 14:42:53 600.777 16623 NXSERVER NXRunCommand: getChildStdin return 10 2018-12-17 14:42:53 600.838 16623 NXSERVER Descriptors set. Child :10, 14, 15. Parent 12, 13. 2018-12-17 14:42:53 600.894 16623 NXSERVER NXRunCommand: getChildStdin return 10 2018-12-17 14:42:53 600.969 16623 NXSERVER nxProcessCreate: '/usr/NX/bin/nxexec' '/usr/NX/bin/nxexec /usr/NX/bin/nxexec --auth' '' '10' '14' '15' '101' 2018-12-17 14:42:53 603.432 16623 NXSERVER Added '17296/17296' to children list. 2018-12-17 14:42:53 603.781 16623 NXSERVER NXRunCommand: Closing ChildStdin 2018-12-17 14:42:53 603.864 16623 NXSERVER NXRunCommand: getChildStdin return 10 2018-12-17 14:42:53 603.948 16623 NXSERVER NXClose FD#10 from Common::NXRunCommand::closeChildDescriptors. 2018-12-17 14:42:53 604.063 16623 NXSERVER NXRunCommand: Closing ChildStdout 2018-12-17 14:42:53 604.127 16623 NXSERVER NXClose FD#14 from Common::NXRunCommand::closeChildDescriptors. 2018-12-17 14:42:53 604.195 16623 NXSERVER NXRunCommand: Closing ChildStderr 2018-12-17 14:42:53 604.262 16623 NXSERVER NXClose FD#15 from Common::NXRunCommand::closeChildDescriptors. 2018-12-17 14:42:53 604.322 16623 NXSERVER NXRunCommand: child descriptors closed. 2018-12-17 14:42:53 604.399 16623 NXSERVER NXRunCommand: saving pid in SCALAR(0x43da060) 2018-12-17 14:42:53 604.497 16623 NXSERVER NXRunCommand: running in background. 2018-12-17 14:42:53 604.617 16623 NXSERVER The running nxexec pid is: 17296 2018-12-17 14:42:53 604.686 16623 NXSERVER The running nxexec in/out/err 12/13/ 2018-12-17 14:42:53 604.908 16623 NXSERVER NXParser: adding handle '6' FD#6 to the selector. 2018-12-17 14:42:53 605.087 16623 NXSERVER NXParser: adding handle '13' FD#13 to the selector. 2018-12-17 14:42:53 605.209 16623 NXSERVER NXParser: main loop started with timeout 30. 2018-12-17 14:42:53 605.316 16623 NXSERVER NXParser: set timeout to : 30000. 2018-12-17 14:42:53 606.972 16623 NXSERVER NXParser: checkCanRead has: FD#13 2018-12-17 14:42:53 607.101 16623 NXSERVER Something to read on stdout of nxexec. 2018-12-17 14:42:53 607.166 16623 NXSERVER Read on stdout: 'Username:'. 2018-12-17 14:42:53 607.307 16623 NXSERVER Printing username to stdin of nxexec: drobson 2018-12-17 14:42:53 607.425 16623 NXSERVER NXParser: set timeout to : 29998. 2018-12-17 14:42:53 613.704 16623 NXSERVER NXParser: checkCanRead has: FD#13 2018-12-17 14:42:53 613.887 16623 NXSERVER Something to read on stdout of nxexec. 2018-12-17 14:42:53 613.973 16623 NXSERVER Read on stdout: 'Password: \n'. 2018-12-17 14:42:53 614.109 16623 NXSERVER Printing password to stdout of nxexec: ******** 2018-12-17 14:42:53 614.253 16623 NXSERVER NXParser: set timeout to : 29991. 2018-12-17 14:42:53 653.080 16623 NXSERVER NXParser: checkCanRead has: FD#13 2018-12-17 14:42:53 653.240 16623 NXSERVER Something to read on stdout of nxexec. 2018-12-17 14:42:53 653.300 16623 NXSERVER Read on stdout: 'Password: \n'. 2018-12-17 14:42:53 653.414 16623 NXSERVER Second request for standard password detected with prompt: Password: . 2018-12-17 14:42:53 653.759 16623 NXSERVER NXParser: adding handle '6' FD#6 to the selector. 2018-12-17 14:42:53 653.885 16623 NXSERVER NXParser: adding handle '8' FD#8 to the selector. 2018-12-17 14:42:53 653.985 16623 NXSERVER NXMsg: We are not connected to console. 2018-12-17 14:42:53 654.070 16623 NXSERVER NXMsg: Sent request message 'NX> 250 Properties: password required for freia004 port: 4000 service login: ' 2018-12-17 14:42:53 654.169 16623 NXSERVER NXParser: main loop started with timeout inf. 2018-12-17 14:42:53 654.254 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 656.208 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 656.342 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 656.474 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 656.575 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 656.696 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 656.793 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 656.898 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 656.990 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 657.092 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 657.184 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 657.285 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 657.374 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 657.481 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 657.570 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 657.682 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 657.776 16623 NXSERVER NXParser: set timeout to : -1. 2018-12-17 14:42:53 657.878 16623 NXSERVER NXParser: checkCanRead has: FD#8 2018-12-17 14:42:53 658.027 16623 NXSERVER NXMsg: We are not connected to console. 2018-12-17 14:42:53 658.092 16623 NXSERVER NXMsg: Received response '********' 2018-12-17 14:42:53 658.186 16623 NXSERVER Printing password to stdout of nxexec: ******** 2018-12-17 14:42:53 658.288 16623 NXSERVER NXParser: set timeout to : 29947. 2018-12-17 14:42:56 525.392 16623 NXSERVER NXParser: checkCanRead has: FD#13 2018-12-17 14:42:56 525.644 16623 NXSERVER Something to read on stdout of nxexec. 2018-12-17 14:42:56 525.769 16623 NXSERVER Read on stdout: 'Enter Your Microsoft verification code\n'. 2018-12-17 14:42:56 525.951 16623 NXSERVER The nxexec received following message on stdout [Enter Your Microsoft verification code]. 2018-12-17 14:42:56 526.123 16623 NXSERVER NXParser: set timeout to : 27079.