debug enabled for xpra.platform.xposix.gui / ('posix', 'mouse')
debug enabled for xpra.gtk_common.error / ('x11', 'util')
debug enabled for xpra.gtk_common.error / ('x11', 'util', 'error')
debug enabled for xpra.gtk_common.gtk_util / ('gtk', 'util')
debug enabled for xpra.client.gtk_base.css_overrides / ('gtk', 'util')
debug enabled for xpra.client.gtk3.splash_screen / ('client', 'util')
 - loaded '/usr/share/xpra/css/10_header_bar.css'
 - loaded '/usr/share/xpra/css/20_progress_bar.css'
get_pixbuf(xpra.png) icon_filename=/usr/share/xpra/icons/xpra.png
read_stdin()
handle_stdin_line('0:Xpra Desktop Server 5.0\n')
handle_stdin_line('10:initializing\n')
handle_stdin_line('10:initializing environment\n')
handle_stdin_line('30:creating sockets\n')
handle_stdin_line('40:connecting to the display\n')
handle_stdin_line('40:starting a virtual display\n')
increase_fraction(40, 1, 10)
increase_fraction(40, 2, 10)
increase_fraction(40, 3, 10)
increase_fraction(40, 4, 10)
increase_fraction(40, 5, 10)
increase_fraction(40, 6, 10)
handle_stdin_line('50:setup ssh agent forwarding\n')
increase_fraction(50, 1, 10)
handle_stdin_line('80:initializing server\n')
increase_fraction(80, 1, 10)
handle_stdin_line('60:initializing local sockets\n')
handle_stdin_line('90:finalizing\n')
increase_fraction(90, 1, 10)
increase_fraction(90, 2, 10)
handle_stdin_line('100:running\n')
handle_signal(SIGTERM, None)
exit() calling <function main_quit at 0x7fea485df370>
exit() calling <function main_quit at 0x7fea485df370>
2022-12-26 14:18:30,476 debug enabled for xpra.platform.xposix.gui / ('posix', 'mouse')
2022-12-26 14:18:30,506 debug enabled for xpra.child_reaper / ('server', 'util', 'exec')
2022-12-26 14:18:30,506 ChildReaper(None)
2022-12-26 14:18:30,507 add_process(<Popen: returncode: None args: ['python3.10', '/usr/bin/xpra', 'splash']>, 'splash', ['python3.10', '/usr/bin/xpra', 'splash'], True, True, None) pid=3638
2022-12-26 14:18:30,511 debug enabled for xpra.gtk_common.error / ('x11', 'util')
2022-12-26 14:18:30,511 debug enabled for xpra.gtk_common.error / ('x11', 'util', 'error')
2022-12-26 14:18:30,531 debug enabled for xpra.gtk_common.gtk_util / ('gtk', 'util')
2022-12-26 14:18:30,544 debug enabled for xpra.client.client_window_base / ('mouse',)
2022-12-26 14:18:30,551 debug enabled for xpra.client.gtk_base.gtk_client_window_base / ('mouse',)
2022-12-26 14:18:30,592 debug enabled for xpra.net.net_util / ('network', 'util')
2022-12-26 14:18:30,614 debug enabled for xpra.client.client_base / ('mouse',)
2022-12-26 14:18:30,620 debug enabled for xpra.client.mixins.window_manager / ('mouse',)
2022-12-26 14:18:30,687 debug enabled for xpra.client.gtk_base.css_overrides / ('gtk', 'util')
2022-12-26 14:18:30,700  - loaded '/usr/share/xpra/css/10_header_bar.css'
2022-12-26 14:18:30,700  - loaded '/usr/share/xpra/css/20_progress_bar.css'
2022-12-26 14:18:30,718 Xpra GTK3 X11 client version 5.0-r32414 (g2b304a19b) beta 64-bit
2022-12-26 14:18:30,769  running on Linux
2022-12-26 14:18:30,770  window manager is 'KWin'
debug enabled for xpra.platform.xposix.gui / ('posix', 'mouse')
debug enabled for xpra.gtk_common.error / ('x11', 'util')
debug enabled for xpra.gtk_common.error / ('x11', 'util', 'error')
debug enabled for xpra.gtk_common.gtk_util / ('gtk', 'util')
debug enabled for xpra.client.gtk_base.css_overrides / ('gtk', 'util')
debug enabled for xpra.client.gtk3.splash_screen / ('client', 'util')
 - loaded '/usr/share/xpra/css/10_header_bar.css'
 - loaded '/usr/share/xpra/css/20_progress_bar.css'
get_pixbuf(xpra.png) icon_filename=/usr/share/xpra/icons/xpra.png
read_stdin()
handle_stdin_line('0:Xpra Client v5.0\n')
handle_stdin_line('10:initializing\n')
handle_stdin_line('20:validating OpenGL configuration\n')
increase_fraction(20, 1, 10)
increase_fraction(20, 2, 10)
increase_fraction(20, 3, 10)
increase_fraction(20, 4, 10)
handle_stdin_line('20:validating OpenGL: success (Mesa Intel(R) UHD Graphics (JSL))\n')
handle_stdin_line('30:client configuration\n')
2022-12-26 14:18:32,918 wheel_map(['on'])={4: 4, 5: 5, 6: 6, 7: 7, 8: 8, 9: 9, 10: 10, 11: 11, 12: 12, 13: 13, 14: 14, 15: 15, 16: 16, 17: 17, 18: 18, 19: 19, 20: 20, 21: 21, 22: 22, 23: 23, 24: 24, 25: 25, 26: 26, 27: 27, 28: 28, 29: 29, 30: 30, 31: 31, 32: 32, 33: 33, 34: 34, 35: 35, 36: 36, 37: 37, 38: 38, 39: 39, 40: 40, 41: 41, 42: 42, 43: 43}, wheel_smooth=True
increase_fraction(30, 1, 10)
2022-12-26 14:18:32,979 debug enabled for xpra.net.subprocess_wrapper / ('util',)
increase_fraction(30, 2, 10)
2022-12-26 14:18:33,517 GStreamer version 1.20.5
2022-12-26 14:18:33,519 add_process(<Popen: returncode: None args: ['/usr/bin/pactl', 'list']>, 'pactl', ['/usr/bin/pactl', 'list'], True, True, None) pid=3731
increase_fraction(30, 3, 10)
2022-12-26 14:18:33,529 add_dead_process(ProcInfo(3731 : ['/usr/bin/pactl', 'list']))
2022-12-26 14:18:33,529 add_dead_process returncode=0, dead=True, callback=None
2022-12-26 14:18:33,529 child 'pactl' with pid 3731 has terminated (ignored)
2022-12-26 14:18:33,530 updated procinfo=ProcInfo(3731 : ['/usr/bin/pactl', 'list'])
2022-12-26 14:18:33,531 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:33,531 check() watched=(), alive=(), quit callback=None
2022-12-26 14:18:33,538 add_process(<Popen: returncode: None args: ['/usr/bin/pactl', 'info']>, 'pactl', ['/usr/bin/pactl', 'info'], True, True, None) pid=3732
2022-12-26 14:18:33,552 add_dead_process(ProcInfo(3732 : ['/usr/bin/pactl', 'info']))
2022-12-26 14:18:33,552 add_dead_process returncode=0, dead=True, callback=None
2022-12-26 14:18:33,552 child 'pactl' with pid 3732 has terminated (ignored)
2022-12-26 14:18:33,552 updated procinfo=ProcInfo(3732 : ['/usr/bin/pactl', 'info'])
2022-12-26 14:18:33,552 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:33,552 check() watched=(), alive=(), quit callback=None
2022-12-26 14:18:33,568 debug enabled for xpra.util / ('util',)
2022-12-26 14:18:33,568 make_instance([<function DBUS_Notifier_factory at 0x7fa3c3934820>, <class 'xpra.gtk_common.gtk_notifier.GTK_Notifier'>, <class 'xpra.client.gtk3.gtk3_notifier.GTK3_Notifier'>], <bound method NotificationClient.notification_closed of gtk3.client>, <bound method NotificationClient.notification_action of gtk3.client>)
2022-12-26 14:18:33,573 make_instance(..) <function DBUS_Notifier_factory at 0x7fa3c3934820>()=<xpra.notifications.dbus_notifier.DBUS_Notifier object at 0x7fa3c36e9690>
2022-12-26 14:18:33,684 debug enabled for importlib._bootstrap / ('util', 'network')
2022-12-26 14:18:33,684 sd_listen_fds(0)=0
2022-12-26 14:18:33,685 get_sd_listen_sockets()=[]
2022-12-26 14:18:33,687 created unix domain socket '/run/user/1000/xpra/clients/fan87-laptop-3610'
2022-12-26 14:18:33,737 Error: invalid codec name 'avcodec2'
2022-12-26 14:18:33,769 make_instance((None, <class 'xpra.client.gtk3.tray_menu.GTK3TrayMenu'>), gtk3.client)
2022-12-26 14:18:33,787 make_instance(..) <class 'xpra.client.gtk3.tray_menu.GTK3TrayMenu'>()=<xpra.client.gtk3.tray_menu.GTK3TrayMenu object at 0x7fa3c2d6eb90>
handle_stdin_line('40:loading user interface\n')
increase_fraction(40, 1, 10)
2022-12-26 14:18:33,845 OpenGL_accelerate module loaded
2022-12-26 14:18:33,852 Using accelerated ArrayDatatype
increase_fraction(40, 2, 10)
2022-12-26 14:18:34,120 Warning: vendor 'Intel' is greylisted,
2022-12-26 14:18:34,120  you may want to turn off OpenGL if you encounter bugs
2022-12-26 14:18:34,310 OpenGL enabled on 'Mesa Intel(R) UHD Graphics (JSL)'
handle_stdin_line('60:connecting to server\n')
2022-12-26 14:18:34,340 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,340 check() watched=(), alive=(), quit callback=None
2022-12-26 14:18:34,341 get_pixbuf(information.png) icon_filename=/usr/share/xpra/icons/information.png
2022-12-26 14:18:34,343 get_pixbuf(xpra.png) icon_filename=/usr/share/xpra/icons/xpra.png
2022-12-26 14:18:34,343 get_pixbuf(statistics.png) icon_filename=/usr/share/xpra/icons/statistics.png
2022-12-26 14:18:34,346 get_pixbuf(qr.png) icon_filename=/usr/share/xpra/icons/qr.png
2022-12-26 14:18:34,348 debug enabled for importlib._bootstrap / ('util',)
2022-12-26 14:18:34,348 get_pixbuf(update.png) icon_filename=/usr/share/xpra/icons/update.png
2022-12-26 14:18:34,350 get_pixbuf(bugs.png) icon_filename=/usr/share/xpra/icons/bugs.png
2022-12-26 14:18:34,351 get_pixbuf(documentation.png) icon_filename=/usr/share/xpra/icons/documentation.png
2022-12-26 14:18:34,353 get_pixbuf(browser.png) icon_filename=/usr/share/xpra/icons/browser.png
2022-12-26 14:18:34,354 get_pixbuf(features.png) icon_filename=/usr/share/xpra/icons/features.png
2022-12-26 14:18:34,357 get_pixbuf(keyboard.png) icon_filename=/usr/share/xpra/icons/keyboard.png
increase_fraction(60, 1, 10)
2022-12-26 14:18:34,361 get_pixbuf(keyboard.png) icon_filename=/usr/share/xpra/icons/keyboard.png
2022-12-26 14:18:34,367 get_pixbuf(clipboard.png) icon_filename=/usr/share/xpra/icons/clipboard.png
2022-12-26 14:18:34,370 get_pixbuf(picture.png) icon_filename=/usr/share/xpra/icons/picture.png
2022-12-26 14:18:34,371 get_pixbuf(bandwidth_limit.png) icon_filename=/usr/share/xpra/icons/bandwidth_limit.png
2022-12-26 14:18:34,373 get_pixbuf(encoding.png) icon_filename=/usr/share/xpra/icons/encoding.png
2022-12-26 14:18:34,375 get_pixbuf(scaling.png) icon_filename=/usr/share/xpra/icons/scaling.png
2022-12-26 14:18:34,378 get_pixbuf(slider.png) icon_filename=/usr/share/xpra/icons/slider.png
2022-12-26 14:18:34,383 get_pixbuf(speed.png) icon_filename=/usr/share/xpra/icons/speed.png
2022-12-26 14:18:34,386 get_pixbuf(audio.png) icon_filename=/usr/share/xpra/icons/audio.png
2022-12-26 14:18:34,389 get_pixbuf(speaker.png) icon_filename=/usr/share/xpra/icons/speaker.png
2022-12-26 14:18:34,392 get_pixbuf(microphone.png) icon_filename=/usr/share/xpra/icons/microphone.png
2022-12-26 14:18:34,393 get_pixbuf(video.png) icon_filename=/usr/share/xpra/icons/video.png
2022-12-26 14:18:34,396 get_pixbuf(webcam.png) icon_filename=/usr/share/xpra/icons/webcam.png
2022-12-26 14:18:34,399 get_pixbuf(display.png) icon_filename=/usr/share/xpra/icons/display.png
2022-12-26 14:18:34,402 get_pixbuf(windows.png) icon_filename=/usr/share/xpra/icons/windows.png
2022-12-26 14:18:34,405 get_pixbuf(raise.png) icon_filename=/usr/share/xpra/icons/raise.png
2022-12-26 14:18:34,407 get_pixbuf(eye-off.png) icon_filename=/usr/share/xpra/icons/eye-off.png
2022-12-26 14:18:34,409 get_pixbuf(minimize.png) icon_filename=/usr/share/xpra/icons/minimize.png
2022-12-26 14:18:34,411 get_pixbuf(retry.png) icon_filename=/usr/share/xpra/icons/retry.png
2022-12-26 14:18:34,413 get_pixbuf(reinitialize.png) icon_filename=/usr/share/xpra/icons/reinitialize.png
2022-12-26 14:18:34,414 get_pixbuf(server.png) icon_filename=/usr/share/xpra/icons/server.png
2022-12-26 14:18:34,415 get_pixbuf(forward.png) icon_filename=/usr/share/xpra/icons/forward.png
2022-12-26 14:18:34,416 get_pixbuf(list.png) icon_filename=/usr/share/xpra/icons/list.png
2022-12-26 14:18:34,417 get_pixbuf(transfer.png) icon_filename=/usr/share/xpra/icons/transfer.png
2022-12-26 14:18:34,418 get_pixbuf(upload.png) icon_filename=/usr/share/xpra/icons/upload.png
2022-12-26 14:18:34,420 get_pixbuf(download.png) icon_filename=/usr/share/xpra/icons/download.png
2022-12-26 14:18:34,421 get_pixbuf(list.png) icon_filename=/usr/share/xpra/icons/list.png
2022-12-26 14:18:34,422 get_pixbuf(shutdown.png) icon_filename=/usr/share/xpra/icons/shutdown.png
2022-12-26 14:18:34,423 get_pixbuf(start.png) icon_filename=/usr/share/xpra/icons/start.png
2022-12-26 14:18:34,424 get_pixbuf(quit.png) icon_filename=/usr/share/xpra/icons/quit.png
2022-12-26 14:18:34,426 get_pixbuf(close.png) icon_filename=/usr/share/xpra/icons/close.png
2022-12-26 14:18:34,439 make_instance([<class 'xpra.platform.xposix.appindicator_tray.AppindicatorTray'>, <class 'xpra.client.gtk_base.statusicon_tray.GTKStatusIconTray'>], gtk3.client, 0, <Gtk.Menu object at 0x7fa3c089d740 (GtkMenu at 0x7fa3b4024e30)>, 'Xpra', 'xpra', <function TrayClient.create_xpra_tray.<locals>.xpra_tray_geometry at 0x7fa3b875d090>, <function TrayClient.create_xpra_tray.<locals>.xpra_tray_click at 0x7fa3c47cbd00>, <function TrayClient.create_xpra_tray.<locals>.xpra_tray_mouseover at 0x7fa3b875cd30>, <function TrayClient.create_xpra_tray.<locals>.xpra_tray_exit at 0x7fa3b875d000>)
2022-12-26 14:18:34,517 make_instance(..) <class 'xpra.platform.xposix.appindicator_tray.AppindicatorTray'>()=Tray(0:Xpra)
2022-12-26 14:18:34,526 sigchld(17, <frame at 0x55da14eda2b0, file '/usr/lib/python3.10/subprocess.py', line 1813, code _execute_child>)
2022-12-26 14:18:34,526 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,526 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:34,527 reap() waitpid=0
2022-12-26 14:18:34,527 sigchld(17, <frame at 0x7fa3d81d7a60, file '/usr/lib/python3.10/subprocess.py', line 1901, code _try_wait>)
2022-12-26 14:18:34,527 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,527 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:34,527 reap() waitpid=0
2022-12-26 14:18:34,528 sigchld(17, <frame at 0x7fa3d81fa320, file '/usr/lib/python3.10/subprocess.py', line 1937, code _wait>)
2022-12-26 14:18:34,528 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,528 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:34,528 reap() waitpid=0
2022-12-26 14:18:34,528 sigchld(17, <frame at 0x7fa3d81d7a60, file '/usr/lib/python3.10/subprocess.py', line 1901, code _try_wait>)
2022-12-26 14:18:34,529 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,529 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:34,529 reap() waitpid=0
2022-12-26 14:18:34,529 sigchld(17, <frame at 0x7fa3d81d7a60, file '/usr/lib/python3.10/subprocess.py', line 1901, code _try_wait>)
2022-12-26 14:18:34,538 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,538 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:34,538 reap() waitpid=0
2022-12-26 14:18:34,538 sigchld(17, <frame at 0x7fa3d81d7a60, file '/usr/lib/python3.10/subprocess.py', line 1901, code _try_wait>)
2022-12-26 14:18:34,539 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])]
2022-12-26 14:18:34,539 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:34,539 reap() waitpid=0
2022-12-26 14:18:34,547 socket_details(None, 'LIVE', '100') sockdir=/run/user/1000/xpra, sockdirs=['/run/user/1000/xpra', '/run/xpra', '/home/fan87/.xpra']
2022-12-26 14:18:34,547 add_session_dir(('/run/user/1000/xpra/100', '100')) state(/run/user/1000/xpra/100/socket)=LIVE
2022-12-26 14:18:34,547 add_session_dir('/run/xpra/100', '100') path does not exist
2022-12-26 14:18:34,547 add_session_dir('/home/fan87/.xpra/100', '100') path does not exist
2022-12-26 14:18:34,564  keyboard settings: rules=evdev, model=pc104, layout=us
increase_fraction(60, 2, 10)
2022-12-26 14:18:34,579  desktop size is 1920x1080:
2022-12-26 14:18:34,580   :0.0 (508x285 mm - DPI: 96x96) workarea: 1920x1036
2022-12-26 14:18:34,580     CMN eDP-1        (309x173 mm - DPI: 158x159)
2022-12-26 14:18:34,929 identical remote version: 5.0
handle_stdin_line('90:starting speaker forwarding\n')
2022-12-26 14:18:34,938 exec_subprocess() command=['python3.10', '/usr/bin/xpra', '--windows=no', '--video-encoders=none', '--csc-modules=none', '--video-decoders=none', '--proxy-video-encoders=none', '_sound_play', '-', '-', '', '', 'mp3', '', '1.0'], env={'KDE_SESSION_VERSION': '5', 'XPRA_UTIL_DEBUG': '1', 'SHELL_SESSION_ID': '094ed8cfd1354b258bf7b9861a39b5d6', 'COLORFGBG': '15;0', 'DBUS_SESSION_BUS_ADDRESS': 'unix:path=/run/user/1000/bus', 'PWD': '/home/fan87', 'DESKTOP_SESSION': 'plasma', 'GTK_IM_MODULE': 'fcitx', 'KONSOLE_DBUS_WINDOW': '/Windows/1', 'GTK_MODULES': 'canberra-gtk-module', 'GTK_RC_FILES': '/etc/gtk/gtkrc:/home/fan87/.gtkrc:/home/fan87/.config/gtkrc', 'XPRA_MOUSE_DEBUG': '1', 'HOME': '/home/fan87', 'JOURNAL_STREAM': '8:21783', 'KDE_APPLICATIONS_AS_SCOPE': '1', 'LS_COLORS': 'rs=0:di=01;34:ln=01;36:mh=00:pi=40;33:so=01;35:do=01;35:bd=40;33;01:cd=40;33;01:or=40;31;01:mi=00:su=37;41:sg=30;43:ca=00:tw=30;42:ow=34;42:st=37;44:ex=01;32:*.tar=01;31:*.tgz=01;31:*.arc=01;31:*.arj=01;31:*.taz=01;31:*.lha=01;31:*.lz4=01;31:*.lzh=01;31:*.lzma=01;31:*.tlz=01;31:*.txz=01;31:*.tzo=01;31:*.t7z=01;31:*.zip=01;31:*.z=01;31:*.dz=01;31:*.gz=01;31:*.lrz=01;31:*.lz=01;31:*.lzo=01;31:*.xz=01;31:*.zst=01;31:*.tzst=01;31:*.bz2=01;31:*.bz=01;31:*.tbz=01;31:*.tbz2=01;31:*.tz=01;31:*.deb=01;31:*.rpm=01;31:*.jar=01;31:*.war=01;31:*.ear=01;31:*.sar=01;31:*.rar=01;31:*.alz=01;31:*.ace=01;31:*.zoo=01;31:*.cpio=01;31:*.7z=01;31:*.rz=01;31:*.cab=01;31:*.wim=01;31:*.swm=01;31:*.dwm=01;31:*.esd=01;31:*.avif=01;35:*.jpg=01;35:*.jpeg=01;35:*.mjpg=01;35:*.mjpeg=01;35:*.gif=01;35:*.bmp=01;35:*.pbm=01;35:*.pgm=01;35:*.ppm=01;35:*.tga=01;35:*.xbm=01;35:*.xpm=01;35:*.tif=01;35:*.tiff=01;35:*.png=01;35:*.svg=01;35:*.svgz=01;35:*.mng=01;35:*.pcx=01;35:*.mov=01;35:*.mpg=01;35:*.mpeg=01;35:*.m2v=01;35:*.mkv=01;35:*.webm=01;35:*.webp=01;35:*.ogm=01;35:*.mp4=01;35:*.m4v=01;35:*.mp4v=01;35:*.vob=01;35:*.qt=01;35:*.nuv=01;35:*.wmv=01;35:*.asf=01;35:*.rm=01;35:*.rmvb=01;35:*.flc=01;35:*.avi=01;35:*.fli=01;35:*.flv=01;35:*.gl=01;35:*.dl=01;35:*.xcf=01;35:*.xwd=01;35:*.yuv=01;35:*.cgm=01;35:*.emf=01;35:*.ogv=01;35:*.ogx=01;35:*.aac=00;36:*.au=00;36:*.flac=00;36:*.m4a=00;36:*.mid=00;36:*.midi=00;36:*.mka=00;36:*.mp3=00;36:*.mpc=00;36:*.ogg=00;36:*.ra=00;36:*.wav=00;36:*.oga=00;36:*.opus=00;36:*.spx=00;36:*.xspf=00;36:*~=00;90:*#=00;90:*.bak=00;90:*.old=00;90:*.orig=00;90:*.part=00;90:*.rej=00;90:*.swp=00;90:*.tmp=00;90:*.dpkg-dist=00;90:*.dpkg-old=00;90:*.ucf-dist=00;90:*.ucf-new=00;90:*.ucf-old=00;90:*.rpmnew=00;90:*.rpmorig=00;90:*.rpmsave=00;90:', 'COLORTERM': 'truecolor', 'INVOCATION_ID': 'bd4ed61cd0964c87a79ce934baa85952', 'KONSOLE_DBUS_SERVICE': ':1.66', 'XPRA_UINPUT_TOUCHPAD': '1', 'KONSOLE_DBUS_SESSION': '/Sessions/1', 'KDE_SESSION_UID': '1000', 'LANG': 'en_US.UTF-8', 'LANGUAGE': 'en_US', 'XDG_RUNTIME_DIR': '/run/user/1000', 'WINDOWID': '58720269', 'XDG_VTNR': '1', 'XDG_SESSION_TYPE': 'x11', 'XCURSOR_SIZE': '24', 'TERM': 'xterm-256color', 'XDG_SEAT': 'seat0', 'QT_IM_MODULE': 'fcitx', 'XMODIFIERS': '@im=fcitx', 'PROFILEHOME': '', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/bin:/usr/bin/site_perl:/usr/bin/vendor_perl:/usr/bin/core_perl', 'USER': 'fan87', 'XAUTHORITY': '/home/fan87/.Xauthority', 'QT_AUTO_SCREEN_SCALE_FACTOR': '0', 'MOTD_SHOWN': 'pam', 'SYSTEMD_EXEC_PID': '604', 'SHLVL': '2', 'PAM_KWALLET5_LOGIN': '/run/user/1000/kwallet5.socket', 'XDG_SESSION_ID': '2', 'XDG_CONFIG_DIRS': '/home/fan87/.config/kdedefaults:/etc/xdg', 'XDG_SESSION_PATH': '/org/freedesktop/DisplayManager/Session2', 'SHELL': '/bin/fish', 'XCURSOR_THEME': 'breeze_cursors', 'LOGNAME': 'fan87', 'MANAGERPID': '517', 'KDE_FULL_SESSION': 'true', 'DISPLAY': ':0', 'MAIL': '/var/spool/mail/fan87', 'XDG_SESSION_CLASS': 'user', 'XDG_SEAT_PATH': '/org/freedesktop/DisplayManager/Seat0', 'GTK2_RC_FILES': '/etc/gtk-2.0/gtkrc:/home/fan87/.gtkrc-2.0:/home/fan87/.config/gtkrc-2.0', 'KONSOLE_VERSION': '221200', 'XDG_SESSION_DESKTOP': 'KDE', 'XDG_CURRENT_DESKTOP': 'KDE', 'GDK_SCALE': '1', 'GDK_DPI_SCALE': '1', 'GTK_CSD': '0', 'GDK_BACKEND': 'x11', 'XPRA_SKIP_UI': '1', 'XPRA_FORCE_COLOR_LOG': '1', 'XPRA_LOG_PREFIX': 'audio playback ', 'XPRA_FIX_UNICODE_OUT': '0', 'PULSE_PROP_application.name': 'xpra', 'PULSE_PROP_application.id': 'xpra', 'PULSE_PROP_application.version': '5.0', 'PULSE_PROP_media.role': 'music', 'PULSE_PROP_application.icon_name': '/usr/share/xpra/icons/xpra.png'}, kwargs={'stderr': 2}
2022-12-26 14:18:34,955 add_process(<Popen: returncode: None args: ['python3.10', '/usr/bin/xpra', '--windows=no...>, 'audio playback', ['python3.10', '/usr/bin/xpra', '--windows=no', '--video-encoders=none', '--csc-modules=none', '--video-decoders=none', '--proxy-video-encoders=none', '_sound_play', '-', '-', '', '', 'mp3', '', '1.0'], True, True, <bound method subprocess_caller.subprocess_exit of sink_subprocess_wrapper(None)>) pid=3759
2022-12-26 14:18:34,955 protocol using rencodeplus
2022-12-26 14:18:34,958 enabled fast mmap transfers using 512MB shared memory area
2022-12-26 14:18:34,959 enabled remote logging
2022-12-26 14:18:34,959 Xpra X11 desktop server version 5.0
2022-12-26 14:18:34,965  running on unknown
2022-12-26 14:18:34,971  remote desktop size is 1280x1024
2022-12-26 14:18:34,972   :100.0 (339x271 mm - DPI: 96x96)
2022-12-26 14:18:34,972     DUMMY0
increase_fraction(90, 1, 10)
handle_stdin_line('100:connection established\n')
2022-12-26 14:18:34,988 Attached to xpra server at socket:///run/user/1000/xpra/100/socket
2022-12-26 14:18:34,993  (press Control-C to detach)

2022-12-26 14:18:34,998 debug enabled for xpra.x11.gtk_x11.selection / ('x11', 'util')
2022-12-26 14:18:35,008 debug enabled for xpra.x11.xroot_props / ('x11', 'util')
2022-12-26 14:18:35,014 XRootPropWatcher.do_notify(RESOURCE_MANAGER)
2022-12-26 14:18:35,051 debug enabled for xpra.platform.ui_thread_watcher / ('util',)
2022-12-26 14:18:35,052 get_UI_watcher(<function timeout_add at 0x7fa3c654ca60>)
2022-12-26 14:18:35,053 UI_thread_wakeup(0) elapsed=0.00ms
2022-12-26 14:18:35,063 poll_UI_loop() running
2022-12-26 14:18:35,067 poll_UI_loop() last_UI_thread_time was 0.0 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:35,068 poll_UI_loop() ok, firing []
2022-12-26 14:18:35,092 running, 1 window
2022-12-26 14:18:35,238 UI_thread_wakeup(2183.992659004) elapsed=164.56ms
2022-12-26 14:18:35,261 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '239', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2183987'}>)
2022-12-26 14:18:35,262 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '239', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2183988'}>)
2022-12-26 14:18:35,279 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '277', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184005'}>)
2022-12-26 14:18:35,282 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '277', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184006'}>)
2022-12-26 14:18:35,294 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '291', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184016'}>)
2022-12-26 14:18:35,298 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '291', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184016'}>)
2022-12-26 14:18:35,326 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2ac', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184031'}>)
2022-12-26 14:18:35,328 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2ac', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184032'}>)
2022-12-26 14:18:35,330 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2ac', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184032'}>)
2022-12-26 14:18:35,331 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2ac', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184032'}>)
2022-12-26 14:18:35,360 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2b8', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_CLIENT_LIST', 'time': '2184038'}>)
2022-12-26 14:18:35,362 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2b8', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_CLIENT_LIST_STACKING', 'time': '2184038'}>)
2022-12-26 14:18:35,365 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2b8', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_ACTIVE_WINDOW', 'time': '2184039'}>)
2022-12-26 14:18:35,367 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '2b8', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_ACTIVE_WINDOW', 'time': '2184039'}>)
2022-12-26 14:18:35,519 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '4eb', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184436'}>)
2022-12-26 14:18:35,523 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '4eb', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2184437'}>)
2022-12-26 14:18:35,575 wait(0.5000) actually waited 0.5039
2022-12-26 14:18:35,575 poll_UI_loop() last_UI_thread_time was 0.3 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:35,576 poll_UI_loop() ok, firing []
2022-12-26 14:18:35,585 UI_thread_wakeup(2184.497665704) elapsed=4.96ms
2022-12-26 14:18:35,802 firing callback for 'state-changed': [(<bound method sound_subprocess_wrapper.state_changed of sink_subprocess_wrapper(3759)>, []), (<bound method AudioClient.sound_sink_state_changed of gtk3.client>, [])]
2022-12-26 14:18:35,998 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:36,076 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:36,076 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:36,076 poll_UI_loop() ok, firing []
2022-12-26 14:18:36,078 UI_thread_wakeup(2184.99822307) elapsed=1.75ms
2022-12-26 14:18:36,577 wait(0.5000) actually waited 0.5003
2022-12-26 14:18:36,577 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:36,577 poll_UI_loop() ok, firing []
2022-12-26 14:18:36,577 UI_thread_wakeup(2185.499009715) elapsed=0.20ms
2022-12-26 14:18:36,687 firing callback for 'state-changed': [(<bound method sound_subprocess_wrapper.state_changed of sink_subprocess_wrapper(3759)>, []), (<bound method AudioClient.sound_sink_state_changed of gtk3.client>, [])]
2022-12-26 14:18:36,690 firing callback for 'state-changed': [(<bound method sound_subprocess_wrapper.state_changed of sink_subprocess_wrapper(3759)>, []), (<bound method AudioClient.sound_sink_state_changed of gtk3.client>, [])]
2022-12-26 14:18:36,729 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:36,967 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:37,077 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:37,078 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:37,078 poll_UI_loop() ok, firing []
2022-12-26 14:18:37,079 UI_thread_wakeup(2186.000422154) elapsed=0.23ms
2022-12-26 14:18:37,180 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:37,242 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80b49f0 (void at 0x55da16679b60)>)=([601, 400, 281, 371], [], []) (x_root=601.4237670898438, y_root=400.09075927734375, window_offset=None)
2022-12-26 14:18:37,243 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80b49f0 (void at 0x55da16679b60)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[601, 400, 281, 371], modifiers=[], buttons=[]
2022-12-26 14:18:37,244 send_mouse_position(('pointer-position', 1, [601, 400, 281, 371], [], [])) elapsed=2186165, delay left=-2186160
2022-12-26 14:18:37,244 do_send_mouse_position() position=('pointer-position', 1, [601, 400, 281, 371], [], [])
2022-12-26 14:18:37,244 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80b4d10 (void at 0x55da167512b0)>)=([601, 400, 281, 371], [], [1]) (x_root=601.4237670898438, y_root=400.09075927734375, window_offset=None)
2022-12-26 14:18:37,245 _button_action(1, <Gdk.EventButton object at 0x7fa3b80b4d10 (void at 0x55da167512b0)>, True) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[601, 400, 281, 371], modifiers=[], buttons=[1]
2022-12-26 14:18:37,245 button packet: ['button-action', 1, 1, True, [601, 400, 281, 371], [], [1]]
2022-12-26 14:18:37,287 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80b4ef0 (void at 0x55da16751210)>)=([602, 400, 282, 371], [], [1]) (x_root=601.9569091796875, y_root=400.09075927734375, window_offset=None)
2022-12-26 14:18:37,287 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80b4ef0 (void at 0x55da16751210)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[602, 400, 282, 371], modifiers=[], buttons=[1]
2022-12-26 14:18:37,288 send_mouse_position(('pointer-position', 1, [602, 400, 282, 371], [], [1])) elapsed=44, delay left=-39
2022-12-26 14:18:37,288 do_send_mouse_position() position=('pointer-position', 1, [602, 400, 282, 371], [], [1])
2022-12-26 14:18:37,388 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:37,579 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:37,579 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:37,579 poll_UI_loop() ok, firing []
2022-12-26 14:18:37,579 UI_thread_wakeup(2186.500936755) elapsed=0.07ms
2022-12-26 14:18:37,617 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:37,824 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:37,872 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80b6f20 (void at 0x55da16679b60)>)=([596, 160, 276, 131], [], [1]) (x_root=596.0919799804688, y_root=159.5133056640625, window_offset=None)
2022-12-26 14:18:37,872 _button_action(1, <Gdk.EventButton object at 0x7fa3b80b6f20 (void at 0x55da16679b60)>, False) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[596, 160, 276, 131], modifiers=[], buttons=[1]
2022-12-26 14:18:37,873 button packet: ['button-action', 1, 1, False, [596, 160, 276, 131], [], [1]]
2022-12-26 14:18:38,036 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:38,079 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:38,080 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:38,080 poll_UI_loop() ok, firing []
2022-12-26 14:18:38,080 UI_thread_wakeup(2187.001786514) elapsed=0.08ms
2022-12-26 14:18:38,246 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:38,453 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80d4db0 (void at 0x55da167513f0)>)=([591, 368, 271, 339], [], []) (x_root=591.2933959960938, y_root=367.66510009765625, window_offset=None)
2022-12-26 14:18:38,453 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80d4db0 (void at 0x55da167513f0)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[591, 368, 271, 339], modifiers=[], buttons=[]
2022-12-26 14:18:38,453 send_mouse_position(('pointer-position', 1, [591, 368, 271, 339], [], [])) elapsed=1165, delay left=-1160
2022-12-26 14:18:38,453 do_send_mouse_position() position=('pointer-position', 1, [591, 368, 271, 339], [], [])
2022-12-26 14:18:38,454 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80d4db0 (void at 0x55da16751210)>)=([591, 368, 271, 339], [], [1]) (x_root=591.2933959960938, y_root=367.66510009765625, window_offset=None)
2022-12-26 14:18:38,455 _button_action(1, <Gdk.EventButton object at 0x7fa3b80d4db0 (void at 0x55da16751210)>, True) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[591, 368, 271, 339], modifiers=[], buttons=[1]
2022-12-26 14:18:38,455 button packet: ['button-action', 1, 1, True, [591, 368, 271, 339], [], [1]]
2022-12-26 14:18:38,459 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:38,480 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80d4f40 (void at 0x55da16751170)>)=([591, 367, 271, 338], [], [1]) (x_root=591.2933959960938, y_root=367.14208984375, window_offset=None)
2022-12-26 14:18:38,481 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80d4f40 (void at 0x55da16751170)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[591, 367, 271, 338], modifiers=[], buttons=[1]
2022-12-26 14:18:38,481 send_mouse_position(('pointer-position', 1, [591, 367, 271, 338], [], [1])) elapsed=27, delay left=-22
2022-12-26 14:18:38,482 do_send_mouse_position() position=('pointer-position', 1, [591, 367, 271, 338], [], [1])
2022-12-26 14:18:38,580 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:38,581 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:38,581 poll_UI_loop() ok, firing []
2022-12-26 14:18:38,583 UI_thread_wakeup(2187.502813791) elapsed=2.51ms
2022-12-26 14:18:38,668 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:38,886 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80b74c0 (void at 0x55da167513f0)>)=([592, 184, 272, 155], [], [1]) (x_root=592.3597412109375, y_root=184.0940399169922, window_offset=None)
2022-12-26 14:18:38,886 _button_action(1, <Gdk.EventButton object at 0x7fa3b80b74c0 (void at 0x55da167513f0)>, False) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[592, 184, 272, 155], modifiers=[], buttons=[1]
2022-12-26 14:18:38,886 button packet: ['button-action', 1, 1, False, [592, 184, 272, 155], [], [1]]
2022-12-26 14:18:38,896 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
exit() calling <function main_quit at 0x7fb8f307f370>
2022-12-26 14:18:39,055 sigchld(17, <frame at 0x7fa3b80b0420, file '/usr/lib/python3.10/site-packages/gi/_ossighelper.py', line 92, code signal_notify>)
2022-12-26 14:18:39,056 poll() procinfo list: [ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash']), ProcInfo(3759 : ['python3.10', '/usr/bin/xpra', '--windows=no', '--video-encoders=none', '--csc-modules=none', '--video-decoders=none', '--proxy-video-encoders=none', '_sound_play', '-', '-', '', '', 'mp3', '', '1.0'])]
2022-12-26 14:18:39,056 add_dead_process(ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash']))
2022-12-26 14:18:39,056 add_dead_process returncode=0, dead=True, callback=None
2022-12-26 14:18:39,057 child 'splash' with pid 3638 has terminated (ignored)
2022-12-26 14:18:39,057 updated procinfo=ProcInfo(3638 : ['python3.10', '/usr/bin/xpra', 'splash'])
2022-12-26 14:18:39,057 poll() procinfo list: [ProcInfo(3759 : ['python3.10', '/usr/bin/xpra', '--windows=no', '--video-encoders=none', '--csc-modules=none', '--video-decoders=none', '--proxy-video-encoders=none', '_sound_play', '-', '-', '', '', 'mp3', '', '1.0'])]
2022-12-26 14:18:39,057 check() watched=(), alive=(), quit callback=None
2022-12-26 14:18:39,057 reap() calling os.waitpid(-1, 'WNOHANG')
2022-12-26 14:18:39,057 reap() waitpid=0
2022-12-26 14:18:39,059 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '559', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_CLIENT_LIST', 'time': '2187978'}>)
2022-12-26 14:18:39,060 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '559', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_CLIENT_LIST_STACKING', 'time': '2187978'}>)
2022-12-26 14:18:39,081 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:39,082 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:39,082 poll_UI_loop() ok, firing []
2022-12-26 14:18:39,082 UI_thread_wakeup(2188.004023111) elapsed=0.12ms
2022-12-26 14:18:39,109 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:39,319 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:39,525 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:39,582 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:39,582 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:39,582 poll_UI_loop() ok, firing []
2022-12-26 14:18:39,583 UI_thread_wakeup(2188.504621428) elapsed=0.47ms
2022-12-26 14:18:39,739 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:39,946 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:40,083 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:40,083 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:40,083 poll_UI_loop() ok, firing []
2022-12-26 14:18:40,084 UI_thread_wakeup(2189.005471084) elapsed=0.09ms
2022-12-26 14:18:40,176 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:40,384 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:40,584 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:40,584 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:40,584 poll_UI_loop() ok, firing []
2022-12-26 14:18:40,584 UI_thread_wakeup(2189.50607286) elapsed=0.14ms
2022-12-26 14:18:40,597 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:40,681 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80d76f0 (void at 0x55da16751210)>)=([899, 377, 579, 348], [], []) (x_root=899.4697265625, y_root=377.0789794921875, window_offset=None)
2022-12-26 14:18:40,682 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80d76f0 (void at 0x55da16751210)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[899, 377, 579, 348], modifiers=[], buttons=[]
2022-12-26 14:18:40,682 send_mouse_position(('pointer-position', 1, [899, 377, 579, 348], [], [])) elapsed=2200, delay left=-2195
2022-12-26 14:18:40,682 do_send_mouse_position() position=('pointer-position', 1, [899, 377, 579, 348], [], [])
2022-12-26 14:18:40,683 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80d76f0 (void at 0x55da167513f0)>)=([899, 377, 579, 348], [], [1]) (x_root=899.4697265625, y_root=377.0789794921875, window_offset=None)
2022-12-26 14:18:40,683 _button_action(1, <Gdk.EventButton object at 0x7fa3b80d76f0 (void at 0x55da167513f0)>, True) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[899, 377, 579, 348], modifiers=[], buttons=[1]
2022-12-26 14:18:40,683 button packet: ['button-action', 1, 1, True, [899, 377, 579, 348], [], [1]]
2022-12-26 14:18:40,702 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80d74c0 (void at 0x55da167512b0)>)=([899, 378, 579, 349], [], [1]) (x_root=899.4697265625, y_root=377.60198974609375, window_offset=None)
2022-12-26 14:18:40,703 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80d74c0 (void at 0x55da167512b0)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[899, 378, 579, 349], modifiers=[], buttons=[1]
2022-12-26 14:18:40,703 send_mouse_position(('pointer-position', 1, [899, 378, 579, 349], [], [1])) elapsed=20, delay left=-15
2022-12-26 14:18:40,703 do_send_mouse_position() position=('pointer-position', 1, [899, 378, 579, 349], [], [1])
2022-12-26 14:18:40,805 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:41,019 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:41,084 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:41,086 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:41,086 poll_UI_loop() ok, firing []
2022-12-26 14:18:41,086 UI_thread_wakeup(2190.008373812) elapsed=0.11ms
2022-12-26 14:18:41,226 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:41,456 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:41,587 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:41,587 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:41,587 poll_UI_loop() ok, firing []
2022-12-26 14:18:41,589 UI_thread_wakeup(2190.509103758) elapsed=1.43ms
2022-12-26 14:18:41,665 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:41,878 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:41,978 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80b7c40 (void at 0x55da167512b0)>)=([912, 509, 592, 480], [], [1]) (x_root=912.2659912109375, y_root=509.3965759277344, window_offset=None)
2022-12-26 14:18:41,979 _button_action(1, <Gdk.EventButton object at 0x7fa3b80b7c40 (void at 0x55da167512b0)>, False) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[912, 509, 592, 480], modifiers=[], buttons=[1]
2022-12-26 14:18:41,979 button packet: ['button-action', 1, 1, False, [912, 509, 592, 480], [], [1]]
2022-12-26 14:18:42,087 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:42,087 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:42,087 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:42,087 poll_UI_loop() ok, firing []
2022-12-26 14:18:42,088 UI_thread_wakeup(2191.009506769) elapsed=0.10ms
2022-12-26 14:18:42,301 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:42,507 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:42,525 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80e21b0 (void at 0x55da167512b0)>)=([1038, 472, 718, 443], [], []) (x_root=1038.0958251953125, y_root=472.26397705078125, window_offset=None)
2022-12-26 14:18:42,526 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80e21b0 (void at 0x55da167512b0)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[1038, 472, 718, 443], modifiers=[], buttons=[]
2022-12-26 14:18:42,526 send_mouse_position(('pointer-position', 1, [1038, 472, 718, 443], [], [])) elapsed=1822, delay left=-1817
2022-12-26 14:18:42,526 do_send_mouse_position() position=('pointer-position', 1, [1038, 472, 718, 443], [], [])
2022-12-26 14:18:42,527 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80b7560 (void at 0x55da16679b60)>)=([1038, 472, 718, 443], [], [1]) (x_root=1038.0958251953125, y_root=472.26397705078125, window_offset=None)
2022-12-26 14:18:42,528 _button_action(1, <Gdk.EventButton object at 0x7fa3b80b7560 (void at 0x55da16679b60)>, True) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[1038, 472, 718, 443], modifiers=[], buttons=[1]
2022-12-26 14:18:42,528 button packet: ['button-action', 1, 1, True, [1038, 472, 718, 443], [], [1]]
2022-12-26 14:18:42,584 pointer_modifiers(<Gdk.EventMotion object at 0x7fa3b80b76f0 (void at 0x55da16751170)>)=([1039, 472, 719, 443], [], [1]) (x_root=1038.6290283203125, y_root=472.26397705078125, window_offset=None)
2022-12-26 14:18:42,586 do_motion_notify_event(<Gdk.EventMotion object at 0x7fa3b80b76f0 (void at 0x55da16751170)>) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[1039, 472, 719, 443], modifiers=[], buttons=[1]
2022-12-26 14:18:42,586 send_mouse_position(('pointer-position', 1, [1039, 472, 719, 443], [], [1])) elapsed=59, delay left=-54
2022-12-26 14:18:42,587 do_send_mouse_position() position=('pointer-position', 1, [1039, 472, 719, 443], [], [1])
2022-12-26 14:18:42,588 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:42,588 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:42,588 poll_UI_loop() ok, firing []
2022-12-26 14:18:42,589 UI_thread_wakeup(2191.510567592) elapsed=0.62ms
2022-12-26 14:18:42,739 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:42,944 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:43,089 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:43,089 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:43,089 poll_UI_loop() ok, firing []
2022-12-26 14:18:43,090 UI_thread_wakeup(2192.011438959) elapsed=0.46ms
2022-12-26 14:18:43,157 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:43,356 pointer_modifiers(<Gdk.EventButton object at 0x7fa3b80d6660 (void at 0x55da16751170)>)=([1048, 295, 728, 266], [], [1]) (x_root=1047.6929931640625, y_root=295.4918518066406, window_offset=None)
2022-12-26 14:18:43,357 _button_action(1, <Gdk.EventButton object at 0x7fa3b80d6660 (void at 0x55da16751170)>, False) wid=1 / focus=1 / window wid=1, device=Virtual core pointer, pointer=[1048, 295, 728, 266], modifiers=[], buttons=[1]
2022-12-26 14:18:43,357 button packet: ['button-action', 1, 1, False, [1048, 295, 728, 266], [], [1]]
2022-12-26 14:18:43,365 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:43,581 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:43,591 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:43,591 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:43,591 poll_UI_loop() ok, firing []
2022-12-26 14:18:43,592 UI_thread_wakeup(2192.513271577) elapsed=0.50ms
2022-12-26 14:18:43,787 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:44,016 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:44,092 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:44,092 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:44,092 poll_UI_loop() ok, firing []
2022-12-26 14:18:44,092 UI_thread_wakeup(2193.014047167) elapsed=0.33ms
2022-12-26 14:18:44,224 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:44,438 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:44,592 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:44,592 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:44,592 poll_UI_loop() ok, firing []
2022-12-26 14:18:44,593 UI_thread_wakeup(2193.514599299) elapsed=0.15ms
2022-12-26 14:18:44,645 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:44,859 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:45,067 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:45,093 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:45,093 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:45,093 poll_UI_loop() ok, firing []
2022-12-26 14:18:45,094 UI_thread_wakeup(2194.015157645) elapsed=0.43ms
2022-12-26 14:18:45,296 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:45,504 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:45,593 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:45,593 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:45,594 poll_UI_loop() ok, firing []
2022-12-26 14:18:45,594 UI_thread_wakeup(2194.51571035) elapsed=0.79ms
2022-12-26 14:18:45,643 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '5db', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2194539'}>)
2022-12-26 14:18:45,644 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '5db', 'delivered_to': '0x798', 'window': '0x798', 'atom': 'CLIP_TEMPORARY', 'time': '2194539'}>)
2022-12-26 14:18:45,645 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '5db', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_CLIENT_LIST_STACKING', 'time': '2194539'}>)
2022-12-26 14:18:45,652 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '5db', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_ACTIVE_WINDOW', 'time': '2194540'}>)
2022-12-26 14:18:45,658 XRootPropWatcher.do_xpra_property_notify_event(<X11:PropertyNotify {'send_event': '0', 'serial': '5db', 'delivered_to': '0x798', 'window': '0x798', 'atom': '_NET_ACTIVE_WINDOW', 'time': '2194563'}>)
2022-12-26 14:18:45,720 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:45,929 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:46,095 wait(0.5000) actually waited 0.5003
2022-12-26 14:18:46,095 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:46,096 poll_UI_loop() ok, firing []
2022-12-26 14:18:46,096 UI_thread_wakeup(2195.017830338) elapsed=0.45ms
2022-12-26 14:18:46,141 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:46,350 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:46,583 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:46,596 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:46,599 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:46,599 poll_UI_loop() ok, firing []
2022-12-26 14:18:46,600 UI_thread_wakeup(2195.52130783) elapsed=0.48ms
2022-12-26 14:18:46,792 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:46,999 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:47,100 wait(0.5000) actually waited 0.5001
2022-12-26 14:18:47,100 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:47,100 poll_UI_loop() ok, firing []
2022-12-26 14:18:47,101 UI_thread_wakeup(2196.022562753) elapsed=0.30ms
2022-12-26 14:18:47,206 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:47,422 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]
2022-12-26 14:18:47,601 wait(0.5000) actually waited 0.5002
2022-12-26 14:18:47,601 poll_UI_loop() last_UI_thread_time was 0.5 seconds ago (max 1), UI_blocked=False
2022-12-26 14:18:47,601 poll_UI_loop() ok, firing []
2022-12-26 14:18:47,602 UI_thread_wakeup(2196.523646584) elapsed=0.35ms
2022-12-26 14:18:47,632 firing callback for 'info': [(<bound method sound_subprocess_wrapper.info_update of sink_subprocess_wrapper(3759)>, [])]