[X2Go-Dev] Problem integrating x2go into Linux-system

[Heinz-Ado Arnolds]

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
________________________________________________________________________
-------------- next part --------------
A non-text attachment was scrubbed...
Name: client-session1.log
Type: text/x-log
Size: 4880 bytes
Desc: not available
URL: <http://lists.x2go.org/pipermail/x2go-dev/attachments/20150805/2f1b4b58/attachment-0003.bin>
-------------- next part --------------
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'.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: server-session1.log
Type: text/x-log
Size: 46815 bytes
Desc: not available
URL: <http://lists.x2go.org/pipermail/x2go-dev/attachments/20150805/2f1b4b58/attachment-0004.bin>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 4922 bytes
Desc: S/MIME Cryptographic Signature
URL: <http://lists.x2go.org/pipermail/x2go-dev/attachments/20150805/2f1b4b58/attachment-0005.bin>