Ticket #197: 2012-04-25_1045_winswitch_failure_server_log.txt

File 2012-04-25_1045_winswitch_failure_server_log.txt, 7.7 KB (added by Thomas Käfer, 12 years ago)
Line 
1[DD] 2012/25/04 10:46:57 FirewallUtil.call_script(['ALLOW', '127.0.0.1', '0.0.0.0', '15062']) firewall_script=/usr/lib/winswitch/firewall
2[DD] 2012/25/04 10:46:57 XpraServerUtil.do_prepare_session_for_attach(ServerSession(:61 - xpra - connected),User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558),<function send_prepared_session at 0x2d72c80>) current status=connected
3handshake-complete: detaching[DD] 2012/25/04 10:46:58 XpraServerUtil.session_logfile_changed()
4[II] 2012/25/04 10:46:58 XpraServerUtil.process_log_data(ServerSession(:61 - xpra - connected),19 lines) setting new status=connecting from line=Handshake complete; enabling connection\n
5[II] 2012/25/04 10:46:58 WinSwitchServer.send_session_status_to_all(ServerSession(:61 - xpra - connected),connecting) clients=[WinSwitchClientChannel(User(Thomas KÀfer : b4922a309ddb48d3bfcac56f28027f15)), WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))]
6[DD] 2012/25/04 10:46:58 WinSwitchServer.update_session_status(ServerSession(:61 - xpra - connecting),connecting,None) connecting_timeout=20
7[II] 2012/25/04 10:46:58 XpraServerUtil.process_log_data(ServerSession(:61 - xpra - connected),19 lines) setting new status=connecting from line=Handshake complete; enabling connection\n
8[II] 2012/25/04 10:46:58 XpraServerUtil.process_log_data(ServerSession(:61 - xpra - connected),19 lines) setting new status=connected from line=Password matches!\n
9[II] 2012/25/04 10:46:58 WinSwitchServer.send_session_status_to_all(ServerSession(:61 - xpra - connecting),connected) clients=[WinSwitchClientChannel(User(Thomas KÀfer : b4922a309ddb48d3bfcac56f28027f15)), WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))]
10[II] 2012/25/04 10:46:58 XpraServerUtil.process_log_data(ServerSession(:61 - xpra - connected),8 lines) setting new status=available from line=xpra client disconnected.\n
11[DD] 2012/25/04 10:46:58 WinSwitchServer.remove_actor(ServerSession(:61 - xpra - connected)) actor=b4922a309ddb48d3bfcac56f28027f15
12[DD] 2012/25/04 10:46:58 FirewallUtil.call_script(['REMOVE', '127.0.0.1', '0.0.0.0', '15062']) firewall_script=/usr/lib/winswitch/firewall
13[II] 2012/25/04 10:46:58 WinSwitchServer.send_session_status_to_all(ServerSession(:61 - xpra - connected),available) clients=[WinSwitchClientChannel(User(Thomas KÀfer : b4922a309ddb48d3bfcac56f28027f15)), WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))]
14[DD] 2012/25/04 10:46:58 WinSwitchServer.send_prepared_session()
15[II] 2012/25/04 10:46:58 WinSwitchServer.do_send_session_to_user(ServerSession(:61 - xpra - available),7b8fbbdc14274c85be5be2264f363558,1335343648.15) found matching channel WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))
16[II] 2012/25/04 10:46:58 WinSwitchServer.send_session_status_to_all(ServerSession(:61 - xpra - available),connecting) clients=[WinSwitchClientChannel(User(Thomas KÀfer : b4922a309ddb48d3bfcac56f28027f15)), WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))]
17[DD] 2012/25/04 10:46:58 WinSwitchServer.update_session_status(ServerSession(:61 - xpra - connecting),connecting,None) connecting_timeout=20
18[II] 2012/25/04 10:46:58 WinSwitchServer.send_session_status_to_all(ServerSession(:61 - xpra - connecting),None) clients=[WinSwitchClientChannel(User(Thomas KÀfer : b4922a309ddb48d3bfcac56f28027f15)), WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))]
19[DD] 2012/25/04 10:46:58 ServerSession.fire_status_callbacks(available) (None, ['available'], <function send_prepared_session at 0x2d72c80>, True, 1335343647.023365)()=None
20[DD] 2012/25/04 10:46:58 XpraServerUtil.poll_server_process(/home/kaefert/.winswitch/server/sessions/61/xpra.log,ServerSession(:61 - xpra - connecting)) pid=6846
21[DD] 2012/25/04 10:46:58 process_util.kill0(6846) pid is still alive
22[DD] 2012/25/04 10:46:58 XpraServerUtil.session_logfile_changed()
23[II] 2012/25/04 10:46:58 XpraServerUtil.process_log_data(ServerSession(:61 - xpra - connecting),6 lines) setting new status=connecting from line=Handshake complete; enabling connection\n
24[DD] 2012/25/04 10:46:58 XpraServerUtil.poll_server_process(/home/kaefert/.winswitch/server/sessions/61/xpra.log,ServerSession(:61 - xpra - connecting)) pid=6846
25[DD] 2012/25/04 10:46:58 process_util.kill0(6846) pid is still alive
26[DD] 2012/25/04 10:47:00 XpraServerUtil.session_logfile_changed()
27[DD] 2012/25/04 10:47:00 XpraServerUtil.poll_server_process(/home/kaefert/.winswitch/server/sessions/61/xpra.log,ServerSession(:61 - xpra - connecting)) pid=6846
28[DD] 2012/25/04 10:47:00 process_util.kill0(6846) pid is still alive
29[DD] 2012/25/04 10:47:00 WinSwitchServer.connecting_timeout(ServerSession(:61 - xpra - connecting),15) not timing out, status=connecting, current status_update_count=20
30[DD] 2012/25/04 10:47:01 XpraServerUtil.session_logfile_changed()
31[DD] 2012/25/04 10:47:01 XpraServerUtil.poll_server_process(/home/kaefert/.winswitch/server/sessions/61/xpra.log,ServerSession(:61 - xpra - connecting)) pid=6846
32[DD] 2012/25/04 10:47:01 process_util.kill0(6846) pid is still alive
33[DD] 2012/25/04 10:47:02 XpraServerUtil.session_logfile_changed()
34[DD] 2012/25/04 10:47:02 XpraServerUtil.poll_server_process(/home/kaefert/.winswitch/server/sessions/61/xpra.log,ServerSession(:61 - xpra - connecting)) pid=6846
35[DD] 2012/25/04 10:47:02 process_util.kill0(6846) pid is still alive
36[DD] 2012/25/04 10:47:04 PyGTKCaptureDisplay.do_capture() window=<gtk.gdk.Window object at 0x2d6c5a0 (GdkWindow at 0x1c31120)>, w=1680 h=1050 pixbuf=<gtk.gdk.Pixbuf object at 0x2d6c320 (GdkPixbuf at 0x2b60c50)>
37[DD] 2012/25/04 10:47:04 WinSwitchServer.send_new_capture_data() session=ServerSession(:0 - X11 - available), util=<winswitch.virt.localX11_server_util.LocalX11ServerUtil instance at 0x2a86ef0>, clients=[<winswitch.server.client_channel.WinSwitchClientChannel instance at 0x2b94c20>, <winswitch.server.client_channel.WinSwitchClientChannel instance at 0x2bf2b00>]
38[DD] 2012/25/04 10:47:17 XpraServerUtil.verify_connected() checking session ServerSession(:61 - xpra - connecting): previous count=16, current status_update_count=20
39[DD] 2012/25/04 10:47:18 WinSwitchServer.connecting_timeout(ServerSession(:61 - xpra - connecting),17) not timing out, status=connecting, current status_update_count=20
40[II] 2012/25/04 10:47:18 WinSwitchServer.connecting_timeout(ServerSession(:61 - xpra - connecting),20) connection timed out
41[DD] 2012/25/04 10:47:18 WinSwitchServer.remove_actor(ServerSession(:61 - xpra - connecting)) actor=7b8fbbdc14274c85be5be2264f363558
42[DD] 2012/25/04 10:47:18 FirewallUtil.call_script(['REMOVE', '127.0.0.1', '0.0.0.0', '15062']) firewall_script=/usr/lib/winswitch/firewall
43[II] 2012/25/04 10:47:18 WinSwitchServer.send_session_status_to_all(ServerSession(:61 - xpra - connecting),available) clients=[WinSwitchClientChannel(User(Thomas KÀfer : b4922a309ddb48d3bfcac56f28027f15)), WinSwitchClientChannel(User(Thomas KÀfer : 7b8fbbdc14274c85be5be2264f363558))]
44[DD] 2012/25/04 10:47:20 WinSwitchServer.capture_err(cannot capture ServerSession(:61 - xpra - available) at the moment..)
45[DD] 2012/25/04 10:47:34 PyGTKCaptureDisplay.do_capture() window=<gtk.gdk.Window object at 0x2d6c1e0 (GdkWindow at 0x1c31120)>, w=1680 h=1050 pixbuf=<gtk.gdk.Pixbuf object at 0x2d6c320 (GdkPixbuf at 0x2b3b370)>
46[DD] 2012/25/04 10:47:34 WinSwitchServer.send_new_capture_data() session=ServerSession(:0 - X11 - available), util=<winswitch.virt.localX11_server_util.LocalX11ServerUtil instance at 0x2a86ef0>, clients=[<winswitch.server.client_channel.WinSwitchClientChannel instance at 0x2b94c20>, <winswitch.server.client_channel.WinSwitchClientChannel instance at 0x2bf2b00>]
47[DD] 2012/25/04 10:48:04 PyGTKCaptureDisplay.do_capture() window=<gtk.gdk.Window object at 0x2d6c280 (GdkWindow at 0x1c31120)>, w=1680 h=1050 pixbuf=<gtk.gdk.Pixbuf object at 0x2d6c1e0 (GdkPixbuf at 0x2b3b2d0)>
48