Dear x2go developers, first I'd like to thank you all for creating such a helpful product! Hope I've addressed the right mailing list?
I'm trying to integrate the x2go system into our own (MPA-)Linux system which we are using and developing for centuries now (it's used non commercial in an research institute). While the client connection from my (MPA-)system to a pre-installed Ubuntu server works without a problem, the connection to a (MPA-)Linux server doesn't work yet. I'd appreciate it very much if you could be of any help to me! I have enabled some debugging and enclosed these log files: client-session1.log - shows the x2goclient 'session.log' to an (MPA-)Linux server. It fails on line 59 with a 'Protocol mismatch or no X authentication data' client-sessions1 - shows the x2goclient 'sessions' file where the problem seems to show up on line 386 where only 14 instead of 50 bytes (which I see in a working connection to the Ubuntu installation) are identified. Followed by "handleAuthorization: WARNING! Failed to match the fake cookie for FD#6." in line 392. Does this mean that the server wasn't able to apply the fake cookie? What could be the reason for this? Where might I look further on? In which code part is the fake cookie written to .Xauthority? Which debug might I enable to track down the problem? server-session1.log - server site log: there I found an Exception on line 91 resulting from an I/O error. Any hint would be very welcome! Where do I find a diagram or description of the data streams/login establishing in x2go? Cheers, Ado ________________________________________________________________________ Dipl.-Ing. Heinz-Ado Arnolds MPI für Astrophysik Karl-Schwarzschild-Strasse 1 Postfach 1317 D-85748 Garching D-85741 Garching Phone: +49/89/30000-2217 FAX : +49/89/30000-2388 email: arnolds[at]MPA-Garching.MPG.DE ________________________________________________________________________
Loop: Going to parse the command line options. Loop: Argc is 3. Loop: Argv[0] is /amd64/system/MPA-7.23/usr/lib/nx/bin/nxproxy. Loop: Argv[1] is -S. Loop: Argv[2] is nx/nx,options=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options:50. Loop: Setting local proxy mode to proxy_server. Loop: Trying to parse options string 'nx/nx,options=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options:50' as a remote NX host. Loop: Can't identify remote NX host in string 'nx/nx,options=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Going to parse the environment options string 'nx/nx,options=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options:50'. Loop: Parsing options string 'options=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Parsing environment option 'options' with value '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Completed parsing of string 'nx/nx,options=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options:50'. Loop: Reading options from '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Going to read options from file '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Read options 'nx/nx,root=/home/arnolds/.x2go,connect=localhost,cookie=fd106b2d133812b04e1b1e7e30593222,port=31004,errors=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions:50' from file '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Going to parse the environment options string 'nx/nx,root=/home/arnolds/.x2go,connect=localhost,cookie=fd106b2d133812b04e1b1e7e30593222,port=31004,errors=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions:50'. Loop: Parsing options string 'root=/home/arnolds/.x2go,connect=localhost,cookie=fd106b2d133812b04e1b1e7e30593222,port=31004,errors=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions'. Loop: Parsing environment option 'root' with value '/home/arnolds/.x2go'. Loop: Parsing environment option 'connect' with value 'localhost'. Loop: Parsing environment option 'cookie' with value 'fd106b2d133812b04e1b1e7e30593222'. Loop: Parsing environment option 'port' with value '31004'. Loop: Parsing environment option 'errors' with value '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions'. Loop: Completed parsing of string 'nx/nx,root=/home/arnolds/.x2go,connect=localhost,cookie=fd106b2d133812b04e1b1e7e30593222,port=31004,errors=/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions:50'. Loop: Assuming name of options file '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Skipping a further parse of environment options string ''. NXTransProxy: Main process started with pid '15402'. Loop: Skipping a further parse of environment options string 'localhost:11.0'. Loop: WARNING! No environment for NX_HOME. Loop: Assuming NX user's home directory '/home/arnolds'. Loop: WARNING! No environment for NX_SYSTEM. Loop: Assuming system NX directory '/usr/NX'. Loop: Assuming temporary NX directory '/tmp'. Loop: Assuming NX client location '/home/arnolds/opt/x2go/bin/x2goclient'. Loop: Assuming default statistics file 'stats'. Loop: Root of NX session is '/home/arnolds/.x2go/S-50'. Loop: Name selected for log file is '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions'. NXPROXY - Version 3.5.0 Copyright (C) 2001, 2010 NoMachine. See http://www.nomachine.com/ for more information. Info: Proxy running in client mode with pid '15402'. Session: Starting session at 'Wed Aug 5 09:36:18 2015'. Info: Using errors file '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions'. Info: Using stats file '/home/arnolds/.x2go/S-50/stats'. Info: Connecting to remote host 'localhost:31004'. Info: Connection to remote proxy 'localhost:31004' established. Info: Connection with remote proxy completed. Warning: Unrecognized session type 'unix-kde-depth_24'. Assuming agent session. Info: Using ADSL link parameters 512/24/1/0. Info: Using cache parameters 4/4096KB/8192KB/8192KB. Info: Using pack method '16m-jpeg-9' with session 'unix-kde-depth_24'. Info: Using ZLIB data compression 1/1/32. Info: Using ZLIB stream compression 4/4. Info: No suitable cache file found. Info: Forwarding X11 connections to display 'localhost:11.0'. Session: Session started at 'Wed Aug 5 09:36:18 2015'. Warning: Protocol mismatch or no X authentication data. Session: Terminating session at 'Wed Aug 5 09:36:22 2015'. Info: Your session was closed before reaching a usable state. Info: This can be due to the local X server refusing access to the client. Info: Please check authorization provided by the remote X application. Session: Session terminated at 'Wed Aug 5 09:36:22 2015'.
NXTransProxy: Going to run the NX transport loop. NXTransPrepare: Going to prepare the NX transport. Loop: Going to run a new negotiation loop with stage 0 at 09:36:18:305.108. Loop: Handling negotiation with 'stage_undefined'. Loop: Going to run a new negotiation loop with stage 1 at 09:36:18:305.226. Loop: Handling negotiation with 'stage_initializing'. Loop: Set RLIMIT_CORE to 18446744073709551615. Loop: Installing signals in process with pid '15402'. Loop: Installing handler for signal 1 'SIGHUP' in process with pid '15402'. Loop: Installing handler for signal 2 'SIGINT' in process with pid '15402'. Loop: Installing handler for signal 10 'SIGUSR1' in process with pid '15402'. Loop: Installing handler for signal 12 'SIGUSR2' in process with pid '15402'. Loop: Installing handler for signal 13 'SIGPIPE' in process with pid '15402'. Loop: Installing handler for signal 14 'SIGALRM' in process with pid '15402'. Loop: Installing handler for signal 15 'SIGTERM' in process with pid '15402'. Loop: Installing handler for signal 17 'SIGCHLD' in process with pid '15402'. Loop: INIT! Taking mark for initialization at 09:36:18:305.386. Loop: Disabling cups connections. Loop: Disabling auxiliary X11 connections. Loop: Disabling SMB connections. Loop: Disabling multimedia connections. Loop: Disabling HTTP connections. Loop: Disabling font server connections. Loop: Disabling slave connections. Loop: Local host is little endian. Loop: Using local X display 'localhost:11.0' with host 'localhost' and port '11'. Loop: Using real X server on TCP port. Loop: Going to run a new negotiation loop with stage 2 at 09:36:18:306.391. Loop: Handling negotiation with 'stage_connecting'. Loop: Going to connect to localhost:31004. Loop: Connecting to remote host 'localhost:31004'. Loop: Timer set to 20 S with retry set to 30 in process with pid '15402'. Loop: Timer set to 20 S and 0 Ms at 09:36:18:306.956 in process with pid '15402'. Loop: Connected to remote proxy on FD#5. Loop: Going to run a new negotiation loop with stage 3 at 09:36:18:307.147. Loop: Handling negotiation with 'stage_connected'. Loop: Going to run a new negotiation loop with stage 9 at 09:36:18:307.201. Loop: Handling negotiation with 'stage_sending_proxy_options'. Loop: Sending remote options 'NXPROXY-1.5.0-3.5.0 cookie=fd106b2d133812b04e1b1e7e30593222,stream=default,data=default '. Loop: WriteLocalData: select() returned with a code of 1 and remaining timeout of 29 sec, 999989usec Loop: Going to run a new negotiation loop with stage 7 at 09:36:18:307.322. Loop: Handling negotiation with 'stage_waiting_proxy_version'. Loop: Going to read the remote proxy version from FD#5. Loop: Going to read remote data from FD#5. Loop: Reading data from FD#5 would block. Loop: Selected proxy FD#5 in negotiation phase with timeout of 5 S and 0 Ms. Loop: Mark - 0 - at 09:36:18:307.432 with 2 Ms elapsed. Loop: New timestamp is 09:36:18:307.431. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [6] at 09:36:18:307.494. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Select result is [1] error is [0] at 09:36:18:361.507 after 55 Ms. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Out of select after 55 Ms at 09:36:18:361.609 with result 1. Loop: New timestamp is 09:36:18:361.609. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Checking size of log file '/home/arnolds/.x2go/S-arnolds-50-1438760175_stRTERMINAL_dp24/sessions'. NXTransPrepare: Going to prepare the NX transport. Loop: Going to run a new negotiation loop with stage 7 at 09:36:18:361.721. Loop: Handling negotiation with 'stage_waiting_proxy_version'. Loop: Going to read the remote proxy version from FD#5. Loop: Going to read remote data from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read stop character from FD#5. Loop: Remote string 'NXPROXY-1.5.0-3.5.0 ' read from FD#5. Loop: Received remote version string 'NXPROXY-1.5.0-3.5.0 ' from FD#5. Loop: Identified remote version '3.5.0'. Loop: Remote compatibility version '1.5.0'. Loop: Local version '3.5.0'. Loop: Using local version '3.5.0'. Loop: Using NX protocol step 10. Loop: Disabling image cache with protocol step '10'. Loop: Going to run a new negotiation loop with stage 8 at 09:36:18:362.128. Loop: Handling negotiation with 'stage_waiting_proxy_options'. Loop: Going to read the remote proxy options from FD#5. Loop: Going to read remote data from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read stop character from FD#5. Loop: Remote string 'link=ADSL,pack=16m-jpeg-9,cache=8192k,limit=0,render=1,taint=0,type=unix-kde-depth_24,images=0,delta=1,stream=4,data=1 ' read from FD#5. Loop: Received remote options string 'link=ADSL,pack=16m-jpeg-9,cache=8192k,limit=0,render=1,taint=0,type=unix-kde-depth_24,images=0,delta=1,stream=4,data=1 ' from FD#5. Loop: Going to parse the remote options string 'link=ADSL,pack=16m-jpeg-9,cache=8192k,limit=0,render=1,taint=0,type=unix-kde-depth_24,images=0,delta=1,stream=4,data=1 '. Loop: Parsing remote option 'link' with value 'ADSL'. Loop: Parsing remote option 'pack' with value '16m-jpeg-9'. Loop: Pack method is -1 quality is -1. Loop: Parsing pack method '16m-jpeg-9'. Loop: Using pack quality '9'. Loop: Parsing remote option 'cache' with value '8192k'. Loop: Parsing integer option 'cache' from string '8192' with base set to K. Loop: Integer option parsed to '8388608'. Loop: Setting size of cache to 8388608 bytes. Loop: Parsing remote option 'limit' with value '0'. Loop: Disabling bitrate limit on proxy link. Loop: Parsing remote option 'render' with value '1'. Loop: Parsing remote option 'taint' with value '0'. Loop: Parsing remote option 'type' with value 'unix-kde-depth_24'. Loop: Parsing remote option 'images' with value '0'. Loop: Setting size of images cache to 0 bytes. Loop: Parsing remote option 'delta' with value '1'. Loop: Parsing remote option 'stream' with value '4'. Loop: Parsing remote option 'data' with value '1'. Loop: Going to run a new negotiation loop with stage 10 at 09:36:18:363.797. Loop: Handling negotiation with 'stage_waiting_proxy_caches'. Loop: Going to receive the list of remote caches. Loop: Path of cache files is '/home/arnolds/.x2go/cache-unix-kde-depth_24'. Loop: Going to read remote data from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read a further character from FD#5. Loop: Read stop character from FD#5. Loop: Remote string 'cachelist=none ' read from FD#5. Loop: Read list of caches from remote side as 'cachelist=none '. Loop: No cache files seem to be available. Loop: Going to run a new negotiation loop with stage 11 at 09:36:18:364.422. Loop: Handling negotiation with 'stage_sending_proxy_caches'. Loop: Synchronizing local and remote caches. Loop: Going to send the selected cache. Loop: No valid cache file was selected. Loop: Sending string 'cachefile=none ' as selected cache file. Loop: WriteLocalData: select() returned with a code of 1 and remaining timeout of 29 sec, 999996usec Loop: Going to run a new negotiation loop with stage 12 at 09:36:18:364.541. Loop: Handling negotiation with 'stage_operational'. Loop: Connection with remote proxy completed. Loop: WARNING! Unrecognized session type 'unix-kde-depth_24'. Assuming agent session. Loop: Assuming session type 'session_agent' with string 'unix-kde-depth_24'. Loop: Setting initial flush policy to 'immediate'. Loop: Assuming proxy running as a standalone program. Loop: Setting link with initial value ADSL. Loop: Link speed is ADSL. Loop: Setting parameters for ADSL. Loop: Storage size limit is 8388608 at client and 8388608 at server. Loop: Storage local limit set to 8388608 remote limit set to 8388608. Loop: Set initial shared memory size to 2097152 bytes. Loop: Setting pack with initial method 35 and quality 9. Loop: Disabling the persistent image cache. Loop: LIMIT! Setting client bitrate limit to 0 server bitrate limit to 0 with local limit 0. Proxy: Created new object at 0x2552600. Auth: Creating X authorization data with cookie 'fd106b2d133812b04e1b1e7e30593222' and display 'localhost:11.0'. Auth: Reading the X authorization file with last update at 01:00:00:0.000. Auth: Using X authorization file '/home/arnolds/.Xauthority'. Auth: Using X auth command 'xauth'. Auth: Executing command [xauth][xauth][-f][/home/arnolds/.Xauthority][list][unix:11.0]. Loop: Disabling signal 1 'SIGHUP' in process with pid '15402'. Loop: Disabling signal 2 'SIGINT' in process with pid '15402'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '15402'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '15402'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '15402'. Loop: Disabling signal 14 'SIGALRM' in process with pid '15402'. Loop: Disabling signal 15 'SIGTERM' in process with pid '15402'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '15402'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '15402'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '15402'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '15402'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '15402'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '15402'. Loop: Disabling signal 29 'SIGIO' in process with pid '15402'. Loop: Registering child process '15405' in process with pid '15402'. Auth: Checking cookie in string 'srv-08/unix:11 MIT-MAGIC-COOKIE-1 aedbbde208a4123a431bfcb81b8adbcc '. Auth: Got cookie 'aedbbde208a4123a431bfcb81b8adbcc' from file '/home/arnolds/.Xauthority'. Loop: Enabling signals in process with pid '15402'. Auth: Validated real cookie 'aedbbde208a4123a431bfcb81b8adbcc' and fake cookie 'fd106b2d133812b04e1b1e7e30593222' with data with size 16. Auth: Ready to accept incoming connections. Auth: Setting last X authorization file update at 09:36:18:380.644. Proxy: Going to initialize the static members in channels for proxy FD#5. ServerChannel: Initializing the static members for the server channels. Proxy: Propagating parameters to channels' read buffers. Proxy: Propagating parameters to proxy buffers. Proxy: Configuring the proxy timeouts. Proxy: Configuring the proxy tokens. Proxy: TOKEN! LIMIT! Setting token [token_control] size to 512 and limit to 24. Proxy: TOKEN! LIMIT! Setting token [token_split] size to 512 and limit to 12. Proxy: TOKEN! LIMIT! Setting token [token_data] size to 512 and limit to 6. Proxy: LIMIT! Using client bitrate limit 0 server bitrate limit 0 with local limit 0. Proxy: LIMIT! Setting split data threshold to 2048 split packet limit to 256 with base 512. Proxy: LIMIT! Setting generic channel initial read size to 256 maximum read size to 256 with base 512. Proxy: Configuring cache according to pack parameters. Proxy: Entering operational mode. Proxy: Nothing else to flush for proxy FD#5. Loop: Starting the house-keeping process with storage size 33554432. Loop: Keeper started with pid '15406'. Loop: INIT! Completed initialization at 09:36:18:381.381 with 76 Ms since the init mark. Loop: Selected proxy FD#5 in negotiation phase with timeout of 5 S and 0 Ms. Loop: Mark - 0 - at 09:36:18:381.472 with 19 Ms elapsed. Loop: New timestamp is 09:36:18:381.472. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [6] at 09:36:18:381.537. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Select result is [1] error is [0] at 09:36:18:381.607 after 1 Ms. Loop: Installing signals in process with pid '15406'. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Disabling signal 1 'SIGHUP' in process with pid '15406'. Loop: Out of select after 1 Ms at 09:36:18:381.697 with result 1. Loop: Disabling signal 2 'SIGINT' in process with pid '15406'. Loop: New timestamp is 09:36:18:381.697. Loop: Disabling signal 10 'SIGUSR1' in process with pid '15406'. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Disabling signal 12 'SIGUSR2' in process with pid '15406'. Loop: Mark - 1 - at 09:36:18:381.782 with 0 Ms elapsed. Loop: Disabling signal 13 'SIGPIPE' in process with pid '15406'. Loop: Going to rotate channels for proxy FD#5. Loop: Disabling signal 14 'SIGALRM' in process with pid '15406'. Loop: Going to check the writable descriptors. Loop: Disabling signal 15 'SIGTERM' in process with pid '15406'. Proxy: Checking descriptors selected for write. Loop: Disabling signal 17 'SIGCHLD' in process with pid '15406'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '15406'. Loop: Mark - 2 - at 09:36:18:381.881 with 0 Ms elapsed. Loop: Disabling signal 21 'SIGTTIN' in process with pid '15406'. Loop: Going to check the readable descriptors. Loop: Disabling signal 22 'SIGTTOU' in process with pid '15406'. Proxy: Checking descriptors selected for read. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '15406'. Proxy: Going to read messages from proxy FD#5. Loop: Disabling signal 28 'SIGWINCH' in process with pid '15406'. Proxy: Decoding data from proxy FD#5. Loop: Disabling signal 29 'SIGIO' in process with pid '15406'. Loop: Closing proxy FD in process with pid '15406'. Loop: Freeing up proxy in process with pid '15406'. Proxy: Read result on proxy FD#5 is 21. Proxy: Getting messages from proxy FD#5 with 21 bytes in the read buffer. Proxy: Received message 'code_new_x_connection' at 09:36:18:382.173 with data ID#1. initCommitQueue: Resetting the queue of split commits for FD#6. Proxy: Adding channel 1 to the list of active channels. Proxy: There are 1 allocated channels for proxy FD#5. ServerChannel: Setting new buffer parameters for FD#6. Proxy: Nothing to read immediately from descriptor FD#6. Proxy: Received message 'code_switch_connection' at 09:36:18:382.421 with data ID#1. Proxy: Identified message of 14 bytes for FD#6 channel ID#1. handleWrite: Called for FD#6. handleWrite: Decoding messages for FD#6 with 14 bytes in the buffer. handleWrite: First request detected. Auth: Received a protocol size of 0 bytes. Auth: WARNING! Protocol mismatch or no X authentication data. handleAuthorization: WARNING! Failed to match the fake cookie for FD#6. Proxy: Failed to write proxy data to FD#6 channel ID#1. ServerChannel: Finishing connection for FD#6. Proxy: Finishing channel for FD#6 channel ID#1 because of failure. Proxy: Sending message 'code_finish_connection' at 09:36:18:382.638 with data ID#1. Proxy: Data length is 0 control length is 3. Proxy: Data length is now 0 control length is 3. Proxy: TOKEN! Checking tokens with frame type [frame_data] with stream ratio 1. Proxy: Adding a new frame for the remote proxy. Proxy: Produced plain output for 0+3+0 out of 3 bytes. Proxy: FLUSH! Immediate with blocked 0 length 0 new 3 flushable 0 tokens 24 after 16 Ms. Proxy: FLUSH! Immediate flush to proxy FD#5 of 3 bytes at 09:36:18:382.751 with priority 1. Proxy: FLUSH! Current bitrate is 0 with 0 in the long frame and top 0. Proxy: Sent 3 bytes of data with checksum [B68FB199E99F7A9D90F1B060E94CCAB1] on proxy FD#5. Proxy: Partial checksums are: [0][B68FB199E99F7A9D90F1B060E94CCAB1] Proxy: Going to reset flush counters for proxy FD#5. Proxy: Clearing the read descriptor for proxy FD#5. Loop: Mark - 3 - at 09:36:18:382.948 with 1 Ms elapsed. Loop: Going to check channel events for proxy FD#5. Proxy: Going to check the events on channels. Proxy: Skipping finishing descriptor FD#6 channel ID#1. Loop: Mark - 4 - at 09:36:18:383.008 with 1 Ms elapsed. Loop: Mark - 5 - at 09:36:18:383.029 with 1 Ms elapsed. Loop: Going to flush any data to the proxy. Proxy: Nothing else to flush for proxy FD#5. Loop: Mark - 6 - at 09:36:18:383.070 with 1 Ms elapsed. Proxy: Checking ping at 09:36:18:383.092 with last loop at 09:36:18:366.577. Proxy: Last bytes in at 09:36:18:382.152 last bytes out at 09:36:18:382.927. Proxy: Last ping at 09:36:18:366.577. Loop: Bitrate is 2 B/s and 0 B/s in 5/30 seconds timeframes. Loop: Mark - 7 - at 09:36:18:383.202 with 1 Ms elapsed. NXTransPrepare: Going to prepare the NX transport. Loop: Preparing the select with requested timeout of 5 S and 0 Ms. Proxy: Initial timeout is 5 S and 0 Ms. Proxy: Proxy descriptor FD#5 selected for read with buffer length 0. Proxy: Descriptor FD#6 not selected for write with blocked 0 and length 0. Proxy: Proxy descriptor FD#5 not selected for write. Blocked is 0 length is 0. Loop: Mark - 0 - at 09:36:18:383.299 with 1 Ms elapsed. Loop: New timestamp is 09:36:18:383.299. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [6] at 09:36:18:383.349. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Select result is [1] error is [0] at 09:36:18:383.441 after 0 Ms. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Out of select after 0 Ms at 09:36:18:383.497 with result 1. Loop: New timestamp is 09:36:18:383.497. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Mark - 1 - at 09:36:18:383.546 with 1 Ms elapsed. Loop: Going to rotate channels for proxy FD#5. Loop: Going to check the writable descriptors. Proxy: Checking descriptors selected for write. Proxy: Looping with current channel 1. Loop: Mark - 2 - at 09:36:18:383.604 with 1 Ms elapsed. Loop: Going to check the readable descriptors. Proxy: Checking descriptors selected for read. Proxy: Looping with current channel 1. Proxy: Going to read messages from proxy FD#5. Proxy: Decoding data from proxy FD#5. Proxy: Read result on proxy FD#5 is 3. Proxy: Getting messages from proxy FD#5 with 3 bytes in the read buffer. Proxy: Received message 'code_drop_connection' at 09:36:18:383.702 with data ID#1. Proxy: Marking channel for FD#6 channel ID#1 as being dropped. Proxy: Clearing the read descriptor for proxy FD#5. Loop: Mark - 3 - at 09:36:18:383.749 with 1 Ms elapsed. Loop: Going to check channel events for proxy FD#5. Proxy: Going to check the events on channels. Proxy: Dropping the descriptor FD#6 channel ID#1. Proxy: Dropping channel for FD#6 channel ID#1. ServerChannel: Freeing image state information. ServerChannel: Freeing shared memory information. ServerChannel: Freeing unpack state information. ServerChannel: Freeing channel caches. Proxy: Removing channel 1 from the list of active channels. Proxy: There are 0 allocated channels for proxy FD#5. Loop: Mark - 4 - at 09:36:18:383.951 with 1 Ms elapsed. Loop: Mark - 5 - at 09:36:18:383.978 with 1 Ms elapsed. Loop: Going to flush any data to the proxy. Proxy: Nothing else to flush for proxy FD#5. Loop: Mark - 6 - at 09:36:18:384.018 with 1 Ms elapsed. Proxy: Checking ping at 09:36:18:384.038 with last loop at 09:36:18:383.298. Proxy: Last bytes in at 09:36:18:383.690 last bytes out at 09:36:18:382.927. Proxy: Last ping at 09:36:18:366.577. Loop: Bitrate is 2 B/s and 0 B/s in 5/30 seconds timeframes. Loop: Mark - 7 - at 09:36:18:384.151 with 1 Ms elapsed. NXTransPrepare: Going to prepare the NX transport. Loop: Preparing the select with requested timeout of 5 S and 0 Ms. Proxy: Initial timeout is 5 S and 0 Ms. Proxy: Proxy descriptor FD#5 selected for read with buffer length 0. Proxy: Proxy descriptor FD#5 not selected for write. Blocked is 0 length is 0. Loop: Mark - 0 - at 09:36:18:384.258 with 1 Ms elapsed. Loop: New timestamp is 09:36:18:384.258. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [6] at 09:36:18:384.308. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Proxy: Deleted proxy object at 0x2552600. Loop: Freeing up auth data in process with pid '15406'. Loop: Freeing up statistics in process with pid '15406'. Loop: Freeing up control in process with pid '15406'. Loop: Enabling signals in process with pid '15406'. Loop: Going to clean up system resources in process '15406'. Loop: Disabling signal 1 'SIGHUP' in process with pid '15406'. Loop: Disabling signal 2 'SIGINT' in process with pid '15406'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '15406'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '15406'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '15406'. Loop: Disabling signal 14 'SIGALRM' in process with pid '15406'. Loop: Disabling signal 15 'SIGTERM' in process with pid '15406'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '15406'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '15406'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '15406'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '15406'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '15406'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '15406'. Loop: Disabling signal 29 'SIGIO' in process with pid '15406'. Loop: Exiting from child process with pid '15406' at 09:36:22:92.301. Loop: Freeing up keeper in process with pid '15406'. Loop: Freeing up streams in process with pid '15406'. Loop: Select result is [-1] error is [4] at 09:36:22:93.201 after 3709 Ms. Loop: Out of select after 3709 Ms at 09:36:22:93.276 with result -1. Loop: New timestamp is 09:36:22:93.276. Loop: Select failed due to EINTR error. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Mark - 1 - at 09:36:22:93.341 with 0 Ms elapsed. Loop: Going to rotate channels for proxy FD#5. Loop: Going to check the writable descriptors. Loop: Mark - 2 - at 09:36:22:93.382 with 0 Ms elapsed. Loop: Going to check the readable descriptors. Proxy: Checking descriptors selected for read. Loop: Mark - 3 - at 09:36:22:93.423 with 0 Ms elapsed. Loop: Going to check channel events for proxy FD#5. Proxy: Going to check the events on channels. Loop: Mark - 4 - at 09:36:22:93.464 with 0 Ms elapsed. Loop: Mark - 5 - at 09:36:22:93.484 with 0 Ms elapsed. Loop: Going to flush any data to the proxy. Proxy: Nothing else to flush for proxy FD#5. Loop: Mark - 6 - at 09:36:22:93.525 with 1 Ms elapsed. Proxy: Checking ping at 09:36:22:93.547 with last loop at 09:36:18:384.258. Proxy: Last bytes in at 09:36:18:383.690 last bytes out at 09:36:18:382.927. Proxy: Last ping at 09:36:18:366.577. Loop: Bitrate is 2 B/s and 0 B/s in 5/30 seconds timeframes. Loop: Mark - 7 - at 09:36:22:93.654 with 1 Ms elapsed. NXTransPrepare: Going to prepare the NX transport. Loop: Preparing the select with requested timeout of 5 S and 0 Ms. Proxy: Initial timeout is 5 S and 0 Ms. Proxy: Proxy descriptor FD#5 selected for read with buffer length 0. Proxy: Proxy descriptor FD#5 not selected for write. Blocked is 0 length is 0. Loop: Mark - 0 - at 09:36:22:93.769 with 1 Ms elapsed. Loop: New timestamp is 09:36:22:93.769. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [6] at 09:36:22:93.820. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Select result is [1] error is [0] at 09:36:22:376.790 after 283 Ms. Loop: Selected for read are [5]. Loop: Selected for write are [none]. Loop: Out of select after 283 Ms at 09:36:22:376.927 with result 1. Loop: New timestamp is 09:36:22:376.927. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Mark - 1 - at 09:36:22:376.986 with 0 Ms elapsed. Loop: Going to rotate channels for proxy FD#5. Loop: Going to check the writable descriptors. Proxy: Checking descriptors selected for write. Loop: Mark - 2 - at 09:36:22:377.038 with 0 Ms elapsed. Loop: Going to check the readable descriptors. Proxy: Checking descriptors selected for read. Proxy: Going to read messages from proxy FD#5. Proxy: Decoding data from proxy FD#5. Proxy: Read result on proxy FD#5 is 3. Proxy: Getting messages from proxy FD#5 with 3 bytes in the read buffer. Proxy: Received message 'code_shutdown_request' at 09:36:22:377.139 with data ID#0. Proxy: Clearing the read descriptor for proxy FD#5. Loop: Mark - 3 - at 09:36:22:377.177 with 0 Ms elapsed. Loop: Going to check channel events for proxy FD#5. Proxy: Going to check the events on channels. Loop: Mark - 4 - at 09:36:22:377.218 with 0 Ms elapsed. Loop: Mark - 5 - at 09:36:22:377.240 with 0 Ms elapsed. Loop: Going to flush any data to the proxy. Proxy: Nothing else to flush for proxy FD#5. Loop: Mark - 6 - at 09:36:22:377.282 with 0 Ms elapsed. Loop: End of NX transport requested by remote. Loop: Bytes received so far are 46. Loop: Shutting down the NX transport. Loop: Going to clean up system resources in process '15402'. Loop: Disabling signal 1 'SIGHUP' in process with pid '15402'. Loop: Disabling signal 2 'SIGINT' in process with pid '15402'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '15402'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '15402'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '15402'. Loop: Disabling signal 14 'SIGALRM' in process with pid '15402'. Loop: Disabling signal 15 'SIGTERM' in process with pid '15402'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '15402'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '15402'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '15402'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '15402'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '15402'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '15402'. Loop: Disabling signal 29 'SIGIO' in process with pid '15402'. Loop: Closing proxy FD in process with pid '15402'. Loop: Freeing up proxy in process with pid '15402'. Proxy: Deleted proxy object at 0x2552600. Loop: Freeing up auth data in process with pid '15402'. Loop: Freeing up statistics in process with pid '15402'. Loop: Freeing up control in process with pid '15402'. Loop: Restoring signals in process with pid '15402'. Loop: Restoring handler for signal 1 'SIGHUP' in process with pid '15402'. Loop: Restoring handler for signal 2 'SIGINT' in process with pid '15402'. Loop: Restoring handler for signal 10 'SIGUSR1' in process with pid '15402'. Loop: Restoring handler for signal 12 'SIGUSR2' in process with pid '15402'. Loop: Restoring handler for signal 13 'SIGPIPE' in process with pid '15402'. Loop: Restoring handler for signal 14 'SIGALRM' in process with pid '15402'. Loop: Restoring handler for signal 15 'SIGTERM' in process with pid '15402'. Loop: Restoring handler for signal 17 'SIGCHLD' in process with pid '15402'. Loop: Enabling signals in process with pid '15402'. Loop: Reverting to loop context in process with pid '15402' at 09:36:22:380.773. Loop: Freeing up streams in process with pid '15402'.
running as X2Go Agent NXAGENT - Version 3.5.0 Copyright (C) 2001, 2011 NoMachine. See http://www.nomachine.com/ for more information. Info: Agent running with pid '31479'. Session: Starting session at 'Wed Aug 5 09:36:17 2015'. nxagentCloseDisplay: Called with full generation [1] and display [(nil)]. nxagentInstallSignalHandlers: Installing the agent signal handlers. NXTransHandler: Set the NX callback for event '0' to '0x48bac0' and parameter '0'. nxagentInstallDisplayHandlers: Installing the error function predicate. nxagentInternalOpenDisplay: Going to open the display [nx/nx,options=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options:50]. NXTransCreate: Caller process running with pid '31479'. Loop: INIT! Initializing with mode NX_MODE_CLIENT at 09:36:17:810.902. Loop: Going to parse the environment options string 'nx,options=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options:50'. Loop: Parsing options string 'options=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Parsing environment option 'options' with value '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Completed parsing of string 'nx,options=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options:50'. Loop: Reading options from '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Going to read options from file '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Read options 'nx/nx,link=adsl,pack=16m-jpeg-9,limit=0,root=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24,cache=8M,images=32M,type=unix-kde-depth_24,id=arnolds-50-1438760175_stRTERMINAL_dp24,cookie=fd106b2d133812b04e1b1e7e30593222,errors=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log,kbtype=null/null,geometry=800x600,resize=1,fullscreen=0,accept=localhost,listen=30004,clipboard=both,client=linux,menu=0,state=/home/arnolds/.x2go/C-arnolds-50-1438760175_stRTERMINAL_dp24/state:50' from file '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: Going to parse the environment options string 'nx/nx,link=adsl,pack=16m-jpeg-9,limit=0,root=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24,cache=8M,images=32M,type=unix-kde-depth_24,id=arnolds-50-1438760175_stRTERMINAL_dp24,cookie=fd106b2d133812b04e1b1e7e30593222,errors=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log,kbtype=null/null,geometry=800x600,resize=1,fullscreen=0,accept=localhost,listen=30004,clipboard=both,client=linux,menu=0,state=/home/arnolds/.x2go/C-arnolds-50-1438760175_stRTERMINAL_dp24/state:50'. Loop: Parsing options string 'link=adsl,pack=16m-jpeg-9,limit=0,root=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24,cache=8M,images=32M,type=unix-kde-depth_24,id=arnolds-50-1438760175_stRTERMINAL_dp24,cookie=fd106b2d133812b04e1b1e7e30593222,errors=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log,kbtype=null/null,geometry=800x600,resize=1,fullscreen=0,accept=localhost,listen=30004,clipboard=both,client=linux,menu=0,state=/home/arnolds/.x2go/C-arnolds-50-1438760175_stRTERMINAL_dp24/state'. Loop: Parsing environment option 'link' with value 'adsl'. Loop: Parsing environment option 'pack' with value '16m-jpeg-9'. Loop: Pack method is -1 quality is -1. Loop: Parsing pack method '16m-jpeg-9'. Loop: Using pack quality '9'. Loop: Parsing environment option 'limit' with value '0'. Loop: Disabling bitrate limit on proxy link. Loop: Parsing environment option 'root' with value '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24'. Loop: Parsing environment option 'cache' with value '8M'. Loop: Parsing integer option 'cache' from string '8' with base set to M. Loop: Integer option parsed to '8388608'. Loop: Setting size of cache to 8388608 bytes. Loop: Parsing environment option 'images' with value '32M'. Loop: Parsing integer option 'images' from string '32' with base set to M. Loop: Integer option parsed to '33554432'. Loop: Setting size of images cache to 33554432 bytes. Loop: Parsing environment option 'type' with value 'unix-kde-depth_24'. Loop: Parsing environment option 'id' with value 'arnolds-50-1438760175_stRTERMINAL_dp24'. Loop: Parsing environment option 'cookie' with value 'fd106b2d133812b04e1b1e7e30593222'. Loop: Parsing environment option 'errors' with value '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log'. Loop: Parsing environment option 'kbtype' with value 'null/null'. Loop: Ignoring agent option 'kbtype' with value 'null/null'. Loop: Parsing environment option 'geometry' with value '800x600'. Loop: Ignoring agent option 'geometry' with value '800x600'. Loop: Parsing environment option 'resize' with value '1'. Loop: Ignoring agent option 'resize' with value '1'. Loop: Parsing environment option 'fullscreen' with value '0'. Loop: Ignoring agent option 'fullscreen' with value '0'. Loop: Parsing environment option 'accept' with value 'localhost'. Loop: Parsing environment option 'listen' with value '30004'. Loop: Parsing environment option 'clipboard' with value 'both'. Loop: Ignoring agent option 'clipboard' with value 'both'. Loop: Parsing environment option 'client' with value 'linux'. Loop: Ignoring agent option 'client' with value 'linux'. Loop: Parsing environment option 'menu' with value '0'. Loop: Ignoring agent option 'menu' with value '0'. Loop: Parsing environment option 'state' with value '/home/arnolds/.x2go/C-arnolds-50-1438760175_stRTERMINAL_dp24/state'. Loop: Ignoring agent option 'state' with value '/home/arnolds/.x2go/C-arnolds-50-1438760175_stRTERMINAL_dp24/state'. Loop: Completed parsing of string 'nx/nx,link=adsl,pack=16m-jpeg-9,limit=0,root=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24,cache=8M,images=32M,type=unix-kde-depth_24,id=arnolds-50-1438760175_stRTERMINAL_dp24,cookie=fd106b2d133812b04e1b1e7e30593222,errors=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log,kbtype=null/null,geometry=800x600,resize=1,fullscreen=0,accept=localhost,listen=30004,clipboard=both,client=linux,menu=0,state=/home/arnolds/.x2go/C-arnolds-50-1438760175_stRTERMINAL_dp24/state:50'. Loop: Assuming name of options file '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options'. Loop: WARNING! No environment for NX_HOME. Loop: Assuming NX user's home directory '/home/arnolds'. Loop: WARNING! No environment for NX_SYSTEM. Loop: Assuming system NX directory '/usr/NX'. Loop: Assuming temporary NX directory '/tmp'. Loop: Assuming NX client location '/@sys/system/MPA-7.23/usr/lib/x2go/x2gosuspend-agent'. Loop: Assuming default statistics file 'stats'. Loop: Root of NX session is '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/C-arnolds-50-1438760175_stRTERMINAL_dp24'. Loop: Name selected for log file is '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log'. Info: Proxy running in server mode with pid '31479'. Info: Using errors file '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log'. Info: Using stats file '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/C-arnolds-50-1438760175_stRTERMINAL_dp24/stats'. Info: Waiting for connection from 'localhost' on port '30004'. Info: Accepted connection from '127.0.0.1'. Info: Connection with remote proxy completed. Info: Using ADSL link parameters 512/24/1/0. Info: Using agent parameters 5000/10/50/0/0. Info: Using cache parameters 4/4096KB/8192KB/8192KB. Info: Using pack method '16m-jpeg-9' with session 'unix-kde-depth_24'. Info: Using ZLIB data compression 1/1/32. Info: Using ZLIB stream compression 4/4. Info: No suitable cache file found. Info: Listening to X11 connections on display ':50'. nxagentDisplayErrorPredicate: CHECK! Error is [0] with [0][0][0][0][0]. nxagentIOErrorHandler: Got I/O error with nxagentException.ioError [0]. nxagentIOErrorHandler: Set nxagentException.ioError to [1]. nxagentInternalOpenDisplay: Setting the NX flush policy to immediate. nxagentInternalOpenDisplay: Function returned display at [(nil)]. nxagentPostInstallSignalHandlers: Dealing with the proxy signal handlers. Error: Aborting session with 'Unable to open display 'nx/nx,options=/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/options:50''. Session: Aborting session at 'Wed Aug 5 09:36:18 2015'. Session: Session aborted at 'Wed Aug 5 09:36:18 2015'. nxagentCloseDisplay: Called with full generation [1] and display [(nil)]. nxagentAbortDisplay: Cleaning up the X server sockets. Warning: Signals were not blocked in process with pid '31479'. nxagentSigchldHandler: Going to check the children processes. nxagentSigchldHandler: Forwarding the signal to the NX transport. Info: Your session has died before reaching an usable state. Info: This can be due to the remote X server refusing access to the client. Info: Please check the authorization provided by your X application. Info: Waiting the cleanup timeout to complete. nxagentSigchldHandler: Going to check the children processes. nxagentSigchldHandler: Forwarding the signal to the NX transport. r ADSL. Loop: Storage size limit is 8388608 at client and 8388608 at server. Loop: Storage local limit set to 8388608 remote limit set to 8388608. Loop: Set initial shared memory size to 2097152 bytes. Loop: Setting pack with initial method 35 and quality 9. Loop: LIMIT! Setting client bitrate limit to 0 server bitrate limit to 0 with local limit 0. Loop: Going to run a new negotiation loop with stage 2 at 09:36:17:814.649. Loop: Handling negotiation with 'stage_connecting'. Loop: Going to wait for connection on port 30004. Loop: Installing signals in process with pid '31491'. Loop: Disabling signal 1 'SIGHUP' in process with pid '31491'. Loop: Disabling signal 2 'SIGINT' in process with pid '31491'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31491'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31491'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31491'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31491'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31491'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31491'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31491'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31491'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31491'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31491'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31491'. Loop: Disabling signal 29 'SIGIO' in process with pid '31491'. Loop: Closing agent FD in process with pid '31491'. Loop: Freeing up agent in process with pid '31491'. Loop: Waiting for connection from 'localhost' on port '30004'. Loop: Freeing up control in process with pid '31491'. Loop: Enabling signals in process with pid '31491'. Loop: Accepted connection from '127.0.0.1' with port '58450'. Loop: Connected to remote proxy on FD#8. Loop: Going to run a new negotiation loop with stage 3 at 09:36:18:360.316. Loop: Handling negotiation with 'stage_connected'. Loop: Going to run a new negotiation loop with stage 7 at 09:36:18:360.342. Loop: Handling negotiation with 'stage_waiting_proxy_version'. Loop: Going to read the remote proxy version from FD#8. Loop: Going to read remote data from FD#8. Loop: Reading data from FD#8 would block. Loop: Selected proxy FD#8 in negotiation phase with timeout of 5 S and 0 Ms. Loop: Preparing the masks for the agent descriptors. Loop: Clearing the read and write agent descriptors. Loop: Mark - 0 - at 09:36:18:360.383 with 546 Ms elapsed. Loop: New timestamp is 09:36:18:360.383. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [9] at 09:36:18:360.413. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Select result is [1] error is [0] at 09:36:18:360.621 after 1 Ms. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Out of select after 1 Ms at 09:36:18:360.641 with result 1. Loop: New timestamp is 09:36:18:360.641. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Checking size of log file '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/session.log'. NXTransPrepare: Going to prepare the NX transport. Loop: Going to run a new negotiation loop with stage 7 at 09:36:18:360.697. Loop: Handling negotiation with 'stage_waiting_proxy_version'. Loop: Going to read the remote proxy version from FD#8. Loop: Going to read remote data from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read stop character from FD#8. Loop: Remote string 'NXPROXY-1.5.0-3.5.0 ' read from FD#8. Loop: Received remote version string 'NXPROXY-1.5.0-3.5.0 ' from FD#8. Loop: Identified remote version '3.5.0'. Loop: Remote compatibility version '1.5.0'. Loop: Local version '3.5.0'. Loop: Using local version '3.5.0'. Loop: Using NX protocol step 10. Loop: Disabling image cache with protocol step '10'. Loop: Going to run a new negotiation loop with stage 8 at 09:36:18:360.800. Loop: Handling negotiation with 'stage_waiting_proxy_options'. Loop: Going to read the remote proxy options from FD#8. Loop: Going to read remote data from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read stop character from FD#8. Loop: Remote string 'cookie=fd106b2d133812b04e1b1e7e30593222,stream=default,data=default ' read from FD#8. Loop: Received remote options string 'cookie=fd106b2d133812b04e1b1e7e30593222,stream=default,data=default ' from FD#8. Loop: Going to parse the remote options string 'cookie=fd106b2d133812b04e1b1e7e30593222,stream=default,data=default '. Loop: Parsing remote option 'cookie' with value 'fd106b2d133812b04e1b1e7e30593222'. Loop: Parsing remote option 'stream' with value 'default'. Loop: Parsing remote option 'data' with value 'default'. Loop: Going to run a new negotiation loop with stage 9 at 09:36:18:361.112. Loop: Handling negotiation with 'stage_sending_proxy_options'. Loop: Sending remote options 'NXPROXY-1.5.0-3.5.0 link=ADSL,pack=16m-jpeg-9,cache=8192k,limit=0,render=1,taint=0,type=unix-kde-depth_24,images=0,delta=1,stream=4,data=1 '. Loop: WriteLocalData: select() returned with a code of 1 and remaining timeout of 29 sec, 999998usec Loop: Going to run a new negotiation loop with stage 11 at 09:36:18:361.153. Loop: Handling negotiation with 'stage_sending_proxy_caches'. Loop: Synchronizing local and remote caches. Loop: Going to send the list of local caches. Loop: Path of cache files is '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/cache-unix-kde-depth_24'. Loop: Looking for cache files in directory '/tmp/.x2go-arnolds/C-arnolds-50-1438760175_stRTERMINAL_dp24/cache-unix-kde-depth_24'. Loop: Writing an empty list to FD#8. Loop: WriteLocalData: select() returned with a code of 1 and remaining timeout of 29 sec, 999998usec Loop: Going to run a new negotiation loop with stage 10 at 09:36:18:361.261. Loop: Handling negotiation with 'stage_waiting_proxy_caches'. Loop: Going to receive the selected proxy cache. Loop: Going to read remote data from FD#8. Loop: Reading data from FD#8 would block. Loop: Selected proxy FD#8 in negotiation phase with timeout of 5 S and 0 Ms. Loop: Preparing the masks for the agent descriptors. Loop: Clearing the read and write agent descriptors. Loop: Mark - 0 - at 09:36:18:361.288 with 0 Ms elapsed. Loop: New timestamp is 09:36:18:361.288. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [9] at 09:36:18:361.308. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Select result is [1] error is [0] at 09:36:18:364.680 after 4 Ms. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Out of select after 4 Ms at 09:36:18:364.700 with result 1. Loop: New timestamp is 09:36:18:364.700. NXTransExecute: Going to execute I/O on the NX descriptors. NXTransPrepare: Going to prepare the NX transport. Loop: Going to run a new negotiation loop with stage 10 at 09:36:18:364.735. Loop: Handling negotiation with 'stage_waiting_proxy_caches'. Loop: Going to receive the selected proxy cache. Loop: Going to read remote data from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read a further character from FD#8. Loop: Read stop character from FD#8. Loop: Remote string 'cachefile=none ' read from FD#8. Loop: No cache file selected by remote proxy. Loop: Going to run a new negotiation loop with stage 12 at 09:36:18:364.808. Loop: Handling negotiation with 'stage_operational'. Loop: Connection with remote proxy completed. Proxy: Created new object at 0x190d160. Proxy: Going to initialize the static members in channels for proxy FD#8. ServerChannel: Initializing the static members for the server channels. Proxy: Propagating parameters to channels' read buffers. Proxy: Propagating parameters to proxy buffers. Proxy: Configuring the proxy timeouts. Proxy: Configuring the proxy tokens. Proxy: TOKEN! LIMIT! Setting token [token_control] size to 512 and limit to 24. Proxy: TOKEN! LIMIT! Setting token [token_split] size to 512 and limit to 12. Proxy: TOKEN! LIMIT! Setting token [token_data] size to 512 and limit to 6. Proxy: LIMIT! Using client bitrate limit 0 server bitrate limit 0 with local limit 0. Proxy: LIMIT! Setting split data threshold to 2048 split packet limit to 256 with base 512. Proxy: LIMIT! Setting generic channel initial read size to 256 maximum read size to 256 with base 512. Proxy: Configuring cache according to pack parameters. Proxy: Loading of cache disabled or no cache file selected. Proxy: Entering operational mode. Proxy: Allocated new channel ID#1 with FD#4. Proxy: Adding channel 1 to the list of active channels. Proxy: There are 1 allocated channels for proxy FD#8. Proxy: Sending message 'code_new_x_connection' at 09:36:18:367.218 with data ID#1. Proxy: Flushing data in the encode buffer. Proxy: Data length is 0 control length is 3. Proxy: Data length is now 0 control length is 3. Proxy: TOKEN! Checking tokens with frame type [frame_data] with stream ratio 1. Loop: Reporting a flush request at 09:36:18:367.270 with 0 bytes written. Proxy: Adding a new frame for the remote proxy. Proxy: Produced plain output for 0+3+0 out of 3 bytes. Proxy: FLUSH! Immediate with blocked 0 length 0 new 3 flushable 0 tokens 24 after 2 Ms. Proxy: FLUSH! Immediate flush to proxy FD#8 of 3 bytes at 09:36:18:367.287 with priority 1. Proxy: FLUSH! Current bitrate is 0 with 0 in the long frame and top 0. Loop: Reporting a flush request at 09:36:18:367.340 with 11 bytes written. Proxy: Sent 3 bytes of data with checksum [380E1E55DF5600FE67EE74C667C27AAE] on proxy FD#8. Proxy: Partial checksums are: [0][380E1E55DF5600FE67EE74C667C27AAE] Proxy: Going to reset flush counters for proxy FD#8. Proxy: Nothing else to flush for proxy FD#8. Loop: Killing the watchdog process '31491' from process with pid '31479' with signal 'SIGTERM'. Loop: Waiting for the watchdog process '31491' to die. Loop: Going to clean up system resources in process '31491'. Loop: Disabling signal 1 'SIGHUP' in process with pid '31491'. Loop: Disabling signal 2 'SIGINT' in process with pid '31491'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31491'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31491'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31491'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31491'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31491'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31491'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31491'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31491'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31491'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31491'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31491'. Loop: Disabling signal 29 'SIGIO' in process with pid '31491'. Loop: Exiting from child process with pid '31491' at 09:36:18:367.540. Loop: Freeing up streams in process with pid '31491'. Loop: No more children processes running. Loop: Starting the house-keeping process with storage size 33554432. Loop: Keeper started with pid '31527'. Loop: INIT! Completed initialization at 09:36:18:368.481 with 554 Ms since the init mark. Loop: Selected proxy FD#8 in negotiation phase with timeout of 5 S and 0 Ms. Loop: Preparing the masks for the agent descriptors. Loop: Setting a null timeout with agent descriptors ready. Loop: Clearing the read and write agent descriptors. Loop: Mark - 0 - at 09:36:18:368.529 with 4 Ms elapsed. Loop: New timestamp is 09:36:18:368.528. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [9] at 09:36:18:368.553. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Select timeout is 0 S and 0 Ms. Loop: WARNING! Executing the select with requested timeout of 0 S and 0 Ms. Loop: Select result is [0] error is [0] at 09:36:18:368.578 after 0 Ms. Loop: Out of select after 0 Ms at 09:36:18:368.588 with result 0. Loop: New timestamp is 09:36:18:368.588. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Setting proxy and local agent descriptors. Loop: Installing signals in process with pid '31527'. Loop: Values were resultFDs 0 errorFDs 0 setFDs 9. Loop: Setting agent descriptor FD#4 as ready to read. Loop: Values are now resultFDs 1 errorFDs 0 setFDs 9. Loop: Mark - 1 - at 09:36:18:368.621 with 0 Ms elapsed. Loop: Disabling signal 1 'SIGHUP' in process with pid '31527'. Loop: Going to rotate channels for proxy FD#8. Loop: Disabling signal 2 'SIGINT' in process with pid '31527'. Loop: Going to check the writable descriptors. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31527'. Proxy: Checking descriptors selected for write. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31527'. Proxy: Looping with current channel 1. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31527'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31527'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31527'. Loop: Mark - 2 - at 09:36:18:368.649 with 0 Ms elapsed. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31527'. Loop: Going to check the readable descriptors. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31527'. Proxy: Checking descriptors selected for read. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31527'. Proxy: Looping with current channel 1. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31527'. Proxy: Going to read messages from FD#4. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31527'. Proxy: Handling data for connection on FD#4. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31527'. Proxy: Reading messages from FD#4 channel ID#1. Loop: Disabling signal 29 'SIGIO' in process with pid '31527'. Proxy: Requested a switch with current channel ID#-1 new channel ID#1. Loop: Closing proxy FD in process with pid '31527'. Loop: Closing agent FD in process with pid '31527'. Proxy: Sending message 'code_switch_connection' at 09:36:18:368.694 with FD#4 channel ID#1. Loop: Freeing up proxy in process with pid '31527'. Proxy: Clearing the read descriptor for FD#4. Loop: Mark - 3 - at 09:36:18:368.724 with 0 Ms elapsed. Loop: Going to check channel events for proxy FD#8. Proxy: Going to check the events on channels. Loop: Mark - 4 - at 09:36:18:368.744 with 0 Ms elapsed. Loop: Setting remote agent descriptors. Loop: Values were resultFDs 0 errorFDs 0 setFDs 9. Loop: Values are now resultFDs 0 errorFDs 0 setFDs 9. Loop: Mark - 5 - at 09:36:18:368.761 with 0 Ms elapsed. Loop: Going to flush any data to the proxy. Loop: WARNING! Flushing the proxy link on behalf of the agent. Proxy: Flushing data in the encode buffer. Proxy: Data length is 14 control length is 3. Proxy: Data length is now 14 control length is 3. Proxy: TOKEN! Checking tokens with frame type [frame_data] with stream ratio 1. Loop: Reporting a flush request at 09:36:18:368.787 with 0 bytes written. Proxy: Adding a new frame for the remote proxy. Proxy: Produced plain output for 14+3+1 out of 18 bytes. Proxy: FLUSH! Immediate with blocked 0 length 0 new 18 flushable 0 tokens 24 after 2 Ms. Proxy: FLUSH! Immediate flush to proxy FD#8 of 18 bytes at 09:36:18:368.804 with priority 1. Proxy: FLUSH! Current bitrate is 2 with 0 in the long frame and top 2. Loop: Reporting a flush request at 09:36:18:368.873 with 17 bytes written. Proxy: Sent 18 bytes of data with checksum [651154632DDF15CF9923118B85E92D88] on proxy FD#8. Proxy: Partial checksums are: [0][651154632DDF15CF9923118B85E92D88] Proxy: Going to reset flush counters for proxy FD#8. Proxy: Nothing else to flush for proxy FD#8. Loop: Mark - 6 - at 09:36:18:368.906 with 0 Ms elapsed. Proxy: Checking ping at 09:36:18:368.917 with last loop at 09:36:18:365.017. Proxy: Last bytes in at 09:36:18:365.017 last bytes out at 09:36:18:368.902. Proxy: Last ping at 09:36:18:365.017. Loop: Bitrate is 5 B/s and 0 B/s in 5/30 seconds timeframes. Loop: Mark - 7 - at 09:36:18:368.971 with 0 Ms elapsed. NXTransFlush: Requesting an immediate flush of proxy FD#8. Proxy: Nothing else to flush for proxy FD#8. NXTransPrepare: Going to prepare the NX transport. Loop: Preparing the select with requested timeout of 5 S and 0 Ms. Proxy: Initial timeout is 5 S and 0 Ms. Proxy: Descriptor FD#4 selected for read with buffer length 0. Proxy: Proxy descriptor FD#8 selected for read with buffer length 0. Proxy: Descriptor FD#4 not selected for write with blocked 0 and length 0. Proxy: Proxy descriptor FD#8 not selected for write. Blocked is 0 length is 0. Loop: Preparing the masks for the agent descriptors. Loop: Clearing the read and write agent descriptors. Loop: Mark - 0 - at 09:36:18:369.031 with 0 Ms elapsed. Loop: New timestamp is 09:36:18:369.031. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [9] at 09:36:18:369.051. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Proxy: Deleted proxy object at 0x190d160. Loop: Freeing up agent in process with pid '31527'. Loop: Freeing up statistics in process with pid '31527'. Loop: Freeing up control in process with pid '31527'. Loop: Enabling signals in process with pid '31527'. Loop: Select result is [1] error is [0] at 09:36:18:382.956 after 14 Ms. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Out of select after 14 Ms at 09:36:18:382.988 with result 1. Loop: New timestamp is 09:36:18:382.988. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Setting proxy and local agent descriptors. Loop: Values were resultFDs 1 errorFDs 0 setFDs 9. Loop: Setting proxy descriptor FD#8 as ready to read. Loop: Values are now resultFDs 1 errorFDs 0 setFDs 9. Loop: Mark - 1 - at 09:36:18:383.023 with 0 Ms elapsed. Loop: Going to rotate channels for proxy FD#8. Loop: Going to check the writable descriptors. Proxy: Checking descriptors selected for write. Proxy: Looping with current channel 1. Loop: Mark - 2 - at 09:36:18:383.041 with 0 Ms elapsed. Loop: Going to check the readable descriptors. Proxy: Checking descriptors selected for read. Proxy: Looping with current channel 1. Proxy: Going to read messages from proxy FD#8. Proxy: Decoding data from proxy FD#8. Proxy: Read result on proxy FD#8 is 3. Proxy: Getting messages from proxy FD#8 with 3 bytes in the read buffer. Proxy: Received message 'code_finish_connection' at 09:36:18:383.088 with data ID#1. Proxy: Received a finish message for FD#4 channel ID#1. Proxy: Marking channel for FD#4 channel ID#1 as closing. Proxy: Finishing channel for FD#4 channel ID#1 because of proxy. Proxy: Clearing the read descriptor for proxy FD#8. Loop: Mark - 3 - at 09:36:18:383.114 with 0 Ms elapsed. Loop: Going to check channel events for proxy FD#8. Proxy: Going to check the events on channels. Proxy: Dropping the descriptor FD#4 channel ID#1. Proxy: Dropping channel for FD#4 channel ID#1. Proxy: Sending message 'code_drop_connection' at 09:36:18:383.133 with data ID#1. Proxy: Data length is 0 control length is 3. Proxy: Data length is now 0 control length is 3. Proxy: TOKEN! Checking tokens with frame type [frame_data] with stream ratio 1. Loop: Reporting a flush request at 09:36:18:383.154 with 0 bytes written. Proxy: Adding a new frame for the remote proxy. Proxy: Produced plain output for 0+3+0 out of 3 bytes. Proxy: FLUSH! Immediate with blocked 0 length 0 new 3 flushable 0 tokens 24 after 14 Ms. Proxy: FLUSH! Immediate flush to proxy FD#8 of 3 bytes at 09:36:18:383.183 with priority 1. Proxy: FLUSH! Current bitrate is 5 with 0 in the long frame and top 5. Loop: Reporting a flush request at 09:36:18:383.214 with 9 bytes written. Proxy: Sent 3 bytes of data with checksum [B38D0BE4560FF0AD65F4803874022B63] on proxy FD#8. Proxy: Partial checksums are: [0][B38D0BE4560FF0AD65F4803874022B63] Proxy: Going to reset flush counters for proxy FD#8. Proxy: Removing channel 1 from the list of active channels. Proxy: There are 0 allocated channels for proxy FD#8. Loop: Mark - 4 - at 09:36:18:383.275 with 0 Ms elapsed. Loop: Setting remote agent descriptors. Loop: Values were resultFDs 0 errorFDs 0 setFDs 9. Loop: Setting agent descriptor FD#3 as ready to read. Loop: Values are now resultFDs 1 errorFDs 0 setFDs 9. Loop: Mark - 5 - at 09:36:18:383.294 with 0 Ms elapsed. Loop: Going to flush any data to the proxy. Loop: WARNING! Flushing the proxy link on behalf of the agent. Proxy: Nothing else to flush for proxy FD#8. Loop: Mark - 6 - at 09:36:18:383.309 with 0 Ms elapsed. Proxy: Checking ping at 09:36:18:383.318 with last loop at 09:36:18:369.026. Proxy: Last bytes in at 09:36:18:383.083 last bytes out at 09:36:18:383.243. Proxy: Last ping at 09:36:18:365.017. Loop: Bitrate is 7 B/s and 1 B/s in 5/30 seconds timeframes. Loop: Mark - 7 - at 09:36:18:383.364 with 0 Ms elapsed. NXTransRead: Dequeuing 8 bytes from FD#3. NXTransRead: Dequeued 0 bytes to FD#3. NXTransClose: Closing down all the X connections. NXTransPolicy: Setting flush policy on proxy FD#8 to 'immediate'. Proxy: Nothing else to flush for proxy FD#8. NXTransSignal: Setting action of signal 'SIGINT' to '2'. Loop: Restoring handler for signal 2 'SIGINT' in process with pid '31479'. NXTransSignal: Setting action of signal 'SIGTERM' to '2'. Loop: Restoring handler for signal 15 'SIGTERM' in process with pid '31479'. NXTransSignal: Setting action of signal 'SIGHUP' to '2'. Loop: Restoring handler for signal 1 'SIGHUP' in process with pid '31479'. NXTransSignal: Setting action of signal 'SIGCHLD' to '2'. Loop: Restoring handler for signal 17 'SIGCHLD' in process with pid '31479'. NXTransExit: Process with pid '31479' called exit with code '1'. Loop: WARNING! Signals were not blocked in process with pid '31479'. NXTransDestroy: Closing down all the X connections. NXTransDestroy: Waiting for the NX transport to terminate. NXTransPrepare: Going to prepare the NX transport. Loop: Preparing the select with requested timeout of 5 S and 0 Ms. Proxy: Initial timeout is 5 S and 0 Ms. Proxy: Proxy descriptor FD#8 selected for read with buffer length 0. Proxy: Proxy descriptor FD#8 not selected for write. Blocked is 0 length is 0. Loop: Preparing the masks for the agent descriptors. Loop: Clearing the read and write agent descriptors. Loop: Mark - 0 - at 09:36:18:384.889 with 2 Ms elapsed. Loop: New timestamp is 09:36:18:384.889. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [9] at 09:36:18:384.913. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Going to clean up system resources in process '31527'. Loop: Disabling signal 1 'SIGHUP' in process with pid '31527'. Loop: Disabling signal 2 'SIGINT' in process with pid '31527'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31527'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31527'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31527'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31527'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31527'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31527'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31527'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31527'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31527'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31527'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31527'. Loop: Disabling signal 29 'SIGIO' in process with pid '31527'. Loop: Exiting from child process with pid '31527' at 09:36:21:871.304. Loop: Freeing up keeper in process with pid '31527'. Loop: Freeing up streams in process with pid '31527'. NXTransSignal: Raising signal 'SIGCHLD' in the proxy handler. Loop: Select result is [-1] error is [4] at 09:36:21:872.301 after 3487 Ms. Loop: Out of select after 3487 Ms at 09:36:21:872.318 with result -1. Loop: New timestamp is 09:36:21:872.318. Loop: Select failed due to EINTR error. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Setting proxy and local agent descriptors. Loop: Mark - 1 - at 09:36:21:872.349 with 0 Ms elapsed. Loop: Going to rotate channels for proxy FD#8. Loop: Going to check the writable descriptors. Loop: Mark - 2 - at 09:36:21:872.364 with 0 Ms elapsed. Loop: Going to check the readable descriptors. Proxy: Checking descriptors selected for read. Loop: Mark - 3 - at 09:36:21:872.379 with 0 Ms elapsed. Loop: Going to check channel events for proxy FD#8. Proxy: Going to check the events on channels. Loop: Mark - 4 - at 09:36:21:872.394 with 0 Ms elapsed. Loop: Setting remote agent descriptors. Loop: Mark - 5 - at 09:36:21:872.407 with 0 Ms elapsed. Loop: Going to flush any data to the proxy. Loop: WARNING! Flushing the proxy link on behalf of the agent. Proxy: Nothing else to flush for proxy FD#8. Loop: Mark - 6 - at 09:36:21:872.424 with 0 Ms elapsed. Proxy: Checking ping at 09:36:21:872.435 with last loop at 09:36:18:384.884. Proxy: Last bytes in at 09:36:18:384.855 last bytes out at 09:36:18:383.243. Proxy: Last ping at 09:36:18:365.017. Loop: End of NX transport requested by agent. Loop: Bytes sent so far are 37. Proxy: Going to save content of client store. Proxy: Cache not saved as size is 0 with threshold set to 102400. Proxy: No cache file produced from message stores. Loop: Starting watchdog process with timeout of 500 Ms. Loop: Watchdog started with pid '31528'. Loop: Waiting the cleanup timeout to complete. Loop: Bitrate is 7 B/s and 1 B/s in 5/30 seconds timeframes. Loop: Mark - 7 - at 09:36:21:872.767 with 1 Ms elapsed. NXTransPrepare: Going to prepare the NX transport. Loop: Preparing the select with requested timeout of 5 S and 0 Ms. Proxy: Initial timeout is 5 S and 0 Ms. Proxy: Proxy descriptor FD#8 selected for read with buffer length 0. Proxy: Proxy descriptor FD#8 not selected for write. Blocked is 0 length is 0. Loop: Preparing the masks for the agent descriptors. Loop: Clearing the read and write agent descriptors. Loop: Mark - 0 - at 09:36:21:872.823 with 1 Ms elapsed. Loop: New timestamp is 09:36:21:872.823. NXTransSelect: Going to select the NX descriptors. Loop: Maximum descriptors is [9] at 09:36:21:872.852. Loop: Selected for read are [8]. Loop: Selected for write are [none]. Loop: Select timeout is 5 S and 0 Ms. Loop: Installing signals in process with pid '31528'. Loop: Installing handler for signal 1 'SIGHUP' in process with pid '31528'. Loop: Installing handler for signal 2 'SIGINT' in process with pid '31528'. Loop: Installing handler for signal 15 'SIGTERM' in process with pid '31528'. Loop: Installing handler for signal 17 'SIGCHLD' in process with pid '31528'. Loop: Disabling signal 1 'SIGHUP' in process with pid '31528'. Loop: Disabling signal 2 'SIGINT' in process with pid '31528'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31528'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31528'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31528'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31528'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31528'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31528'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31528'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31528'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31528'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31528'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31528'. Loop: Disabling signal 29 'SIGIO' in process with pid '31528'. Loop: Closing proxy FD in process with pid '31528'. Loop: Closing agent FD in process with pid '31528'. Loop: Freeing up proxy in process with pid '31528'. Proxy: Deleted proxy object at 0x190d160. Loop: Freeing up agent in process with pid '31528'. Loop: Freeing up statistics in process with pid '31528'. Loop: Freeing up control in process with pid '31528'. Loop: Enabling signals in process with pid '31528'. Loop: Going to clean up system resources in process '31528'. Loop: Disabling signal 1 'SIGHUP' in process with pid '31528'. Loop: Disabling signal 2 'SIGINT' in process with pid '31528'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31528'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31528'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31528'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31528'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31528'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31528'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31528'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31528'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31528'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31528'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31528'. Loop: Disabling signal 29 'SIGIO' in process with pid '31528'. Loop: Exiting from child process with pid '31528' at 09:36:22:375.478. Loop: Freeing up streams in process with pid '31528'. NXTransSignal: Raising signal 'SIGCHLD' in the proxy handler. Loop: Select result is [-1] error is [4] at 09:36:22:376.226 after 503 Ms. Loop: Out of select after 503 Ms at 09:36:22:376.241 with result -1. Loop: New timestamp is 09:36:22:376.241. Loop: Select failed due to EINTR error. NXTransExecute: Going to execute I/O on the NX descriptors. Loop: Setting proxy and local agent descriptors. Loop: Mark - 1 - at 09:36:22:376.270 with 0 Ms elapsed. Loop: Going to rotate channels for proxy FD#8. Loop: Going to check the writable descriptors. Loop: Mark - 2 - at 09:36:22:376.285 with 0 Ms elapsed. Loop: Going to check the readable descriptors. Proxy: Checking descriptors selected for read. Loop: Mark - 3 - at 09:36:22:376.300 with 0 Ms elapsed. Loop: Going to check channel events for proxy FD#8. Proxy: Going to check the events on channels. Loop: Mark - 4 - at 09:36:22:376.316 with 0 Ms elapsed. Loop: Setting remote agent descriptors. Loop: Mark - 5 - at 09:36:22:376.329 with 0 Ms elapsed. Loop: Going to flush any data to the proxy. Loop: WARNING! Flushing the proxy link on behalf of the agent. Proxy: Nothing else to flush for proxy FD#8. Loop: Mark - 6 - at 09:36:22:376.346 with 0 Ms elapsed. Proxy: Checking ping at 09:36:22:376.357 with last loop at 09:36:21:872.812. Proxy: Last bytes in at 09:36:21:872.520 last bytes out at 09:36:18:383.243. Proxy: Last ping at 09:36:18:365.017. Loop: Shutting down the NX transport. Proxy: Sending message 'code_shutdown_request' at 09:36:22:376.414. Proxy: Data length is 0 control length is 3. Proxy: Data length is now 0 control length is 3. Proxy: TOKEN! Checking tokens with frame type [frame_data] with stream ratio 1. Loop: Reporting a flush request at 09:36:22:376.442 with 0 bytes written. Proxy: Adding a new frame for the remote proxy. Proxy: Produced plain output for 0+3+0 out of 3 bytes. Proxy: FLUSH! Immediate with blocked 0 length 0 new 3 flushable 0 tokens 24 after 3993 Ms. Proxy: FLUSH! Immediate flush to proxy FD#8 of 3 bytes at 09:36:22:376.463 with priority 1. Proxy: FLUSH! Current bitrate is 7 with 1 in the long frame and top 7. Loop: Reporting a flush request at 09:36:22:376.525 with 9 bytes written. Proxy: Sent 3 bytes of data with checksum [4BF58EDD18C36F13E1403BD7835612AF] on proxy FD#8. Proxy: Partial checksums are: [0][4BF58EDD18C36F13E1403BD7835612AF] Proxy: Going to reset flush counters for proxy FD#8. Proxy: Starting shutdown procedure for proxy FD#8. Proxy: Ending shutdown procedure for proxy FD#8. Loop: Going to clean up system resources in process '31479'. Loop: Disabling signal 1 'SIGHUP' in process with pid '31479'. Loop: Disabling signal 2 'SIGINT' in process with pid '31479'. Loop: Disabling signal 10 'SIGUSR1' in process with pid '31479'. Loop: Disabling signal 12 'SIGUSR2' in process with pid '31479'. Loop: Disabling signal 13 'SIGPIPE' in process with pid '31479'. Loop: Disabling signal 14 'SIGALRM' in process with pid '31479'. Loop: Disabling signal 15 'SIGTERM' in process with pid '31479'. Loop: Disabling signal 17 'SIGCHLD' in process with pid '31479'. Loop: Disabling signal 20 'SIGTSTP' in process with pid '31479'. Loop: Disabling signal 21 'SIGTTIN' in process with pid '31479'. Loop: Disabling signal 22 'SIGTTOU' in process with pid '31479'. Loop: Disabling signal 26 'SIGVTALRM' in process with pid '31479'. Loop: Disabling signal 28 'SIGWINCH' in process with pid '31479'. Loop: Disabling signal 29 'SIGIO' in process with pid '31479'. Loop: Closing proxy FD in process with pid '31479'. Loop: Closing agent FD in process with pid '31479'. Loop: Freeing up proxy in process with pid '31479'. Proxy: Deleted proxy object at 0x190d160. Loop: Freeing up agent in process with pid '31479'. Loop: Freeing up statistics in process with pid '31479'. Loop: Freeing up control in process with pid '31479'. Loop: Restoring signals in process with pid '31479'. Loop: Restoring handler for signal 10 'SIGUSR1' in process with pid '31479'. Loop: Restoring handler for signal 12 'SIGUSR2' in process with pid '31479'. Loop: Restoring handler for signal 13 'SIGPIPE' in process with pid '31479'. Loop: Restoring handler for signal 14 'SIGALRM' in process with pid '31479'. Loop: Enabling signals in process with pid '31479'. Loop: Reverting to loop context in process with pid '31479' at 09:36:22:379.369. Loop: Freeing up streams in process with pid '31479'.
smime.p7s
Description: S/MIME Cryptographic Signature
_______________________________________________ x2go-dev mailing list [email protected] http://lists.x2go.org/listinfo/x2go-dev
