pastebin - collaborative debugging tool
kpaste.net RSS


winswitch.log
Posted by Admin on Tue 11th Sep 2012 22:02
raw | new post

  1. [II] 2012/11/09 21:57:27 WinSwitchServer.start_session(User(Administrator : f629f85ae9424587bea966f155801421),xpra,Command(LXTerminal: lxterminal),,{'read_only': 'False', 'encoding': '""'},None)
  2. [DD] 2012/11/09 21:57:27 WinSwitchServer.real_start_session(User(Administrator : f629f85ae9424587bea966f155801421),xpra,Command(LXTerminal: lxterminal),,{'read_only': 'False', 'encoding': '""'},None)
  3. [DD] 2012/11/09 21:57:27 XpraServerUtil.get_prelaunch_session(f629f85ae9424587bea966f155801421,nathan,,{'read_only': 'False', 'encoding': '""'}) going to test: [ServerSession(:66 - xpra - available)]
  4. [DD] 2012/11/09 21:57:27 XpraServerUtil.is_prelaunched_session_compatible(ServerSession(:66 - xpra - available),,{'read_only': 'False', 'encoding': '""'}) prelaunch session options are not compatible with request: {}
  5. [DD] 2012/11/09 21:57:27 XpraServerUtil.get_prelaunch_session(f629f85ae9424587bea966f155801421,nathan,,{'read_only': 'False', 'encoding': '""'}) session ServerSession(:66 - xpra - available) is not compatible
  6. [DD] 2012/11/09 21:57:27 XpraServerUtil.do_start_session(User(Administrator : f629f85ae9424587bea966f155801421),nathan,Command(LXTerminal: lxterminal),False,,{'read_only': 'False', 'encoding': '""'},None)
  7. [DD] 2012/11/09 21:57:27 XpraServerUtil.initialize_session_from_command(ServerSession(:69 -  - ),Command(LXTerminal: lxterminal)) now set name=LXTerminal, uses_sound_out=False, uses_sound_in=False
  8. [DD] 2012/11/09 21:57:27 XpraServerUtil.initialize_new_session(nathan,Command(LXTerminal: lxterminal),False,,:69,15070)=ServerSession(:69 - xpra - starting)
  9. [DD] 2012/11/09 21:57:27 XpraServerUtil.start_dbus() dbus_command=dbus-daemon --fork --print-address=1 --print-pid=1 --session
  10. [DD] 2012/11/09 21:57:27 XpraServerUtil.start_dbus(ServerSession(:69 - xpra - starting)) output(dbus-daemon --fork --print-address=1 --print-pid=1 --session)=(0,unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4\n14123\n)
  11. [II] 2012/11/09 21:57:27 XpraServerUtil.start_dbus(ServerSession(:69 - xpra - starting)) dbus_pid=14123, dbus_address=unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4
  12. [DD] 2012/11/09 21:57:27 XpraServerUtil.start_sound_server(ServerSession(:69 - xpra - starting)) existing pulse_pid=-1, pulseaudio_command=/usr/bin/pulseaudio
  13. [DD] 2012/11/09 21:57:27 file_io.get_sub_dir(/home/nathan/.winswitch/server/sessions,69,False,511) directory /home/nathan/.winswitch/server/sessions/69 does not exist, creating it
  14. [II] 2012/11/09 21:57:27 virt_server_daemonizer.grab_daemon_pid(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/nathan/.winswitch/server/sessions/69/pulse.log', '--', '/usr/bin/pulseaudio', '--start', '-vvvv', '--disable-shm=true', '--daemonize=false', '--use-pid-file=false', '--system=false', '--exit-idle-time=-1', '-n', '--load=module-suspend-on-idle', '--load=module-null-sink', '--load=module-native-protocol-unix socket=/tmp/pulse-69/native'],None,{'LANG': 'en_GB.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4', 'PWD': '/home/nathan', 'USER': 'nathan', 'HOME': '/home/nathan/.winswitch/server/sessions/69', 'PATH': '/home/nathan/bin:/usr/lib/ccache:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':69'}) <subprocess.Popen object at 0x3677f10>.returncode=0, stdoutdata=14131\n, stderrdata=, pid=14131
  15. [DD] 2012/11/09 21:57:27 XpraServerUtil.start_sound_server(ServerSession(:69 - xpra - starting)) pulseaudio pid=14131
  16. [DD] 2012/11/09 21:57:27 file_io.save_object_to_properties(ServerSession(:69 - xpra - starting),None,None) /home/nathan/.winswitch/server/sessions/69/config.txt
  17. [DD] 2012/11/09 21:57:27 XpraServerUtil.start_display(ServerSession(:69 - xpra - starting),User(Administrator : f629f85ae9424587bea966f155801421),False,False)
  18. [II] 2012/11/09 21:57:27 XpraServerUtil.start_daemon(ServerSession(:69 - xpra - starting),{'LANG': 'en_GB.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4', 'PWD': '/home/nathan', 'PULSE_SERVER': 'unix:/tmp/pulse-69/native', 'USER': 'nathan', 'HOME': '/home/nathan', 'PATH': '/home/nathan/bin:/usr/lib/ccache:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':69', 'ALSA_PCM_NAME': 'pulse'},True) starting ['/usr/bin/xpra', '--bind-tcp=127.0.0.1:15070', '--password-file=/home/nathan/.winswitch/server/sessions/69/session.pass', '--no-daemon', '--no-pulseaudio', '--session-name=LXTerminal', 'start', ':69'], logging to /home/nathan/.winswitch/server/sessions/69/xpra.log
  19. [II] 2012/11/09 21:57:28 virt_server_daemonizer.grab_daemon_pid(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/nathan/.winswitch/server/sessions/69/xpra.log', '--', '/usr/bin/xpra', '--bind-tcp=127.0.0.1:15070', '--password-file=/home/nathan/.winswitch/server/sessions/69/session.pass', '--no-daemon', '--no-pulseaudio', '--session-name=LXTerminal', 'start', ':69'],/home/nathan,{'LANG': 'en_GB.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4', 'PWD': '/home/nathan', 'PULSE_SERVER': 'unix:/tmp/pulse-69/native', 'USER': 'nathan', 'HOME': '/home/nathan', 'PATH': '/home/nathan/bin:/usr/lib/ccache:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':69', 'ALSA_PCM_NAME': 'pulse'}) <subprocess.Popen object at 0x3680090>.returncode=0, stdoutdata=14141\n, stderrdata=, pid=14141
  20. [II] 2012/11/09 21:57:28 XpraServerUtil.session_process_started(14141,ServerSession(:69 - xpra - starting))
  21. [DD] 2012/11/09 21:57:28 XpraServerUtil.watch_session_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - starting))
  22. [DD] 2012/11/09 21:57:28 XpraServerUtil.watch_session_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - starting)) logfile=18
  23. [DD] 2012/11/09 21:57:28 XpraServerUtil.do_watch_session_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) will use gio to monitor for changes
  24. [II] 2012/11/09 21:57:28 XpraServerUtil.do_watch_session_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) watching log with gio: <__main__.GInotifyFileMonitor object at 0x367e640 (GInotifyFileMonitor at 0x2e993c0)>
  25. [DD] 2012/11/09 21:57:28 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting))
  26. [DD] 2012/11/09 21:57:28 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) about to read, current buffer: 0 bytes
  27. [DD] 2012/11/09 21:57:28 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) read 0 bytes
  28. [DD] 2012/11/09 21:57:28 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - starting)) pid=14141
  29. [DD] 2012/11/09 21:57:28 process_util.kill0(14141) pid is still alive
  30. [DD] 2012/11/09 21:57:28 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - starting)) pid=14141
  31. [DD] 2012/11/09 21:57:28 process_util.kill0(14141) pid is still alive
  32. [DD] 2012/11/09 21:57:28 XpraServerUtil.start_session_object(ServerSession(:69 - xpra - starting),User(Administrator : f629f85ae9424587bea966f155801421),False) started=True
  33. [DD] 2012/11/09 21:57:28 FirewallUtil.call_script(['ADD', '127.0.0.1', '15070']) firewall_script=/usr/lib/winswitch/firewall
  34. [DD] 2012/11/09 21:57:28 FirewallUtil.call_script(['ALLOW', '127.0.0.1', '127.0.0.1', '15070']) firewall_script=/usr/lib/winswitch/firewall
  35. [DD] 2012/11/09 21:57:28 file_io.save_object_to_properties(ServerSession(:69 - xpra - starting),None,None) /home/nathan/.winswitch/server/sessions/69/config.txt
  36. [DD] 2012/11/09 21:57:28 WinSwitchServer.add_new_session(ServerSession(:69 - xpra - starting)) can_capture=True, screen_capture_command=/usr/bin/import -screen -silent -window root -compress JPEG jpeg:
  37. [II] 2012/11/09 21:57:28 WinSwitchServer.send_session_to_all(ServerSession(:69 - xpra - starting)) clients=[WinSwitchClientChannel(User(Administrator : f629f85ae9424587bea966f155801421))]
  38. [II] 2012/11/09 21:57:28 XpraServerUtil.wait_for_session_readyness(ServerSession(:69 - xpra - starting),User(Administrator : f629f85ae9424587bea966f155801421),<function session_started at 0x367f0c8>,<function session_error at 0x367f140>) adding/firing session status update callbacks
  39. [DD] 2012/11/09 21:57:28 ProtocolHandler.send_session_icon(ServerSession(:69 - xpra - starting),True) no icon data available...
  40. [DD] 2012/11/09 21:57:29 XpraServerUtil.watched_directory_changed(<__main__.GInotifyDirectoryMonitor object at 0x7f6dc4241050 (GInotifyDirectoryMonitor at 0x3250b10)>,<__main__.GLocalFile at 0x367e550: file:///home/nathan/.xpra/hostname.net-69>,None,<enum G_FILE_MONITOR_EVENT_CREATED of type GFileMonitorEvent>)
  41. [DD] 2012/11/09 21:57:29 XpraServerUtil.session_logfile_changed()
  42. [DD] 2012/11/09 21:57:29 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) read_pending=False
  43. [DD] 2012/11/09 21:57:29 XpraServerUtil.batch_detect_sessions()
  44. [II] 2012/11/09 21:57:29 XpraServerUtil.detect_sessions() found: [('LIVE', ':66'), ('LIVE', ':69')]
  45. [DD] 2012/11/09 21:57:29 XpraServerUtil.with_version(False) xpra_version=0.6.0
  46. [DD] 2012/11/09 21:57:29 XpraServerUtil.with_version() xpra version is up to date
  47. [DD] 2012/11/09 21:57:29 XpraServerUtil.with_version(False) xpra_version=0.6.0
  48. [DD] 2012/11/09 21:57:29 XpraServerUtil.with_version() xpra version is up to date
  49. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) started thread: 140109536573184
  50. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting))
  51. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) about to read, current buffer: 0 bytes
  52. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) read 829 bytes
  53. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - starting)) read 11 lines
  54. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) reloading=False, lines=['[dix] Could not init font path element /usr/share/fonts/X11/cyrillic, removing from list!\n', '[dix] Could not init font path element /usr/share/fonts/X11/75dpi/:unscaled, removing from list!\n', '[dix] Could not init font path element /usr/share/fonts/X11/75dpi, removing from list!\n', 'Xlib:  extension "RANDR" missing on display ":69".\n', 'Xlib:  extension "RANDR" missing on display ":69".\n', 'Xlib:  extension "RANDR" missing on display ":69".\n', '2012-09-11 21:57:28,253 Randr not supported: X server does not support required extension Randr\n', '2012-09-11 21:57:28,274 using notification forwarder: DBUSNotificationsForwarder(org.freedesktop.Notifications)\n', '2012-09-11 21:57:28,275 xpra server version 0.6.0\n', '2012-09-11 21:57:28,289 xpra is ready.\n', '2012-09-11 21:57:29,175 New connection received: SocketConnection(/home/nathan/.xpra/hostname.net-69 - )\n']
  55. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status([dix] Could not init font path element /usr/share/fonts/X11/cyrillic, removing from list!\n)=None
  56. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status([dix] Could not init font path element /usr/share/fonts/X11/75dpi/:unscaled, removing from list!\n)=None
  57. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status([dix] Could not init font path element /usr/share/fonts/X11/75dpi, removing from list!\n)=None
  58. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status(Xlib:  extension "RANDR" missing on display ":69".\n)=None
  59. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),[[dix] Could not init font path element /usr/share/fonts/X11/cyrillic, removing from list!, [dix]...) previous line repeated 2 times!
  60. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status(2012-09-11 21:57:28,253 Randr not supported: X server does not support required extension Randr\n)=None
  61. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status(2012-09-11 21:57:28,274 using notification forwarder: DBUSNotificationsForwarder(org.freedesktop.Notifications)\n)=None
  62. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status(2012-09-11 21:57:28,275 xpra server version 0.6.0\n)=None
  63. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status(2012-09-11 21:57:28,289 xpra is ready.\n)=available
  64. [II] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) setting new status=available from line=2012-09-11 21:57:28,289 xpra is ready.\n
  65. [II] 2012/11/09 21:57:29 WinSwitchServer.send_session_status_to_all(ServerSession(:69 - xpra - starting),available) clients=[WinSwitchClientChannel(User(Administrator : f629f85ae9424587bea966f155801421))]
  66. [DD] 2012/11/09 21:57:29 ServerSession.fire_status_callbacks(available) (None, None, <function status_change_schedule_capture at 0x367f050>, False, None): <function status_change_schedule_capture at 0x367f050>()=False
  67. [DD] 2012/11/09 21:57:29 file_io.save_object_to_properties(ServerSession(:69 - xpra - available),None,None) /home/nathan/.winswitch/server/sessions/69/config.txt
  68. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_session_command(ServerSession(:69 - xpra - available)) start_gnome_keyring_daemon=True
  69. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_gnome_keyring_daemon(ServerSession(:69 - xpra - available)) listdir(/home/nathan/.winswitch/server/sessions/69/gnome-keyring)=[]
  70. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - starting),11 lines) new status(2012-09-11 21:57:29,175 New connection received: SocketConnection(/home/nathan/.xpra/hostname.net-69 - )\n)=None
  71. [DD] 2012/11/09 21:57:29 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - available)) pid=14141
  72. [DD] 2012/11/09 21:57:29 process_util.kill0(14141) pid is still alive
  73. [II] 2012/11/09 21:57:29 XpraServerUtil.start_gnome_keyring_daemon(ServerSession(:69 - xpra - available)) gnome_keyring_env={'GNOME_KEYRING_CONTROL': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring', 'SSH_AUTH_SOCK': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/ssh', 'GPG_AGENT_INFO': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/gpg:0:1', 'GNOME_KEYRING_PID': '14162'}, pid=14162
  74. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_session_wrapper() XDG_OPEN_COMMAND=/usr/bin/xdg-open, WSW_XDG_OPEN=/usr/lib/pymodules/python2.7/winswitch/server/xdg_open.pyc, valid exe=True
  75. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_session_wrapper(ServerSession(:69 - xpra - available)) args=[/usr/lib/winswitch/delayed_start, /home/nathan/.winswitch/server/sessions/69/config.txt, --print-pid, --daemon, --log-file, /home/nathan/.winswitch/server/sessions/69/command.log], env={'_WINSWITCH_SERVER_PID': '13383', 'USER': 'nathan', 'QT_GRAPHICSSYSTEM': 'native', 'HOME': '/home/nathan', 'DISPLAY': ':69', 'GNOME_KEYRING_CONTROL': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring', 'LANG': 'en_GB.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'WINSWITCH_SERVER': '0.12.16', 'WSW_SESSION_ID': '991eebeeba6347ba939dda6b294addf4', 'GPG_AGENT_INFO': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/gpg:0:1', 'SSH_AUTH_SOCK': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/ssh', 'WSW_XDG_OPEN': '/usr/lib/pymodules/python2.7/winswitch/server/xdg_open.pyc', 'PATH': '/usr/lib/winswitch/bin-override:/home/nathan/bin:/usr/lib/ccache:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'PULSE_SERVER': 'unix:/tmp/pulse-69/native', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4', 'ORBIT_SOCKETDIR': '/home/nathan/.winswitch/server/sessions/69/pulse-69', 'GNOME_KEYRING_PID': '14162', 'PWD': '/home/nathan', 'WSW_ORIGINAL_XDG_OPEN': '/usr/bin/xdg-open', 'ALSA_PCM_NAME': 'pulse'}, session_file=/home/nathan/.winswitch/server/sessions/69/config.txt
  76. [DD] 2012/11/09 21:57:29 file_io.save_object_to_properties(ServerSession(:69 - xpra - available),None,None) /home/nathan/.winswitch/server/sessions/69/config.txt
  77. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_session_wrapper(ServerSession(:69 - xpra - available)) proc=<subprocess.Popen object at 0x3677fd0>
  78. [II] 2012/11/09 21:57:29 XpraServerUtil.start_session_wrapper(ServerSession(:69 - xpra - available)) pid(['/usr/lib/winswitch/delayed_start', '/home/nathan/.winswitch/server/sessions/69/config.txt', '--print-pid', '--daemon', '--log-file', '/home/nathan/.winswitch/server/sessions/69/command.log'])=14219
  79. [DD] 2012/11/09 21:57:29 FirewallUtil.call_script(['ALLOW', '127.0.0.1', '127.0.0.1', '15070']) firewall_script=/usr/lib/winswitch/firewall
  80. [DD] 2012/11/09 21:57:29 WinSwitchServer.prepare_and_send_session_to_user(ServerSession(:69 - xpra - available),User(Administrator : f629f85ae9424587bea966f155801421)) display_util=<winswitch.virt.xpra_server_util.XpraServerUtil instance at 0x7f6dc4225200>, deadline=1347397079.7
  81. [DD] 2012/11/09 21:57:29 FirewallUtil.call_script(['ALLOW', '127.0.0.1', '127.0.0.1', '15070']) firewall_script=/usr/lib/winswitch/firewall
  82. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_prepare_session_for_attach(ServerSession(:69 - xpra - available),User(Administrator : f629f85ae9424587bea966f155801421),<function session_prepared at 0x3676f50>) current status=available
  83. [DD] 2012/11/09 21:57:29 WinSwitchServer.session_prepared()
  84. [II] 2012/11/09 21:57:29 WinSwitchServer.do_send_session_to_user(ServerSession(:69 - xpra - available),f629f85ae9424587bea966f155801421,1347397079.7) found matching channel WinSwitchClientChannel(User(Administrator : f629f85ae9424587bea966f155801421))
  85. [II] 2012/11/09 21:57:29 WinSwitchServer.send_session_status_to_all(ServerSession(:69 - xpra - available),connecting) clients=[WinSwitchClientChannel(User(Administrator : f629f85ae9424587bea966f155801421))]
  86. [DD] 2012/11/09 21:57:29 ServerSession.fire_status_callbacks(connecting) (None, None, <function status_change_schedule_capture at 0x367f050>, False, None): <function status_change_schedule_capture at 0x367f050>()=False
  87. [DD] 2012/11/09 21:57:29 WinSwitchServer.update_session_status(ServerSession(:69 - xpra - connecting),connecting,None) connecting_timeout=20
  88. [II] 2012/11/09 21:57:29 WinSwitchServer.send_session_status_to_all(ServerSession(:69 - xpra - connecting),None) clients=[WinSwitchClientChannel(User(Administrator : f629f85ae9424587bea966f155801421))]
  89. [DD] 2012/11/09 21:57:29 ServerSession.fire_status_callbacks(available) (['starting'], ['available'], <function session_started at 0x367f0c8>, True, 1347397078.06351): <function session_started at 0x367f0c8>()=None
  90. [DD] 2012/11/09 21:57:29 XpraServerUtil.session_logfile_changed()
  91. [DD] 2012/11/09 21:57:29 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read_pending=False
  92. [DD] 2012/11/09 21:57:29 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) started thread: 140109536573184
  93. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting))
  94. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) about to read, current buffer: 0 bytes
  95. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read 40 bytes
  96. [DD] 2012/11/09 21:57:29 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read 1 lines
  97. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - connecting),1 lines) reloading=False, lines=['2012-09-11 21:57:29,177 Connection lost\n']
  98. [DD] 2012/11/09 21:57:29 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - connecting),1 lines) new status(2012-09-11 21:57:29,177 Connection lost\n)=None
  99. [DD] 2012/11/09 21:57:29 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - connecting)) pid=14141
  100. [DD] 2012/11/09 21:57:29 process_util.kill0(14141) pid is still alive
  101. [DD] 2012/11/09 21:57:30 XpraServerUtil.session_logfile_changed()
  102. [DD] 2012/11/09 21:57:30 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read_pending=False
  103. [DD] 2012/11/09 21:57:30 XpraServerUtil.session_logfile_changed()
  104. [DD] 2012/11/09 21:57:30 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read_pending=False
  105. [DD] 2012/11/09 21:57:30 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) started thread: 140109536573184
  106. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available))
  107. [DD] 2012/11/09 21:57:30 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) started thread: 140109517018880
  108. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) about to read, current buffer: 0 bytes
  109. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting))
  110. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) about to read, current buffer: 0 bytes
  111. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read 145 bytes
  112. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read 2 lines
  113. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read 151 bytes
  114. [DD] 2012/11/09 21:57:30 XpraServerUtil.process_log_data(ServerSession(:66 - xpra - available),2 lines) reloading=False, lines=['2012-09-11 21:57:29,175 New connection received: SocketConnection(/home/nathan/.xpra/hostname.net-66 - )\n', '2012-09-11 21:57:29,177 Connection lost\n']
  115. [DD] 2012/11/09 21:57:30 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read 2 lines
  116. [DD] 2012/11/09 21:57:30 XpraServerUtil.process_log_data(ServerSession(:66 - xpra - available),2 lines) new status(2012-09-11 21:57:29,175 New connection received: SocketConnection(/home/nathan/.xpra/hostname.net-66 - )\n)=None
  117. [DD] 2012/11/09 21:57:30 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - connecting),2 lines) reloading=False, lines=["2012-09-11 21:57:30,128 New connection received: SocketConnection(('127.0.0.1', 15070) - ('127.0.0.1', 52909))\n", '2012-09-11 21:57:30,289 Connection lost\n']
  118. [DD] 2012/11/09 21:57:30 XpraServerUtil.process_log_data(ServerSession(:66 - xpra - available),2 lines) new status(2012-09-11 21:57:29,177 Connection lost\n)=None
  119. [DD] 2012/11/09 21:57:30 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - connecting),2 lines) new status(2012-09-11 21:57:30,128 New connection received: SocketConnection(('127.0.0.1', 15070) - ('127.0.0.1', 52909))\n)=None
  120. [DD] 2012/11/09 21:57:30 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/66/xpra.log,ServerSession(:66 - xpra - available)) pid=13688
  121. [DD] 2012/11/09 21:57:30 XpraServerUtil.process_log_data(ServerSession(:69 - xpra - connecting),2 lines) new status(2012-09-11 21:57:30,289 Connection lost\n)=None
  122. [DD] 2012/11/09 21:57:30 process_util.kill0(13688) pid is still alive
  123. [DD] 2012/11/09 21:57:30 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - connecting)) pid=14141
  124. [DD] 2012/11/09 21:57:30 process_util.kill0(14141) pid is still alive
  125. [DD] 2012/11/09 21:57:31 WinSwitchServer.capture_err(cannot capture ServerSession(:69 - xpra - connecting) at the moment..)
  126. [DD] 2012/11/09 21:57:31 XpraServerUtil.session_logfile_changed()
  127. [DD] 2012/11/09 21:57:31 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read_pending=False
  128. [DD] 2012/11/09 21:57:31 XpraServerUtil.session_logfile_changed()
  129. [DD] 2012/11/09 21:57:31 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read_pending=False
  130. [DD] 2012/11/09 21:57:31 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) started thread: 140109517018880
  131. [DD] 2012/11/09 21:57:31 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available))
  132. [DD] 2012/11/09 21:57:31 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) about to read, current buffer: 0 bytes
  133. [DD] 2012/11/09 21:57:31 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting))
  134. [DD] 2012/11/09 21:57:31 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) started thread: 140109536573184
  135. [DD] 2012/11/09 21:57:31 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) about to read, current buffer: 0 bytes
  136. [DD] 2012/11/09 21:57:31 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read 0 bytes
  137. [DD] 2012/11/09 21:57:31 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/66/xpra.log,ServerSession(:66 - xpra - available)) pid=13688
  138. [DD] 2012/11/09 21:57:31 process_util.kill0(13688) pid is still alive
  139. [DD] 2012/11/09 21:57:31 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read 0 bytes
  140. [DD] 2012/11/09 21:57:31 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - connecting)) pid=14141
  141. [DD] 2012/11/09 21:57:31 process_util.kill0(14141) pid is still alive
  142. [DD] 2012/11/09 21:57:32 XpraServerUtil.session_logfile_changed()
  143. [DD] 2012/11/09 21:57:32 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read_pending=False
  144. [DD] 2012/11/09 21:57:32 XpraServerUtil.session_logfile_changed()
  145. [DD] 2012/11/09 21:57:32 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read_pending=False
  146. [DD] 2012/11/09 21:57:32 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) started thread: 140109536573184
  147. [DD] 2012/11/09 21:57:32 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available))
  148. [DD] 2012/11/09 21:57:32 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) about to read, current buffer: 0 bytes
  149. [DD] 2012/11/09 21:57:32 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/66/xpra.log,16,ServerSession(:66 - xpra - available)) read 0 bytes
  150. [DD] 2012/11/09 21:57:32 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/66/xpra.log,ServerSession(:66 - xpra - available)) pid=13688
  151. [DD] 2012/11/09 21:57:32 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) started thread: 140109517018880
  152. [DD] 2012/11/09 21:57:32 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting))
  153. [DD] 2012/11/09 21:57:32 process_util.kill0(13688) pid is still alive
  154. [DD] 2012/11/09 21:57:32 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) about to read, current buffer: 0 bytes
  155. [DD] 2012/11/09 21:57:32 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - connecting)) read 0 bytes
  156. [DD] 2012/11/09 21:57:32 XpraServerUtil.poll_server_process(/home/nathan/.winswitch/server/sessions/69/xpra.log,ServerSession(:69 - xpra - connecting)) pid=14141
  157. [DD] 2012/11/09 21:57:32 process_util.kill0(14141) pid is still alive
  158. [DD] 2012/11/09 21:57:37 XpraServerUtil.do_disable_gnome_screensaver(ServerSession(:69 - xpra - connecting))
  159. [II] 2012/11/09 21:57:37 virt_server_daemonizer.grab_daemon_pid(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/nathan/.winswitch/server/sessions/69/dbus-send-disable-gnome-screensaver.log', '--', 'dbus-send', '--session', '--dest=org.gnome.ScreenSaver', '--print-reply', '--type=method_call', '--reply-timeout=20000', '/org/gnome/ScreenSaver', 'org.gnome.ScreenSaver.Inhibit', 'string:"Window Switch"', 'string:"Virtual Session"'],None,{'LANG': 'en_GB.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'GNOME_KEYRING_PID': '14162', 'SSH_AUTH_SOCK': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/ssh', 'ORBIT_SOCKETDIR': '/home/nathan/.winswitch/server/sessions/69/pulse-69', 'DISPLAY': ':69', 'PWD': '/home/nathan', 'PULSE_SERVER': 'unix:/tmp/pulse-69/native', 'USER': 'nathan', 'HOME': '/home/nathan', 'PATH': '/home/nathan/bin:/usr/lib/ccache:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'GPG_AGENT_INFO': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/gpg:0:1', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4', 'GNOME_KEYRING_CONTROL': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring', 'ALSA_PCM_NAME': 'pulse'}) <subprocess.Popen object at 0x3677f90>.returncode=0, stdoutdata=14317\n, stderrdata=, pid=14317
  160. [DD] 2012/11/09 21:57:37 XpraServerUtil.do_disable_xscreensaver(ServerSession(:69 - xpra - connecting))
  161. [II] 2012/11/09 21:57:37 process_util.exec_nopipe(['/usr/bin/xscreensaver-command', '-exit'],{'LANG': 'en_GB.UTF-8', 'TERM': 'xterm', 'SHELL': '/bin/bash', 'GNOME_KEYRING_PID': '14162', 'SSH_AUTH_SOCK': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/ssh', 'ORBIT_SOCKETDIR': '/home/nathan/.winswitch/server/sessions/69/pulse-69', 'DISPLAY': ':69', 'PWD': '/home/nathan', 'PULSE_SERVER': 'unix:/tmp/pulse-69/native', 'USER': 'nathan', 'HOME': '/home/nathan', 'PATH': '/home/nathan/bin:/usr/lib/ccache:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'GPG_AGENT_INFO': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring/gpg:0:1', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-RbrSHe6MvY,guid=7484208738b0e4ffe5c1985200129ea4', 'GNOME_KEYRING_CONTROL': '/home/nathan/.winswitch/server/sessions/69/gnome-keyring', 'ALSA_PCM_NAME': 'pulse'},None,False,False,None,True)=<subprocess.Popen object at 0x3677fd0>
  162. [II] 2012/11/09 21:57:49 WinSwitchServer.connecting_timeout(ServerSession(:69 - xpra - connecting),2) connection timed out
  163. [DD] 2012/11/09 21:57:49 WinSwitchServer.remove_actor(ServerSession(:69 - xpra - connecting)) actor=f629f85ae9424587bea966f155801421
  164. [DD] 2012/11/09 21:57:49 FirewallUtil.call_script(['REMOVE', '127.0.0.1', '127.0.0.1', '15070']) firewall_script=/usr/lib/winswitch/firewall
  165. [II] 2012/11/09 21:57:49 WinSwitchServer.send_session_status_to_all(ServerSession(:69 - xpra - connecting),available) clients=[WinSwitchClientChannel(User(Administrator : f629f85ae9424587bea966f155801421))]
  166. [DD] 2012/11/09 21:57:49 XpraServerUtil.verify_connected() checking session ServerSession(:69 - xpra - available): previous count=2, current status_update_count=3
  167. [DD] 2012/11/09 21:57:50 XpraServerUtil.session_logfile_changed()
  168. [DD] 2012/11/09 21:57:50 XpraServerUtil.read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - available)) read_pending=False
  169. [DD] 2012/11/09 21:57:50 XpraServerUtil.start_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - available)) started thread: 140109517018880
  170. [DD] 2012/11/09 21:57:50 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - available))
  171. [DD] 2012/11/09 21:57:50 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/69/xpra.log,18,ServerSession(:69 - xpra - available)) about to read, current buffer: 0 bytes
  172. [DD] 2012/11/09 21:57:50 XpraServerUtil.do_read_from_log(/home/nathan/.winswitch/server/sessions/

Submit a correction or amendment below (click here to make a fresh posting)
After submitting an amendment, you'll be able to view the differences between the old and new posts easily.

Syntax highlighting:

To highlight particular lines, prefix each line with {%HIGHLIGHT}




All content is user-submitted.
The administrators of this site (kpaste.net) are not responsible for their content.
Abuse reports should be emailed to us at