[DD] 2018/28/04 08:26:13.758559 ExecCaptureDisplay.exec_capture(['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img'],0)
[DD] 2018/28/04 08:26:13.758813 process_util.twisted_exec(['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img'],<function start_checking at 0x7fda3d7e3320>,<bound method ExecCaptureDisplay.err of <winswitch.ui.capture_util.ExecCaptureDisplay instance at 0x7fda3d7d4fc8>>,None,{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'PWD': '/home/username', 'PULSE_SERVER': 'unix:/run/user/1000/pulse/native', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'ALSA_PCM_NAME': 'pulse'})
[DD] 2018/28/04 08:26:13.759063 ProcessOutput.start() usePTY=False, env={'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'PWD': '/home/username', 'PULSE_SERVER': 'unix:/run/user/1000/pulse/native', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'ALSA_PCM_NAME': 'pulse'}, cmd=['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img']
[DD] 2018/28/04 08:26:13.763703 ProcessOutput.connectionMade()
[DD] 2018/28/04 08:26:13.775756 ProcessOutput.processExited([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n]) Failure (command=['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img']), exitCode=1
[DD] 2018/28/04 08:26:13.775885 ProcessOutput.processEnded([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n])
[DD] 2018/28/04 08:26:13.776035 WinSwitchServer.capture_err(winswitch.ui.capture_util.ExecCaptureDisplay failed for display :0: import: unable to open X server `' @ error/import.c/ImportImageCommand/360.)
[DD] 2018/28/04 08:31:13.758501 ExecCaptureDisplay.exec_capture(['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img'],0)
[DD] 2018/28/04 08:31:13.758755 process_util.twisted_exec(['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img'],<function start_checking at 0x7fda3d7e3668>,<bound method ExecCaptureDisplay.err of <winswitch.ui.capture_util.ExecCaptureDisplay instance at 0x7fda3d7d4cf8>>,None,{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'PWD': '/home/username', 'PULSE_SERVER': 'unix:/run/user/1000/pulse/native', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'ALSA_PCM_NAME': 'pulse'})
[DD] 2018/28/04 08:31:13.759010 ProcessOutput.start() usePTY=False, env={'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'PWD': '/home/username', 'PULSE_SERVER': 'unix:/run/user/1000/pulse/native', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'ALSA_PCM_NAME': 'pulse'}, cmd=['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img']
[DD] 2018/28/04 08:31:13.764246 ProcessOutput.connectionMade()
[DD] 2018/28/04 08:31:13.794833 ProcessOutput.processExited([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n]) Failure (command=['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img']), exitCode=1
[DD] 2018/28/04 08:31:13.794927 ProcessOutput.processEnded([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n])
[DD] 2018/28/04 08:31:13.795034 WinSwitchServer.capture_err(winswitch.ui.capture_util.ExecCaptureDisplay failed for display :0: import: unable to open X server `' @ error/import.c/ImportImageCommand/360.)
[DD] 2018/28/04 08:33:17.661923 WinSwitchClientChannel.connectionMade() via local socket
[DD] 2018/28/04 08:33:17.662393 ProtocolHandler.__init__(ServerSettings(desktop),<bound method WinSwitchClientChannel.send_message of <winswitch.server.client_channel.WinSwitchClientChannel instance at 0x7fda3d7d44d0>>)
[DD] 2018/28/04 08:33:17.662632 WinSwitchClientChannel.get_handler() adding local handlers
[II] 2018/28/04 08:33:17.662784 ProtocolHandler.do_set_salt(1806588a41ec4271a5db7bff4a6819aa) previous salt=None
[DD] 2018/28/04 08:33:17.662868 ProtocolHandler.check_parse_version(3,0.12.23)
[II] 2018/28/04 08:33:17.662954 common.check_remote_version([0, 12, 23]) OK: remote application version matches the minimum required ([0, 12, 8])
[II] 2018/28/04 08:33:17.663024 WinSwitchClientChannel.sync_local()
[DD] 2018/28/04 08:33:17.678576 crypt_util.sign_long(<type 'instance'>,1806588a41ec4271a5db7bff4a6819aa)=6868604771680513532177434239670346913575638465451260648040441203241347659471645917375156291836454...
[II] 2018/28/04 08:33:17.729061 WinSwitchClientChannel.add_user(('username', 'username', 'username', '9e007000aad84b759b6f03735717ba4d', 'w5400khaninev', '666733883908679...)
[II] 2018/28/04 08:33:17.729223 WinSwitchClientChannel.do_add_user(16606462400449058...,2b581a13540545e69...,User(username : 9e007000aad84b759b6f03735717ba4d)) remote host=127.0.0.1, tunneled=True
[II] 2018/28/04 08:33:17.730294 WinSwitchClientChannel.check_signature(<_RSAobj @0x7fda3d795b48 n(4096),e>,16606462400449058...,0b548a7730234f9cb933d06eb6c66575)=True
[II] 2018/28/04 08:33:17.730330 WinSwitchClientChannel.do_add_user(16606462400449058...,2b581a13540545e69...,User(username : 9e007000aad84b759b6f03735717ba4d)) local socket connection, not checking password
[DD] 2018/28/04 08:33:17.730375 WinSwitchClientChannel.do_add_user(16606462400449058...,2b581a13540545e69...,User(username : 9e007000aad84b759b6f03735717ba4d)) existing connection=None
[DD] 2018/28/04 08:33:17.730516 WinSwitchServer.add_local_user(username,9e007000aad84b759b6f03735717ba4d,xpra)
[DD] 2018/28/04 08:33:17.730587 GSTVideoServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730617 NXServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730647 ScreenServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730677 XpraServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730706 VNCServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730734 VirtualboxServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730764 LocalX11ServerUtil.add_local_user(username)
[DD] 2018/28/04 08:33:17.730790 LocalX11ServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730819 LocalX11ServerUtil.schedule_detect_local_displays(800) detect_local_displays_pending=False
[DD] 2018/28/04 08:33:17.730885 LibvirtServerUtil.add_local_user(username,9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.730920 WinSwitchClientChannel.logged_in_ok(User(username : 9e007000aad84b759b6f03735717ba4d)) username=username, uuid=9e007000aad84b759b6f03735717ba4d
[DD] 2018/28/04 08:33:17.731057 WinSwitchServer.add_client(WinSwitchClientChannel(User(username : 9e007000aad84b759b6f03735717ba4d))) user=User(username : 9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.731156 WinSwitchClientChannel.send_tunnel_ports() tunnel_printer=False, user.local_ipp_port=0, FORCE_IPP_TUNNEL=False
[DD] 2018/28/04 08:33:17.731186 WinSwitchClientChannel.send_tunnel_ports() tunnel_fs=False, user.local_samba_port=139, FORCE_SAMBA_TUNNEL=False
[DD] 2018/28/04 08:33:17.761847 ProtocolHandler.do_set_user_icon(9e007000aad84b759b6f03735717ba4d,..) user=User(username : 9e007000aad84b759b6f03735717ba4d)
[DD] 2018/28/04 08:33:17.761919 WinSwitchClientChannel.handle_command(set_user_icon '9e007000aad84b759b6f03735717ba4d','base64:iVBORw0KGgoAAAANSUhEUgAAADAAAAAwCAYAAABX...) forwarding command to all clients
[II] 2018/28/04 08:33:17.761975 WinSwitchServer.send_all(set_user_icon '9e007000aad84b759b6f03735717ba4d','base64:iVBORw0KGgoAAAANSUhEUgAAADAAAAAwCAYAAABX...,WinSwitchClientChannel(User(username : 9e007000aad84b759b6f03735717ba4d))) clients=[WinSwitchClientChannel(User(username : 9e007000aad84b759b6f03735717ba4d))]
[II] 2018/28/04 08:33:17.789774 WinSwitchClientChannel.set_xmodmap(14695 bytes,407 bytes)
[II] 2018/28/04 08:33:17.790028 WinSwitchClientChannel.set_xmodmap(...) saving xmodmap data (length=11015) to /home/username/.winswitch/server/xmodmap/9e007000aad84b759b6f03735717ba4d.keys
[II] 2018/28/04 08:33:17.794479 WinSwitchClientChannel.set_xmodmap(...) saving xmodmap data (length=299) to /home/username/.winswitch/server/xmodmap/9e007000aad84b759b6f03735717ba4d.modifiers
[DD] 2018/28/04 08:33:17.794599 ServerSettings.get_connected_sessions(9e007000aad84b759b6f03735717ba4d,[X11, windows, osx],True)=[]
[II] 2018/28/04 08:33:17.794634 WinSwitchServer.set_new_user_keymap(...) will now update these connected/connecting sessions with the new xmodmap: []
[DD] 2018/28/04 08:33:17.794678 ProtocolHandler.check_parse_version(3,0.12.23)
[II] 2018/28/04 08:33:17.794709 common.check_remote_version([0, 12, 23]) OK: remote application version matches the minimum required ([0, 12, 8])
[II] 2018/28/04 08:33:17.794746 WinSwitchClientChannel.sync()
[II] 2018/28/04 08:33:17.799569 WinSwitchClientChannel.send_server_commands() sent 10 items of type directory, 193 items of type command, 2 items of type desktops, 1 items of type actions
[II] 2018/28/04 08:33:17.799600 WinSwitchClientChannel.send_visible_users()
[II] 2018/28/04 08:33:17.799681 WinSwitchClientChannel.send_accessible_sessions()
[DD] 2018/28/04 08:33:18.531413 LocalX11ServerUtil.detect_local_displays()
[DD] 2018/28/04 08:33:18.531970 LocalX11ServerUtil.detect_local_displays() <function python_utmp_getutents at 0x7fda3f59b410>()=[7 4200 tty7 tty7 username :0 0 None 1524803036 (0, 0, 0, 0), 7 9046 pts/2 pts/2 username XXX.YYY.ZZZ.72 0 None 1524820060 (1216931118, 0, 0, 0), 7 4601 pts/0 pts/0 username :0.0 0 None 1524803104 (0, 0, 0, 0), 7 6302 pts/4 pts/4 username :0.0 0 None 1524885558 (0, 0, 0, 0)]
[DD] 2018/28/04 08:33:18.532058 LocalX11ServerUtil.X11_display(tty7) skipped plain tty 7 4200 tty7 tty7 username :0 0 None 1524803036 (0, 0, 0, 0)
[DD] 2018/28/04 08:33:18.532133 LocalX11ServerUtil.X11_display(pts/2) skipped pts 7 9046 pts/2 pts/2 username XXX.YYY.ZZZ.72 0 None 1524820060 (1216931118, 0, 0, 0)
[DD] 2018/28/04 08:33:18.532194 LocalX11ServerUtil.X11_display(46) utmp entry <winswitch.virt.utmp_wrapper.utmp_rec instance at 0x7fda406d3248> does not look like a display or tty
[DD] 2018/28/04 08:33:18.532256 LocalX11ServerUtil.X11_display(pts/0) skipped pts 7 4601 pts/0 pts/0 username :0.0 0 None 1524803104 (0, 0, 0, 0)
[DD] 2018/28/04 08:33:18.532319 LocalX11ServerUtil.X11_display(pts/4) skipped pts 7 6302 pts/4 pts/4 username :0.0 0 None 1524885558 (0, 0, 0, 0)
[DD] 2018/28/04 08:33:18.532376 LocalX11ServerUtil.detect_local_displays() testing: {':0': ('username', 4200, 1524803036L)}
[DD] 2018/28/04 08:33:18.532456 process_util.get_output(['/usr/bin/xprop', '-display', ':0', '-root', '_WINSWITCH_UUID'],None,None,False,False)
[II] 2018/28/04 08:33:18.543928 LocalX11ServerUtil.get_xprop_via_command(:0,_WINSWITCH_UUID) stdout(['/usr/bin/xprop', '-display', ':0', '-root', '_WINSWITCH_UUID'])=_WINSWITCH_UUID:  no such atom on any window.
[DD] 2018/28/04 08:33:18.544084 LocalX11ServerUtil.get_xprop_via_command(:0,_WINSWITCH_UUID) no such atom on any window
[DD] 2018/28/04 08:33:20.666747 ProtocolHandler.send_session_icon(ServerSession(VBOX-testing_9 - virtualbox - suspended),True) no icon data available...
[DD] 2018/28/04 08:33:20.666909 ProtocolHandler.send_session_icon(ServerSession(:0 - X11 - available),True) no icon data available...
[DD] 2018/28/04 08:33:20.667032 ProtocolHandler.send_session_icon(ServerSession(VBOX-test7 - virtualbox - suspended),True) no icon data available...
[DD] 2018/28/04 08:33:20.687322 ProtocolHandler.send_session_icon(ServerSession(VBOX-test_docker_owncloud_horde - virtualbox - suspended),True) no icon data available...
[DD] 2018/28/04 08:33:20.687522 ProtocolHandler.send_session_icon(ServerSession(VBOX-test_horde_apache - virtualbox - suspended),True) no icon data available...
[DD] 2018/28/04 08:33:20.687686 ProtocolHandler.send_session_icon(ServerSession(VBOX-test_owncloud_horde - virtualbox - suspended),True) no icon data available...
[DD] 2018/28/04 08:33:20.687873 ProtocolHandler.send_session_icon(ServerSession(VBOX-test_disc - virtualbox - suspended),True) no icon data available...
[DD] 2018/28/04 08:33:27.737377 ProtocolHandler.check_response_received(ping,4) response_counter=82
[DD] 2018/28/04 08:33:42.281399 WinSwitchClientChannel.start_session(d32aa3969bd03d60907936d73194713e,xpra,,,None) options={}
[II] 2018/28/04 08:33:42.281617 WinSwitchClientChannel.start_session(d32aa3969bd03d60907936d73194713e,xpra,,) command=Command(Mousepad: mousepad %F), user=User(username : 9e007000aad84b759b6f03735717ba4d)
[II] 2018/28/04 08:33:42.281694 WinSwitchServer.start_session(User(username : 9e007000aad84b759b6f03735717ba4d),xpra,Command(Mousepad: mousepad %F),,{},None)
[DD] 2018/28/04 08:33:42.281758 WinSwitchServer.real_start_session(User(username : 9e007000aad84b759b6f03735717ba4d),xpra,Command(Mousepad: mousepad %F),,{},None)
[DD] 2018/28/04 08:33:42.281865 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) going to test: [ServerSession(VBOX-testing_9 - virtualbox - suspended), ServerSession(:0 - X11 - available), ServerSession(VBOX-test7 - virtualbox - suspended), ServerSession(VBOX-test_docker_owncloud_horde - virtualbox - suspended), ServerSession(VBOX-test_horde_apache - virtualbox - suspended), ServerSession(VBOX-test_owncloud_horde - virtualbox - suspended), ServerSession(VBOX-test_disc - virtualbox - suspended)]
[DD] 2018/28/04 08:33:42.281948 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(VBOX-testing_9 - virtualbox - suspended) is not a xpra session: virtualbox
[DD] 2018/28/04 08:33:42.282002 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(:0 - X11 - available) is not a xpra session: X11
[DD] 2018/28/04 08:33:42.282057 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(VBOX-test7 - virtualbox - suspended) is not a xpra session: virtualbox
[DD] 2018/28/04 08:33:42.282107 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(VBOX-test_docker_owncloud_horde - virtualbox - suspended) is not a xpra session: virtualbox
[DD] 2018/28/04 08:33:42.282154 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(VBOX-test_horde_apache - virtualbox - suspended) is not a xpra session: virtualbox
[DD] 2018/28/04 08:33:42.282202 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(VBOX-test_owncloud_horde - virtualbox - suspended) is not a xpra session: virtualbox
[DD] 2018/28/04 08:33:42.282248 XpraServerUtil.get_prelaunch_session(9e007000aad84b759b6f03735717ba4d,username,,{}) session ServerSession(VBOX-test_disc - virtualbox - suspended) is not a xpra session: virtualbox
[DD] 2018/28/04 08:33:42.282317 XpraServerUtil.do_start_session(User(username : 9e007000aad84b759b6f03735717ba4d),username,Command(Mousepad: mousepad %F),False,,{},None)
[DD] 2018/28/04 08:33:42.282764 XpraServerUtil.initialize_session_from_command(ServerSession(:64 -  - ),Command(Mousepad: mousepad %F)) now set name=Mousepad, uses_sound_out=False, uses_sound_in=False
[DD] 2018/28/04 08:33:42.283093 XpraServerUtil.initialize_new_session(username,Command(Mousepad: mousepad %F),False,,:64,15065)=ServerSession(:64 - xpra - starting)
[DD] 2018/28/04 08:33:42.283262 XpraServerUtil.start_dbus() dbus_command=dbus-daemon --fork --print-address=1 --print-pid=1 --session
[DD] 2018/28/04 08:33:42.283536 process_util.get_output(['dbus-daemon', '--fork', '--print-address=1', '--print-pid=1', '--session'],None,None,False,False)
[DD] 2018/28/04 08:33:42.536745 XpraServerUtil.start_dbus(ServerSession(:64 - xpra - starting)) output(dbus-daemon --fork --print-address=1 --print-pid=1 --session)=(0,unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6\n9999\n)
[II] 2018/28/04 08:33:42.536839 XpraServerUtil.start_dbus(ServerSession(:64 - xpra - starting)) dbus_pid=9999, dbus_address=unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6
[DD] 2018/28/04 08:33:42.537045 XpraServerUtil.start_sound_server(ServerSession(:64 - xpra - starting)) existing pulse_pid=-1, pulseaudio_command=/usr/bin/pulseaudio
[DD] 2018/28/04 08:33:42.537190 file_io.get_sub_dir(/home/username/.winswitch/server/sessions,64,False,511) directory /home/username/.winswitch/server/sessions/64 does not exist, creating it
[DD] 2018/28/04 08:33:42.537258 file_io.save_object_to_properties(ServerSession(:64 - xpra - starting),None,None) /home/username/.winswitch/server/sessions/64/config.txt
[DD] 2018/28/04 08:33:42.537539 XpraServerUtil.start_display(ServerSession(:64 - xpra - starting),User(username : 9e007000aad84b759b6f03735717ba4d),False,False)
[II] 2018/28/04 08:33:42.537753 XpraServerUtil.start_daemon(ServerSession(:64 - xpra - starting),{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6', 'PWD': '/home/username', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'DISPLAY': ':64'},True) starting ['/usr/bin/xpra', '--bind-tcp=0.0.0.0:15065', '--no-daemon', '--tcp-auth=file:filename=/home/username/.winswitch/server/sessions/64/session.pass', '--systemd-run=no', '--session-name=Mousepad', 'start', ':64'], logging to /home/username/.winswitch/server/sessions/64/xpra.log
[DD] 2018/28/04 08:33:42.537808 process_util.get_output(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/username/.winswitch/server/sessions/64/xpra.log', '--', '/usr/bin/xpra', '--bind-tcp=0.0.0.0:15065', '--no-daemon', '--tcp-auth=file:filename=/home/username/.winswitch/server/sessions/64/session.pass', '--systemd-run=no', '--session-name=Mousepad', 'start', ':64'],/home/username,{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6', 'PWD': '/home/username', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'DISPLAY': ':64'},False,False)
[II] 2018/28/04 08:33:42.639823 virt_server_daemonizer.grab_daemon_pid(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/username/.winswitch/server/sessions/64/xpra.log', '--', '/usr/bin/xpra', '--bind-tcp=0.0.0.0:15065', '--no-daemon', '--tcp-auth=file:filename=/home/username/.winswitch/server/sessions/64/session.pass', '--systemd-run=no', '--session-name=Mousepad', 'start', ':64'],/home/username,{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6', 'PWD': '/home/username', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'DISPLAY': ':64'}) returncode=0, stdoutdata=10004\n, stderrdata=, pid=10004
[II] 2018/28/04 08:33:42.639950 XpraServerUtil.session_process_started(10004,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:42.639998 XpraServerUtil.watch_session_log(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:42.640044 XpraServerUtil.watch_session_log(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) logfile=24
[DD] 2018/28/04 08:33:42.640088 XpraServerUtil.do_watch_session_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) will use gio to monitor for changes
[II] 2018/28/04 08:33:42.640260 XpraServerUtil.do_watch_session_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) watching log with gio: <__main__.GInotifyFileMonitor object at 0x7fda3d7262d0 (GInotifyFileMonitor at 0x55fda50e2290)>
[DD] 2018/28/04 08:33:42.640330 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:42.640369 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:42.640413 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 0 bytes
[DD] 2018/28/04 08:33:42.640447 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:42.640486 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:42.640527 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:42.640556 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:42.640606 XpraServerUtil.start_session_object(ServerSession(:64 - xpra - starting),User(username : 9e007000aad84b759b6f03735717ba4d),False) started=True
[DD] 2018/28/04 08:33:42.640648 FirewallUtil.call_script(['ADD', '0.0.0.0', '15065']) firewall_script=/usr/lib/winswitch/firewall
[DD] 2018/28/04 08:33:42.640682 FirewallUtil.call_script(['ALLOW', '127.0.0.1', '0.0.0.0', '15065']) firewall_script=/usr/lib/winswitch/firewall
[DD] 2018/28/04 08:33:42.709837 icon_util.load_pixmap_file(/usr/share/icons/Faenza/apps/64/mousepad.png,48) gtk pixmap load failed
[DD] 2018/28/04 08:33:42.711304 file_io.save_object_to_properties(ServerSession(:64 - xpra - starting),None,None) /home/username/.winswitch/server/sessions/64/config.txt
[DD] 2018/28/04 08:33:42.711611 WinSwitchServer.add_new_session(ServerSession(:64 - xpra - starting)) can_capture=True, screen_capture_command=/usr/bin/import -screen -silent -window root -compress JPEG jpeg:
[II] 2018/28/04 08:33:42.711670 WinSwitchServer.send_session_to_all(ServerSession(:64 - xpra - starting)) clients=[WinSwitchClientChannel(User(username : 9e007000aad84b759b6f03735717ba4d))]
[II] 2018/28/04 08:33:42.711815 XpraServerUtil.wait_for_session_readyness(ServerSession(:64 - xpra - starting),User(username : 9e007000aad84b759b6f03735717ba4d),<function session_started at 0x7fda3e4009b0>,<function session_error at 0x7fda3e400938>) adding/firing session status update callbacks
[DD] 2018/28/04 08:33:42.741972 ProtocolHandler.send_session_icon(ServerSession(:64 - xpra - starting),True) no icon data available...
[DD] 2018/28/04 08:33:44.183030 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:33:44.183150 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read_pending=False
[DD] 2018/28/04 08:33:44.283309 XpraServerUtil.start_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) started thread: 140575339976448
[DD] 2018/28/04 08:33:44.283434 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:44.283492 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:44.283542 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 1024 bytes
[DD] 2018/28/04 08:33:44.283594 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 19 lines
[DD] 2018/28/04 08:33:44.283669 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) reloading=False, lines=['2018-04-28 08:33:44,182 cannot access python uinput module:\n', '2018-04-28 08:33:44,182  No module named uinput\n', '\n', 'X.Org X Server 1.19.6\n', 'Release Date: 2017-12-20\n', 'X Protocol Version 11, Revision 0\n', 'Build Operating System: Linux 4.9.0-5-amd64 x86_64 Debian\n', 'Current Operating System: Linux desktop 4.15.0-2-amd64 #1 SMP Debian 4.15.11-1 (2018-03-20) x86_64\n', 'Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-2-amd64 root=/dev/mapper/vg_desktop-lv_root ro intel_iommu=on quiet\n', 'Build Date: 26 January 2018  04:30:21PM\n', 'xorg-server 2:1.19.6-1 (https://www.debian.org/support) \n', 'Current version of pixman: 0.34.0\n', '\tBefore reporting problems, check http://wiki.x.org\n', '\tto make sure that you have the latest version.\n', 'Markers: (--) probed, (**) from config file, (==) default setting,\n', '\t(++) from command line, (!!) notice, (II) informational,\n', '\t(WW) warning, (EE) error, (NI) not implemented, (??) unknown.\n', '(++) Log file: "/run/user/1000/xpra/Xorg.:64.log", Time: Sat Apr 28 08:33:44 2018\n', '(++) Using config file: "/etc/xpra/xorg.conf"\n']
[II] 2018/28/04 08:33:44.283722 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:44,182 cannot access python uinput module:\n)
[DD] 2018/28/04 08:33:44.283837 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(2018-04-28 08:33:44,182 cannot access python uinput module:\n)=None
[II] 2018/28/04 08:33:44.283881 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:44,182  No module named uinput\n)
[DD] 2018/28/04 08:33:44.283931 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(2018-04-28 08:33:44,182  No module named uinput\n)=None
[II] 2018/28/04 08:33:44.283970 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),\n)
[DD] 2018/28/04 08:33:44.284040 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(\n)=None
[II] 2018/28/04 08:33:44.284092 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),X.Org X Server 1.19.6\n)
[DD] 2018/28/04 08:33:44.284143 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(X.Org X Server 1.19.6\n)=None
[II] 2018/28/04 08:33:44.284180 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Release Date: 2017-12-20\n)
[DD] 2018/28/04 08:33:44.284221 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Release Date: 2017-12-20\n)=None
[II] 2018/28/04 08:33:44.284257 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),X Protocol Version 11, Revision 0\n)
[DD] 2018/28/04 08:33:44.284299 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(X Protocol Version 11, Revision 0\n)=None
[II] 2018/28/04 08:33:44.284335 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Build Operating System: Linux 4.9.0-5-amd64 x86_64 Debian\n)
[DD] 2018/28/04 08:33:44.284376 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Build Operating System: Linux 4.9.0-5-amd64 x86_64 Debian\n)=None
[II] 2018/28/04 08:33:44.284425 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Current Operating System: Linux desktop 4.15.0-2-amd64 #1 SMP Debian 4.15.11-1 (2018-03-20) x86_64\n)
[DD] 2018/28/04 08:33:44.284471 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Current Operating System: Linux desktop 4.15.0-2-amd64 #1 SMP Debian 4.15.11-1 (2018-03-20) x86_64\n)=None
[II] 2018/28/04 08:33:44.284509 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-2-amd64 root=/dev/mapper/vg_desktop-lv_root ro intel_iommu=on quiet\n)
[DD] 2018/28/04 08:33:44.284551 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-2-amd64 root=/dev/mapper/vg_desktop-lv_root ro intel_iommu=on quiet\n)=None
[II] 2018/28/04 08:33:44.284590 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Build Date: 26 January 2018  04:30:21PM\n)
[DD] 2018/28/04 08:33:44.284631 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Build Date: 26 January 2018  04:30:21PM\n)=None
[II] 2018/28/04 08:33:44.284667 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),xorg-server 2:1.19.6-1 (https://www.debian.org/support) \n)
[DD] 2018/28/04 08:33:44.284708 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(xorg-server 2:1.19.6-1 (https://www.debian.org/support) \n)=None
[II] 2018/28/04 08:33:44.284744 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Current version of pixman: 0.34.0\n)
[DD] 2018/28/04 08:33:44.284784 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Current version of pixman: 0.34.0\n)=None
[II] 2018/28/04 08:33:44.284820 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),	Before reporting problems, check http://wiki.x.org\n)
[DD] 2018/28/04 08:33:44.284860 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(	Before reporting problems, check http://wiki.x.org\n)=None
[II] 2018/28/04 08:33:44.284896 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),	to make sure that you have the latest version.\n)
[DD] 2018/28/04 08:33:44.284936 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(	to make sure that you have the latest version.\n)=None
[II] 2018/28/04 08:33:44.284972 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),Markers: (--) probed, (**) from config file, (==) default setting,\n)
[DD] 2018/28/04 08:33:44.285011 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(Markers: (--) probed, (**) from config file, (==) default setting,\n)=None
[II] 2018/28/04 08:33:44.285054 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),	(++) from command line, (!!) notice, (II) informational,\n)
[DD] 2018/28/04 08:33:44.285096 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(	(++) from command line, (!!) notice, (II) informational,\n)=None
[II] 2018/28/04 08:33:44.285131 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.\n)
[DD] 2018/28/04 08:33:44.285172 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status(	(WW) warning, (EE) error, (NI) not implemented, (??) unknown.\n)=None
[II] 2018/28/04 08:33:44.285207 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),(++) Log file: "/run/user/1000/xpra/Xorg.:64.log", Time: Sat Apr 28 08:33:44 2018\n)
[DD] 2018/28/04 08:33:44.285248 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status((++) Log file: "/run/user/1000/xpra/Xorg.:64.log", Time: Sat Apr 28 08:33:44 2018\n)=None
[II] 2018/28/04 08:33:44.285285 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),(++) Using config file: "/etc/xpra/xorg.conf"\n)
[DD] 2018/28/04 08:33:44.285325 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),19 lines) new status((++) Using config file: "/etc/xpra/xorg.conf"\n)=None
[DD] 2018/28/04 08:33:44.285364 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 9 bytes
[DD] 2018/28/04 08:33:44.285404 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 55 bytes
[DD] 2018/28/04 08:33:44.285444 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 1 lines
[DD] 2018/28/04 08:33:44.285487 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),1 lines) reloading=False, lines=['(==) Using system config directory "/usr/share/X11/xorg.conf.d"\n']
[II] 2018/28/04 08:33:44.285524 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),(==) Using system config directory "/usr/share/X11/xorg.conf.d"\n)
[DD] 2018/28/04 08:33:44.285566 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),1 lines) new status((==) Using system config directory "/usr/share/X11/xorg.conf.d"\n)=None
[DD] 2018/28/04 08:33:44.285606 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:44.285646 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:44.984188 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:33:44.984268 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read_pending=False
[DD] 2018/28/04 08:33:45.084349 XpraServerUtil.start_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) started thread: 140575339976448
[DD] 2018/28/04 08:33:45.084420 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:45.084470 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:45.084499 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 0 bytes
[DD] 2018/28/04 08:33:45.084523 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:45.084549 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:46.986392 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:33:46.986471 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read_pending=False
[DD] 2018/28/04 08:33:47.086567 XpraServerUtil.start_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) started thread: 140575339976448
[DD] 2018/28/04 08:33:47.086649 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:47.086699 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:47.086750 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 0 bytes
[DD] 2018/28/04 08:33:47.086780 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:47.086810 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:47.254262 XpraServerUtil.watched_directory_changed(<__main__.GInotifyFileMonitor object at 0x7fda3f5a8af0 (GInotifyFileMonitor at 0x55fda4b5c0b0)>,<__main__.GLocalFile at 0x7fda406f2460: file:///home/username/.xpra/desktop-64>,None,<enum G_FILE_MONITOR_EVENT_CREATED of type GFileMonitorEvent>)
[DD] 2018/28/04 08:33:47.254489 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:33:47.254563 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read_pending=False
[DD] 2018/28/04 08:33:47.354430 XpraServerUtil.batch_detect_sessions()
[II] 2018/28/04 08:33:47.354731 XpraServerUtil.detect_sessions() found: []
[DD] 2018/28/04 08:33:47.384210 XpraServerUtil.detect_sessions() no sockets found
[DD] 2018/28/04 08:33:47.384308 XpraServerUtil.start_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) started thread: 140575339976448
[DD] 2018/28/04 08:33:47.384352 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:47.384494 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:47.384584 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 671 bytes
[DD] 2018/28/04 08:33:47.384646 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 8 lines
[DD] 2018/28/04 08:33:47.384740 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) reloading=False, lines=['2018-04-28 08:33:47,253 created unix domain socket: /run/user/1000/xpra/desktop-64\n', '2018-04-28 08:33:47,254 created unix domain socket: /home/username/.xpra/desktop-64\n', "2018-04-28 08:33:47,254 Warning: cannot create socket '/run/xpra/desktop-64'\n", '2018-04-28 08:33:47,254  [Errno 13] \xd0\x9e\xd1\x82\xd0\xba\xd0\xb0\xd0\xb7\xd0\xb0\xd0\xbd\xd0\xbe \xd0\xb2 \xd0\xb4\xd0\xbe\xd1\x81\xd1\x82\xd1\x83\xd0\xbf\xd0\xb5\n', "2018-04-28 08:33:47,255  user 'username' is a member of groups: cdrom, floppy, audio, dip, video, plugdev, netdev, lpadmin, scanner, bluetooth, davfs2, vboxusers, kvm, libvirt, libvirt-qemu\n", "2018-04-28 08:33:47,255   missing 'xpra' group membership?\n", '2018-04-28 08:33:47,255   permissions on directory /run/xpra: 0775\n', '2018-04-28 08:33:47,255   ownership root:xpra\n']
[II] 2018/28/04 08:33:47.384797 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,253 created unix domain socket: /run/user/1000/xpra/desktop-64\n)
[DD] 2018/28/04 08:33:47.384879 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,253 created unix domain socket: /run/user/1000/xpra/desktop-64\n)=None
[II] 2018/28/04 08:33:47.384944 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,254 created unix domain socket: /home/username/.xpra/desktop-64\n)
[DD] 2018/28/04 08:33:47.385007 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,254 created unix domain socket: /home/username/.xpra/desktop-64\n)=None
[II] 2018/28/04 08:33:47.385056 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,254 Warning: cannot create socket '/run/xpra/desktop-64'\n)
[DD] 2018/28/04 08:33:47.385116 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,254 Warning: cannot create socket '/run/xpra/desktop-64'\n)=None
[II] 2018/28/04 08:33:47.385163 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,254  [Errno 13] Отказано в доступе\n)
[DD] 2018/28/04 08:33:47.385222 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,254  [Errno 13] Отказано в доступе\n)=None
[II] 2018/28/04 08:33:47.385271 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,255  user 'username' is a member of groups: cdrom, floppy, audio, dip, video, plugdev, netdev, lpadmin, scanner, bluetooth, davfs2, vboxusers, kvm, libvirt, libvirt-qemu\n)
[DD] 2018/28/04 08:33:47.385332 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,255  user 'username' is a member of groups: cdrom, floppy, audio, dip, video, plugdev, netdev, lpadmin, scanner, bluetooth, davfs2, vboxusers, kvm, libvirt, libvirt-qemu\n)=None
[II] 2018/28/04 08:33:47.385380 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,255   missing 'xpra' group membership?\n)
[DD] 2018/28/04 08:33:47.385438 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,255   missing 'xpra' group membership?\n)=None
[II] 2018/28/04 08:33:47.385486 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,255   permissions on directory /run/xpra: 0775\n)
[DD] 2018/28/04 08:33:47.385544 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,255   permissions on directory /run/xpra: 0775\n)=None
[II] 2018/28/04 08:33:47.385591 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,255   ownership root:xpra\n)
[DD] 2018/28/04 08:33:47.385650 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),8 lines) new status(2018-04-28 08:33:47,255   ownership root:xpra\n)=None
[DD] 2018/28/04 08:33:47.385702 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:47.385755 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:48.055218 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:33:48.055335 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read_pending=False
[DD] 2018/28/04 08:33:48.155424 XpraServerUtil.start_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) started thread: 140575339976448
[DD] 2018/28/04 08:33:48.155472 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:48.155612 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:48.155643 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 61 bytes
[DD] 2018/28/04 08:33:48.155674 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 1 lines
[DD] 2018/28/04 08:33:48.155703 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),1 lines) reloading=False, lines=['2018-04-28 08:33:47,876 pointer device emulation using XTest\n']
[II] 2018/28/04 08:33:48.155728 XpraServerUtil.process_log_line(ServerSession(:64 - xpra - starting),2018-04-28 08:33:47,876 pointer device emulation using XTest\n)
[DD] 2018/28/04 08:33:48.155767 XpraServerUtil.process_log_data(ServerSession(:64 - xpra - starting),1 lines) new status(2018-04-28 08:33:47,876 pointer device emulation using XTest\n)=None
[DD] 2018/28/04 08:33:48.155799 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:48.155845 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:50.057590 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:33:50.057731 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read_pending=False
[DD] 2018/28/04 08:33:50.157875 XpraServerUtil.start_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) started thread: 140575339976448
[DD] 2018/28/04 08:33:50.158052 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:50.158151 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) about to read, current buffer: 0 bytes
[DD] 2018/28/04 08:33:50.158215 XpraServerUtil.do_read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - starting)) read 0 bytes
[DD] 2018/28/04 08:33:50.158268 XpraServerUtil.poll_server_process(/home/username/.winswitch/server/sessions/64/xpra.log,ServerSession(:64 - xpra - starting)) pid=10004
[DD] 2018/28/04 08:33:50.158323 process_util.kill0(10004) pid is still alive
[DD] 2018/28/04 08:33:52.538489 XpraServerUtil.do_disable_gnome_screensaver(ServerSession(:64 - xpra - starting))
[DD] 2018/28/04 08:33:52.538662 process_util.get_output(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/username/.winswitch/server/sessions/64/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': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6', 'PWD': '/home/username', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'DISPLAY': ':64'},False,False)
[II] 2018/28/04 08:33:52.626829 virt_server_daemonizer.grab_daemon_pid(['/usr/lib/winswitch/virt_server_daemonizer', '--quiet', '--print-pid', '--daemon', '--log-file', '/home/username/.winswitch/server/sessions/64/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': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6', 'PWD': '/home/username', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'DISPLAY': ':64'}) returncode=0, stdoutdata=10070\n, stderrdata=, pid=10070
[DD] 2018/28/04 08:33:52.626970 XpraServerUtil.do_disable_xscreensaver(ServerSession(:64 - xpra - starting))
[II] 2018/28/04 08:33:52.652834 process_util.exec_nopipe(['/usr/bin/xscreensaver-command', '-exit'],{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'DBUS_SESSION_BUS_ADDRESS': 'unix:abstract=/tmp/dbus-wwn122qN7O,guid=e468374faae748efd85cfcfb5ae407b6', 'PWD': '/home/username', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'DISPLAY': ':64'},None,False,False,None,True)=<subprocess.Popen object at 0x7fda406e1550>
[DD] 2018/28/04 08:34:12.731881 XpraServerUtil.check_session_has_started() status=starting, preload=False
[DD] 2018/28/04 08:34:12.732464 process_util.twisted_exec(['/usr/bin/xpra', 'stop', ':64', '--password-file=/home/username/.winswitch/server/sessions/64/session.pass'],<function display_stopped at 0x7fda3e400aa0>,<function display_stop_error at 0x7fda3e4008c0>,None,None)
[DD] 2018/28/04 08:34:12.732605 ProcessOutput.start() usePTY=False, env={'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'PWD': '/home/username', 'LOGNAME': 'username', 'USER': 'username', 'HOME': '/home/username'}, cmd=['/usr/bin/xpra', 'stop', ':64', '--password-file=/home/username/.winswitch/server/sessions/64/session.pass']
[DD] 2018/28/04 08:34:12.737242 ProcessOutput.connectionMade()
[EE] 2018/28/04 08:34:12.737511 XpraServerUtil.session_error(ServerSession(:64 - xpra - starting),Session failed to start, waited 30 seconds,closed) notification handler=<bound method WinSwitchServer.session_failed of <winswitch.server.controller.WinSwitchServer instance at 0x7fda5d9aca28>>
[II] 2018/28/04 08:34:12.737557 WinSwitchServer.session_failed(ServerSession(:64 - xpra - starting),Session failed to start, waited 30 seconds,closed) owner=9e007000aad84b759b6f03735717ba4d
[II] 2018/28/04 08:34:12.737664 WinSwitchServer.send_session_status_to_all(ServerSession(:64 - xpra - starting),closed) clients=[WinSwitchClientChannel(User(username : 9e007000aad84b759b6f03735717ba4d))]
[DD] 2018/28/04 08:34:12.737908 PortMapper.free_port(15065)
[DD] 2018/28/04 08:34:12.737949 PortMapper.free_port(6064)
[DD] 2018/28/04 08:34:12.737995 ServerSession.fire_status_callbacks(closed) (None, ['closed'], <function close_session_free_ports at 0x7fda3e41f488>, True, None): <function close_session_free_ports at 0x7fda3e41f488>()=None
[II] 2018/28/04 08:34:12.738090 WinSwitchClientChannel.send_remove_session(ServerSession(:64 - xpra - closed))
[DD] 2018/28/04 08:34:12.738149 PortMapper.free_port(6064)
[II] 2018/28/04 08:34:12.738277 XpraServerUtil.session_cleanup(ServerSession(:64 - xpra - closed)) clearing /home/username/.winswitch/server/sessions/64
[DD] 2018/28/04 08:34:12.748018 ServerSession.fire_status_callbacks(closed) (None, ['closed'], <function close_session_cleanup at 0x7fda4080fed8>, True, None): <function close_session_cleanup at 0x7fda4080fed8>()=None
[DD] 2018/28/04 08:34:12.748087 ServerSession.fire_status_callbacks(closed) (None, ['closed'], <function close_session_stop_watching_logfile at 0x7fda3e400e60>, True, None): <function close_session_stop_watching_logfile at 0x7fda3e400e60>()=None
[DD] 2018/28/04 08:34:12.748124 ServerSession.fire_status_callbacks(closed) (None, None, <function status_change_schedule_capture at 0x7fda3e400758>, False, None): <function status_change_schedule_capture at 0x7fda3e400758>()=False
[II] 2018/28/04 08:34:12.748179 ServerSession.close() self=ServerSession(:64 - xpra - closed), killing {'99-server-process': 10004, '10-dbus': 9999}
[DD] 2018/28/04 08:34:12.748205 ServerSession.close() killing 10-dbus: 9999
[II] 2018/28/04 08:34:12.748241 process_util.send_kill_signal(9999,15,True)
[DD] 2018/28/04 08:34:12.748262 process_util.send_kill_signal(9999,15,True) using os.kill
[DD] 2018/28/04 08:34:12.748287 ServerSession.close() killing 99-server-process: 10004
[II] 2018/28/04 08:34:12.748310 process_util.send_kill_signal(10004,15,True)
[DD] 2018/28/04 08:34:12.748329 process_util.send_kill_signal(10004,15,True) using os.kill
[II] 2018/28/04 08:34:12.751611 WinSwitchClientChannel.send_remove_session(ServerSession(:64 - xpra - closed))
[DD] 2018/28/04 08:34:12.832673 XpraServerUtil.session_logfile_changed()
[DD] 2018/28/04 08:34:12.832746 XpraServerUtil.read_from_log(/home/username/.winswitch/server/sessions/64/xpra.log,24,ServerSession(:64 - xpra - closed)) read_pending=False
[DD] 2018/28/04 08:34:13.459039 ProcessOutput.processExited([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n]) Failure (command=['/usr/bin/xpra', 'stop', ':64', '--password-file=/home/username/.winswitch/server/sessions/64/session.pass']), exitCode=1
[DD] 2018/28/04 08:34:13.459151 ProcessOutput.processEnded([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n])
[EE] 2018/28/04 08:34:13.459188 XpraServerUtil.display_stop_error(xpra initialization error:\n cannot find live server for display :64)
[II] 2018/28/04 08:34:13.459216 XpraServerUtil.stop_display(ServerSession(:64 - xpra - closed),None,:64)
[II] 2018/28/04 08:34:13.459240 XpraServerUtil.kill_display(ServerSession(:64 - xpra - closed),10004,15)
[DD] 2018/28/04 08:36:13.802842 ExecCaptureDisplay.exec_capture(['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img'],0)
[DD] 2018/28/04 08:36:13.803092 process_util.twisted_exec(['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img'],<function start_checking at 0x7fda3e400b90>,<bound method ExecCaptureDisplay.err of <winswitch.ui.capture_util.ExecCaptureDisplay instance at 0x7fda3d8b1b90>>,None,{'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'PWD': '/home/username', 'PULSE_SERVER': 'unix:/run/user/1000/pulse/native', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'ALSA_PCM_NAME': 'pulse'})
[DD] 2018/28/04 08:36:13.803355 ProcessOutput.start() usePTY=False, env={'LANG': 'ru_RU.UTF-8', 'SHELL': '/bin/bash', 'PWD': '/home/username', 'PULSE_SERVER': 'unix:/run/user/1000/pulse/native', 'USER': 'username', 'HOME': '/home/username', 'PATH': '/usr/local/bin:/usr/bin:/bin:/usr/games', 'ALSA_PCM_NAME': 'pulse'}, cmd=['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img']
[DD] 2018/28/04 08:36:13.808364 ProcessOutput.connectionMade()
[DD] 2018/28/04 08:36:13.820299 ProcessOutput.processExited([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n]) Failure (command=['sh', '-c', '/usr/bin/import -screen -silent -window root -compress JPEG jpeg:/home/username/.winswitch/server/sessions/0/snapshot.img']), exitCode=1
[DD] 2018/28/04 08:36:13.820412 ProcessOutput.processEnded([Failure instance: Traceback (failure with no frames): <class 'twisted.internet.error.ProcessTerminated'>: A process has ended with a probable error condition: process ended with exit code 1.\n])
[DD] 2018/28/04 08:36:13.820550 WinSwitchServer.capture_err(winswitch.ui.capture_util.ExecCaptureDisplay failed for display :0: import: unable to open X server `' @ error/import.c/ImportImageCommand/360.)
