xpra icon
Bug tracker and wiki

Ticket #1911: xpra-no-bw-det.txt

File xpra-no-bw-det.txt, 59.4 KB (added by tc424, 19 months ago)

log of XPRA_BANDWIDTH_DETECTION=0 xpra start -d stats,bandwidth --start=xfce4-terminal

Line 
12018-07-08 10:58:28,401 cannot access python uinput module:
22018-07-08 10:58:28,401  No module named uinput
3_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
4_XSERVTransMakeAllCOTSServerListeners: server already running
5Failed to rename log file "/run/user/1000/xpra/Xorg.S11773.log" to "/run/user/1000/xpra/Xorg.S11773.log": No such file or directory
6
7X.Org X Server 1.19.6
8Release Date: 2017-12-20
9X Protocol Version 11, Revision 0
10Build Operating System: Linux 4.4.0-119-generic x86_64 Ubuntu
11Current Operating System: Linux beelink 4.15.0-23-generic #25-Ubuntu SMP Wed May 23 18:02:16 UTC 2018 x86_64
12Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-23-generic root=/dev/mapper/xubuntu--vg-root ro noresume
13Build Date: 13 April 2018  08:07:36PM
14xorg-server 2:1.19.6-1ubuntu4 (For technical support please see http://www.ubuntu.com/support)
15Current version of pixman: 0.34.0
16Before reporting problems, check http://wiki.x.org
17to make sure that you have the latest version.
18Markers: (--) probed, (**) from config file, (==) default setting,
19(++) from command line, (!!) notice, (II) informational,
20(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
21(++) Log file: "/run/user/1000/xpra/Xorg.S11773.log", Time: Sun Jul  8 10:58:28 2018
22(++) Using config file: "/etc/xpra/xorg.conf"
23(==) Using system config directory "/usr/share/X11/xorg.conf.d"
242018-07-08 10:58:35,447 created unix domain socket: /run/user/1000/xpra/beelink-1
252018-07-08 10:58:35,448 created unix domain socket: /home/steved/.xpra/beelink-1
262018-07-08 10:58:35,448 cannot create group socket '/run/xpra/beelink-1'
272018-07-08 10:58:35,448  [Errno 13] Permission denied
282018-07-08 10:58:35,586 Warning: the python netifaces package is missing
292018-07-08 10:58:35,615 pointer device emulation using XTest
302018-07-08 10:58:37,880 serving html content from: /usr/share/xpra/www
312018-07-08 10:58:38,100 D-Bus notification forwarding is available
322018-07-08 10:58:38,350 Warning: webcam forwarding is disabled
332018-07-08 10:58:38,350  the virtual video directory '/sys/devices/virtual/video4linux' was not found
342018-07-08 10:58:38,351  make sure that the 'v4l2loopback' kernel module is installed and loaded
352018-07-08 10:58:38,351 found 0 virtual video devices for webcam forwarding
362018-07-08 10:58:38,373 pulseaudio server started with pid 11852
372018-07-08 10:58:38,374  private server socket path:
382018-07-08 10:58:38,374  '/run/user/1000/xpra/pulse-:1/pulse/native'
392018-07-08 10:58:40,305 GStreamer version 1.14.1 for Python 2.7.15 64-bit
402018-07-08 10:58:40,446 Warning: failed to load the mdns avahi publisher:
412018-07-08 10:58:40,446  No module named avahi
422018-07-08 10:58:40,447  either fix your installation or use the 'mdns=no' option
432018-07-08 10:58:40,447 xpra X11 version 2.3.2-r19729 64-bit
442018-07-08 10:58:40,448  uid=1000 (steved), gid=1000 (steved)
452018-07-08 10:58:40,448  running with pid 11805 on Linux Ubuntu 18.04 bionic
462018-07-08 10:58:40,448  connected to X11 display :1 with 24 bit colors
472018-07-08 10:58:40,703 xpra is ready.
482018-07-08 10:58:52,741 printer forwarding enabled using postscript and pdf
492018-07-08 10:58:52,758 3.7GB of system memory
50Failed to connect to session manager: Failed to connect to the session manager: SESSION_MANAGER environment variable not defined
512018-07-08 10:59:09,313 New unix-domain connection received on /run/user/1000/xpra/beelink-1
522018-07-08 10:59:09,320 Handshake complete; enabling connection
532018-07-08 10:59:09,325 get_client_bandwidth_limit(Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1))=0
542018-07-08 10:59:09,345  automatic picture encoding enabled, also available:
552018-07-08 10:59:09,346   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
562018-07-08 10:59:09,348  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.ue8N1u.mmap
572018-07-08 10:59:09,348 server bandwidth-limit=0, client bandwidth-limit=0, value=0
582018-07-08 10:59:09,349 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
592018-07-08 10:59:09,350  connected from 'beelink' as 'steved' - 'Steve Dee'
602018-07-08 10:59:09,359  client root window size is 2880x900 with 1 display:
612018-07-08 10:59:09,360   :0.0 (762x238 mm - DPI: 96x96)
622018-07-08 10:59:09,360     monitor 1 1440x900 (160x90 mm - DPI: 228x254)
632018-07-08 10:59:09,360     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
642018-07-08 10:59:09,628 server virtual display now set to 2880x960 (best match for 2880x900)
652018-07-08 10:59:09,644 setting key repeat rate from client: 500ms delay / 50ms interval
662018-07-08 10:59:09,646 setting keymap: rules=evdev, model=pc105, layout=gb
672018-07-08 10:59:09,687 setting keyboard layout to 'gb'
682018-07-08 10:59:09,792 New unix-domain connection received on /home/steved/.xpra/beelink-1
692018-07-08 10:59:09,793 New unix-domain connection received on /run/user/1000/xpra/beelink-1
702018-07-08 10:59:09,799 client @05.065 Xpra X11 server version 2.3.2-r19729 64-bit
712018-07-08 10:59:09,817 client @05.065  running on Linux Ubuntu 18.04 bionic
722018-07-08 10:59:09,817 client @05.072 Attached to :1
732018-07-08 10:59:09,818 client @05.073  (press Control-C to detach)
742018-07-08 10:59:09,823 DPI set to 34 x 22 (wanted 96 x 96)
752018-07-08 10:59:09,823  you may experience scaling problems, such as huge or small fonts, etc
762018-07-08 10:59:09,823  to fix this issue, try the dpi switch, or use a patched Xorg dummy driver
772018-07-08 10:59:09,918 client @05.190 server does not support xi input devices
782018-07-08 10:59:09,920 client @05.191  server uses: xtest
792018-07-08 10:59:10,060 packet decoding sequence 1 for window 1: 734x459 took 7.3ms
802018-07-08 10:59:10,061 record_latency: took   12.7 ms round trip,   12.6 for echo,    7.0 for decoding of   336906 pixels,       57 bytes sent over the network in    5.4 ms,    5.3 ms for echo
812018-07-08 10:59:10,061 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
822018-07-08 10:59:10,063 calculate_batch_delay for wid=1 current batch delay=29, last update 425661 seconds ago
832018-07-08 10:59:10,064 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
842018-07-08 10:59:10,064 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 22, 'recent': 22}
852018-07-08 10:59:10,064 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 44}
862018-07-08 10:59:10,065 update_batch_delay: client-latency               : 0.70,0.33  {'target': 10, 'weight_multiplier': 604, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 485, 'div': 1000, 'avg': 5, 'recent': 5}
872018-07-08 10:59:10,065 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
882018-07-08 10:59:10,065 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
892018-07-08 10:59:10,066 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
902018-07-08 10:59:10,066 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
912018-07-08 10:59:10,067 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
922018-07-08 10:59:10,072 total_time=7262, total_pixels=336906
932018-07-08 10:59:10,072 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
942018-07-08 10:59:10,074 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
952018-07-08 10:59:10,074 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
962018-07-08 10:59:10,074 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
972018-07-08 10:59:10,075 update_batch_delay: delay=20
982018-07-08 10:59:10,259 packet decoding sequence 2 for window 1: 734x459 took 3.5ms
992018-07-08 10:59:10,260 record_latency: took    5.6 ms round trip,    5.5 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.1 ms,    2.0 ms for echo
1002018-07-08 10:59:10,260 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
1012018-07-08 10:59:10,617 packet decoding sequence 3 for window 1: 734x459 took 2.4ms
1022018-07-08 10:59:10,617 record_latency: took    4.6 ms round trip,    4.6 for echo,    2.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.3 ms,    2.2 ms for echo
1032018-07-08 10:59:10,647 packet decoding sequence 4 for window 1: 12x20 took 1.3ms
1042018-07-08 10:59:10,647 record_latency: took    3.2 ms round trip,    3.2 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.0 ms,    1.9 ms for echo
1052018-07-08 10:59:10,684 packet decoding sequence 5 for window 1: 12x20 took 3.7ms
1062018-07-08 10:59:10,684 record_latency: took    5.8 ms round trip,    5.8 for echo,    3.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.1 ms,    2.0 ms for echo
1072018-07-08 10:59:10,723 packet decoding sequence 6 for window 1: 12x20 took 1.0ms
1082018-07-08 10:59:10,723 record_latency: took    6.9 ms round trip,    6.8 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    5.8 ms,    5.8 ms for echo
1092018-07-08 10:59:11,063 calculate_batch_delay for wid=1 current batch delay=20, last update 1 seconds ago
1102018-07-08 10:59:11,063 calculate_batch_delay for wid=1, skipping - only 5 events since the last update
1112018-07-08 10:59:12,934 using pulseaudio device:
1122018-07-08 10:59:12,934  'Monitor of Xpra Speaker'
1132018-07-08 10:59:12,969 packet decoding sequence 7 for window 1: 12x20 took 14.3ms
1142018-07-08 10:59:12,969 record_latency: took 1294.6 ms round trip, 1294.5 for echo,   14.0 for decoding of      240 pixels,       59 bytes sent over the network in 1280.4 ms, 1280.3 ms for echo
1152018-07-08 10:59:12,970 networksend_congestion_event(late-ack for sequence      7: late by 1042ms, target latency=252 ((2, 0, 0, 250)), 129200, 0) 0Kbps (average=0Kbps)
1162018-07-08 10:59:12,970 packet decoding sequence 8 for window 1: 734x459 took 30.3ms
1172018-07-08 10:59:12,971 record_latency: took 1022.6 ms round trip, 1022.5 for echo,   30.0 for decoding of   336906 pixels,       61 bytes sent over the network in  992.3 ms,  992.2 ms for echo
1182018-07-08 10:59:12,971 networksend_congestion_event(late-ack for sequence      8: late by 767ms, target latency=255 ((2, 0, 3, 250)), 101700, 479) 0Kbps (average=0Kbps)
1192018-07-08 10:59:12,972 may_recalculate(1, 336906) total 337146 pixels, scheduling recalculate work item
1202018-07-08 10:59:12,972 packet decoding sequence 9 for window 1: 734x459 took 47.6ms
1212018-07-08 10:59:12,972 record_latency: took  720.3 ms round trip,  717.9 for echo,   47.0 for decoding of   336906 pixels,       61 bytes sent over the network in  672.7 ms,  670.3 ms for echo
1222018-07-08 10:59:12,973 calculate_batch_delay for wid=1 current batch delay=34, last update 2 seconds ago
1232018-07-08 10:59:12,974 networksend_congestion_event(late-ack for sequence      9: late by 464ms, target latency=255 ((2, 0, 3, 250)), 71600, 681) 0Kbps (average=0Kbps)
1242018-07-08 10:59:12,975 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 41, 'recent': 41}
1252018-07-08 10:59:12,975 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
1262018-07-08 10:59:12,975 update_batch_delay: client-decode-speed          : 2.06,0.53  {'avg': 30, 'recent': 95}
1272018-07-08 10:59:12,976 update_batch_delay: damage-rate                  : 0.63,0.61  {'max_latency': 100, 'elapsed': 572}
1282018-07-08 10:59:12,976 update_batch_delay: client-latency               : 4.41,0.80  {'target': 6, 'weight_multiplier': 432, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 19482, 'div': 1000, 'avg': 609, 'recent': 1082}
1292018-07-08 10:59:12,977 update_batch_delay: client-ping-latency          : 0.55,0.13  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 305, 'div': 1000, 'avg': 2, 'recent': 2}
1302018-07-08 10:59:12,977 packet decoding sequence 10 for window 1: 734x459 took 63.2ms
1312018-07-08 10:59:12,977 update_batch_delay: server-ping-latency          : 0.92,0.21  {'target': 32, 'weight_multiplier': 713, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 838, 'div': 1000, 'avg': 27, 'recent': 27}
1322018-07-08 10:59:12,978 record_latency: took  432.4 ms round trip,  432.4 for echo,   63.0 for decoding of   336906 pixels,       61 bytes sent over the network in  369.2 ms,  369.1 ms for echo
1332018-07-08 10:59:12,978 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
1342018-07-08 10:59:12,978 networksend_congestion_event(late-ack for sequence     10: late by 178ms, target latency=255 ((2, 0, 3, 250)), 42800, 1140) 1Kbps (average=0Kbps)
1352018-07-08 10:59:12,979 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
1362018-07-08 10:59:12,980 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
1372018-07-08 10:59:12,980 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
1382018-07-08 10:59:12,981 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
1392018-07-08 10:59:12,981 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
1402018-07-08 10:59:12,981 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
1412018-07-08 10:59:12,982 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
1422018-07-08 10:59:12,982 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
1432018-07-08 10:59:12,982 update_batch_delay: delay=77
1442018-07-08 10:59:13,463 packet decoding sequence 11 for window 1: 12x20 took 1.2ms
1452018-07-08 10:59:13,463 record_latency: took    3.2 ms round trip,    3.0 for echo,    1.0 for decoding of      240 pixels,       59 bytes sent over the network in    2.0 ms,    1.8 ms for echo
1462018-07-08 10:59:13,975 calculate_batch_delay for wid=1 current batch delay=77, last update 1 seconds ago
1472018-07-08 10:59:13,975 calculate_batch_delay for wid=1, skipping - only 1 events since the last update
1482018-07-08 10:59:15,367 sound source using 'opus' audio codec
1492018-07-08 10:59:15,420 packet decoding sequence 12 for window 1: 12x20 took 1.4ms
1502018-07-08 10:59:15,421 record_latency: took    4.1 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.7 ms,    2.6 ms for echo
1512018-07-08 10:59:15,505 packet decoding sequence 13 for window 1: 12x20 took 1.6ms
1522018-07-08 10:59:15,505 record_latency: took    4.8 ms round trip,    4.8 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.2 ms,    3.2 ms for echo
1532018-07-08 10:59:15,589 packet decoding sequence 14 for window 1: 12x20 took 1.5ms
1542018-07-08 10:59:15,589 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.8 ms,    2.7 ms for echo
1552018-07-08 10:59:16,271 packet decoding sequence 15 for window 1: 263x17 took 2.0ms
1562018-07-08 10:59:16,272 record_latency: took    5.6 ms round trip,    5.3 for echo,    2.0 for decoding of     4471 pixels,       57 bytes sent over the network in    3.5 ms,    3.2 ms for echo
1572018-07-08 10:59:16,272 may_recalculate(1, 4471) total 5191 pixels, scheduling recalculate work item
1582018-07-08 10:59:16,274 calculate_batch_delay for wid=1 current batch delay=77, last update 3 seconds ago
1592018-07-08 10:59:16,274 calculate_batch_delay for wid=1, skipping - only 5 events since the last update
1602018-07-08 10:59:19,724 total_time=182458, total_pixels=2027827
1612018-07-08 10:59:20,108 packet decoding sequence 16 for window 1: 12x20 took 3.2ms
1622018-07-08 10:59:20,108 record_latency: took    7.0 ms round trip,    6.9 for echo,    3.0 for decoding of      240 pixels,       63 bytes sent over the network in    3.8 ms,    3.7 ms for echo
1632018-07-08 10:59:20,110 packet decoding sequence 17 for window 1: 263x17 took 2.0ms
1642018-07-08 10:59:20,111 record_latency: took    8.3 ms round trip,    8.2 for echo,    2.0 for decoding of     4471 pixels,       57 bytes sent over the network in    6.3 ms,    6.2 ms for echo
1652018-07-08 10:59:20,111 may_recalculate(1, 4471) total 4711 pixels, scheduling recalculate work item
1662018-07-08 10:59:20,112 calculate_batch_delay for wid=1 current batch delay=77, last update 7 seconds ago
1672018-07-08 10:59:20,112 calculate_batch_delay for wid=1, skipping - only 10142 pixels updated since the last update
1682018-07-08 10:59:20,949 total_time=187644, total_pixels=2032538
1692018-07-08 10:59:21,959 total_time=187644, total_pixels=2032538
1702018-07-08 10:59:22,257 total_time=187644, total_pixels=2032538
1712018-07-08 10:59:22,362 total_time=187644, total_pixels=2032538
1722018-07-08 10:59:22,462 total_time=187644, total_pixels=2032538
1732018-07-08 10:59:22,563 total_time=187644, total_pixels=2032538
1742018-07-08 10:59:22,665 total_time=187644, total_pixels=2032538
1752018-07-08 10:59:22,764 total_time=187644, total_pixels=2032538
1762018-07-08 10:59:22,864 total_time=187644, total_pixels=2032538
1772018-07-08 10:59:22,966 total_time=187644, total_pixels=2032538
1782018-07-08 10:59:23,067 total_time=187644, total_pixels=2032538
1792018-07-08 10:59:23,168 total_time=187644, total_pixels=2032538
1802018-07-08 10:59:23,270 total_time=187644, total_pixels=2032538
1812018-07-08 10:59:23,369 total_time=187644, total_pixels=2032538
1822018-07-08 10:59:23,470 total_time=187644, total_pixels=2032538
1832018-07-08 10:59:23,568 total_time=187644, total_pixels=2032538
1842018-07-08 10:59:23,671 total_time=187644, total_pixels=2032538
1852018-07-08 10:59:23,770 total_time=187644, total_pixels=2032538
1862018-07-08 10:59:23,870 total_time=187644, total_pixels=2032538
1872018-07-08 10:59:23,974 total_time=187644, total_pixels=2032538
1882018-07-08 10:59:24,076 total_time=187644, total_pixels=2032538
1892018-07-08 10:59:24,177 total_time=187644, total_pixels=2032538
1902018-07-08 10:59:24,272 total_time=187644, total_pixels=2032538
1912018-07-08 10:59:24,376 total_time=187644, total_pixels=2032538
1922018-07-08 10:59:24,475 total_time=187644, total_pixels=2032538
1932018-07-08 10:59:24,577 total_time=187644, total_pixels=2032538
1942018-07-08 10:59:24,676 total_time=187644, total_pixels=2032538
1952018-07-08 10:59:24,777 total_time=187644, total_pixels=2032538
1962018-07-08 10:59:24,876 total_time=187644, total_pixels=2032538
1972018-07-08 10:59:24,979 total_time=187644, total_pixels=2032538
1982018-07-08 10:59:25,082 total_time=187644, total_pixels=2032538
1992018-07-08 10:59:25,139 packet decoding sequence 18 for window 1: 174x20 took 1.7ms
2002018-07-08 10:59:25,140 record_latency: took    6.6 ms round trip,    6.4 for echo,    1.0 for decoding of     3480 pixels,       57 bytes sent over the network in    4.9 ms,    4.6 ms for echo
2012018-07-08 10:59:25,141 may_recalculate(1, 3480) total 3480 pixels, scheduling recalculate work item
2022018-07-08 10:59:25,142 calculate_batch_delay for wid=1 current batch delay=77, last update 12 seconds ago
2032018-07-08 10:59:25,145 calculate_batch_delay for wid=1, skipping - only 13622 pixels updated since the last update
2042018-07-08 10:59:25,181 total_time=189382, total_pixels=2036018
2052018-07-08 10:59:25,233 packet decoding sequence 19 for window 1: 12x20 took 1.3ms
2062018-07-08 10:59:25,233 record_latency: took    5.1 ms round trip,    5.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2072018-07-08 10:59:25,282 total_time=190672, total_pixels=2036258
2082018-07-08 10:59:25,321 packet decoding sequence 20 for window 1: 12x20 took 0.9ms
2092018-07-08 10:59:25,322 record_latency: took    3.2 ms round trip,    3.2 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.3 ms,    2.3 ms for echo
2102018-07-08 10:59:25,403 total_time=191576, total_pixels=2036498
2112018-07-08 10:59:25,464 packet decoding sequence 21 for window 1: 12x20 took 1.4ms
2122018-07-08 10:59:25,465 record_latency: took   60.7 ms round trip,   60.5 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   59.4 ms,   59.1 ms for echo
2132018-07-08 10:59:25,489 total_time=192948, total_pixels=2036738
2142018-07-08 10:59:25,594 total_time=192948, total_pixels=2036738
2152018-07-08 10:59:25,595 packet decoding sequence 1 for window 2: 312x259 took 3.8ms
2162018-07-08 10:59:25,595 record_latency: took   13.7 ms round trip,   13.6 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    9.9 ms,    9.8 ms for echo
2172018-07-08 10:59:25,596 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2182018-07-08 10:59:25,691 total_time=196722, total_pixels=2117546
2192018-07-08 10:59:25,700 packet decoding sequence 22 for window 1: 734x459 took 8.2ms
2202018-07-08 10:59:25,700 record_latency: took   22.7 ms round trip,   22.6 for echo,    8.0 for decoding of   336906 pixels,       61 bytes sent over the network in   14.5 ms,   14.5 ms for echo
2212018-07-08 10:59:25,700 may_recalculate(1, 336906) total 337626 pixels, scheduling recalculate work item
2222018-07-08 10:59:25,789 total_time=204910, total_pixels=2454452
2232018-07-08 10:59:25,889 total_time=204910, total_pixels=2454452
2242018-07-08 10:59:25,994 total_time=204910, total_pixels=2454452
2252018-07-08 10:59:26,091 total_time=204910, total_pixels=2454452
2262018-07-08 10:59:26,142 calculate_batch_delay for wid=1 current batch delay=77, last update 13 seconds ago
2272018-07-08 10:59:26,142 calculate_batch_delay for wid=1, 351248 pixels updated since the last update
2282018-07-08 10:59:26,143 calculate_batch_delay for wid=1, skipping - only 12 bytes sent since the last update
2292018-07-08 10:59:26,143 calculate_batch_delay for wid=2 current batch delay=64, last update 425677 seconds ago
2302018-07-08 10:59:26,144 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2312018-07-08 10:59:26,144 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 48, 'recent': 48}
2322018-07-08 10:59:26,144 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 130, 'elapsed': 97}
2332018-07-08 10:59:26,144 update_batch_delay: client-latency               : 0.74,0.22  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 549, 'div': 1000, 'avg': 60, 'recent': 19}
2342018-07-08 10:59:26,144 update_batch_delay: client-ping-latency          : 0.73,0.10  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 539, 'div': 1000, 'avg': 3, 'recent': 4}
2352018-07-08 10:59:26,145 update_batch_delay: server-ping-latency          : 0.51,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 258, 'div': 1000, 'avg': 3, 'recent': 2}
2362018-07-08 10:59:26,145 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2372018-07-08 10:59:26,145 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2382018-07-08 10:59:26,145 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2392018-07-08 10:59:26,145 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2402018-07-08 10:59:26,146 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2412018-07-08 10:59:26,146 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2422018-07-08 10:59:26,146 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2432018-07-08 10:59:26,146 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2442018-07-08 10:59:26,146 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2452018-07-08 10:59:26,147 update_batch_delay: delay=45
2462018-07-08 10:59:26,186 total_time=204910, total_pixels=2454452
2472018-07-08 10:59:26,224 packet decoding sequence 2 for window 2: 312x259 took 1.5ms
2482018-07-08 10:59:26,225 record_latency: took   26.5 ms round trip,   26.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   24.9 ms,   24.9 ms for echo
2492018-07-08 10:59:26,225 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2502018-07-08 10:59:26,296 total_time=206452, total_pixels=2535260
2512018-07-08 10:59:26,297 packet decoding sequence 3 for window 2: 312x259 took 1.6ms
2522018-07-08 10:59:26,297 record_latency: took   17.2 ms round trip,   17.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   15.6 ms,   15.5 ms for echo
2532018-07-08 10:59:26,395 total_time=208071, total_pixels=2616068
2542018-07-08 10:59:26,424 packet decoding sequence 4 for window 2: 312x259 took 51.5ms
2552018-07-08 10:59:26,424 record_latency: took   54.1 ms round trip,   54.0 for echo,   51.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.6 ms,    2.6 ms for echo
2562018-07-08 10:59:26,496 total_time=259523, total_pixels=2696876
2572018-07-08 10:59:26,523 packet decoding sequence 5 for window 2: 312x259 took 1.5ms
2582018-07-08 10:59:26,524 record_latency: took    7.7 ms round trip,    7.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.1 ms,    6.1 ms for echo
2592018-07-08 10:59:26,575 packet decoding sequence 6 for window 2: 312x259 took 2.2ms
2602018-07-08 10:59:26,577 record_latency: took    7.9 ms round trip,    7.7 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    5.8 ms,    5.6 ms for echo
2612018-07-08 10:59:26,598 total_time=263225, total_pixels=2858492
2622018-07-08 10:59:26,673 packet decoding sequence 7 for window 2: 312x259 took 2.5ms
2632018-07-08 10:59:26,674 record_latency: took    5.3 ms round trip,    4.8 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.3 ms for echo
2642018-07-08 10:59:26,691 total_time=265684, total_pixels=2939300
2652018-07-08 10:59:26,797 total_time=265684, total_pixels=2939300
2662018-07-08 10:59:26,871 packet decoding sequence 8 for window 2: 312x259 took 2.6ms
2672018-07-08 10:59:26,871 record_latency: took    5.4 ms round trip,    5.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.8 ms for echo
2682018-07-08 10:59:26,899 total_time=268263, total_pixels=3020108
2692018-07-08 10:59:27,016 total_time=268263, total_pixels=3020108
2702018-07-08 10:59:27,047 packet decoding sequence 1 for window 3: 237x156 took 3.3ms
2712018-07-08 10:59:27,047 record_latency: took   34.8 ms round trip,   34.7 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in   31.5 ms,   31.4 ms for echo
2722018-07-08 10:59:27,047 may_recalculate(3, 36972) total 36972 pixels, scheduling recalculate work item
2732018-07-08 10:59:27,112 total_time=271566, total_pixels=3057080
2742018-07-08 10:59:27,143 calculate_batch_delay for wid=3 current batch delay=69, last update 425678 seconds ago
2752018-07-08 10:59:27,143 packet decoding sequence 23 for window 1: 190x25 took 1.5ms
2762018-07-08 10:59:27,144 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2772018-07-08 10:59:27,144 record_latency: took    5.1 ms round trip,    5.0 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    3.6 ms,    3.5 ms for echo
2782018-07-08 10:59:27,145 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 93, 'recent': 93}
2792018-07-08 10:59:27,145 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
2802018-07-08 10:59:27,145 update_batch_delay: damage-rate                  : 1.00,0.05  {'max_latency': 100, 'elapsed': 202}
2812018-07-08 10:59:27,146 update_batch_delay: client-latency               : 0.74,0.22  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 554, 'div': 1000, 'avg': 36, 'recent': 15}
2822018-07-08 10:59:27,146 update_batch_delay: client-ping-latency          : 0.65,0.11  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 422, 'div': 1000, 'avg': 3, 'recent': 3}
2832018-07-08 10:59:27,146 update_batch_delay: server-ping-latency          : 0.56,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 316, 'div': 1000, 'avg': 3, 'recent': 2}
2842018-07-08 10:59:27,147 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2852018-07-08 10:59:27,147 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
2862018-07-08 10:59:27,147 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2872018-07-08 10:59:27,147 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
2882018-07-08 10:59:27,147 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2892018-07-08 10:59:27,147 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2902018-07-08 10:59:27,147 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2912018-07-08 10:59:27,148 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2922018-07-08 10:59:27,148 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2932018-07-08 10:59:27,148 update_batch_delay: delay=49
2942018-07-08 10:59:27,216 total_time=273028, total_pixels=3061830
2952018-07-08 10:59:27,317 total_time=273028, total_pixels=3061830
2962018-07-08 10:59:27,416 total_time=273028, total_pixels=3061830
2972018-07-08 10:59:27,518 total_time=273028, total_pixels=3061830
2982018-07-08 10:59:27,617 total_time=273028, total_pixels=3061830
2992018-07-08 10:59:27,661 packet decoding sequence 2 for window 3: 237x156 took 1.2ms
3002018-07-08 10:59:27,661 record_latency: took   37.1 ms round trip,   37.0 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   35.9 ms,   35.8 ms for echo
3012018-07-08 10:59:27,662 may_recalculate(3, 36972) total 36972 pixels, scheduling recalculate work item
3022018-07-08 10:59:27,695 packet decoding sequence 3 for window 3: 237x156 took 2.3ms
3032018-07-08 10:59:27,696 record_latency: took    7.1 ms round trip,    7.0 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.8 ms,    4.7 ms for echo
3042018-07-08 10:59:27,714 total_time=276470, total_pixels=3135774
3052018-07-08 10:59:27,817 total_time=276470, total_pixels=3135774
3062018-07-08 10:59:27,916 total_time=276470, total_pixels=3135774
3072018-07-08 10:59:27,957 packet decoding sequence 4 for window 3: 237x156 took 2.5ms
3082018-07-08 10:59:27,957 record_latency: took    6.7 ms round trip,    6.5 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.2 ms,    4.1 ms for echo
3092018-07-08 10:59:28,018 total_time=278932, total_pixels=3172746
3102018-07-08 10:59:28,064 packet decoding sequence 5 for window 3: 237x156 took 3.3ms
3112018-07-08 10:59:28,065 record_latency: took   36.2 ms round trip,   36.2 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in   33.0 ms,   32.9 ms for echo
3122018-07-08 10:59:28,126 total_time=282188, total_pixels=3209718
3132018-07-08 10:59:28,143 calculate_batch_delay for wid=1 current batch delay=77, last update 15 seconds ago
3142018-07-08 10:59:28,144 calculate_batch_delay for wid=1, 355998 pixels updated since the last update
3152018-07-08 10:59:28,144 calculate_batch_delay for wid=1, skipping - only 13 bytes sent since the last update
3162018-07-08 10:59:28,145 calculate_batch_delay for wid=3 current batch delay=49, last update 1 seconds ago
3172018-07-08 10:59:28,146 calculate_batch_delay for wid=3, skipping - only 5 events since the last update
3182018-07-08 10:59:28,167 packet decoding sequence 6 for window 3: 237x156 took 1.2ms
3192018-07-08 10:59:28,168 record_latency: took   49.8 ms round trip,   49.2 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   48.6 ms,   48.0 ms for echo
3202018-07-08 10:59:28,168 may_recalculate(3, 36972) total 36972 pixels, scheduling recalculate work item
3212018-07-08 10:59:28,218 total_time=283405, total_pixels=3246690
3222018-07-08 10:59:28,332 total_time=283405, total_pixels=3246690
3232018-07-08 10:59:28,426 total_time=283405, total_pixels=3246690
3242018-07-08 10:59:28,537 total_time=283405, total_pixels=3246690
3252018-07-08 10:59:28,587 packet decoding sequence 24 for window 1: 234x25 took 104.5ms
3262018-07-08 10:59:28,587 record_latency: took  123.8 ms round trip,  123.7 for echo,  104.0 for decoding of     5850 pixels,       57 bytes sent over the network in   19.3 ms,   19.3 ms for echo
3272018-07-08 10:59:28,589 may_recalculate(1, 5850) total 5850 pixels, scheduling recalculate work item
3282018-07-08 10:59:28,630 total_time=489886, total_pixels=3294984
3292018-07-08 10:59:28,730 total_time=489886, total_pixels=3294984
3302018-07-08 10:59:28,776 packet decoding sequence 1 for window 6: 130x54 took 61.5ms
3312018-07-08 10:59:28,777 record_latency: took   69.2 ms round trip,   69.1 for echo,   61.0 for decoding of     7020 pixels,       57 bytes sent over the network in    7.7 ms,    7.6 ms for echo
3322018-07-08 10:59:28,777 may_recalculate(6, 7020) total 7020 pixels, scheduling recalculate work item
3332018-07-08 10:59:28,781 packet decoding sequence 25 for window 1: 279x25 took 3.6ms
3342018-07-08 10:59:28,782 record_latency: took   26.9 ms round trip,   26.9 for echo,    3.0 for decoding of     6975 pixels,       57 bytes sent over the network in   23.3 ms,   23.3 ms for echo
3352018-07-08 10:59:28,834 total_time=554977, total_pixels=3308979
3362018-07-08 10:59:28,939 total_time=554977, total_pixels=3308979
3372018-07-08 10:59:28,984 packet decoding sequence 2 for window 6: 130x54 took 1.1ms
3382018-07-08 10:59:28,985 record_latency: took    3.5 ms round trip,    3.4 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    2.4 ms,    2.3 ms for echo
3392018-07-08 10:59:29,029 total_time=556081, total_pixels=3315999
3402018-07-08 10:59:29,094 packet decoding sequence 3 for window 6: 130x54 took 1.3ms
3412018-07-08 10:59:29,094 record_latency: took    4.7 ms round trip,    4.6 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    3.4 ms,    3.3 ms for echo
3422018-07-08 10:59:29,134 total_time=557387, total_pixels=3323019
3432018-07-08 10:59:29,144 calculate_batch_delay for wid=1 current batch delay=77, last update 16 seconds ago
3442018-07-08 10:59:29,145 calculate_batch_delay for wid=1, 368823 pixels updated since the last update
3452018-07-08 10:59:29,145 calculate_batch_delay for wid=1, skipping - only 15 bytes sent since the last update
3462018-07-08 10:59:29,145 calculate_batch_delay for wid=6 current batch delay=34, last update 425680 seconds ago
3472018-07-08 10:59:29,146 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
3482018-07-08 10:59:29,146 update_batch_delay: client-decode-speed          : 0.92,0.09  {'avg': 249, 'recent': 222}
3492018-07-08 10:59:29,146 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 157, 'elapsed': 52}
3502018-07-08 10:59:29,147 update_batch_delay: client-latency               : 0.64,0.26  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 415, 'div': 1000, 'avg': 30, 'recent': 10}
3512018-07-08 10:59:29,147 update_batch_delay: client-ping-latency          : 0.70,0.10  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 483, 'div': 1000, 'avg': 4, 'recent': 4}
3522018-07-08 10:59:29,147 update_batch_delay: server-ping-latency          : 0.47,0.12  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 221, 'div': 1000, 'avg': 3, 'recent': 1}
3532018-07-08 10:59:29,147 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
3542018-07-08 10:59:29,148 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 7020000, 'avg': 0, 'recent': 0}
3552018-07-08 10:59:29,148 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
3562018-07-08 10:59:29,148 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 7020000, 'avg': 0, 'recent': 0}
3572018-07-08 10:59:29,148 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
3582018-07-08 10:59:29,148 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
3592018-07-08 10:59:29,149 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
3602018-07-08 10:59:29,149 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
3612018-07-08 10:59:29,149 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
3622018-07-08 10:59:29,149 update_batch_delay: delay=24
3632018-07-08 10:59:29,235 total_time=557387, total_pixels=3323019
3642018-07-08 10:59:29,291 packet decoding sequence 4 for window 6: 130x54 took 1.2ms
3652018-07-08 10:59:29,291 record_latency: took    4.1 ms round trip,    4.0 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    2.9 ms,    2.8 ms for echo
3662018-07-08 10:59:29,292 may_recalculate(6, 7020) total 7020 pixels, scheduling recalculate work item
3672018-07-08 10:59:29,339 total_time=558625, total_pixels=3330039
3682018-07-08 10:59:29,438 total_time=558625, total_pixels=3330039
3692018-07-08 10:59:29,483 packet decoding sequence 5 for window 6: 130x54 took 1.0ms
3702018-07-08 10:59:29,483 record_latency: took   42.5 ms round trip,   41.7 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in   41.5 ms,   40.6 ms for echo
3712018-07-08 10:59:29,494 packet decoding sequence 6 for window 6: 130x54 took 2.7ms
3722018-07-08 10:59:29,495 record_latency: took    5.2 ms round trip,    4.9 for echo,    2.0 for decoding of     7020 pixels,       57 bytes sent over the network in    2.5 ms,    2.3 ms for echo
3732018-07-08 10:59:29,537 total_time=562327, total_pixels=3344079
3742018-07-08 10:59:29,650 total_time=562327, total_pixels=3344079
3752018-07-08 10:59:29,748 total_time=562327, total_pixels=3344079
3762018-07-08 10:59:29,887 total_time=562327, total_pixels=3344079
3772018-07-08 10:59:29,928 packet decoding sequence 26 for window 1: 279x25 took 1.8ms
3782018-07-08 10:59:29,928 record_latency: took  105.0 ms round trip,  104.9 for echo,    1.0 for decoding of     6975 pixels,       57 bytes sent over the network in  103.1 ms,  103.1 ms for echo
3792018-07-08 10:59:29,928 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
3802018-07-08 10:59:29,932 packet decoding sequence 1 for window 8: 248x181 took 4.7ms
3812018-07-08 10:59:29,933 record_latency: took   49.7 ms round trip,   49.2 for echo,    4.0 for decoding of    44888 pixels,       60 bytes sent over the network in   44.9 ms,   44.5 ms for echo
3822018-07-08 10:59:29,933 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
3832018-07-08 10:59:29,946 packet decoding sequence 27 for window 1: 76x25 took 1.5ms
3842018-07-08 10:59:29,947 record_latency: took    4.6 ms round trip,    4.5 for echo,    1.0 for decoding of     1900 pixels,       56 bytes sent over the network in    3.0 ms,    3.0 ms for echo
3852018-07-08 10:59:29,975 total_time=570420, total_pixels=3397842
3862018-07-08 10:59:30,080 total_time=570420, total_pixels=3397842
3872018-07-08 10:59:30,144 calculate_batch_delay for wid=8 current batch delay=94, last update 425681 seconds ago
3882018-07-08 10:59:30,145 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
3892018-07-08 10:59:30,145 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 110, 'recent': 110}
3902018-07-08 10:59:30,146 update_batch_delay: damage-rate                  : 0.84,0.40  {'max_latency': 100, 'elapsed': 359}
3912018-07-08 10:59:30,146 update_batch_delay: client-latency               : 1.18,0.18  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1395, 'div': 1000, 'avg': 36, 'recent': 38}
3922018-07-08 10:59:30,146 update_batch_delay: client-ping-latency          : 0.71,0.10  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 505, 'div': 1000, 'avg': 4, 'recent': 4}
3932018-07-08 10:59:30,146 update_batch_delay: server-ping-latency          : 0.57,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 320, 'div': 1000, 'avg': 3, 'recent': 2}
3942018-07-08 10:59:30,146 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
3952018-07-08 10:59:30,147 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 44888000, 'avg': 0, 'recent': 0}
3962018-07-08 10:59:30,147 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
3972018-07-08 10:59:30,147 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 44888000, 'avg': 0, 'recent': 0}
3982018-07-08 10:59:30,147 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
3992018-07-08 10:59:30,148 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
4002018-07-08 10:59:30,150 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
4012018-07-08 10:59:30,150 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
4022018-07-08 10:59:30,150 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
4032018-07-08 10:59:30,151 update_batch_delay: delay=70
4042018-07-08 10:59:30,153 calculate_batch_delay for wid=1 current batch delay=77, last update 17 seconds ago
4052018-07-08 10:59:30,154 calculate_batch_delay for wid=1, 377698 pixels updated since the last update
4062018-07-08 10:59:30,155 calculate_batch_delay for wid=1, skipping - only 17 bytes sent since the last update
4072018-07-08 10:59:30,179 total_time=570420, total_pixels=3397842
4082018-07-08 10:59:30,252 packet decoding sequence 2 for window 8: 248x181 took 1.7ms
4092018-07-08 10:59:30,252 record_latency: took    4.7 ms round trip,    4.5 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    3.0 ms,    2.8 ms for echo
4102018-07-08 10:59:30,253 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
4112018-07-08 10:59:30,281 total_time=572103, total_pixels=3442730
4122018-07-08 10:59:30,351 packet decoding sequence 3 for window 8: 248x181 took 1.6ms
4132018-07-08 10:59:30,351 record_latency: took    6.7 ms round trip,    6.6 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    5.1 ms,    5.1 ms for echo
4142018-07-08 10:59:30,378 total_time=573659, total_pixels=3487618
4152018-07-08 10:59:30,433 packet decoding sequence 4 for window 8: 248x181 took 2.1ms
4162018-07-08 10:59:30,433 record_latency: took   12.4 ms round trip,   12.4 for echo,    2.0 for decoding of    44888 pixels,       60 bytes sent over the network in   10.3 ms,   10.2 ms for echo
4172018-07-08 10:59:30,482 total_time=575808, total_pixels=3532506
4182018-07-08 10:59:30,518 packet decoding sequence 5 for window 8: 248x181 took 1.2ms
4192018-07-08 10:59:30,519 record_latency: took    3.4 ms round trip,    3.4 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.2 ms,    2.2 ms for echo
4202018-07-08 10:59:30,577 total_time=577005, total_pixels=3577394
4212018-07-08 10:59:30,684 total_time=577005, total_pixels=3577394
4222018-07-08 10:59:30,732 packet decoding sequence 6 for window 8: 248x181 took 1.6ms
4232018-07-08 10:59:30,733 record_latency: took   14.3 ms round trip,   14.3 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   12.8 ms,   12.7 ms for echo
4242018-07-08 10:59:30,781 total_time=578573, total_pixels=3622282
4252018-07-08 10:59:30,826 packet decoding sequence 7 for window 8: 248x181 took 1.2ms
4262018-07-08 10:59:30,827 record_latency: took    3.0 ms round trip,    3.0 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    1.8 ms,    1.7 ms for echo
4272018-07-08 10:59:30,913 total_time=579817, total_pixels=3667170
4282018-07-08 10:59:30,985 total_time=579817, total_pixels=3667170
4292018-07-08 10:59:31,084 total_time=579817, total_pixels=3667170
4302018-07-08 10:59:31,139 packet decoding sequence 1 for window 9: 237x156 took 2.1ms
4312018-07-08 10:59:31,139 record_latency: took  165.6 ms round trip,  165.5 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in  163.6 ms,  163.4 ms for echo
4322018-07-08 10:59:31,140 may_recalculate(9, 36972) total 36972 pixels, scheduling recalculate work item
4332018-07-08 10:59:31,141 packet decoding sequence 28 for window 1: 76x25 took 4.7ms
4342018-07-08 10:59:31,142 record_latency: took   89.0 ms round trip,   89.0 for echo,    4.0 for decoding of     1900 pixels,       56 bytes sent over the network in   84.3 ms,   84.3 ms for echo
4352018-07-08 10:59:31,145 calculate_batch_delay for wid=9 current batch delay=77, last update 425682 seconds ago
4362018-07-08 10:59:31,145 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
4372018-07-08 10:59:31,146 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 58, 'recent': 58}
4382018-07-08 10:59:31,146 update_batch_delay: damage-rate                  : 0.95,0.23  {'max_latency': 100, 'elapsed': 254}
4392018-07-08 10:59:31,148 update_batch_delay: client-latency               : 1.52,0.30  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2311, 'div': 1000, 'avg': 42, 'recent': 68}
4402018-07-08 10:59:31,148 update_batch_delay: client-ping-latency          : 0.97,0.03  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 938, 'div': 1000, 'avg': 8, 'recent': 12}
4412018-07-08 10:59:31,148 update_batch_delay: server-ping-latency          : 0.59,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 344, 'div': 1000, 'avg': 3, 'recent': 2}
4422018-07-08 10:59:31,148 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
4432018-07-08 10:59:31,149 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
4442018-07-08 10:59:31,149 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
4452018-07-08 10:59:31,149 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
4462018-07-08 10:59:31,149 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
4472018-07-08 10:59:31,149 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
4482018-07-08 10:59:31,149 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
4492018-07-08 10:59:31,149 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
4502018-07-08 10:59:31,149 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
4512018-07-08 10:59:31,150 update_batch_delay: delay=61
4522018-07-08 10:59:31,185 total_time=586572, total_pixels=3706042
4532018-07-08 10:59:31,291 total_time=586572, total_pixels=3706042
4542018-07-08 10:59:31,402 total_time=586572, total_pixels=3706042
4552018-07-08 10:59:31,459 packet decoding sequence 1 for window 10: 248x181 took 1.7ms
4562018-07-08 10:59:31,459 record_latency: took   99.7 ms round trip,   99.6 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   98.0 ms,   97.9 ms for echo
4572018-07-08 10:59:31,460 may_recalculate(10, 44888) total 44888 pixels, scheduling recalculate work item
4582018-07-08 10:59:31,461 packet decoding sequence 29 for window 1: 76x25 took 3.5ms
4592018-07-08 10:59:31,461 record_latency: took   31.8 ms round trip,   31.7 for echo,    3.0 for decoding of     1900 pixels,       56 bytes sent over the network in   28.3 ms,   28.3 ms for echo
4602018-07-08 10:59:31,462 may_recalculate(1, 1900) total 3800 pixels, scheduling recalculate work item
4612018-07-08 10:59:31,505 total_time=591725, total_pixels=3752830
4622018-07-08 10:59:31,604 total_time=591725, total_pixels=3752830
4632018-07-08 10:59:31,707 total_time=591725, total_pixels=3752830
4642018-07-08 10:59:31,807 total_time=591725, total_pixels=3752830
4652018-07-08 10:59:31,908 total_time=591725, total_pixels=3752830
4662018-07-08 10:59:32,007 total_time=591725, total_pixels=3752830
4672018-07-08 10:59:32,110 total_time=591725, total_pixels=3752830
4682018-07-08 10:59:32,145 calculate_batch_delay for wid=1 current batch delay=77, last update 19 seconds ago
4692018-07-08 10:59:32,145 calculate_batch_delay for wid=1, 381498 pixels updated since the last update
4702018-07-08 10:59:32,145 calculate_batch_delay for wid=1, skipping - only 19 bytes sent since the last update
4712018-07-08 10:59:32,146 calculate_batch_delay for wid=10 current batch delay=87, last update 425683 seconds ago
4722018-07-08 10:59:32,147 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
4732018-07-08 10:59:32,147 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 39, 'recent': 39}
4742018-07-08 10:59:32,147 update_batch_delay: damage-rate                  : 0.33,0.82  {'max_latency': 100, 'elapsed': 874}
4752018-07-08 10:59:32,147 update_batch_delay: client-latency               : 1.33,0.24  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1766, 'div': 1000, 'avg': 44, 'recent': 53}
4762018-07-08 10:59:32,147 update_batch_delay: client-ping-latency          : 0.88,0.06  {'target': 6, 'weight_multiplier': 188, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 782, 'div': 1000, 'avg': 8, 'recent': 10}
4772018-07-08 10:59:32,148 update_batch_delay: server-ping-latency          : 0.68,0.09  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 458, 'div': 1000, 'avg': 3, 'recent': 4}
4782018-07-08 10:59:32,148 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
4792018-07-08 10:59:32,148 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 44888000, 'avg': 0, 'recent': 0}
4802018-07-08 10:59:32,148 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
4812018-07-08 10:59:32,148 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 44888000, 'avg': 0, 'recent': 0}
4822018-07-08 10:59:32,148 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
4832018-07-08 10:59:32,149 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
4842018-07-08 10:59:32,149 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
4852018-07-08 10:59:32,149 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
4862018-07-08 10:59:32,149 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
4872018-07-08 10:59:32,149 update_batch_delay: delay=57
4882018-07-08 10:59:32,208 total_time=591725, total_pixels=3752830
4892018-07-08 10:59:32,309 total_time=591725, total_pixels=3752830
4902018-07-08 10:59:32,413 total_time=591725, total_pixels=3752830
4912018-07-08 10:59:32,510 total_time=591725, total_pixels=3752830
4922018-07-08 10:59:32,609 total_time=591725, total_pixels=3752830
4932018-07-08 10:59:32,709 total_time=591725, total_pixels=3752830
4942018-07-08 10:59:32,814 total_time=591725, total_pixels=3752830
4952018-07-08 10:59:32,916 total_time=591725, total_pixels=3752830
4962018-07-08 10:59:33,014 total_time=591725, total_pixels=3752830
4972018-07-08 10:59:33,115 total_time=591725, total_pixels=3752830
4982018-07-08 10:59:33,217 total_time=591725, total_pixels=3752830
4992018-07-08 10:59:33,315 total_time=591725, total_pixels=3752830
5002018-07-08 10:59:33,346 packet decoding sequence 30 for window 1: 734x459 took 4.3ms
5012018-07-08 10:59:33,347 record_latency: took   48.3 ms round trip,   48.2 for echo,    4.0 for decoding of   336906 pixels,       61 bytes sent over the network in   44.0 ms,   43.9 ms for echo
5022018-07-08 10:59:33,347 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
5032018-07-08 10:59:33,348 calculate_batch_delay for wid=1 current batch delay=77, last update 20 seconds ago
5042018-07-08 10:59:33,349 calculate_batch_delay for wid=1, 718404 pixels updated since the last update
5052018-07-08 10:59:33,350 calculate_batch_delay for wid=1, skipping - only 20 bytes sent since the last update
5062018-07-08 10:59:33,420 total_time=596015, total_pixels=4089736
5072018-07-08 10:59:33,517 total_time=596015, total_pixels=4089736
5082018-07-08 10:59:33,619 total_time=596015, total_pixels=4089736
5092018-07-08 10:59:33,720 total_time=596015, total_pixels=4089736
5102018-07-08 10:59:33,820 total_time=596015, total_pixels=4089736
5112018-07-08 10:59:33,923 total_time=596015, total_pixels=4089736
5122018-07-08 10:59:33,975 packet decoding sequence 31 for window 1: 12x20 took 1.1ms
5132018-07-08 10:59:33,976 record_latency: took    3.1 ms round trip,    3.0 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    2.0 ms,    1.9 ms for echo
5142018-07-08 10:59:34,021 total_time=597109, total_pixels=4089976
5152018-07-08 10:59:34,121 total_time=597109, total_pixels=4089976
5162018-07-08 10:59:34,222 total_time=597109, total_pixels=4089976
5172018-07-08 10:59:34,323 total_time=597109, total_pixels=4089976
5182018-07-08 10:59:34,428 total_time=597109, total_pixels=4089976
5192018-07-08 10:59:34,527 total_time=597109, total_pixels=4089976
5202018-07-08 10:59:34,629 total_time=597109, total_pixels=4089976
5212018-07-08 10:59:34,729 total_time=597109, total_pixels=4089976
5222018-07-08 10:59:34,827 total_time=597109, total_pixels=4089976
5232018-07-08 10:59:34,927 total_time=597109, total_pixels=4089976
5242018-07-08 10:59:35,027 total_time=597109, total_pixels=4089976
5252018-07-08 10:59:35,128 total_time=597109, total_pixels=4089976
5262018-07-08 10:59:35,226 total_time=597109, total_pixels=4089976
5272018-07-08 10:59:35,329 total_time=597109, total_pixels=4089976
5282018-07-08 10:59:35,431 total_time=597109, total_pixels=4089976
5292018-07-08 10:59:35,530 total_time=597109, total_pixels=4089976
5302018-07-08 10:59:35,632 total_time=597109, total_pixels=4089976
5312018-07-08 10:59:35,732 total_time=597109, total_pixels=4089976
5322018-07-08 10:59:35,833 total_time=597109, total_pixels=4089976
5332018-07-08 10:59:35,933 total_time=597109, total_pixels=4089976
5342018-07-08 10:59:36,034 total_time=597109, total_pixels=4089976
5352018-07-08 10:59:36,134 total_time=597109, total_pixels=4089976
5362018-07-08 10:59:36,235 total_time=597109, total_pixels=4089976
5372018-07-08 10:59:36,333 total_time=597109, total_pixels=4089976
5382018-07-08 10:59:36,436 total_time=597109, total_pixels=4089976
5392018-07-08 10:59:36,538 total_time=597109, total_pixels=4089976
5402018-07-08 10:59:36,643 total_time=597109, total_pixels=4089976
5412018-07-08 10:59:36,740 total_time=597109, total_pixels=4089976
5422018-07-08 10:59:36,839 total_time=597109, total_pixels=4089976
5432018-07-08 10:59:36,940 total_time=597109, total_pixels=4089976
5442018-07-08 10:59:37,041 total_time=597109, total_pixels=4089976
5452018-07-08 10:59:37,143 total_time=597109, total_pixels=4089976
5462018-07-08 10:59:37,242 total_time=597109, total_pixels=4089976
5472018-07-08 10:59:37,347 total_time=597109, total_pixels=4089976
5482018-07-08 10:59:37,445 total_time=597109, total_pixels=4089976
5492018-07-08 10:59:37,551 total_time=597109, total_pixels=4089976
5502018-07-08 10:59:37,645 total_time=597109, total_pixels=4089976
5512018-07-08 10:59:37,746 total_time=597109, total_pixels=4089976
5522018-07-08 10:59:37,850 total_time=597109, total_pixels=4089976
5532018-07-08 10:59:37,946 total_time=597109, total_pixels=4089976
5542018-07-08 10:59:38,051 total_time=597109, total_pixels=4089976
5552018-07-08 10:59:38,154 total_time=597109, total_pixels=4089976
5562018-07-08 10:59:38,254 total_time=597109, total_pixels=4089976
5572018-07-08 10:59:38,352 total_time=597109, total_pixels=4089976
5582018-07-08 10:59:38,381 New unix-domain connection received on /run/user/1000/xpra/beelink-1
5592018-07-08 10:59:38,384 Handshake complete; enabling connection
5602018-07-08 10:59:38,385 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1):
5612018-07-08 10:59:38,386  new client (new connection from the same uuid)
5622018-07-08 10:59:38,390 sound source stopping
5632018-07-08 10:59:38,400 xpra client 1 disconnected.
5642018-07-08 10:59:38,401 get_client_bandwidth_limit(Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1))=0
5652018-07-08 10:59:38,405 server bandwidth-limit=0, client bandwidth-limit=0, value=0
5662018-07-08 10:59:38,406 Python2/GObject client version 2.3.2-r19729 64-bit
5672018-07-08 10:59:38,407  connected from 'beelink' as 'steved' - 'Steve Dee'
5682018-07-08 10:59:38,431 Shutting down in response to client request
5692018-07-08 10:59:38,431 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1):
5702018-07-08 10:59:38,432  server shutdown
5712018-07-08 10:59:38,433 xpra client 2 disconnected.
5722018-07-08 10:59:38,779 New unix-domain connection received on /run/user/1000/xpra/beelink-1
5732018-07-08 10:59:38,779 New unix-domain connection received on /home/steved/.xpra/beelink-1
5742018-07-08 10:59:38,937 stopping pulseaudio with pid 11852
5752018-07-08 10:59:39,474 killing xvfb with pid 11806
5762018-07-08 10:59:39,475 removing socket /run/user/1000/xpra/beelink-1
5772018-07-08 10:59:39,476 removing socket /home/steved/.xpra/beelink-1
578Gdk-Message: 10:59:39.479: xfce4-terminal: Fatal IO error 11 (Resource temporarily unavailable) on X server :1.
579
580(II) Server terminated successfully (0). Closing log file.