2015-09-29T11:43:19.539981-05:00 myhost1 nxserver[13211]: Current user is 'testuser1' from '10.10.106.52' 2015-09-29T11:43:19.540055-05:00 myhost1 nxserver[13211]: User not yet authorized to access. 2015-09-29T11:43:19.540143-05:00 myhost1 nxserver[13211]: getLastParameters: command parameters ''. 2015-09-29T11:43:19.540165-05:00 myhost1 nxserver[13211]: getParameters: parameter string is ''. 2015-09-29T11:43:19.540254-05:00 myhost1 nxserver[13211]: Resource list: only one parameter param []. 2015-09-29T11:43:19.540333-05:00 myhost1 nxserver[13211]: Resource list for user[testuser1], class[], value[] node[] 2015-09-29T11:43:19.540372-05:00 myhost1 nxserver[13211]: Get session resources: user 'testuser1' node '' filtered_value ''. 2015-09-29T11:43:19.540562-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:19.540634-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:19.540673-05:00 myhost1 nxserver[13211]: Check resources for nodes 'localhost:4000'. 2015-09-29T11:43:19.551112-05:00 myhost1 nxserver[13211]: Get value result for class: session is type unix-script with value . 2015-09-29T11:43:19.551141-05:00 myhost1 nxserver[13211]: Get nodes sessions. 2015-09-29T11:43:19.551274-05:00 myhost1 nxserver[13211]: Server config type: unix-console,unix-gnome,unix-default,unix-application,physical-desktop,shadow,unix-xsession-default,unix-kde,unix-xdm. 2015-09-29T11:43:19.551350-05:00 myhost1 nxserver[13211]: Server config internal types: virtualConsole,virtualGnome,virtualApplicationDefault,virtualUnixApplication,physicalDesktop,virtualAttach,virtualDefault,virtualKde,virtualXdm, 2015-09-29T11:43:19.551371-05:00 myhost1 nxserver[13211]: Sessions available on node localhost:4000: virtualConsole,virtualGnome,virtualApplicationDefault,virtualUnixApplication,physicalDesktop,virtualAttach,virtualDefault,virtualKde,virtualXdm,. 2015-09-29T11:43:19.551468-05:00 myhost1 nxserver[13211]: Session 'virtualConsole' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551496-05:00 myhost1 nxserver[13211]: Session 'nxFrameBuffer' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551504-05:00 myhost1 nxserver[13211]: Session 'virtualGnome' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551540-05:00 myhost1 nxserver[13211]: Session 'virtualVms' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551547-05:00 myhost1 nxserver[13211]: Session 'customForeignMaster' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551580-05:00 myhost1 nxserver[13211]: Session 'virtualVnc' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551586-05:00 myhost1 nxserver[13211]: Session 'virtualDefault' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551618-05:00 myhost1 nxserver[13211]: Session 'virtualRDP' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551649-05:00 myhost1 nxserver[13211]: Session 'foreignDesktop' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551664-05:00 myhost1 nxserver[13211]: Session 'virtualDesktop' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551687-05:00 myhost1 nxserver[13211]: Session 'virtualCde' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551733-05:00 myhost1 nxserver[13211]: Session 'virtualAttach' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551752-05:00 myhost1 nxserver[13211]: Session 'virtualKde' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551759-05:00 myhost1 nxserver[13211]: Session 'virtualUnixApplication' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551783-05:00 myhost1 nxserver[13211]: Session 'foreignAttach' allow in profiles '1' not deny on nodes ''. 2015-09-29T11:43:19.551808-05:00 myhost1 nxserver[13211]: Session 'virtualApplicationDefault' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551838-05:00 myhost1 nxserver[13211]: Session 'virtualXdm' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.551863-05:00 myhost1 nxserver[13211]: Session 'physicalDesktop' allow in profiles '1' not deny on nodes '1'. 2015-09-29T11:43:19.552165-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:19.552225-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:19.552274-05:00 myhost1 nxserver[13211]: Check resources for nodes 'localhost:4000'. 2015-09-29T11:43:19.559773-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:19.559840-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:19.559954-05:00 myhost1 nxserver[13211]: getNodeDenyServices: resources not retrieved, checking local node.cfg 2015-09-29T11:43:19.560108-05:00 myhost1 nxserver[13211]: NXOpen FD#10 - file /usr/NX/etc/node.cfg with mode 0 and permissions 0 from Common::NXCore::parse_config_file. 2015-09-29T11:43:19.560142-05:00 myhost1 nxserver[13211]: initialization: starting parse of config file '/usr/NX/etc/node.cfg' 2015-09-29T11:43:19.560182-05:00 myhost1 nxserver[13211]: Process initialized: nxserver -c /etc/NX/nxserver --login -H 5 2015-09-29T11:43:19.574001-05:00 myhost1 nxserver[13211]: NXClose FD#10 from Common::NXCore::parse_config_file. 2015-09-29T11:43:19.574078-05:00 myhost1 nxserver[13211]: Initialization: file /usr/NX/etc/testuser1.node.cfg doesn't exist. Skipping configuration. 2015-09-29T11:43:19.574184-05:00 myhost1 nxserver[13211]: checkNodeCfg: denied services [] in node.cfg 2015-09-29T11:43:19.574218-05:00 myhost1 nxserver[13211]: Services denied in node profile and deny on node: [] 2015-09-29T11:43:19.574527-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:19.574609-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:19.574735-05:00 myhost1 nxserver[13211]: Check resources for nodes 'localhost:4000'. 2015-09-29T11:43:19.579184-05:00 myhost1 nxserver[13211]: Get value result for class: feature is type bandwidth with value . 2015-09-29T11:43:19.579286-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:19.579308-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:19.579377-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:19.579397-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:19.579430-05:00 myhost1 nxserver[13211]: Check resources for nodes 'localhost:4000'. 2015-09-29T11:43:19.579911-05:00 myhost1 nxserver[13211]: Get value result for class: node is type limit with value . 2015-09-29T11:43:19.580028-05:00 myhost1 nxserver[13211]: Sent response message 'NX> 104 Resource list:' 2015-09-29T11:43:19.580397-05:00 myhost1 nxserver[13211]: Checking ignore message for command 'resourcelist'. 2015-09-29T11:43:19.580429-05:00 myhost1 nxserver[13211]: Dont have to send ignore message. 2015-09-29T11:43:19.580464-05:00 myhost1 nxserver[13211]: Request for disconnect never sent to main server. 2015-09-29T11:43:19.580617-05:00 myhost1 nxserver[13211]: Timestamp 'Command resourcelist' time passed '0.041' seconds. 2015-09-29T11:43:19.580642-05:00 myhost1 nxserver[13211]: Finished handling command 'resourcelist' after 0.041 seconds. 2015-09-29T11:43:19.580835-05:00 myhost1 nxserver[13211]: Sent request message 'NX> 105 ' 2015-09-29T11:43:19.644116-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644151-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644156-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644159-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644163-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644166-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644169-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644171-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644197-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644201-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644274-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644408-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644550-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644805-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.644971-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.645146-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.645357-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.645454-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.645621-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.645780-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.645922-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646064-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646206-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646346-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646510-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646692-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646827-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.646965-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647106-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647246-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647397-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647539-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647678-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647817-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.647957-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.648107-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.648239-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.648477-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.648736-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.648815-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.648954-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.649160-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.649290-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.649428-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.649629-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.649805-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.649952-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.650092-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.650232-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.650387-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.650591-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.650789-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.650947-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.651111-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.651251-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.651463-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.651690-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.651850-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.651995-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.652134-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.652296-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.652436-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.652574-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.652714-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.652864-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.653005-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.653144-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.653334-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.653510-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.653692-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.653884-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.654060-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.654235-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.654408-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.654593-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.654752-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.654889-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.655030-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:19.655163-05:00 myhost1 nxserver[13211]: received response 'listsession --status="disconnected,connected" --type="all" --shadowable="yes"' 2015-09-29T11:43:19.656004-05:00 myhost1 nxserver[13211]: Checking NXCluster feature. 2015-09-29T11:43:19.656045-05:00 myhost1 nxserver[13211]: NXCluster: disabled by config. 2015-09-29T11:43:19.656098-05:00 myhost1 nxserver[13211]: Current user is 'testuser1' from '10.10.106.52' 2015-09-29T11:43:19.656180-05:00 myhost1 nxserver[13211]: User not yet authorized to access. 2015-09-29T11:43:19.656283-05:00 myhost1 nxserver[13211]: getLastParameters: command parameters '--status="disconnected,connected" --type="all" --shadowable="yes"'. 2015-09-29T11:43:19.656324-05:00 myhost1 nxserver[13211]: getParameters: parameter string is '--status="disconnected,connected" --type="all" --shadowable="yes"'. 2015-09-29T11:43:19.656863-05:00 myhost1 nxserver[13211]: getPwNam: Returning userinfo. 2015-09-29T11:43:19.656880-05:00 myhost1 nxserver[13211]: Found real user name 'testuser1' from name: 'testuser1'. 2015-09-29T11:43:19.659578-05:00 myhost1 nxserver[13211]: count_user_capacity: calculation for user: 2015-09-29T11:43:19.659606-05:00 myhost1 nxserver[13211]: User capacity: virtual/connections: 0/0 2015-09-29T11:43:19.659614-05:00 myhost1 nxserver[13211]: __setCapacityLimits user_capacity: [0/0] 2015-09-29T11:43:19.659659-05:00 myhost1 nxserver[13211]: __getSessions in module: size of array is 1 2015-09-29T11:43:19.659748-05:00 myhost1 nxserver[13211]: __checkFilterStatus: [Connected,Disconnected,Connecting,Disconnecting] =~ [Connected] 2015-09-29T11:43:19.659805-05:00 myhost1 nxserver[13211]: checkFilterType: [all][physicalDesktop] [ok] 2015-09-29T11:43:19.659836-05:00 myhost1 nxserver[13211]: __checkFilterUserByFlags: [testuser1][testuser2][not set][yes][ok] 2015-09-29T11:43:19.659886-05:00 myhost1 nxserver[13211]: __checkFilterSessionId: [][50C83872F4E321C5763A1E8C98168AF6] 2015-09-29T11:43:19.659907-05:00 myhost1 nxserver[13211]: __checkFilterSessionId: Common variable $fitlerSessionId is empty 2015-09-29T11:43:19.659939-05:00 myhost1 nxserver[13211]: __checkFilterScreenInfo: Client version isn't 3 or older 2015-09-29T11:43:19.659986-05:00 myhost1 nxserver[13211]: __checkFilterShadowable [yes][physicalDesktop] 2015-09-29T11:43:19.660062-05:00 myhost1 nxserver[13211]: __checkFilterShadowable [yes][physicalDesktop] [ok] 2015-09-29T11:43:19.660083-05:00 myhost1 nxserver[13211]: __checkFilterReconnectable [not set][][physicalDesktop][Connected] [ok] 2015-09-29T11:43:19.660115-05:00 myhost1 nxserver[13211]: __checkFilterHideShadow: [physicalDesktop][] [not hide] 2015-09-29T11:43:19.660210-05:00 myhost1 nxserver[13211]: Check session type physicalDesktop for user testuser1 and node localhost:4000. 2015-09-29T11:43:19.660977-05:00 myhost1 nxserver[13211]: __checkFilterProfiles [physicalDesktop] [localhost:4000] [not hide] 2015-09-29T11:43:19.661288-05:00 myhost1 nxserver[13211]: check_filter_profiles node localhost:4000 not hide 2015-09-29T11:43:19.661311-05:00 myhost1 nxserver[13211]: All tests passed, adding session to array back 2015-09-29T11:43:19.661368-05:00 myhost1 nxserver[13211]: __setNode: session name is Local display on localhost 2015-09-29T11:43:19.661388-05:00 myhost1 nxserver[13211]: Session type to translate is: physicalDesktop,ubuntu 12.04.5 lts 2015-09-29T11:43:19.661420-05:00 myhost1 nxserver[13211]: Translated result is: [physical-desktop] 2015-09-29T11:43:19.661626-05:00 myhost1 nxserver[13211]: __checkFieldPlatform: platform is ubuntu 12.04.5 lts, 2015-09-29T11:43:19.661705-05:00 myhost1 nxserver[13211]: __setDataToColumns: client is supporting platform parameters 2015-09-29T11:43:19.661818-05:00 myhost1 nxserver[13211]: Sent response message 'NX> 127 Session list of user 'testuser1': ' 2015-09-29T11:43:19.662217-05:00 myhost1 nxserver[13211]: Session type to translate is: physicalDesktop,ubuntu 12.04.5 lts 2015-09-29T11:43:19.662239-05:00 myhost1 nxserver[13211]: Translated result is: [physical-desktop] 2015-09-29T11:43:19.662588-05:00 myhost1 nxserver[13211]: Sent response message 'NX> 148 Server capacity: not reached for user: testuser1' 2015-09-29T11:43:19.662665-05:00 myhost1 nxserver[13211]: Checking ignore message for command 'listsession'. 2015-09-29T11:43:19.662683-05:00 myhost1 nxserver[13211]: Dont have to send ignore message. 2015-09-29T11:43:19.662733-05:00 myhost1 nxserver[13211]: Request for disconnect never sent to main server. 2015-09-29T11:43:19.662830-05:00 myhost1 nxserver[13211]: Timestamp 'Command listsession' time passed '0.006' seconds. 2015-09-29T11:43:19.662850-05:00 myhost1 nxserver[13211]: Finished handling command 'listsession' after 0.006 seconds. 2015-09-29T11:43:19.663008-05:00 myhost1 nxserver[13211]: Sent request message 'NX> 105 ' 2015-09-29T11:43:22.747817-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748009-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748150-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748296-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748440-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748583-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748747-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.748892-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749033-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749175-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749316-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749457-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749596-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749735-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.749873-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750033-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750207-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750348-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750488-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750628-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750766-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.750905-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751046-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751187-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751327-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751466-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751606-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751745-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.751885-05:00 myhost1 nxserver[13211]: NXParser: checkCanRead has: FD#8 2015-09-29T11:43:22.752080-05:00 myhost1 nxserver[13211]: received response 'setsession --type="unix-kde"' 2015-09-29T11:43:22.752421-05:00 myhost1 nxserver[13211]: Checking NXCluster feature. 2015-09-29T11:43:22.752456-05:00 myhost1 nxserver[13211]: NXCluster: disabled by config. 2015-09-29T11:43:22.752508-05:00 myhost1 nxserver[13211]: Current user is 'testuser1' from '10.10.106.52' 2015-09-29T11:43:22.752585-05:00 myhost1 nxserver[13211]: User not yet authorized to access. 2015-09-29T11:43:22.752690-05:00 myhost1 nxserver[13211]: getLastParameters: command parameters '--type="unix-kde"'. 2015-09-29T11:43:22.752747-05:00 myhost1 nxserver[13211]: getParameters: parameter string is '--type="unix-kde"'. 2015-09-29T11:43:22.752932-05:00 myhost1 nxserver[13211]: Start handler session set. 2015-09-29T11:43:22.752992-05:00 myhost1 nxserver[13211]: Session type request by client: virtualKde. 2015-09-29T11:43:22.753032-05:00 myhost1 nxserver[13211]: handler_session_set CLIENT_SUPPORT_NODE_SELECTION [1] 2015-09-29T11:43:22.753457-05:00 myhost1 nxserver[13211]: Save ssl context 170 2015-09-29T11:43:22.753541-05:00 myhost1 nxserver[13211]: Adding state: sessionSelectNode with functionToHandle __handleStateSessionSelectNode. 2015-09-29T11:43:22.753586-05:00 myhost1 nxserver[13211]: Adding transition between sessionSelectNode and nxshell with nodeSelected. 2015-09-29T11:43:22.753623-05:00 myhost1 nxserver[13211]: Adding transition between sessionSelectNode and sessionSetRemoteNodeSpecificParameters with nodeSelectedWithoutSessionSet. 2015-09-29T11:43:22.753656-05:00 myhost1 nxserver[13211]: Adding state: sessionParametersVerification with functionToHandle __handleStateSessionParametersVerification. 2015-09-29T11:43:22.753684-05:00 myhost1 nxserver[13211]: Adding transition between sessionParametersVerification and nxshell with backFromSubsystem. 2015-09-29T11:43:22.753719-05:00 myhost1 nxserver[13211]: Adding transition between sessionParametersVerification and sessionSetRemoteNodeSpecificParameters with parametersInitialized. 2015-09-29T11:43:22.753750-05:00 myhost1 nxserver[13211]: Adding transition between sessionParametersVerification and sessionID with nodeNotSelected. 2015-09-29T11:43:22.753783-05:00 myhost1 nxserver[13211]: Adding transition between sessionParametersVerification and setSessionToAttach with setNodeToAttach. 2015-09-29T11:43:22.753815-05:00 myhost1 nxserver[13211]: Adding state: sessionSetRemoteNodeSpecificParameters with functionToHandle __handleStateSessionSetRemoteNodeSpecificParameters. 2015-09-29T11:43:22.753846-05:00 myhost1 nxserver[13211]: Adding transition between sessionSetRemoteNodeSpecificParameters and sessionID with parametersSet. 2015-09-29T11:43:22.753877-05:00 myhost1 nxserver[13211]: Adding state: sessionID with functionToHandle __handleStateSessionID. 2015-09-29T11:43:22.753913-05:00 myhost1 nxserver[13211]: Adding transition between sessionID and sessionGetMainSessionParameters with sessionIDset. 2015-09-29T11:43:22.753948-05:00 myhost1 nxserver[13211]: Adding transition between sessionID and sessionCreateInDB with nodeNotSelected. 2015-09-29T11:43:22.753982-05:00 myhost1 nxserver[13211]: Adding state: sessionGetMainSessionParameters with functionToHandle __handleStateSessionGetMainSessionParameters. 2015-09-29T11:43:22.754016-05:00 myhost1 nxserver[13211]: Adding transition between sessionGetMainSessionParameters and sessionCheckProfiles with parametersGet. 2015-09-29T11:43:22.754049-05:00 myhost1 nxserver[13211]: Adding transition between sessionGetMainSessionParameters and sessionInitializeForeignNode with initializeForeignNode. 2015-09-29T11:43:22.754080-05:00 myhost1 nxserver[13211]: Adding state: sessionCheckProfiles with functionToHandle __handleStateSessionCheckProfiles. 2015-09-29T11:43:22.754112-05:00 myhost1 nxserver[13211]: Adding transition between sessionCheckProfiles and nxshell with error. 2015-09-29T11:43:22.754148-05:00 myhost1 nxserver[13211]: Adding transition between sessionCheckProfiles and sessionDisconnectOld with profileUpdated. 2015-09-29T11:43:22.754180-05:00 myhost1 nxserver[13211]: Adding state: sessionDisconnectOld with functionToHandle __handleStateDisconnectDirectlyConnected. 2015-09-29T11:43:22.754214-05:00 myhost1 nxserver[13211]: Adding transition between sessionDisconnectOld and nxshell with waitingOnSessionClose. 2015-09-29T11:43:22.754246-05:00 myhost1 nxserver[13211]: Adding transition between sessionDisconnectOld and sessionAsk4attach with sessionNotNeedToBeClosed. 2015-09-29T11:43:22.754279-05:00 myhost1 nxserver[13211]: Adding transition between sessionDisconnectOld and sessionAsk4attach with sessionClosed. 2015-09-29T11:43:22.754311-05:00 myhost1 nxserver[13211]: Adding state: sessionAsk4attach with functionToHandle __handleStateSessionAsk4attach. 2015-09-29T11:43:22.754343-05:00 myhost1 nxserver[13211]: Adding transition between sessionAsk4attach and nxshell with waitingForUserAnswer. 2015-09-29T11:43:22.754401-05:00 myhost1 nxserver[13211]: Adding transition between sessionAsk4attach and sessionHandlePrepareToShadow with accessAllow. 2015-09-29T11:43:22.754434-05:00 myhost1 nxserver[13211]: Adding transition between sessionAsk4attach and nxshell with error. 2015-09-29T11:43:22.754466-05:00 myhost1 nxserver[13211]: Adding state: sessionHandlePrepareToShadow with functionToHandle __sessionHandlePrepareToShadow. 2015-09-29T11:43:22.754498-05:00 myhost1 nxserver[13211]: Adding transition between sessionHandlePrepareToShadow and nxshell with prepareToShadowSent. 2015-09-29T11:43:22.754534-05:00 myhost1 nxserver[13211]: Adding transition between sessionHandlePrepareToShadow and sessionCreateInDB with readyToShadow. 2015-09-29T11:43:22.754568-05:00 myhost1 nxserver[13211]: Adding state: nxshell with functionToHandle __handleStateNXShell. 2015-09-29T11:43:22.754603-05:00 myhost1 nxserver[13211]: Adding transition between nxshell and sessionAsk4attach with sessionClosed. 2015-09-29T11:43:22.754634-05:00 myhost1 nxserver[13211]: Adding transition between nxshell and sessionHandlePrepareToShadow with accessAllow. 2015-09-29T11:43:22.754667-05:00 myhost1 nxserver[13211]: Adding transition between nxshell and sessionDisconnectOld with tryToDisconnectAgain. 2015-09-29T11:43:22.754698-05:00 myhost1 nxserver[13211]: Adding transition between nxshell and sessionCreateInDB with readyToShadow. 2015-09-29T11:43:22.754729-05:00 myhost1 nxserver[13211]: Adding state: sessionCreateInDB with functionToHandle __handleStateSessionCreateInDB. 2015-09-29T11:43:22.754761-05:00 myhost1 nxserver[13211]: Adding transition between sessionCreateInDB and sessionCreateOnRemoteNode with skipCreateSession. 2015-09-29T11:43:22.754793-05:00 myhost1 nxserver[13211]: Adding transition between sessionCreateInDB and sessionCreateOnRemoteNode with sessionCreated. 2015-09-29T11:43:22.754825-05:00 myhost1 nxserver[13211]: Adding transition between sessionCreateInDB and sessionSelectNode with nodeNotSelected. 2015-09-29T11:43:22.754859-05:00 myhost1 nxserver[13211]: Adding state: sessionCreateOnRemoteNode with functionToHandle __handleStateSessionCreateOnRemoteNode. 2015-09-29T11:43:22.754891-05:00 myhost1 nxserver[13211]: Adding transition between sessionCreateOnRemoteNode and sessionSaveDisplay with localNode. 2015-09-29T11:43:22.754924-05:00 myhost1 nxserver[13211]: Adding transition between sessionCreateOnRemoteNode and nxshell with sessionClosed. 2015-09-29T11:43:22.754956-05:00 myhost1 nxserver[13211]: Adding state: sessionSaveDisplay with functionToHandle __handleStateSessionSaveDisplay. 2015-09-29T11:43:22.754989-05:00 myhost1 nxserver[13211]: Adding transition between sessionSaveDisplay and sessionParametersSave with displaySaved. 2015-09-29T11:43:22.755021-05:00 myhost1 nxserver[13211]: Adding transition between sessionSaveDisplay and sessionParametersSave with skipSaveingDisplay. 2015-09-29T11:43:22.755053-05:00 myhost1 nxserver[13211]: Adding state: sessionParametersSave with functionToHandle __handleStateSessionParametersSave. 2015-09-29T11:43:22.755084-05:00 myhost1 nxserver[13211]: Adding transition between sessionParametersSave and sessionSetUDPAndFirewall with parametersSaved. 2015-09-29T11:43:22.755116-05:00 myhost1 nxserver[13211]: Adding transition between sessionParametersSave and sessionSetUDPAndFirewall with skipParametersSave. 2015-09-29T11:43:22.755147-05:00 myhost1 nxserver[13211]: Adding state: sessionSetUDPAndFirewall with functionToHandle __handleStateSessionSetUDPAndFirewall. 2015-09-29T11:43:22.755180-05:00 myhost1 nxserver[13211]: Adding transition between sessionSetUDPAndFirewall and sessionStart with firewallSet. 2015-09-29T11:43:22.755215-05:00 myhost1 nxserver[13211]: Adding transition between sessionSetUDPAndFirewall and sessionStart with skipFirewallSet. 2015-09-29T11:43:22.755247-05:00 myhost1 nxserver[13211]: Adding state: sessionStart with functionToHandle __handleStateStartSession. 2015-09-29T11:43:22.755281-05:00 myhost1 nxserver[13211]: Adding transition between sessionStart and sessionParametersVerification with startFirstAttach. 2015-09-29T11:43:22.755300-05:00 myhost1 nxserver[13211]: Adding transition between sessionStart and nxshell with sessionStarted. 2015-09-29T11:43:22.755341-05:00 myhost1 nxserver[13211]: Adding transition between sessionStart and nxshell with error. 2015-09-29T11:43:22.755381-05:00 myhost1 nxserver[13211]: Adding state: sessionInitializeForeignNode with functionToHandle __handleStateInitializeForeignNode. 2015-09-29T11:43:22.755413-05:00 myhost1 nxserver[13211]: Adding transition between sessionInitializeForeignNode and sessionCheckProfiles with foreignNodeInitialized. 2015-09-29T11:43:22.755446-05:00 myhost1 nxserver[13211]: Adding state: setSessionToAttach with functionToHandle __handleSetSessionForAttach. 2015-09-29T11:43:22.755478-05:00 myhost1 nxserver[13211]: Adding transition between setSessionToAttach and sessionSetRemoteNodeSpecificParameters with nodeSelected. 2015-09-29T11:43:22.755518-05:00 myhost1 nxserver[13211]: Adding transition between setSessionToAttach and nxshell with error. 2015-09-29T11:43:22.755597-05:00 myhost1 nxserver[13211]: Going to handling state: sessionSetRemoteNodeSpecificParameters with __handleStateSessionSetRemoteNodeSpecificParameters. 2015-09-29T11:43:22.755620-05:00 myhost1 nxserver[13211]: Handle state: set remote node specific parameters. 2015-09-29T11:43:22.755695-05:00 myhost1 nxserver[13211]: Transition from sessionSetRemoteNodeSpecificParameters with event parametersSet is sessionID. 2015-09-29T11:43:22.755711-05:00 myhost1 nxserver[13211]: Next state: sessionID 2015-09-29T11:43:22.755740-05:00 myhost1 nxserver[13211]: Going to handling state: sessionID with __handleStateSessionID. 2015-09-29T11:43:22.755772-05:00 myhost1 nxserver[13211]: Handle state: SessionID 2015-09-29T11:43:22.755828-05:00 myhost1 nxserver[13211]: Generating session ID. 2015-09-29T11:43:22.755866-05:00 myhost1 nxserver[13211]: NXPL was already loaded. 2015-09-29T11:43:22.755896-05:00 myhost1 nxserver[13211]: NXPL::NXGetRandomString start. 2015-09-29T11:43:22.755966-05:00 myhost1 nxserver[13211]: NXPL::NXGetRandomString stop. 2015-09-29T11:43:22.756010-05:00 myhost1 nxserver[13211]: Set session id to: 735B0DC5D46C6396CC69FB1D25E1A3CE 2015-09-29T11:43:22.756058-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:22.756130-05:00 myhost1 nxserver[13211]: Transition from sessionID with event nodeNotSelected is sessionCreateInDB. 2015-09-29T11:43:22.756157-05:00 myhost1 nxserver[13211]: Next state: sessionCreateInDB 2015-09-29T11:43:22.756191-05:00 myhost1 nxserver[13211]: Going to handling state: sessionCreateInDB with __handleStateSessionCreateInDB. 2015-09-29T11:43:22.756229-05:00 myhost1 nxserver[13211]: Handle state: create session in dabatase. 2015-09-29T11:43:22.756354-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:22.756505-05:00 myhost1 nxserver[13211]: NXPL was already loaded. 2015-09-29T11:43:22.756518-05:00 myhost1 nxserver[13211]: NXPL::NXGetRandomString start. 2015-09-29T11:43:22.756552-05:00 myhost1 nxserver[13211]: NXPL::NXGetRandomString stop. 2015-09-29T11:43:22.757214-05:00 myhost1 nxserver[13211]: nxListenOnSocket: listening on [20000] from NXTools::findFreePortAndOpen. 2015-09-29T11:43:22.757230-05:00 myhost1 nxserver[13211]: nxListenOnSocket: NXPL::NXOpenSocketToListenEx(20000, 100, 1) returned 'FD#10'. 2015-09-29T11:43:22.757278-05:00 myhost1 nxserver[13211]: Starting listen on socket: 20000 2015-09-29T11:43:22.757322-05:00 myhost1 nxserver[13211]: Creating selector for server -local 2015-09-29T11:43:22.757390-05:00 myhost1 nxserver[13211]: Global selector: adding FD#10 - server's socket with callback NXNodeExec::serverSocket_parser. 2015-09-29T11:43:22.757457-05:00 myhost1 nxserver[13211]: Added NXNodeExec::socketserver to the gSelector 2015-09-29T11:43:22.757535-05:00 myhost1 nxserver[13211]: Handle connection limits. 2015-09-29T11:43:22.757598-05:00 myhost1 nxserver[13211]: Locking ... /usr/NX/var/db/running/.InfoManager.lock 2015-09-29T11:43:22.757631-05:00 myhost1 nxserver[13211]: lock-refcounter [0] 2015-09-29T11:43:22.757722-05:00 myhost1 nxserver[13211]: refcounter set to: 1 2015-09-29T11:43:22.757749-05:00 myhost1 nxserver[13211]: immediately lock file '/usr/NX/var/db/running/.InfoManager.lock' 2015-09-29T11:43:22.759662-05:00 myhost1 nxserver[13211]: count_user_capacity: calculation for user: testuser1 2015-09-29T11:43:22.759680-05:00 myhost1 nxserver[13211]: User capacity: virtual/connections: 0/0 2015-09-29T11:43:22.759858-05:00 myhost1 nxserver[13211]: count_user_capacity: calculation for user: testuser1 2015-09-29T11:43:22.759891-05:00 myhost1 nxserver[13211]: User capacity: virtual/connections: 0/0 2015-09-29T11:43:22.759979-05:00 myhost1 nxserver[13211]: Handle connection limits OK 2015-09-29T11:43:22.760013-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:22.760305-05:00 myhost1 nxserver[13211]: setServerPid called 13211 2015-09-29T11:43:22.760374-05:00 myhost1 nxserver[13211]: setStatus --> Starting 2015-09-29T11:43:22.760497-05:00 myhost1 nxserver[13211]: Inserting session sessionId [735B0DC5D46C6396CC69FB1D25E1A3CE] on 'running' DB. 2015-09-29T11:43:22.761103-05:00 myhost1 nxserver[13211]: Unlocking ... 2015-09-29T11:43:22.761127-05:00 myhost1 nxserver[13211]: lock-refcounter [1] 2015-09-29T11:43:22.761181-05:00 myhost1 nxserver[13211]: dec lock refcounter: 0 2015-09-29T11:43:22.761228-05:00 myhost1 nxserver[13211]: immediately unlock file '/usr/NX/var/db/running/.InfoManager.lock' 2015-09-29T11:43:22.761321-05:00 myhost1 nxserver[13211]: Unlocked 2015-09-29T11:43:22.761381-05:00 myhost1 nxserver[13211]: Transition from sessionCreateInDB with event nodeNotSelected is sessionSelectNode. 2015-09-29T11:43:22.761431-05:00 myhost1 nxserver[13211]: Next state: sessionSelectNode 2015-09-29T11:43:22.761521-05:00 myhost1 nxserver[13211]: Going to handling state: sessionSelectNode with __handleStateSessionSelectNode. 2015-09-29T11:43:22.761578-05:00 myhost1 nxserver[13211]: Handle state: select node. 2015-09-29T11:43:22.761702-05:00 myhost1 nxserver[13211]: Starting select node procedure for session type virtualKde. 2015-09-29T11:43:22.761773-05:00 myhost1 nxserver[13211]: Searching nodes in database. 2015-09-29T11:43:22.761796-05:00 myhost1 nxserver[13211]: Searching parameters: sessionType 'virtualKde'. 2015-09-29T11:43:22.761875-05:00 myhost1 nxserver[13211]: Server config type: unix-console,unix-gnome,unix-default,unix-application,physical-desktop,shadow,unix-xsession-default,unix-kde,unix-xdm. 2015-09-29T11:43:22.761953-05:00 myhost1 nxserver[13211]: Server config internal types: virtualConsole,virtualGnome,virtualApplicationDefault,virtualUnixApplication,physicalDesktop,virtualAttach,virtualDefault,virtualKde,virtualXdm, 2015-09-29T11:43:22.761991-05:00 myhost1 nxserver[13211]: Node 'localhost:4000' sessions: 'virtualConsole,virtualGnome,virtualApplicationDefault,virtualUnixApplication,physicalDesktop,virtualAttach,virtualDefault,virtualKde,virtualXdm,'. 2015-09-29T11:43:22.762035-05:00 myhost1 nxserver[13211]: Amount of nodes in array: 1. 2015-09-29T11:43:22.762107-05:00 myhost1 nxserver[13211]: Nodes weight is not available. 2015-09-29T11:43:22.764224-05:00 myhost1 nxserver[13211]: Node 'localhost:4000' limit is 0. 2015-09-29T11:43:22.764304-05:00 myhost1 nxserver[13211]: NXOpen FD#11 - file /usr/NX/var/num with mode 0 and permissions 0 from NXSelectNode::__takeNodeNumber. 2015-09-29T11:43:22.764391-05:00 myhost1 nxserver[13211]: NXClose FD#11 from NXSelectNode::__takeNodeNumber. 2015-09-29T11:43:22.764465-05:00 myhost1 nxserver[13211]: NXOpen FD#11 - file /usr/NX/var/num with mode 65 and permissions 384 from NXSelectNode::__takeNodeNumber. 2015-09-29T11:43:22.764522-05:00 myhost1 nxserver[13211]: NXClose FD#11 from NXSelectNode::__takeNodeNumber. 2015-09-29T11:43:22.764573-05:00 myhost1 nxserver[13211]: Simple round robin node list: localhost:4000. 2015-09-29T11:43:22.764597-05:00 myhost1 nxserver[13211]: There are 1 nodes available: localhost:4000. 2015-09-29T11:43:22.764647-05:00 myhost1 nxserver[13211]: Using round robin algorithm for selecting the node. 2015-09-29T11:43:22.764685-05:00 myhost1 nxserver[13211]: Node selected to start session is localhost:4000. 2015-09-29T11:43:22.764776-05:00 myhost1 nxserver[13211]: Checking profiles for node localhost:4000 and session type virtualKde. 2015-09-29T11:43:22.765137-05:00 myhost1 nxserver[13211]: Check session type virtualKde for user testuser1 and node localhost:4000. 2015-09-29T11:43:22.765704-05:00 myhost1 nxserver[13211]: Node localhost:4000 is enable on server profiles. 2015-09-29T11:43:22.765750-05:00 myhost1 nxserver[13211]: Checking resources on node localhost:4000. 2015-09-29T11:43:22.767818-05:00 myhost1 nxserver[13211]: Node 'localhost:4000' limit is 0. 2015-09-29T11:43:22.767840-05:00 myhost1 nxserver[13211]: Check if nxnode [localhost:4000] could run next session. 2015-09-29T11:43:22.767961-05:00 myhost1 nxserver[13211]: askNode: service 'getConnectionLimit' with '' on 'localhost:4000'. 2015-09-29T11:43:22.768031-05:00 myhost1 nxserver[13211]: askNode login '' preferredAuthMethod '' specifyTimeout ''skipForwarding '' protocol '' 2015-09-29T11:43:22.768071-05:00 myhost1 nxserver[13211]: Login value is empty, try to get login. 2015-09-29T11:43:22.768141-05:00 myhost1 nxserver[13211]: New login value is 'testuser1'. 2015-09-29T11:43:22.768197-05:00 myhost1 nxserver[13211]: isRunned - localhost/testuser1 2015-09-29T11:43:22.768309-05:00 myhost1 nxserver[13211]: NXNodeExec::stderr is set to FD#. 2015-09-29T11:43:22.768351-05:00 myhost1 nxserver[13211]: NXNodeExec::stdin is set to FD#. 2015-09-29T11:43:22.768395-05:00 myhost1 nxserver[13211]: NXNodeExec::stdout is set to FD#. 2015-09-29T11:43:22.768439-05:00 myhost1 nxserver[13211]: askNode run __start 2015-09-29T11:43:22.768499-05:00 myhost1 nxserver[13211]: __start 'getConnectionLimit' '' on 'localhost:4000'. 2015-09-29T11:43:22.768556-05:00 myhost1 nxserver[13211]: __start login 'testuser1' authMethod '' specifyTimeout '' protocol '' 2015-09-29T11:43:22.768638-05:00 myhost1 nxserver[13211]: Starting local node for user testuser1. 2015-09-29T11:43:22.768729-05:00 myhost1 nxserver[13211]: Starting session node through nxserver --daemon. 2015-09-29T11:43:22.768903-05:00 myhost1 nxserver[13211]: NXOpen FD#11 - file /usr/NX/var/run/nxserver.pid with mode 0 and permissions 0 from NXClientSystemDaemons::__getServerOptionsFile. 2015-09-29T11:43:22.769042-05:00 myhost1 nxserver[13211]: NXClose FD#11 from NXClientSystemDaemons::__getServerOptionsFile. 2015-09-29T11:43:22.769123-05:00 myhost1 nxserver[13211]: Read PID from file '/usr/NX/var/run/nxserver.pid': 12940. 2015-09-29T11:43:22.769298-05:00 myhost1 nxserver[13211]: NXOpen FD#11 - file /usr/NX/var/db/server/cookie with mode 0 and permissions 0 from main::get_cookie_and_port. 2015-09-29T11:43:22.769385-05:00 myhost1 nxserver[13211]: NXClose FD#11 from main::get_cookie_and_port. 2015-09-29T11:43:22.769490-05:00 myhost1 nxserver[13211]: NXOpen FD#11 - file /usr/NX/var/db/server/port with mode 0 and permissions 0 from main::get_cookie_and_port. 2015-09-29T11:43:22.769579-05:00 myhost1 nxserver[13211]: NXClose FD#11 from main::get_cookie_and_port. 2015-09-29T11:43:22.769660-05:00 myhost1 nxserver[13211]: Connecting to the nxserver on port: 24981 2015-09-29T11:43:22.769738-05:00 myhost1 nxserver[13211]: NXConnectLocal to localhost:24981 from NXClientSystemDaemons::openConnection. 2015-09-29T11:43:22.770745-05:00 myhost1 nxserver[12940]: Global selector: something to read on FD#8 - server daemon's socket. 2015-09-29T11:43:22.770827-05:00 myhost1 nxserver[12940]: AcceptSocket accepted on 'FD#8': [FD#0]. 2015-09-29T11:43:22.770875-05:00 myhost1 nxserver[12940]: Accepted new connection on FD#0. 2015-09-29T11:43:22.770909-05:00 myhost1 nxserver[12940]: Adding custom handle for FD#0 to monitoring hash. 2015-09-29T11:43:22.770972-05:00 myhost1 nxserver[12940]: Global selector: adding FD#0 - server --daemon's socket accepted handle with callback NXServerDaemon::serverSocketAccepted_parser. 2015-09-29T11:43:22.775363-05:00 myhost1 nxserver[13211]: NXPL::NXConnectLocal return: FD#11. 2015-09-29T11:43:22.775430-05:00 myhost1 nxserver[13211]: Descriptor FD#11 has been set as not inheritable. 2015-09-29T11:43:22.775600-05:00 myhost1 nxserver[13211]: Message sent to nxserver: NX> ***** 2015-09-29T11:43:22.775635-05:00 myhost1 nxserver[12940]: Global selector: something to read on FD#0 - server --daemon's socket accepted handle. 2015-09-29T11:43:22.775657-05:00 myhost1 nxserver[13211]: NXPL was already loaded. 2015-09-29T11:43:22.775664-05:00 myhost1 nxserver[12940]: Server --daemon parser for FD#0. 2015-09-29T11:43:22.775712-05:00 myhost1 nxserver[13211]: NXPL::NXGetRandomString start. 2015-09-29T11:43:22.775733-05:00 myhost1 nxserver[12940]: Read 'NX> *****', 36 bytes from FD#0. 2015-09-29T11:43:22.775742-05:00 myhost1 nxserver[12940]: Connection FD#0 not authorized, yet. 2015-09-29T11:43:22.775748-05:00 myhost1 nxserver[13211]: NXPL::NXGetRandomString stop. 2015-09-29T11:43:22.775753-05:00 myhost1 nxserver[12940]: Authorizing connection on FD#0. 2015-09-29T11:43:22.775804-05:00 myhost1 nxserver[12940]: Connection authorized on FD#0. 2015-09-29T11:43:22.775826-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:22.775854-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:22.775996-05:00 myhost1 nxserver[13211]: Set node priority based on session type [virtualKde] 2015-09-29T11:43:22.776083-05:00 myhost1 nxserver[13211]: Node will be run with [realtime] priority. 2015-09-29T11:43:22.776306-05:00 myhost1 nxserver[13211]: setSocketsForServerNodeChain - NXPL::NXPipeCreate 2015-09-29T11:43:22.776392-05:00 myhost1 nxserver[13211]: NXPipeCreate(FD#12, FD#13, 1) returned '0' 2015-09-29T11:43:22.776454-05:00 myhost1 nxserver[13211]: Descriptor FD#13 has been set as inheritable. 2015-09-29T11:43:22.776614-05:00 myhost1 nxserver[12940]: Global selector: something to read on FD#0 - server --daemon's socket accepted handle. 2015-09-29T11:43:22.776637-05:00 myhost1 nxserver[13211]: Message sent to nxserver: NX> 1044 Start node for virtual session=***** username=testuser1 pid=13211 socketPathEncoded=%%2ftmp%%2fsession-***** socketCookie=***** priotity=realtime runNodeMode=0 NX_CONNECTION=10.10.106.52+39906+10.2.2.46+4000 KRB5CCNAME=FILE%%3a%%2ftmp%%2fkrb5cc_10402_DlgJ4P NX_USER_GROUPS=UnixUsers%%2cUnix-OH-SSH%%2cUnix-ENT-SSH%%2cUnix-git-all%%2cUnix-git-infrastructure%%2cUnix-hw-support%%2cUNIX-OH-Prod%%2cUnix-Admins%%2cUnix-Nagios%%2cUNIX-OpenNebula%%2cp6unix-admins. \n 2015-09-29T11:43:22.776645-05:00 myhost1 nxserver[12940]: Server --daemon parser for FD#0. 2015-09-29T11:43:22.776688-05:00 myhost1 nxserver[13211]: NXPL::NXYieldDescriptor(13, /tmp/session-735B0DC5D46C6396CC69FB1D25E1A3CE, ***) 2015-09-29T11:43:22.776702-05:00 myhost1 nxserver[12940]: Read 'NX> 1044 Start node for virtual session=***** username=testuser1 pid=13211 socketPathEncoded=%%2ftmp%%2fsession-***** socketCookie=***** priotity=realtime runNodeMode=0 NX_CONNECTION=10.10.106.52+39906+10.2.2.46+4000 KRB5CCNAME=FILE%%3a%%2ftmp%%2fkrb5cc_10402_DlgJ4P NX_USER_GROUPS=UnixUsers%%2cUnix-OH-SSH%%2cUnix-ENT-SSH%%2cUnix-git-all%%2cUnix-git-infrastructure%%2cUnix-hw-support%%2cUNIX-OH-Prod%%2cUnix-Admins%%2cUnix-Nagios%%2cUNIX-OpenNebula%%2cp6unix-admins. \n', 533 bytes from FD#0. 2015-09-29T11:43:22.776762-05:00 myhost1 nxserver[12940]: Cannot find node assigned to socket FD#0. 2015-09-29T11:43:22.776855-05:00 myhost1 nxserver[12940]: Parse daemon message 'NX> 1044 Start node for virtual session=***** username=testuser1 pid=13211 socketPathEncoded=%%2ftmp%%2fsession-***** socketCookie=***** priotity=realtime runNodeMode=0 NX_CONNECTION=10.10.106.52+39906+10.2.2.46+4000 KRB5CCNAME=FILE%%3a%%2ftmp%%2fkrb5cc_10402_DlgJ4P NX_USER_GROUPS=UnixUsers%%2cUnix-OH-SSH%%2cUnix-ENT-SSH%%2cUnix-git-all%%2cUnix-git-infrastructure%%2cUnix-hw-support%%2cUNIX-OH-Prod%%2cUnix-Admins%%2cUnix-Nagios%%2cUNIX-OpenNebula%%2cp6unix-admins. ' in buffer left ''. 2015-09-29T11:43:22.776944-05:00 myhost1 nxserver[12940]: Received message [1044][Start node for virtual session=735B0DC5D46C6396CC69FB1D25E1A3CE username=testuser1 pid=13211 socketPathEncoded=%%2ftmp%%2fsession-735B0DC5D46C6396CC69FB1D25E1A3CE socketCookie=DC58A87FF8761261E53227DB9C58855F priotity=realtime runNodeMode=0 NX_CONNECTION=10.10.106.52+39906+10.2.2.46+4000 KRB5CCNAME=FILE%%3a%%2ftmp%%2fkrb5cc_10402_DlgJ4P NX_USER_GROUPS=UnixUsers%%2cUnix-OH-SSH%%2cUnix-ENT-SSH%%2cUnix-git-all%%2cUnix-git-infrastructure%%2cUnix-hw-support%%2cUNIX-OH-Prod%%2cUnix-Admins%%2cUnix-Nagios%%2cUNIX-OpenNebula%%2cp6unix-admins. ] from FD#0 2015-09-29T11:43:22.777022-05:00 myhost1 nxserver[12940]: Received request for create virtual session node process. 2015-09-29T11:43:22.777199-05:00 myhost1 nxserver[12940]: NXPL::NXAcquireDescriptor(13211, 1, /tmp/session-735B0DC5D46C6396CC69FB1D25E1A3CE, ***) 2015-09-29T11:43:22.777498-05:00 myhost1 nxserver[12940]: NXPL::NXAcquireDescriptor(13211, 1, /tmp/session-735B0DC5D46C6396CC69FB1D25E1A3CE, ***) 9 2015-09-29T11:43:22.777525-05:00 myhost1 nxserver[13211]: NXPL::NXYieldDescriptor(13, /tmp/session-735B0DC5D46C6396CC69FB1D25E1A3CE, ***) 13 2015-09-29T11:43:22.777733-05:00 myhost1 nxserver[12940]: NXRunCommand: setting command to '/usr/NX/bin/nxexec --node --user testuser1 --priority --mode 0'. 2015-09-29T11:43:22.777813-05:00 myhost1 nxserver[12940]: NXRunCommand: setting to save pid to 'SCALAR(0x28b7d20)'. 2015-09-29T11:43:22.777859-05:00 myhost1 nxserver[12940]: NXRunCommand: key 'use as stdin' set getStdinFD 2015-09-29T11:43:22.777895-05:00 myhost1 nxserver[12940]: NXRunCommand: call: setStdinFD_redirect(9) 2015-09-29T11:43:22.777930-05:00 myhost1 nxserver[12940]: NXRunCommand: set stdinFD redirect as: 9 2015-09-29T11:43:22.777968-05:00 myhost1 nxserver[12940]: NXRunCommand: key 'use as stdout' set getStdOutFD 2015-09-29T11:43:22.777996-05:00 myhost1 nxserver[12940]: NXRunCommand: call: setStdoutFD_redirect(9) 2015-09-29T11:43:22.778031-05:00 myhost1 nxserver[12940]: NXRunCommand: set stdoutFD redirect as: 9 2015-09-29T11:43:22.778067-05:00 myhost1 nxserver[12940]: NXRunCommand: setting CMD_IN not to be closed. 2015-09-29T11:43:22.778102-05:00 myhost1 nxserver[12940]: NXRunCommand: setting CMD_OUT not to be closed. 2015-09-29T11:43:22.778186-05:00 myhost1 nxserver[12940]: NXRunCommand: setChildDescriptors started 2015-09-29T11:43:22.778220-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdin started 2015-09-29T11:43:22.778254-05:00 myhost1 nxserver[12940]: NXRunCommand: getStdin_redirect return 9 2015-09-29T11:43:22.778296-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdout started 2015-09-29T11:43:22.778406-05:00 myhost1 nxserver[12940]: NXOpen FD#1 - file /usr/NX/var/log/nxerror.log with mode 1089 and permissions 384 from Common::NXRunCommand::__setupParentChildStderr. 2015-09-29T11:43:22.778453-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 9 2015-09-29T11:43:22.778489-05:00 myhost1 nxserver[12940]: Descriptors set. Child :9, 9, 1. Parent , . 2015-09-29T11:43:22.778516-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 9 2015-09-29T11:43:22.778566-05:00 myhost1 nxserver[12940]: nxProcessCreate: '/usr/NX/bin/nxexec' '/usr/NX/bin/nxexec /usr/NX/bin/nxexec --node --user testuser1 --priority --mode 0' 'KRB5CCNAME=FILE:/tmp/krb5cc_10402_DlgJ4P NX_CONNECTION=10.10.106.52 39906 10.2.2.46 4000 NX_FEATURES=myhost1,VMware#037Inc.#037VMware#037Virtual#037Platform,Linux,Ubuntu 12.04.5 LTS,3.13.0,x86_64,12,67156676608,311 NX_USER_GROUPS=UnixUsers,Unix-OH-SSH,Unix-ENT-SSH,Unix-git-all,Unix-git-infrastructure,Unix-hw-support,UNIX-OH-Prod,Unix-Admins,Unix-Nagios,UNIX-OpenNebula,p6unix-admins. NX_VERSION=4.6.16' '9' '9' '1' '101' 2015-09-29T11:43:22.780302-05:00 myhost1 nxserver[12940]: Added '13229/13229' to children list. 2015-09-29T11:43:22.780562-05:00 myhost1 nxserver[12940]: NXRunCommand: close after run - close FD#9. 2015-09-29T11:43:22.780652-05:00 myhost1 nxserver[12940]: NXClose FD#9 from Common::NXRunCommand::run. 2015-09-29T11:43:22.780725-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStderr 2015-09-29T11:43:22.780786-05:00 myhost1 nxserver[12940]: NXClose FD#1 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:22.780813-05:00 myhost1 nxserver[12940]: NXRunCommand: child descriptors closed. 2015-09-29T11:43:22.780868-05:00 myhost1 nxserver[12940]: NXRunCommand: saving pid in SCALAR(0x28b7d20) 2015-09-29T11:43:22.780930-05:00 myhost1 nxserver[12940]: NXRunCommand: running in background. 2015-09-29T11:43:22.781156-05:00 myhost1 nxserver[13211]: Something to read from accepted nxserver monitor socket on FD#11. 2015-09-29T11:43:22.781235-05:00 myhost1 nxserver[13211]: Read 'Node started with pid=13229 ' on FD#11 2015-09-29T11:43:22.781257-05:00 myhost1 nxserver[12940]: NXClose FD#0 from NXServerDaemon::serverSocketAccepted_parser. 2015-09-29T11:43:22.781405-05:00 myhost1 nxserver[13211]: Something to read from accepted nxserver monitor socket on FD#11. 2015-09-29T11:43:22.781470-05:00 myhost1 nxserver[13211]: Connection was closed. 2015-09-29T11:43:22.781568-05:00 myhost1 nxserver[13211]: NXClose FD#11 from NXClientSystemDaemons::closeConnection. 2015-09-29T11:43:22.781765-05:00 myhost1 nxserver[13211]: Reply from daemon: Node started with pid=13229 . 2015-09-29T11:43:22.781835-05:00 myhost1 nxserver[13211]: Session node started with pid 13229 on handler 12. 2015-09-29T11:43:22.781899-05:00 myhost1 nxserver[13211]: NXNodeExec::stdin is set to FD#12. 2015-09-29T11:43:22.781965-05:00 myhost1 nxserver[13211]: NXNodeExec::stdout is set to FD#12. 2015-09-29T11:43:22.782013-05:00 myhost1 nxserver[13211]: NXNodeExec::stderr is set to FD#2. 2015-09-29T11:43:22.782105-05:00 myhost1 nxserver[13211]: Start read until: 1000 2015-09-29T11:43:22.782172-05:00 myhost1 nxserver[13211]: Adding to the selector: FD#12 2015-09-29T11:43:23.171864-05:00 myhost1 nxserver[13211]: Something to read on FD#12. 2015-09-29T11:43:23.171902-05:00 myhost1 nxserver[13211]: Something to read on node stdout. 2015-09-29T11:43:23.171908-05:00 myhost1 nxserver[13211]: Read 'NX> 1000 NoMachine Enterprise Server Evaluation Node - Version 4.6.16\n' 70 from FD#12. 2015-09-29T11:43:23.171999-05:00 myhost1 nxserver[13211]: __read_until stop 1000 2015-09-29T11:43:23.172031-05:00 myhost1 nxserver[13211]: __read_until 'NX> 1000 NoMachine Enterprise Server Evaluation Node - Version 4.6.16\n'. 2015-09-29T11:43:23.172227-05:00 myhost1 nxserver[13211]: __send_request_to_node LOCAL 2015-09-29T11:43:23.172296-05:00 myhost1 nxserver[13211]: [7] 'shell\n' sent to NXNode on FD#12. 2015-09-29T11:43:23.172313-05:00 myhost1 nxserver[13211]: request [shell - ] sent 2015-09-29T11:43:23.172350-05:00 myhost1 nxserver[13211]: Start read until: 1025 2015-09-29T11:43:23.172417-05:00 myhost1 nxserver[13211]: Adding to the selector: FD#12 2015-09-29T11:43:23.172755-05:00 myhost1 nxserver[13211]: Something to read on FD#12. 2015-09-29T11:43:23.172776-05:00 myhost1 nxserver[13211]: Something to read on node stdout. 2015-09-29T11:43:23.172823-05:00 myhost1 nxserver[13211]: Read 'NX> 1025 done.\n' 15 from FD#12. 2015-09-29T11:43:23.172881-05:00 myhost1 nxserver[13211]: __read_until stop 1025 2015-09-29T11:43:23.172893-05:00 myhost1 nxserver[13211]: askNode ran __start 2015-09-29T11:43:23.172930-05:00 myhost1 nxserver[13211]: askNode run __node_reply 2015-09-29T11:43:23.173005-05:00 myhost1 nxserver[13211]: __send_request_to_node LOCAL 2015-09-29T11:43:23.173067-05:00 myhost1 nxserver[13211]: [20] 'getConnectionLimit\n' sent to NXNode on FD#12. 2015-09-29T11:43:23.173089-05:00 myhost1 nxserver[13211]: request [getConnectionLimit - ] sent 2015-09-29T11:43:23.173126-05:00 myhost1 nxserver[13211]: __get_request_from_node [1] 2015-09-29T11:43:23.173164-05:00 myhost1 nxserver[13211]: NXPingManager: Time between pings from the node: 75. 2015-09-29T11:43:23.173406-05:00 myhost1 nxserver[13211]: NXPingManager: Timeout for ping after request: 120. 2015-09-29T11:43:23.173415-05:00 myhost1 nxserver[13211]: Added server's socket: [10] to the gSelector. 2015-09-29T11:43:23.173420-05:00 myhost1 nxserver[13211]: Global selector: FD#10 already added. 2015-09-29T11:43:23.173638-05:00 myhost1 nxserver[13211]: Something to read on #FD12. 2015-09-29T11:43:23.173802-05:00 myhost1 nxserver[13211]: Received node shell promt. 2015-09-29T11:43:23.173847-05:00 myhost1 nxserver[13211]: __node_reply return: [] 2015-09-29T11:43:23.173872-05:00 myhost1 nxserver[13211]: askNode ran __node_reply [NX> 741 Connection limit: 0\n] @ [] 2015-09-29T11:43:23.173973-05:00 myhost1 nxserver[13211]: NXNode answer [NX> 741 Connection limit: 0\n]. 2015-09-29T11:43:23.173996-05:00 myhost1 nxserver[13211]: Unlimited session on nxnode [localhost:4000]. 2015-09-29T11:43:23.174108-05:00 myhost1 nxserver[13211]: askNode: service 'getconfigkey' with 'configkey=AvailableSessionTypes' on 'localhost:4000'. 2015-09-29T11:43:23.174141-05:00 myhost1 nxserver[13211]: askNode login '' preferredAuthMethod '' specifyTimeout ''skipForwarding '' protocol '' 2015-09-29T11:43:23.174149-05:00 myhost1 nxserver[13211]: Login value is empty, try to get login. 2015-09-29T11:43:23.174214-05:00 myhost1 nxserver[13211]: New login value is 'testuser1'. 2015-09-29T11:43:23.174255-05:00 myhost1 nxserver[13211]: isRunned - localhost/testuser1 2015-09-29T11:43:23.174357-05:00 myhost1 nxserver[13211]: askNode run __node_reply 2015-09-29T11:43:23.174422-05:00 myhost1 nxserver[13211]: __send_request_to_node LOCAL 2015-09-29T11:43:23.174486-05:00 myhost1 nxserver[13211]: [45] 'getconfigkey\nconfigkey=AvailableSessionTypes' sent to NXNode on FD#12. 2015-09-29T11:43:23.174509-05:00 myhost1 nxserver[13211]: request [getconfigkey - configkey=AvailableSessionTypes] sent 2015-09-29T11:43:23.174515-05:00 myhost1 nxserver[13211]: __get_request_from_node [1] 2015-09-29T11:43:23.174552-05:00 myhost1 nxserver[13211]: NXPingManager: Time between pings from the node: 75. 2015-09-29T11:43:23.174570-05:00 myhost1 nxserver[13211]: NXPingManager: Timeout for ping after request: 120. 2015-09-29T11:43:23.174681-05:00 myhost1 nxserver[13211]: Added server's socket: [10] to the gSelector. 2015-09-29T11:43:23.174705-05:00 myhost1 nxserver[13211]: Global selector: FD#10 already added. 2015-09-29T11:43:23.175494-05:00 myhost1 nxserver[13211]: Something to read on #FD12. 2015-09-29T11:43:23.175668-05:00 myhost1 nxserver[13211]: Received node shell promt. 2015-09-29T11:43:23.175691-05:00 myhost1 nxserver[13211]: __node_reply return: [] 2015-09-29T11:43:23.175765-05:00 myhost1 nxserver[13211]: askNode ran __node_reply [NX> 729 Get config key: virtualConsole,virtualApplicationDefault,virtualUnixApplication,physicalDesktop,virtualAttach,virtualDefault,virtualGnome,virtualXdm\n] @ [] 2015-09-29T11:43:23.175848-05:00 myhost1 nxserver[13211]: __checkNodeIsAvailableSessionType: Sessions: Get config key: virtualConsole,virtualApplicationDefault,virtualUnixApplication,physicalDesktop,virtualAttach,virtualDefault,virtualGnome,virtualXdm 2015-09-29T11:43:23.175870-05:00 myhost1 nxserver[13211]: __checkNodeIsAvailableSessionType: Session virtualKde can not be open on localhost 2015-09-29T11:43:23.175907-05:00 myhost1 nxserver[13211]: Session type to translate is: virtualKde, 2015-09-29T11:43:23.175935-05:00 myhost1 nxserver[13211]: Translated result is: [unix-kde] 2015-09-29T11:43:23.175970-05:00 myhost1 nxserver[13211]: ERROR! Session type unix-kde is not available on this server. 2015-09-29T11:43:23.176065-05:00 myhost1 nxserver[13211]: Sent response message 'NX> 596 ERROR: Session type unix-kde is not available on this server.' 2015-09-29T11:43:23.176441-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:23.176520-05:00 myhost1 nxserver[13211]: Session '735B0DC5D46C6396CC69FB1D25E1A3CE' failed. 2015-09-29T11:43:23.177873-05:00 myhost1 nxserver[13211]: Updating session '735B0DC5D46C6396CC69FB1D25E1A3CE' data in DB. 2015-09-29T11:43:23.178826-05:00 myhost1 nxserver[13211]: Main session id is empty: 2015-09-29T11:43:23.178841-05:00 myhost1 nxserver[13211]: Main session id is empty: 2015-09-29T11:43:23.178903-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:23.178959-05:00 myhost1 nxserver[13211]: Main session id is empty: 2015-09-29T11:43:23.180480-05:00 myhost1 nxserver[13211]: Get session id: 735B0DC5D46C6396CC69FB1D25E1A3CE. 2015-09-29T11:43:23.180692-05:00 myhost1 nxserver[13211]: Finalize process. 2015-09-29T11:43:23.180750-05:00 myhost1 nxserver[13211]: Connection to client still open. 2015-09-29T11:43:23.180817-05:00 myhost1 nxserver[13211]: Closing client connection. 2015-09-29T11:43:23.180887-05:00 myhost1 nxserver[13211]: Free EncryptorContext 2015-09-29T11:43:23.180958-05:00 myhost1 nxserver[13211]: NXPL::NXEncryptorFreeContext return . 2015-09-29T11:43:23.182928-05:00 myhost1 nxserver[13211]: NXPL::NXEncryptorDestroy return 1. 2015-09-29T11:43:23.182960-05:00 myhost1 nxserver[13211]: NXClose FD#8 from Server::shutdownClientConnectionNXD. 2015-09-29T11:43:23.183033-05:00 myhost1 nxserver[13211]: NXClose FD#5 from Server::shutdownClientConnectionNXD. 2015-09-29T11:43:23.183345-05:00 myhost1 nxserver[13211]: NXClose FD#3 from NXBegin::finalizeProcess. 2015-09-29T11:43:23.183522-05:00 myhost1 nxserver[13211]: Removed watchdog on process 13223 with result ''. 2015-09-29T11:43:23.183576-05:00 myhost1 nxserver[13211]: NXClose FD#12 from NXBegin::finalizeProcess. 2015-09-29T11:43:23.183638-05:00 myhost1 nxserver[13211]: NXClose FD#2 from NXBegin::finalizeProcess. 2015-09-29T11:43:23.183689-05:00 myhost1 nxserver[13211]: NXClose FD#10 from NXBegin::finalizeProcess. 2015-09-29T11:43:23.195837-05:00 myhost1 nxserver[12940]: NXParser: signal 'CHLD' received. 2015-09-29T11:43:23.195881-05:00 myhost1 nxserver[12940]: Received 'SIGCHLD' signal:going to handle it 2015-09-29T11:43:23.195903-05:00 myhost1 nxserver[12940]: handleSIGCHLD 2015-09-29T11:43:23.195911-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 12958 2015-09-29T11:43:23.196038-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 12975 2015-09-29T11:43:23.196073-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 13229 2015-09-29T11:43:23.196381-05:00 myhost1 nxserver[12940]: Process (13229/13229) finished with exit status '1'. 2015-09-29T11:43:23.196409-05:00 myhost1 nxserver[12940]: Going to call NXProcessRemove(13229) 2015-09-29T11:43:23.196416-05:00 myhost1 nxserver[12940]: Process '/usr/NX/bin/nxexec --node --user testuser1 --priority --mode 0' with pid '13229/13229' finished after 0,416 seconds. 2015-09-29T11:43:23.196482-05:00 myhost1 nxserver[12940]: nxDefaultExitCodeHandler: process with pid 13229 just died with exit code '1'. 2015-09-29T11:43:33.053672-05:00 myhost1 nxserver[12940]: Global selector: something to read on FD#11 - server --daemon's socket accepted handle. 2015-09-29T11:43:33.053710-05:00 myhost1 nxserver[12940]: Server --daemon parser for FD#11. 2015-09-29T11:43:33.053775-05:00 myhost1 nxserver[12940]: Read 'NX> 1011 Ping\n', 14 bytes from FD#11. 2015-09-29T11:43:33.053836-05:00 myhost1 nxserver[12940]: Found node assigned to socket FD#11. 2015-09-29T11:43:33.053901-05:00 myhost1 nxserver[12940]: Parse local node message 'NX> 1011 Ping\n' on FD#11. 2015-09-29T11:43:33.053956-05:00 myhost1 nxserver[12940]: Handle message to local node by NXNodeExec. 2015-09-29T11:43:33.053994-05:00 myhost1 nxserver[12940]: Saving current NXNodeExec node stdin: FD#10. 2015-09-29T11:43:33.054030-05:00 myhost1 nxserver[12940]: Found node assigned to socket FD#11. 2015-09-29T11:43:33.054076-05:00 myhost1 nxserver[12940]: NXNodeExec::stdin is set to FD#10. 2015-09-29T11:43:33.054117-05:00 myhost1 nxserver[12940]: Handling code: 1011 with function: NXNodeExec::handle_message_node_ping 2015-09-29T11:43:33.054162-05:00 myhost1 nxserver[12940]: Received ping message. 2015-09-29T11:43:33.054203-05:00 myhost1 nxserver[12940]: Sending pong message. 2015-09-29T11:43:33.054298-05:00 myhost1 nxserver[12940]: [21] 'NX> 1012 Ping reply\n' sent to NXNode on FD#10. 2015-09-29T11:43:33.054391-05:00 myhost1 nxserver[12940]: Finished handling message by NXNodeExec. 2015-09-29T11:43:33.054411-05:00 myhost1 nxserver[12940]: Restoring NXNodeExec node stdin: FD#10. 2015-09-29T11:43:33.054415-05:00 myhost1 nxserver[12940]: NXNodeExec::stdin is set to FD#10. 2015-09-29T11:43:33.054565-05:00 myhost1 nxserver[12940]: Starting find X servers procedure. 2015-09-29T11:43:33.055181-05:00 myhost1 nxserver[12940]: NXRunCommand: setting command to '/bin/netstat -ln --protocol=unix'. 2015-09-29T11:43:33.055269-05:00 myhost1 nxserver[12940]: NXRunCommand: setChildDescriptors started 2015-09-29T11:43:33.055315-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdin started 2015-09-29T11:43:33.055387-05:00 myhost1 nxserver[12940]: NXPipeCreate(FD#0, FD#1, 0) returned '1' 2015-09-29T11:43:33.055458-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdout started 2015-09-29T11:43:33.055509-05:00 myhost1 nxserver[12940]: NXPipeCreate(FD#9, FD#12, 0) returned '1' 2015-09-29T11:43:33.055639-05:00 myhost1 nxserver[12940]: NXOpen FD#13 - file /usr/NX/var/log/nxerror.log with mode 1089 and permissions 384 from Common::NXRunCommand::__setupParentChildStderr. 2015-09-29T11:43:33.055691-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.055730-05:00 myhost1 nxserver[12940]: Descriptors set. Child :0, 12, 13. Parent 1, 9. 2015-09-29T11:43:33.055763-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.055806-05:00 myhost1 nxserver[12940]: nxProcessCreate: '/bin/netstat' '/bin/netstat /bin/netstat -ln --protocol=unix' '' '0' '12' '13' '101' 2015-09-29T11:43:33.057924-05:00 myhost1 nxserver[12940]: Added '13244/13244' to children list. 2015-09-29T11:43:33.058212-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStdin 2015-09-29T11:43:33.058273-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.058429-05:00 myhost1 nxserver[12940]: NXClose FD#0 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.058499-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStdout 2015-09-29T11:43:33.058548-05:00 myhost1 nxserver[12940]: NXClose FD#12 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.058594-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStderr 2015-09-29T11:43:33.058641-05:00 myhost1 nxserver[12940]: NXClose FD#13 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.058693-05:00 myhost1 nxserver[12940]: NXRunCommand: child descriptors closed. 2015-09-29T11:43:33.058773-05:00 myhost1 nxserver[12940]: NXClose FD#1 from Common::NXRunCommand::handleParentStdin. 2015-09-29T11:43:33.059055-05:00 myhost1 nxserver[12940]: NXParser: adding handle '5' FD#5 to the selector. 2015-09-29T11:43:33.059166-05:00 myhost1 nxserver[12940]: NXParser: adding handle '9' FD#9 to the selector. 2015-09-29T11:43:33.059313-05:00 myhost1 nxserver[12940]: NXParser: main loop started with timeout 30. 2015-09-29T11:43:33.059409-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 30000. 2015-09-29T11:43:33.060820-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.060967-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29999. 2015-09-29T11:43:33.061878-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.061985-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29998. 2015-09-29T11:43:33.062143-05:00 myhost1 nxserver[12940]: NXParser: signal 'CHLD' received. 2015-09-29T11:43:33.062190-05:00 myhost1 nxserver[12940]: Received 'SIGCHLD' signal:going to handle it 2015-09-29T11:43:33.062224-05:00 myhost1 nxserver[12940]: handleSIGCHLD 2015-09-29T11:43:33.062257-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 13244 2015-09-29T11:43:33.062316-05:00 myhost1 nxserver[12940]: Process (13244/13244) finished with exit status '0'. 2015-09-29T11:43:33.062359-05:00 myhost1 nxserver[12940]: Going to call NXProcessRemove(13244) 2015-09-29T11:43:33.062438-05:00 myhost1 nxserver[12940]: Process '/bin/netstat -ln --protocol=unix' with pid '13244/13244' finished after 0,5 seconds. 2015-09-29T11:43:33.062488-05:00 myhost1 nxserver[12940]: nxDefaultExitCodeHandler: process with pid 13244 just died with exit code '0'. 2015-09-29T11:43:33.062534-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.062593-05:00 myhost1 nxserver[12940]: NXParser: EOF on FD#9 2015-09-29T11:43:33.062673-05:00 myhost1 nxserver[12940]: NXClose FD#9 from Common::NXParser::removeAndCloseHandle. 2015-09-29T11:43:33.062716-05:00 myhost1 nxserver[12940]: NXParser: FD#9 was closed. 2015-09-29T11:43:33.062845-05:00 myhost1 nxserver[12940]: NXRunCommand: process '13244' exit status was '0' 2015-09-29T11:43:33.062884-05:00 myhost1 nxserver[12940]: NXRunCommand: process '13244' stderr was '' 2015-09-29T11:43:33.062914-05:00 myhost1 nxserver[12940]: NXRunCommand: return: 0 2015-09-29T11:43:33.063112-05:00 myhost1 nxserver[12940]: NXRunCommand: setting command to '/bin/ps awwxo ppid,pid,sid,comm,args'. 2015-09-29T11:43:33.063163-05:00 myhost1 nxserver[12940]: NXRunCommand: setChildDescriptors started 2015-09-29T11:43:33.063198-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdin started 2015-09-29T11:43:33.063258-05:00 myhost1 nxserver[12940]: NXPipeCreate(FD#0, FD#1, 0) returned '1' 2015-09-29T11:43:33.063296-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdout started 2015-09-29T11:43:33.063340-05:00 myhost1 nxserver[12940]: NXPipeCreate(FD#9, FD#12, 0) returned '1' 2015-09-29T11:43:33.063432-05:00 myhost1 nxserver[12940]: NXOpen FD#13 - file /usr/NX/var/log/nxerror.log with mode 1089 and permissions 384 from Common::NXRunCommand::__setupParentChildStderr. 2015-09-29T11:43:33.063476-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.063509-05:00 myhost1 nxserver[12940]: Descriptors set. Child :0, 12, 13. Parent 1, 9. 2015-09-29T11:43:33.063540-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.063576-05:00 myhost1 nxserver[12940]: nxProcessCreate: '/bin/ps' '/bin/ps /bin/ps awwxo ppid,pid,sid,comm,args' '' '0' '12' '13' '101' 2015-09-29T11:43:33.064683-05:00 myhost1 nxserver[12940]: Added '13245/13245' to children list. 2015-09-29T11:43:33.064972-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStdin 2015-09-29T11:43:33.065038-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.065146-05:00 myhost1 nxserver[12940]: NXClose FD#0 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.065200-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStdout 2015-09-29T11:43:33.065239-05:00 myhost1 nxserver[12940]: NXClose FD#12 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.065284-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStderr 2015-09-29T11:43:33.065335-05:00 myhost1 nxserver[12940]: NXClose FD#13 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.065380-05:00 myhost1 nxserver[12940]: NXRunCommand: child descriptors closed. 2015-09-29T11:43:33.065449-05:00 myhost1 nxserver[12940]: NXClose FD#1 from Common::NXRunCommand::handleParentStdin. 2015-09-29T11:43:33.065614-05:00 myhost1 nxserver[12940]: NXParser: adding handle '5' FD#5 to the selector. 2015-09-29T11:43:33.065696-05:00 myhost1 nxserver[12940]: NXParser: adding handle '9' FD#9 to the selector. 2015-09-29T11:43:33.065812-05:00 myhost1 nxserver[12940]: NXParser: main loop started with timeout 30. 2015-09-29T11:43:33.065892-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 30000. 2015-09-29T11:43:33.070421-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.070575-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29995. 2015-09-29T11:43:33.072955-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.073072-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29992. 2015-09-29T11:43:33.075964-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.076169-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29989. 2015-09-29T11:43:33.078323-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.078525-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29987. 2015-09-29T11:43:33.081114-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.081329-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29984. 2015-09-29T11:43:33.082137-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.082343-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29983. 2015-09-29T11:43:33.082630-05:00 myhost1 nxserver[12940]: NXParser: signal 'CHLD' received. 2015-09-29T11:43:33.082711-05:00 myhost1 nxserver[12940]: Received 'SIGCHLD' signal:going to handle it 2015-09-29T11:43:33.082774-05:00 myhost1 nxserver[12940]: handleSIGCHLD 2015-09-29T11:43:33.082840-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 13245 2015-09-29T11:43:33.082931-05:00 myhost1 nxserver[12940]: Process (13245/13245) finished with exit status '0'. 2015-09-29T11:43:33.083008-05:00 myhost1 nxserver[12940]: Going to call NXProcessRemove(13245) 2015-09-29T11:43:33.083126-05:00 myhost1 nxserver[12940]: Process '/bin/ps awwxo ppid,pid,sid,comm,args' with pid '13245/13245' finished after 0,19 seconds. 2015-09-29T11:43:33.083207-05:00 myhost1 nxserver[12940]: nxDefaultExitCodeHandler: process with pid 13245 just died with exit code '0'. 2015-09-29T11:43:33.083291-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.083391-05:00 myhost1 nxserver[12940]: NXParser: EOF on FD#9 2015-09-29T11:43:33.083542-05:00 myhost1 nxserver[12940]: NXClose FD#9 from Common::NXParser::removeAndCloseHandle. 2015-09-29T11:43:33.083620-05:00 myhost1 nxserver[12940]: NXParser: FD#9 was closed. 2015-09-29T11:43:33.083802-05:00 myhost1 nxserver[12940]: NXRunCommand: process '13245' exit status was '0' 2015-09-29T11:43:33.083886-05:00 myhost1 nxserver[12940]: NXRunCommand: process '13245' stderr was '' 2015-09-29T11:43:33.083949-05:00 myhost1 nxserver[12940]: NXRunCommand: return: 0 2015-09-29T11:43:33.087753-05:00 myhost1 nxserver[12940]: NXRunCommand: setting command to '/usr/bin/ck-list-sessions'. 2015-09-29T11:43:33.087866-05:00 myhost1 nxserver[12940]: NXRunCommand: setting not to log stdout. 2015-09-29T11:43:33.087953-05:00 myhost1 nxserver[12940]: NXRunCommand: setChildDescriptors started 2015-09-29T11:43:33.088015-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdin started 2015-09-29T11:43:33.088127-05:00 myhost1 nxserver[12940]: NXPipeCreate(FD#0, FD#1, 0) returned '1' 2015-09-29T11:43:33.088202-05:00 myhost1 nxserver[12940]: NXRunCommand: __setupParentChildStdout started 2015-09-29T11:43:33.088286-05:00 myhost1 nxserver[12940]: NXPipeCreate(FD#9, FD#12, 0) returned '1' 2015-09-29T11:43:33.088467-05:00 myhost1 nxserver[12940]: NXOpen FD#13 - file /usr/NX/var/log/nxerror.log with mode 1089 and permissions 384 from Common::NXRunCommand::__setupParentChildStderr. 2015-09-29T11:43:33.088554-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.088620-05:00 myhost1 nxserver[12940]: Descriptors set. Child :0, 12, 13. Parent 1, 9. 2015-09-29T11:43:33.088680-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.088881-05:00 myhost1 nxserver[12940]: nxProcessCreate: '/usr/bin/ck-list-sessions' '/usr/bin/ck-list-sessions /usr/bin/ck-list-sessions' '' '0' '12' '13' '101' 2015-09-29T11:43:33.091498-05:00 myhost1 nxserver[12940]: Added '13246/13246' to children list. 2015-09-29T11:43:33.091821-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStdin 2015-09-29T11:43:33.091887-05:00 myhost1 nxserver[12940]: NXRunCommand: getChildStdin return 0 2015-09-29T11:43:33.092014-05:00 myhost1 nxserver[12940]: NXClose FD#0 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.092093-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStdout 2015-09-29T11:43:33.092178-05:00 myhost1 nxserver[12940]: NXClose FD#12 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.092246-05:00 myhost1 nxserver[12940]: NXRunCommand: Closing ChildStderr 2015-09-29T11:43:33.092316-05:00 myhost1 nxserver[12940]: NXClose FD#13 from Common::NXRunCommand::closeChildDescriptors. 2015-09-29T11:43:33.092390-05:00 myhost1 nxserver[12940]: NXRunCommand: child descriptors closed. 2015-09-29T11:43:33.092513-05:00 myhost1 nxserver[12940]: NXClose FD#1 from Common::NXRunCommand::handleParentStdin. 2015-09-29T11:43:33.092853-05:00 myhost1 nxserver[12940]: NXParser: adding handle '5' FD#5 to the selector. 2015-09-29T11:43:33.093013-05:00 myhost1 nxserver[12940]: NXParser: adding handle '9' FD#9 to the selector. 2015-09-29T11:43:33.093243-05:00 myhost1 nxserver[12940]: NXParser: main loop started with timeout 30. 2015-09-29T11:43:33.093418-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 30000. 2015-09-29T11:43:33.101604-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.102844-05:00 myhost1 nxserver[12940]: NXParser: set timeout to : 29992. 2015-09-29T11:43:33.102870-05:00 myhost1 nxserver[12940]: NXParser: signal 'CHLD' received. 2015-09-29T11:43:33.102877-05:00 myhost1 nxserver[12940]: Received 'SIGCHLD' signal:going to handle it 2015-09-29T11:43:33.102882-05:00 myhost1 nxserver[12940]: handleSIGCHLD 2015-09-29T11:43:33.102890-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 12958 2015-09-29T11:43:33.102896-05:00 myhost1 nxserver[12940]: handleSIGCHLD checking child: 13246 2015-09-29T11:43:33.102903-05:00 myhost1 nxserver[12940]: Process (13246/13246) finished with exit status '0'. 2015-09-29T11:43:33.102909-05:00 myhost1 nxserver[12940]: Going to call NXProcessRemove(13246) 2015-09-29T11:43:33.102915-05:00 myhost1 nxserver[12940]: Process '/usr/bin/ck-list-sessions' with pid '13246/13246' finished after 0,11 seconds. 2015-09-29T11:43:33.102920-05:00 myhost1 nxserver[12940]: nxDefaultExitCodeHandler: process with pid 13246 just died with exit code '0'. 2015-09-29T11:43:33.102926-05:00 myhost1 nxserver[12940]: NXParser: checkCanRead has: FD#9 2015-09-29T11:43:33.102982-05:00 myhost1 nxserver[12940]: NXParser: EOF on FD#9 2015-09-29T11:43:33.103041-05:00 myhost1 nxserver[12940]: NXClose FD#9 from Common::NXParser::removeAndCloseHandle. 2015-09-29T11:43:33.103102-05:00 myhost1 nxserver[12940]: NXParser: FD#9 was closed. 2015-09-29T11:43:33.103227-05:00 myhost1 nxserver[12940]: NXRunCommand: process '13246' exit status was '0' 2015-09-29T11:43:33.103249-05:00 myhost1 nxserver[12940]: NXRunCommand: process '13246' stderr was '' 2015-09-29T11:43:33.103270-05:00 myhost1 nxserver[12940]: NXRunCommand: return: 0 2015-09-29T11:43:33.103543-05:00 myhost1 nxserver[12940]: checkLocalSession: Found socket for display [1] - [unix 2 [ ACC ] STREAM LISTENING 190202 /tmp/.X11-unix/X1] 2015-09-29T11:43:33.103565-05:00 myhost1 nxserver[12940]: Going to find X server for display: [1] 2015-09-29T11:43:33.104109-05:00 myhost1 nxserver[12940]: Found X server process: [Xvnc4 Xvnc4 :1 -desktop myhost1:1 (testuser2) -auth /home/testuser2/.Xauthority -geometry 2560x1600 -depth 16 -rfbwait 30000 -rfbauth /home/testuser2/.vnc/passwd -rfbport 5901 -pn -fp /usr/X11R6/lib/X11/fonts/Type1/,/usr/X11R6/lib/X11/fonts/Speedo/,/usr/X11R6/lib/X11/fonts/misc/,/usr/X11R6/lib/X11/fonts/75dpi/,/usr/X11R6/lib/X11/fonts/100dpi/,/usr/share/fonts/X11/misc/,/usr/share/fonts/X11/Type1/,/usr/share/fonts/X11/75dpi/,/usr/share/fonts/X11/100dpi/ -co /etc/X11/rgb] 2015-09-29T11:43:33.104208-05:00 myhost1 nxserver[12940]: checkLocalSession: Found authority /home/testuser2/.Xauthority. 2015-09-29T11:43:33.104298-05:00 myhost1 nxserver[12940]: Parent process pid is 1. 2015-09-29T11:43:33.104388-05:00 myhost1 nxserver[12940]: recognizeAloneXSession: kind [aloneXserver] based on command [Xvnc4 Xvnc4 :1 -desktop myhost1:1 (testuser2) -auth /home/testuser2/.Xauthority -geometry 2560x1600 -depth 16 -rfbwait 30000 -rfbauth /home/testuser2/.vnc/passwd -rfbport 5901 -pn -fp /usr/X11R6/lib/X11/fonts/Type1/,/usr/X11R6/lib/X11/fonts/Speedo/,/usr/X11R6/lib/X11/fonts/misc/,/usr/X11R6/lib/X11/fonts/75dpi/,/usr/X11R6/lib/X11/fonts/100dpi/,/usr/share/fonts/X11/misc/,/usr/share/fonts/X11/Type1/,/usr/share/fonts/X11/75dpi/,/usr/share/fonts/X11/100dpi/ -co /etc/X11/rgb] pid [11739] 2015-09-29T11:43:33.104551-05:00 myhost1 nxserver[12940]: checkLocalSession: Found socket for display [1001] - [unix 2 [ ACC ] STREAM LISTENING 29342385 /tmp/.X11-unix/X1001] 2015-09-29T11:43:33.104565-05:00 myhost1 nxserver[12940]: Going to find X server for display: [1001] 2015-09-29T11:43:33.105387-05:00 myhost1 nxserver[12940]: checkLocalSession: Found socket for display [1001] - [unix 2 [ ACC ] STREAM LISTENING 29342384 @/tmp/.X11-unix/X1001] 2015-09-29T11:43:33.105405-05:00 myhost1 nxserver[12940]: Going to find X server for display: [1001] 2015-09-29T11:43:33.106263-05:00 myhost1 nxserver[12940]: Found node assigned to session pid 11739. 2015-09-29T11:43:33.106359-05:00 myhost1 nxserver[12940]: NXPL was already loaded. 2015-09-29T11:43:33.106382-05:00 myhost1 nxserver[12940]: NXPL::NXGetRandomString start. 2015-09-29T11:43:33.106390-05:00 myhost1 nxserver[12940]: NXPL::NXGetRandomString stop. 2015-09-29T11:43:33.106437-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL random string: 3CB9861A915EA71C3D001752B2195F1D 2015-09-29T11:43:33.106503-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL tempfile: /var/NX/nx/.nx/3CB9861A915EA71C3D001752B2195F1D.lcookie_2 2015-09-29T11:43:33.106702-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL addCookie(/var/NX/nx/.nx/3CB9861A915EA71C3D001752B2195F1D.lcookie_2, :1, MIT-MAGIC-COOKIE-1, ********) return 0 2015-09-29T11:43:33.106725-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL set XAUTHORITY to /var/NX/nx/.nx/3CB9861A915EA71C3D001752B2195F1D.lcookie_2 2015-09-29T11:43:33.106765-05:00 myhost1 nxserver[12940]: isWMRunningByDisplay call on :1 2015-09-29T11:43:33.107634-05:00 myhost1 nxserver[12940]: NXPL::NXWmRunning(:1) [1] 2015-09-29T11:43:33.107668-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL isWMRunningByDisplay return [1] 2015-09-29T11:43:33.107719-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL clean out file: /var/NX/nx/.nx/3CB9861A915EA71C3D001752B2195F1D.lcookie_2 2015-09-29T11:43:33.107802-05:00 myhost1 nxserver[12940]: isLoginWindowByNXPL(1, ********) return [1] 2015-09-29T11:43:33.107877-05:00 myhost1 nxserver[12940]: __reportLocalSession: alone X server, do not confirm. 2015-09-29T11:43:33.108319-05:00 myhost1 nxserver[12940]: Checking local session with line: NX> 153 10086:testuser2:1:ubuntu+12.04.5+lts:desktop:aloneXserver:11739:%%2fhome%%2ftestuser2%%2f.Xauthority:*****:1:empty . 2015-09-29T11:43:33.108499-05:00 myhost1 nxserver[12940]: Checking local X server for pid: 11739. 2015-09-29T11:43:33.108523-05:00 myhost1 nxserver[12940]: Session 50C83872F4E321C5763A1E8C98168AF6 associated with pid 11739 is already known. 2015-09-29T11:43:33.108532-05:00 myhost1 nxserver[12940]: Found node assigned to session 50C83872F4E321C5763A1E8C98168AF6. 2015-09-29T11:43:33.108538-05:00 myhost1 nxserver[12940]: Node stdin: FD#10, stdout: FD#11, socket FD#10. 2015-09-29T11:43:33.108594-05:00 myhost1 nxserver[12940]: Session 50C83872F4E321C5763A1E8C98168AF6 associated with pid 11739 is already running. 2015-09-29T11:43:33.108630-05:00 myhost1 nxserver[12940]: Found node assigned to session 50C83872F4E321C5763A1E8C98168AF6. 2015-09-29T11:43:33.108671-05:00 myhost1 nxserver[12940]: Node stdin: FD#10, stdout: FD#11, socket FD#10.