xpra icon
Bug tracker and wiki

This bug tracker and wiki are being discontinued
please use https://github.com/Xpra-org/xpra instead.


Ticket #1911: xpra-1804-local.txt

File xpra-1804-local.txt, 151.3 KB (added by tc424, 3 years ago)

stats log for local connection on 18.04

Line 
12018-07-07 22:02:45,753 cannot access python uinput module:
22018-07-07 22:02:45,753  No module named uinput
3_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
4_XSERVTransMakeAllCOTSServerListeners: server already running
5Failed to rename log file "/run/user/1000/xpra/Xorg.S28882.log" to "/run/user/1000/xpra/Xorg.S28882.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.S28882.log", Time: Sat Jul  7 22:02:45 2018
22(++) Using config file: "/etc/xpra/xorg.conf"
23(==) Using system config directory "/usr/share/X11/xorg.conf.d"
242018-07-07 22:02:52,342 created unix domain socket: /run/user/1000/xpra/beelink-1
252018-07-07 22:02:52,343 created unix domain socket: /home/steved/.xpra/beelink-1
262018-07-07 22:02:52,343 cannot create group socket '/run/xpra/beelink-1'
272018-07-07 22:02:52,343  [Errno 13] Permission denied
282018-07-07 22:02:52,630 Warning: the python netifaces package is missing
292018-07-07 22:02:52,661 pointer device emulation using XTest
302018-07-07 22:02:54,929 serving html content from: /usr/share/xpra/www
312018-07-07 22:02:55,149 D-Bus notification forwarding is available
322018-07-07 22:02:55,401 Warning: webcam forwarding is disabled
332018-07-07 22:02:55,402  the virtual video directory '/sys/devices/virtual/video4linux' was not found
342018-07-07 22:02:55,402  make sure that the 'v4l2loopback' kernel module is installed and loaded
352018-07-07 22:02:55,403 found 0 virtual video devices for webcam forwarding
362018-07-07 22:02:55,426 pulseaudio server started with pid 28969
372018-07-07 22:02:55,427  private server socket path:
382018-07-07 22:02:55,427  '/run/user/1000/xpra/pulse-:1/pulse/native'
392018-07-07 22:02:57,379 GStreamer version 1.14.1 for Python 2.7.15 64-bit
402018-07-07 22:02:57,520 Warning: failed to load the mdns avahi publisher:
412018-07-07 22:02:57,520  No module named avahi
422018-07-07 22:02:57,521  either fix your installation or use the 'mdns=no' option
432018-07-07 22:02:57,521 xpra X11 version 2.3.2-r19729 64-bit
442018-07-07 22:02:57,521  uid=1000 (steved), gid=1000 (steved)
452018-07-07 22:02:57,522  running with pid 28914 on Linux Ubuntu 18.04 bionic
462018-07-07 22:02:57,522  connected to X11 display :1 with 24 bit colors
472018-07-07 22:02:57,766 xpra is ready.
482018-07-07 22:03:09,817 printer forwarding enabled using postscript and pdf
492018-07-07 22:03:09,839 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-07 22:03:10,772 New unix-domain connection received on /run/user/1000/xpra/beelink-1
522018-07-07 22:03:10,781 Handshake complete; enabling connection
532018-07-07 22:03:10,806  automatic picture encoding enabled, also available:
542018-07-07 22:03:10,807   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
552018-07-07 22:03:10,808  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.AuxenV.mmap
562018-07-07 22:03:10,809 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
572018-07-07 22:03:10,810  connected from 'beelink' as 'steved' - 'Steve Dee'
582018-07-07 22:03:10,815  client root window size is 2880x900 with 1 display:
592018-07-07 22:03:10,815   :0.0 (761x237 mm - DPI: 96x96)
602018-07-07 22:03:10,816     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
612018-07-07 22:03:10,816     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
622018-07-07 22:03:11,076 server virtual display now set to 2880x960 (best match for 2880x900)
632018-07-07 22:03:11,093 setting key repeat rate from client: 500ms delay / 50ms interval
642018-07-07 22:03:11,096 setting keymap: rules=evdev, model=pc105, layout=gb
652018-07-07 22:03:11,137 setting keyboard layout to 'gb'
662018-07-07 22:03:11,237 New unix-domain connection received on /run/user/1000/xpra/beelink-1
672018-07-07 22:03:11,238 New unix-domain connection received on /home/steved/.xpra/beelink-1
682018-07-07 22:03:11,248 client @05.051 Xpra X11 server version 2.3.2-r19729 64-bit
692018-07-07 22:03:11,248 DPI set to 34 x 22 (wanted 96 x 96)
702018-07-07 22:03:11,249  you may experience scaling problems, such as huge or small fonts, etc
712018-07-07 22:03:11,249  to fix this issue, try the dpi switch, or use a patched Xorg dummy driver
722018-07-07 22:03:11,274 client @05.053  running on Linux Ubuntu 18.04 bionic
732018-07-07 22:03:11,275 client @05.068 Attached to :1
742018-07-07 22:03:11,276 client @05.069  (press Control-C to detach)
752018-07-07 22:03:11,402 total_time=0, total_pixels=0
762018-07-07 22:03:11,458 client @05.248 server does not support xi input devices
772018-07-07 22:03:11,458 client @05.250  server uses: xtest
782018-07-07 22:03:11,554 packet decoding sequence 1 for window 1: 734x459 took 11.4ms
792018-07-07 22:03:11,554 record_latency: took   34.0 ms round trip,   34.0 for echo,   11.0 for decoding of   336906 pixels,       57 bytes sent over the network in   22.6 ms,   22.5 ms for echo
802018-07-07 22:03:11,555 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
812018-07-07 22:03:11,556 calculate_batch_delay for wid=1 current batch delay=29, last update 416856 seconds ago
822018-07-07 22:03:11,556 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
832018-07-07 22:03:11,558 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 35, 'recent': 35}
842018-07-07 22:03:11,561 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 25}
852018-07-07 22:03:11,562 update_batch_delay: client-latency               : 0.89,0.41  {'target': 27, 'weight_multiplier': 1249, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 793, 'div': 1000, 'avg': 22, 'recent': 22}
862018-07-07 22:03:11,562 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}
872018-07-07 22:03:11,562 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}
882018-07-07 22:03:11,563 update_batch_delay: compression-work-queue       : 0.28,0.48  {'target': 1000, 'weight_multiplier': 618, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 213, 'div': 1000, 'avg': 353, 'recent': 382}
892018-07-07 22:03:11,563 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}
902018-07-07 22:03:11,563 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
912018-07-07 22:03:11,564 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
922018-07-07 22:03:11,564 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
932018-07-07 22:03:11,564 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
942018-07-07 22:03:11,564 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
952018-07-07 22:03:11,564 update_batch_delay: delay=23
962018-07-07 22:03:11,568 packet decoding sequence 2 for window 1: 734x459 took 4.0ms
972018-07-07 22:03:11,569 record_latency: took    6.4 ms round trip,    6.2 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.4 ms,    2.2 ms for echo
982018-07-07 22:03:11,569 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
992018-07-07 22:03:11,754 packet decoding sequence 3 for window 1: 734x459 took 2.6ms
1002018-07-07 22:03:11,754 record_latency: took    5.2 ms round trip,    5.1 for echo,    2.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.5 ms,    2.5 ms for echo
1012018-07-07 22:03:12,556 calculate_batch_delay for wid=1 current batch delay=23, last update 1 seconds ago
1022018-07-07 22:03:12,557 calculate_batch_delay for wid=1, skipping - only 2 events since the last update
1032018-07-07 22:03:13,211 using pulseaudio device:
1042018-07-07 22:03:13,211  'Monitor of Xpra Speaker'
1052018-07-07 22:03:13,294 packet decoding sequence 4 for window 1: 12x20 took 1.4ms
1062018-07-07 22:03:13,295 record_latency: took   13.2 ms round trip,   13.2 for echo,    1.0 for decoding of      240 pixels,       59 bytes sent over the network in   11.8 ms,   11.7 ms for echo
1072018-07-07 22:03:13,328 packet decoding sequence 5 for window 1: 734x459 took 3.6ms
1082018-07-07 22:03:13,329 record_latency: took    6.2 ms round trip,    6.1 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.6 ms,    2.6 ms for echo
1092018-07-07 22:03:13,329 may_recalculate(1, 336906) total 337146 pixels, scheduling recalculate work item
1102018-07-07 22:03:13,355 packet decoding sequence 6 for window 1: 12x20 took 1.7ms
1112018-07-07 22:03:13,356 record_latency: took    4.0 ms round trip,    3.9 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.3 ms,    2.3 ms for echo
1122018-07-07 22:03:13,389 packet decoding sequence 7 for window 1: 12x20 took 1.1ms
1132018-07-07 22:03:13,389 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.1 ms,    3.1 ms for echo
1142018-07-07 22:03:13,432 packet decoding sequence 8 for window 1: 12x20 took 2.1ms
1152018-07-07 22:03:13,432 record_latency: took    4.5 ms round trip,    4.5 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.4 ms,    2.3 ms for echo
1162018-07-07 22:03:13,464 packet decoding sequence 9 for window 1: 12x20 took 1.1ms
1172018-07-07 22:03:13,464 record_latency: took    3.1 ms round trip,    3.0 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.0 ms,    2.0 ms for echo
1182018-07-07 22:03:13,500 packet decoding sequence 10 for window 1: 12x20 took 1.0ms
1192018-07-07 22:03:13,500 record_latency: took    4.5 ms round trip,    4.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.5 ms,    3.1 ms for echo
1202018-07-07 22:03:13,538 packet decoding sequence 11 for window 1: 12x20 took 0.9ms
1212018-07-07 22:03:13,538 record_latency: took    4.2 ms round trip,    3.8 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.3 ms,    2.9 ms for echo
1222018-07-07 22:03:13,558 calculate_batch_delay for wid=1 current batch delay=23, last update 2 seconds ago
1232018-07-07 22:03:13,559 calculate_batch_delay for wid=1, 1012398 pixels updated since the last update
1242018-07-07 22:03:13,561 calculate_batch_delay for wid=1, skipping - only 10 bytes sent since the last update
1252018-07-07 22:03:13,577 packet decoding sequence 12 for window 1: 12x20 took 1.0ms
1262018-07-07 22:03:13,578 record_latency: took   10.0 ms round trip,   10.0 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    9.0 ms,    9.0 ms for echo
1272018-07-07 22:03:13,617 packet decoding sequence 13 for window 1: 12x20 took 1.4ms
1282018-07-07 22:03:13,617 record_latency: took    9.6 ms round trip,    9.5 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    8.2 ms,    8.1 ms for echo
1292018-07-07 22:03:13,647 packet decoding sequence 14 for window 1: 12x20 took 1.0ms
1302018-07-07 22:03:13,647 record_latency: took    3.3 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.3 ms,    2.1 ms for echo
1312018-07-07 22:03:13,679 packet decoding sequence 15 for window 1: 12x20 took 1.0ms
1322018-07-07 22:03:13,679 record_latency: took    3.4 ms round trip,    3.3 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.4 ms,    2.3 ms for echo
1332018-07-07 22:03:13,715 packet decoding sequence 16 for window 1: 12x20 took 1.0ms
1342018-07-07 22:03:13,716 record_latency: took    3.8 ms round trip,    3.6 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.7 ms,    2.6 ms for echo
1352018-07-07 22:03:13,819 packet decoding sequence 17 for window 1: 12x20 took 0.9ms
1362018-07-07 22:03:13,819 record_latency: took    3.5 ms round trip,    3.5 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.6 ms,    2.5 ms for echo
1372018-07-07 22:03:13,855 packet decoding sequence 18 for window 1: 12x20 took 0.9ms
1382018-07-07 22:03:13,856 record_latency: took    5.2 ms round trip,    5.2 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    4.3 ms,    4.2 ms for echo
1392018-07-07 22:03:13,888 packet decoding sequence 19 for window 1: 12x20 took 1.0ms
1402018-07-07 22:03:13,888 record_latency: took    3.0 ms round trip,    2.9 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    1.9 ms,    1.9 ms for echo
1412018-07-07 22:03:13,924 packet decoding sequence 20 for window 1: 12x20 took 1.0ms
1422018-07-07 22:03:13,924 record_latency: took    3.0 ms round trip,    2.7 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.0 ms,    1.8 ms for echo
1432018-07-07 22:03:13,925 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
1442018-07-07 22:03:13,959 packet decoding sequence 21 for window 1: 12x20 took 1.0ms
1452018-07-07 22:03:13,960 record_latency: took    3.2 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.2 ms,    2.1 ms for echo
1462018-07-07 22:03:14,559 calculate_batch_delay for wid=1 current batch delay=23, last update 3 seconds ago
1472018-07-07 22:03:14,559 calculate_batch_delay for wid=1, 1014798 pixels updated since the last update
1482018-07-07 22:03:14,559 calculate_batch_delay for wid=1, skipping - only 20 bytes sent since the last update
1492018-07-07 22:03:14,639 packet decoding sequence 22 for window 1: 12x20 took 1.0ms
1502018-07-07 22:03:14,639 record_latency: took    3.3 ms round trip,    3.2 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.2 ms,    2.2 ms for echo
1512018-07-07 22:03:14,675 packet decoding sequence 23 for window 1: 12x20 took 0.9ms
1522018-07-07 22:03:14,675 record_latency: took    4.9 ms round trip,    4.8 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    4.0 ms,    3.9 ms for echo
1532018-07-07 22:03:14,710 packet decoding sequence 24 for window 1: 12x20 took 0.9ms
1542018-07-07 22:03:14,710 record_latency: took    3.2 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.2 ms,    2.2 ms for echo
1552018-07-07 22:03:15,295 packet decoding sequence 1 for window 2: 312x259 took 3.0ms
1562018-07-07 22:03:15,295 record_latency: took   29.4 ms round trip,   29.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   26.4 ms,   26.4 ms for echo
1572018-07-07 22:03:15,296 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
1582018-07-07 22:03:15,301 packet decoding sequence 25 for window 1: 734x459 took 8.9ms
1592018-07-07 22:03:15,302 record_latency: took   26.0 ms round trip,   25.9 for echo,    8.0 for decoding of   336906 pixels,       61 bytes sent over the network in   17.1 ms,   17.0 ms for echo
1602018-07-07 22:03:15,302 may_recalculate(1, 336906) total 337626 pixels, scheduling recalculate work item
1612018-07-07 22:03:15,560 calculate_batch_delay for wid=1 current batch delay=23, last update 4 seconds ago
1622018-07-07 22:03:15,560 calculate_batch_delay for wid=1, 1352424 pixels updated since the last update
1632018-07-07 22:03:15,560 calculate_batch_delay for wid=1, skipping - only 24 bytes sent since the last update
1642018-07-07 22:03:15,561 calculate_batch_delay for wid=2 current batch delay=19, last update 416860 seconds ago
1652018-07-07 22:03:15,562 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
1662018-07-07 22:03:15,562 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 38, 'recent': 38}
1672018-07-07 22:03:15,562 update_batch_delay: damage-rate                  : 0.88,0.34  {'max_latency': 100, 'elapsed': 318}
1682018-07-07 22:03:15,563 update_batch_delay: client-latency               : 1.09,0.13  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1195, 'div': 1000, 'avg': 6, 'recent': 14}
1692018-07-07 22:03:15,563 update_batch_delay: client-ping-latency          : 0.54,0.13  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 292, 'div': 1000, 'avg': 2, 'recent': 2}
1702018-07-07 22:03:15,563 update_batch_delay: server-ping-latency          : 0.94,0.23  {'target': 45, 'weight_multiplier': 925, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 884, 'div': 1000, 'avg': 39, 'recent': 39}
1712018-07-07 22:03:15,563 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}
1722018-07-07 22:03:15,564 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}
1732018-07-07 22:03:15,564 update_batch_delay: compression-work-queue       : 0.00,0.14  {'target': 1000, 'weight_multiplier': 139, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 1, 'div': 1000, 'avg': 19, 'recent': 3}
1742018-07-07 22:03:15,564 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}
1752018-07-07 22:03:15,565 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
1762018-07-07 22:03:15,565 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
1772018-07-07 22:03:15,565 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
1782018-07-07 22:03:15,565 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
1792018-07-07 22:03:15,566 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
1802018-07-07 22:03:15,566 update_batch_delay: delay=14
1812018-07-07 22:03:15,735 packet decoding sequence 2 for window 2: 312x259 took 2.9ms
1822018-07-07 22:03:15,736 record_latency: took    7.0 ms round trip,    6.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
1832018-07-07 22:03:15,736 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
1842018-07-07 22:03:15,774 packet decoding sequence 3 for window 2: 312x259 took 2.3ms
1852018-07-07 22:03:15,775 record_latency: took    6.6 ms round trip,    6.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.3 ms,    4.2 ms for echo
1862018-07-07 22:03:15,876 sound source using 'opus' audio codec
1872018-07-07 22:03:15,895 packet decoding sequence 4 for window 2: 312x259 took 2.2ms
1882018-07-07 22:03:15,896 record_latency: took    5.4 ms round trip,    5.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.1 ms,    2.9 ms for echo
1892018-07-07 22:03:15,948 packet decoding sequence 5 for window 2: 312x259 took 2.3ms
1902018-07-07 22:03:15,948 record_latency: took    5.9 ms round trip,    5.7 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.7 ms,    3.5 ms for echo
1912018-07-07 22:03:15,960 packet decoding sequence 6 for window 2: 312x259 took 1.7ms
1922018-07-07 22:03:15,961 record_latency: took    3.8 ms round trip,    3.7 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.0 ms,    2.0 ms for echo
1932018-07-07 22:03:16,051 packet decoding sequence 7 for window 2: 312x259 took 2.3ms
1942018-07-07 22:03:16,051 record_latency: took    5.4 ms round trip,    5.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.2 ms,    3.0 ms for echo
1952018-07-07 22:03:16,192 packet decoding sequence 8 for window 2: 312x259 took 2.6ms
1962018-07-07 22:03:16,193 record_latency: took    7.1 ms round trip,    6.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.6 ms,    4.3 ms for echo
1972018-07-07 22:03:16,236 packet decoding sequence 9 for window 2: 312x259 took 1.9ms
1982018-07-07 22:03:16,236 record_latency: took    4.7 ms round trip,    4.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.6 ms for echo
1992018-07-07 22:03:16,273 packet decoding sequence 10 for window 2: 312x259 took 2.6ms
2002018-07-07 22:03:16,274 record_latency: took    6.4 ms round trip,    6.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2012018-07-07 22:03:16,418 packet decoding sequence 11 for window 2: 312x259 took 1.8ms
2022018-07-07 22:03:16,418 record_latency: took    6.2 ms round trip,    6.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.4 ms,    4.2 ms for echo
2032018-07-07 22:03:16,473 packet decoding sequence 12 for window 2: 312x259 took 2.2ms
2042018-07-07 22:03:16,473 record_latency: took    7.1 ms round trip,    6.8 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.9 ms,    4.6 ms for echo
2052018-07-07 22:03:16,478 packet decoding sequence 13 for window 2: 312x259 took 2.8ms
2062018-07-07 22:03:16,478 record_latency: took    6.9 ms round trip,    6.8 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
2072018-07-07 22:03:16,495 packet decoding sequence 14 for window 2: 312x259 took 1.5ms
2082018-07-07 22:03:16,495 record_latency: took    9.5 ms round trip,    9.4 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    8.0 ms,    8.0 ms for echo
2092018-07-07 22:03:16,527 packet decoding sequence 15 for window 2: 312x259 took 1.7ms
2102018-07-07 22:03:16,528 record_latency: took    9.7 ms round trip,    9.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    8.0 ms,    7.9 ms for echo
2112018-07-07 22:03:16,562 calculate_batch_delay for wid=2 current batch delay=14, last update 1 seconds ago
2122018-07-07 22:03:16,562 calculate_batch_delay for wid=2, 1131312 pixels updated since the last update
2132018-07-07 22:03:16,563 calculate_batch_delay for wid=2, skipping - only 14 bytes sent since the last update
2142018-07-07 22:03:16,574 packet decoding sequence 16 for window 2: 312x25 took 1.5ms
2152018-07-07 22:03:16,574 record_latency: took    4.4 ms round trip,    4.2 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    2.9 ms,    2.6 ms for echo
2162018-07-07 22:03:16,574 may_recalculate(2, 7800) total 7800 pixels, scheduling recalculate work item
2172018-07-07 22:03:16,591 packet decoding sequence 17 for window 2: 312x259 took 1.6ms
2182018-07-07 22:03:16,591 record_latency: took    3.9 ms round trip,    3.8 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.3 ms,    2.2 ms for echo
2192018-07-07 22:03:16,669 packet decoding sequence 18 for window 2: 312x259 took 2.5ms
2202018-07-07 22:03:16,670 record_latency: took    5.6 ms round trip,    5.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.1 ms,    3.0 ms for echo
2212018-07-07 22:03:16,917 packet decoding sequence 19 for window 2: 312x259 took 2.3ms
2222018-07-07 22:03:16,917 record_latency: took    6.1 ms round trip,    5.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.8 ms,    3.6 ms for echo
2232018-07-07 22:03:16,966 packet decoding sequence 20 for window 2: 312x259 took 2.3ms
2242018-07-07 22:03:16,966 record_latency: took    5.3 ms round trip,    3.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    1.6 ms for echo
2252018-07-07 22:03:17,025 packet decoding sequence 21 for window 2: 312x259 took 2.1ms
2262018-07-07 22:03:17,026 record_latency: took    6.0 ms round trip,    5.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.9 ms,    3.8 ms for echo
2272018-07-07 22:03:17,037 packet decoding sequence 22 for window 2: 312x259 took 2.2ms
2282018-07-07 22:03:17,037 record_latency: took    5.0 ms round trip,    4.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    1.8 ms for echo
2292018-07-07 22:03:17,077 packet decoding sequence 23 for window 2: 312x259 took 1.8ms
2302018-07-07 22:03:17,077 record_latency: took    4.6 ms round trip,    4.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2312018-07-07 22:03:17,181 packet decoding sequence 24 for window 2: 312x259 took 2.4ms
2322018-07-07 22:03:17,181 record_latency: took    5.4 ms round trip,    5.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    3.0 ms for echo
2332018-07-07 22:03:17,332 packet decoding sequence 25 for window 2: 312x259 took 2.9ms
2342018-07-07 22:03:17,333 record_latency: took    6.7 ms round trip,    6.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2352018-07-07 22:03:17,543 packet decoding sequence 26 for window 2: 312x259 took 1.4ms
2362018-07-07 22:03:17,543 record_latency: took    3.4 ms round trip,    3.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    1.9 ms,    1.7 ms for echo
2372018-07-07 22:03:17,562 calculate_batch_delay for wid=2 current batch delay=14, last update 2 seconds ago
2382018-07-07 22:03:17,563 calculate_batch_delay for wid=2, 1947192 pixels updated since the last update
2392018-07-07 22:03:17,563 calculate_batch_delay for wid=2, skipping - only 25 bytes sent since the last update
2402018-07-07 22:03:17,590 packet decoding sequence 27 for window 2: 312x259 took 1.6ms
2412018-07-07 22:03:17,590 record_latency: took    9.1 ms round trip,    9.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    7.4 ms,    7.3 ms for echo
2422018-07-07 22:03:17,590 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2432018-07-07 22:03:17,637 packet decoding sequence 28 for window 2: 312x259 took 2.3ms
2442018-07-07 22:03:17,637 record_latency: took    7.1 ms round trip,    7.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.8 ms,    4.7 ms for echo
2452018-07-07 22:03:17,690 packet decoding sequence 29 for window 2: 312x259 took 4.0ms
2462018-07-07 22:03:17,690 record_latency: took    7.0 ms round trip,    6.9 for echo,    4.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.9 ms for echo
2472018-07-07 22:03:17,739 packet decoding sequence 30 for window 2: 312x25 took 1.4ms
2482018-07-07 22:03:17,739 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    2.9 ms,    2.9 ms for echo
2492018-07-07 22:03:17,769 packet decoding sequence 31 for window 2: 312x259 took 10.8ms
2502018-07-07 22:03:17,769 record_latency: took   13.0 ms round trip,   12.7 for echo,   10.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.2 ms,    1.9 ms for echo
2512018-07-07 22:03:17,965 packet decoding sequence 32 for window 2: 312x259 took 5.2ms
2522018-07-07 22:03:17,965 record_latency: took    8.2 ms round trip,    8.0 for echo,    5.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    2.7 ms for echo
2532018-07-07 22:03:18,122 packet decoding sequence 33 for window 2: 312x259 took 2.0ms
2542018-07-07 22:03:18,123 record_latency: took    6.4 ms round trip,    6.3 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.4 ms,    4.3 ms for echo
2552018-07-07 22:03:18,270 packet decoding sequence 34 for window 2: 312x259 took 1.7ms
2562018-07-07 22:03:18,270 record_latency: took    5.3 ms round trip,    5.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.6 ms,    3.5 ms for echo
2572018-07-07 22:03:18,308 packet decoding sequence 35 for window 2: 312x259 took 2.0ms
2582018-07-07 22:03:18,309 record_latency: took    4.5 ms round trip,    4.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.5 ms,    2.4 ms for echo
2592018-07-07 22:03:18,328 packet decoding sequence 36 for window 2: 312x259 took 2.0ms
2602018-07-07 22:03:18,329 record_latency: took    4.1 ms round trip,    4.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.1 ms,    2.1 ms for echo
2612018-07-07 22:03:18,346 packet decoding sequence 37 for window 2: 312x259 took 1.7ms
2622018-07-07 22:03:18,346 record_latency: took    4.0 ms round trip,    3.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.2 ms,    2.1 ms for echo
2632018-07-07 22:03:18,420 packet decoding sequence 38 for window 2: 312x259 took 2.2ms
2642018-07-07 22:03:18,421 record_latency: took    7.6 ms round trip,    7.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    5.4 ms,    5.2 ms for echo
2652018-07-07 22:03:18,505 packet decoding sequence 39 for window 2: 312x259 took 4.5ms
2662018-07-07 22:03:18,505 record_latency: took    8.1 ms round trip,    8.0 for echo,    4.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.6 ms,    3.6 ms for echo
2672018-07-07 22:03:18,563 calculate_batch_delay for wid=2 current batch delay=14, last update 3 seconds ago
2682018-07-07 22:03:18,564 calculate_batch_delay for wid=2, 2924688 pixels updated since the last update
2692018-07-07 22:03:18,565 calculate_batch_delay for wid=2, skipping - only 38 bytes sent since the last update
2702018-07-07 22:03:18,710 packet decoding sequence 40 for window 2: 312x259 took 2.1ms
2712018-07-07 22:03:18,711 record_latency: took    4.8 ms round trip,    4.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.7 ms,    1.9 ms for echo
2722018-07-07 22:03:18,711 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2732018-07-07 22:03:18,735 packet decoding sequence 41 for window 2: 312x259 took 1.9ms
2742018-07-07 22:03:18,735 record_latency: took    8.7 ms round trip,    8.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.8 ms,    6.6 ms for echo
2752018-07-07 22:03:18,749 packet decoding sequence 42 for window 2: 312x259 took 1.7ms
2762018-07-07 22:03:18,750 record_latency: took    5.0 ms round trip,    4.8 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.3 ms,    3.1 ms for echo
2772018-07-07 22:03:18,783 packet decoding sequence 43 for window 2: 312x259 took 2.6ms
2782018-07-07 22:03:18,783 record_latency: took    8.0 ms round trip,    7.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    5.3 ms,    5.3 ms for echo
2792018-07-07 22:03:18,797 packet decoding sequence 44 for window 2: 312x259 took 1.9ms
2802018-07-07 22:03:18,798 record_latency: took    4.7 ms round trip,    4.5 for echo,    1.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.8 ms,    2.6 ms for echo
2812018-07-07 22:03:18,871 packet decoding sequence 45 for window 2: 312x259 took 3.3ms
2822018-07-07 22:03:18,871 record_latency: took    6.0 ms round trip,    5.9 for echo,    3.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.7 ms,    2.7 ms for echo
2832018-07-07 22:03:18,942 packet decoding sequence 46 for window 2: 312x259 took 2.7ms
2842018-07-07 22:03:18,943 record_latency: took    7.2 ms round trip,    5.7 for echo,    2.0 for decoding of    80808 pixels,       61 bytes sent over the network in    4.5 ms,    3.1 ms for echo
2852018-07-07 22:03:18,947 packet decoding sequence 47 for window 2: 312x259 took 2.0ms
2862018-07-07 22:03:18,947 record_latency: took    4.6 ms round trip,    4.0 for echo,    2.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.6 ms,    2.0 ms for echo
2872018-07-07 22:03:18,970 packet decoding sequence 48 for window 2: 312x259 took 1.9ms
2882018-07-07 22:03:18,970 record_latency: took    4.1 ms round trip,    3.8 for echo,    1.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.2 ms,    2.0 ms for echo
2892018-07-07 22:03:18,977 packet decoding sequence 49 for window 2: 312x51 took 1.7ms
2902018-07-07 22:03:18,977 record_latency: took    4.2 ms round trip,    4.0 for echo,    1.0 for decoding of    15912 pixels,       60 bytes sent over the network in    2.5 ms,    2.3 ms for echo
2912018-07-07 22:03:19,612 packet decoding sequence 26 for window 1: 734x459 took 5.0ms
2922018-07-07 22:03:19,612 record_latency: took    9.6 ms round trip,    9.5 for echo,    4.0 for decoding of   336906 pixels,       61 bytes sent over the network in    4.6 ms,    4.5 ms for echo
2932018-07-07 22:03:19,613 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2942018-07-07 22:03:19,840 packet decoding sequence 27 for window 1: 12x20 took 1.5ms
2952018-07-07 22:03:19,840 record_latency: took   13.1 ms round trip,   12.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   11.6 ms,   11.4 ms for echo
2962018-07-07 22:03:19,885 packet decoding sequence 28 for window 1: 12x20 took 1.1ms
2972018-07-07 22:03:19,885 record_latency: took    3.3 ms round trip,    3.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.1 ms,    2.0 ms for echo
2982018-07-07 22:03:19,901 packet decoding sequence 29 for window 1: 12x20 took 1.6ms
2992018-07-07 22:03:19,901 record_latency: took    5.2 ms round trip,    5.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.7 ms,    3.6 ms for echo
3002018-07-07 22:03:20,057 packet decoding sequence 30 for window 1: 12x20 took 1.5ms
3012018-07-07 22:03:20,057 record_latency: took    4.6 ms round trip,    4.5 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.1 ms,    3.0 ms for echo
3022018-07-07 22:03:20,091 packet decoding sequence 31 for window 1: 12x20 took 1.1ms
3032018-07-07 22:03:20,091 record_latency: took    3.2 ms round trip,    3.1 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    2.1 ms,    2.0 ms for echo
3042018-07-07 22:03:20,566 calculate_batch_delay for wid=1 current batch delay=23, last update 9 seconds ago
3052018-07-07 22:03:20,566 calculate_batch_delay for wid=1, 1690530 pixels updated since the last update
3062018-07-07 22:03:20,567 calculate_batch_delay for wid=1, skipping - only 30 bytes sent since the last update
3072018-07-07 22:03:25,228 total_time=184690, total_pixels=5776116
3082018-07-07 22:03:26,454 total_time=184690, total_pixels=5776116
3092018-07-07 22:03:26,557 total_time=184690, total_pixels=5776116
3102018-07-07 22:03:26,659 total_time=184690, total_pixels=5776116
3112018-07-07 22:03:26,759 total_time=184690, total_pixels=5776116
3122018-07-07 22:03:26,856 total_time=184690, total_pixels=5776116
3132018-07-07 22:03:26,962 total_time=184690, total_pixels=5776116
3142018-07-07 22:03:27,063 total_time=184690, total_pixels=5776116
3152018-07-07 22:03:27,161 total_time=184690, total_pixels=5776116
3162018-07-07 22:03:27,260 total_time=184690, total_pixels=5776116
3172018-07-07 22:03:27,362 total_time=184690, total_pixels=5776116
3182018-07-07 22:03:27,469 total_time=184690, total_pixels=5776116
3192018-07-07 22:03:27,564 total_time=184690, total_pixels=5776116
3202018-07-07 22:03:27,663 total_time=184690, total_pixels=5776116
3212018-07-07 22:03:27,764 total_time=184690, total_pixels=5776116
3222018-07-07 22:03:27,862 total_time=184690, total_pixels=5776116
3232018-07-07 22:03:27,967 total_time=184690, total_pixels=5776116
3242018-07-07 22:03:28,066 total_time=184690, total_pixels=5776116
3252018-07-07 22:03:28,165 total_time=184690, total_pixels=5776116
3262018-07-07 22:03:28,268 total_time=184690, total_pixels=5776116
3272018-07-07 22:03:28,367 total_time=184690, total_pixels=5776116
3282018-07-07 22:03:28,469 total_time=184690, total_pixels=5776116
3292018-07-07 22:03:28,570 total_time=184690, total_pixels=5776116
3302018-07-07 22:03:28,669 total_time=184690, total_pixels=5776116
3312018-07-07 22:03:28,774 total_time=184690, total_pixels=5776116
3322018-07-07 22:03:28,872 total_time=184690, total_pixels=5776116
3332018-07-07 22:03:28,973 total_time=184690, total_pixels=5776116
3342018-07-07 22:03:29,080 total_time=184690, total_pixels=5776116
3352018-07-07 22:03:29,174 total_time=184690, total_pixels=5776116
3362018-07-07 22:03:29,275 total_time=184690, total_pixels=5776116
3372018-07-07 22:03:29,322 packet decoding sequence 32 for window 1: 12x20 took 2.7ms
3382018-07-07 22:03:29,324 record_latency: took    7.8 ms round trip,    7.2 for echo,    2.0 for decoding of      240 pixels,       63 bytes sent over the network in    5.2 ms,    4.6 ms for echo
3392018-07-07 22:03:29,327 packet decoding sequence 33 for window 1: 12x20 took 2.3ms
3402018-07-07 22:03:29,328 record_latency: took    8.9 ms round trip,    8.8 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.7 ms,    6.5 ms for echo
3412018-07-07 22:03:29,355 packet decoding sequence 34 for window 1: 12x20 took 0.9ms
3422018-07-07 22:03:29,356 record_latency: took    5.0 ms round trip,    5.0 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.1 ms,    4.1 ms for echo
3432018-07-07 22:03:29,376 total_time=190544, total_pixels=5776836
3442018-07-07 22:03:29,397 packet decoding sequence 35 for window 1: 12x20 took 0.9ms
3452018-07-07 22:03:29,397 record_latency: took    3.1 ms round trip,    3.0 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.2 ms,    2.1 ms for echo
3462018-07-07 22:03:29,439 packet decoding sequence 36 for window 1: 12x20 took 1.3ms
3472018-07-07 22:03:29,439 record_latency: took    7.7 ms round trip,    7.6 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.4 ms,    6.3 ms for echo
3482018-07-07 22:03:29,477 total_time=192710, total_pixels=5777316
3492018-07-07 22:03:29,579 total_time=192710, total_pixels=5777316
3502018-07-07 22:03:29,657 packet decoding sequence 37 for window 1: 12x20 took 1.7ms
3512018-07-07 22:03:29,658 record_latency: took    4.9 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.1 ms for echo
3522018-07-07 22:03:29,677 total_time=194447, total_pixels=5777556
3532018-07-07 22:03:29,709 packet decoding sequence 38 for window 1: 12x20 took 0.9ms
3542018-07-07 22:03:29,709 record_latency: took    6.8 ms round trip,    6.7 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.9 ms,    5.8 ms for echo
3552018-07-07 22:03:29,777 total_time=195364, total_pixels=5777796
3562018-07-07 22:03:29,804 packet decoding sequence 39 for window 1: 12x20 took 2.1ms
3572018-07-07 22:03:29,804 record_latency: took   46.1 ms round trip,   46.0 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in   44.0 ms,   43.9 ms for echo
3582018-07-07 22:03:29,810 packet decoding sequence 40 for window 1: 12x20 took 1.0ms
3592018-07-07 22:03:29,811 record_latency: took    3.3 ms round trip,    2.8 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.3 ms,    1.9 ms for echo
3602018-07-07 22:03:29,811 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
3612018-07-07 22:03:29,812 calculate_batch_delay for wid=1 current batch delay=23, last update 18 seconds ago
3622018-07-07 22:03:29,812 calculate_batch_delay for wid=1, 1692690 pixels updated since the last update
3632018-07-07 22:03:29,812 calculate_batch_delay for wid=1, skipping - only 39 bytes sent since the last update
3642018-07-07 22:03:29,882 total_time=198404, total_pixels=5778276
3652018-07-07 22:03:29,986 total_time=198404, total_pixels=5778276
3662018-07-07 22:03:30,084 total_time=198404, total_pixels=5778276
3672018-07-07 22:03:30,207 total_time=198404, total_pixels=5778276
3682018-07-07 22:03:30,208 packet decoding sequence 1 for window 3: 130x54 took 1.4ms
3692018-07-07 22:03:30,209 record_latency: took   65.8 ms round trip,   65.8 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in   64.4 ms,   64.4 ms for echo
3702018-07-07 22:03:30,209 may_recalculate(3, 7020) total 7020 pixels, scheduling recalculate work item
3712018-07-07 22:03:30,224 packet decoding sequence 2 for window 3: 130x54 took 18.8ms
3722018-07-07 22:03:30,224 record_latency: took   76.5 ms round trip,   76.4 for echo,   18.0 for decoding of     7020 pixels,       57 bytes sent over the network in   57.7 ms,   57.6 ms for echo
3732018-07-07 22:03:30,255 packet decoding sequence 41 for window 1: 734x459 took 4.3ms
3742018-07-07 22:03:30,255 record_latency: took    6.4 ms round trip,    6.4 for echo,    4.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.1 ms,    2.0 ms for echo
3752018-07-07 22:03:30,255 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
3762018-07-07 22:03:30,306 total_time=222925, total_pixels=6129222
3772018-07-07 22:03:30,407 total_time=222925, total_pixels=6129222
3782018-07-07 22:03:30,513 total_time=222925, total_pixels=6129222
3792018-07-07 22:03:30,626 total_time=225957, total_pixels=6189744
3802018-07-07 22:03:30,730 total_time=273713, total_pixels=6250266
3812018-07-07 22:03:30,754 packet decoding sequence 42 for window 1: 279x25 took 119.7ms
3822018-07-07 22:03:30,754 record_latency: took  161.1 ms round trip,  160.9 for echo,  119.0 for decoding of     6975 pixels,       57 bytes sent over the network in   41.4 ms,   41.2 ms for echo
3832018-07-07 22:03:30,757 packet decoding sequence 1 for window 5: 312x259 took 2.4ms
3842018-07-07 22:03:30,758 record_latency: took  112.3 ms round trip,  112.2 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in  110.0 ms,  109.9 ms for echo
3852018-07-07 22:03:30,758 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
3862018-07-07 22:03:30,761 packet decoding sequence 43 for window 1: 234x25 took 4.3ms
3872018-07-07 22:03:30,762 record_latency: took   43.7 ms round trip,   43.6 for echo,    4.0 for decoding of     5850 pixels,       57 bytes sent over the network in   39.3 ms,   39.2 ms for echo
3882018-07-07 22:03:30,814 calculate_batch_delay for wid=1 current batch delay=23, last update 19 seconds ago
3892018-07-07 22:03:30,814 calculate_batch_delay for wid=1, 2042421 pixels updated since the last update
3902018-07-07 22:03:30,815 calculate_batch_delay for wid=1, skipping - only 42 bytes sent since the last update
3912018-07-07 22:03:30,815 calculate_batch_delay for wid=5 current batch delay=19, last update 416875 seconds ago
3922018-07-07 22:03:30,816 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
3932018-07-07 22:03:30,816 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 30, 'recent': 30}
3942018-07-07 22:03:30,816 update_batch_delay: damage-rate                  : 0.99,0.08  {'max_latency': 100, 'elapsed': 205}
3952018-07-07 22:03:30,816 update_batch_delay: client-latency               : 1.54,0.32  {'target': 6, 'weight_multiplier': 429, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2376, 'div': 1000, 'avg': 22, 'recent': 52}
3962018-07-07 22:03:30,817 update_batch_delay: client-ping-latency          : 1.14,0.07  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1307, 'div': 1000, 'avg': 8, 'recent': 16}
3972018-07-07 22:03:30,817 update_batch_delay: server-ping-latency          : 0.49,0.14  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 239, 'div': 1000, 'avg': 3, 'recent': 2}
3982018-07-07 22:03:30,817 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}
3992018-07-07 22:03:30,817 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}
4002018-07-07 22:03:30,817 update_batch_delay: compression-work-queue       : 0.00,0.05  {'target': 1000, 'weight_multiplier': 45, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 2, 'recent': 0}
4012018-07-07 22:03:30,818 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}
4022018-07-07 22:03:30,818 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
4032018-07-07 22:03:30,818 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
4042018-07-07 22:03:30,818 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
4052018-07-07 22:03:30,819 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
4062018-07-07 22:03:30,819 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
4072018-07-07 22:03:30,819 update_batch_delay: delay=16
4082018-07-07 22:03:30,829 total_time=400127, total_pixels=6343899
4092018-07-07 22:03:30,929 total_time=400127, total_pixels=6343899
4102018-07-07 22:03:31,025 total_time=400127, total_pixels=6343899
4112018-07-07 22:03:31,106 packet decoding sequence 2 for window 5: 312x259 took 2.2ms
4122018-07-07 22:03:31,107 record_latency: took    8.2 ms round trip,    8.2 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.0 ms,    5.9 ms for echo
4132018-07-07 22:03:31,107 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
4142018-07-07 22:03:31,132 total_time=402358, total_pixels=6424707
4152018-07-07 22:03:31,165 packet decoding sequence 3 for window 5: 312x259 took 1.6ms
4162018-07-07 22:03:31,165 record_latency: took   21.2 ms round trip,   21.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   19.6 ms,   19.6 ms for echo
4172018-07-07 22:03:31,203 packet decoding sequence 4 for window 5: 312x259 took 2.7ms
4182018-07-07 22:03:31,203 record_latency: took    5.7 ms round trip,    5.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    2.7 ms for echo
4192018-07-07 22:03:31,226 total_time=406700, total_pixels=6586323
4202018-07-07 22:03:31,267 packet decoding sequence 5 for window 5: 312x259 took 1.4ms
4212018-07-07 22:03:31,268 record_latency: took   30.2 ms round trip,   30.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   28.8 ms,   28.7 ms for echo
4222018-07-07 22:03:31,306 packet decoding sequence 6 for window 5: 312x259 took 2.6ms
4232018-07-07 22:03:31,307 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.8 ms,    2.8 ms for echo
4242018-07-07 22:03:31,332 total_time=410727, total_pixels=6747939
4252018-07-07 22:03:31,357 packet decoding sequence 7 for window 5: 312x259 took 1.5ms
4262018-07-07 22:03:31,357 record_latency: took   29.2 ms round trip,   29.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   27.7 ms,   27.6 ms for echo
4272018-07-07 22:03:31,408 packet decoding sequence 8 for window 5: 312x259 took 3.8ms
4282018-07-07 22:03:31,409 record_latency: took    8.1 ms round trip,    8.0 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.2 ms,    4.1 ms for echo
4292018-07-07 22:03:31,434 total_time=416063, total_pixels=6909555
4302018-07-07 22:03:31,469 packet decoding sequence 9 for window 5: 312x259 took 1.6ms
4312018-07-07 22:03:31,469 record_latency: took   23.5 ms round trip,   23.4 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   21.9 ms,   21.8 ms for echo
4322018-07-07 22:03:31,533 total_time=417652, total_pixels=6990363
4332018-07-07 22:03:31,607 packet decoding sequence 10 for window 5: 312x259 took 2.9ms
4342018-07-07 22:03:31,608 record_latency: took    6.1 ms round trip,    6.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.1 ms,    3.0 ms for echo
4352018-07-07 22:03:31,632 total_time=420597, total_pixels=7071171
4362018-07-07 22:03:31,666 packet decoding sequence 11 for window 5: 312x259 took 1.6ms
4372018-07-07 22:03:31,666 record_latency: took   22.1 ms round trip,   21.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   20.4 ms,   20.3 ms for echo
4382018-07-07 22:03:31,702 packet decoding sequence 12 for window 5: 312x259 took 1.4ms
4392018-07-07 22:03:31,702 record_latency: took    3.3 ms round trip,    3.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    1.9 ms,    1.8 ms for echo
4402018-07-07 22:03:31,731 total_time=423651, total_pixels=7232787
4412018-07-07 22:03:31,814 calculate_batch_delay for wid=5 current batch delay=16, last update 0 seconds ago
4422018-07-07 22:03:31,815 calculate_batch_delay for wid=5, 888888 pixels updated since the last update
4432018-07-07 22:03:31,819 calculate_batch_delay for wid=5, skipping - only 11 bytes sent since the last update
4442018-07-07 22:03:31,834 total_time=423651, total_pixels=7232787
4452018-07-07 22:03:31,895 packet decoding sequence 13 for window 5: 312x259 took 2.1ms
4462018-07-07 22:03:31,896 record_latency: took    4.5 ms round trip,    4.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.1 ms for echo
4472018-07-07 22:03:31,896 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
4482018-07-07 22:03:31,933 total_time=425768, total_pixels=7313595
4492018-07-07 22:03:32,007 packet decoding sequence 14 for window 5: 312x259 took 1.8ms
4502018-07-07 22:03:32,007 record_latency: took    5.4 ms round trip,    5.3 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.6 ms,    3.6 ms for echo
4512018-07-07 22:03:32,036 total_time=427521, total_pixels=7394403
4522018-07-07 22:03:32,067 packet decoding sequence 15 for window 5: 312x259 took 1.6ms
4532018-07-07 22:03:32,068 record_latency: took   18.1 ms round trip,   18.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   16.5 ms,   16.4 ms for echo
4542018-07-07 22:03:32,131 total_time=429101, total_pixels=7475211
4552018-07-07 22:03:32,163 packet decoding sequence 16 for window 5: 312x259 took 1.7ms
4562018-07-07 22:03:32,163 record_latency: took   53.0 ms round trip,   52.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   51.3 ms,   51.2 ms for echo
4572018-07-07 22:03:32,169 packet decoding sequence 17 for window 5: 312x259 took 7.6ms
4582018-07-07 22:03:32,169 record_latency: took   24.7 ms round trip,   24.7 for echo,    7.0 for decoding of    80808 pixels,       60 bytes sent over the network in   17.1 ms,   17.1 ms for echo
4592018-07-07 22:03:32,229 total_time=438406, total_pixels=7636827
4602018-07-07 22:03:32,342 total_time=438406, total_pixels=7636827
4612018-07-07 22:03:32,361 packet decoding sequence 18 for window 5: 312x259 took 3.1ms
4622018-07-07 22:03:32,361 record_latency: took   32.7 ms round trip,   32.5 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in   29.5 ms,   29.4 ms for echo
4632018-07-07 22:03:32,369 packet decoding sequence 19 for window 5: 312x259 took 12.6ms
4642018-07-07 22:03:32,369 record_latency: took   22.4 ms round trip,   22.3 for echo,   12.0 for decoding of    80808 pixels,       60 bytes sent over the network in    9.8 ms,    9.7 ms for echo
4652018-07-07 22:03:32,396 packet decoding sequence 20 for window 5: 312x259 took 1.9ms
4662018-07-07 22:03:32,397 record_latency: took    4.3 ms round trip,    4.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.1 ms for echo
4672018-07-07 22:03:32,439 total_time=456002, total_pixels=7879251
4682018-07-07 22:03:32,475 packet decoding sequence 21 for window 5: 312x259 took 1.7ms
4692018-07-07 22:03:32,476 record_latency: took   44.9 ms round trip,   44.8 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   43.2 ms,   43.1 ms for echo
4702018-07-07 22:03:32,480 packet decoding sequence 22 for window 5: 312x259 took 6.1ms
4712018-07-07 22:03:32,480 record_latency: took   34.4 ms round trip,   34.3 for echo,    6.0 for decoding of    80808 pixels,       60 bytes sent over the network in   28.3 ms,   28.3 ms for echo
4722018-07-07 22:03:32,533 total_time=463770, total_pixels=8040867
4732018-07-07 22:03:32,569 packet decoding sequence 23 for window 5: 312x259 took 1.7ms
4742018-07-07 22:03:32,570 record_latency: took   58.2 ms round trip,   58.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   56.5 ms,   56.3 ms for echo
4752018-07-07 22:03:32,635 total_time=465514, total_pixels=8121675
4762018-07-07 22:03:32,743 total_time=465514, total_pixels=8121675
4772018-07-07 22:03:32,860 total_time=465514, total_pixels=8121675
4782018-07-07 22:03:32,889 packet decoding sequence 1 for window 6: 244x180 took 2.7ms
4792018-07-07 22:03:32,890 record_latency: took   95.4 ms round trip,   95.4 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in   92.8 ms,   92.7 ms for echo
4802018-07-07 22:03:32,890 may_recalculate(6, 43920) total 43920 pixels, scheduling recalculate work item
4812018-07-07 22:03:32,892 packet decoding sequence 44 for window 1: 190x25 took 5.1ms
4822018-07-07 22:03:32,892 record_latency: took   15.8 ms round trip,   15.8 for echo,    5.0 for decoding of     4750 pixels,       58 bytes sent over the network in   10.7 ms,   10.7 ms for echo
4832018-07-07 22:03:32,893 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
4842018-07-07 22:03:32,955 total_time=473282, total_pixels=8170345
4852018-07-07 22:03:33,058 total_time=473282, total_pixels=8170345
4862018-07-07 22:03:33,161 total_time=473282, total_pixels=8170345
4872018-07-07 22:03:33,197 packet decoding sequence 2 for window 6: 244x180 took 1.4ms
4882018-07-07 22:03:33,197 record_latency: took   44.8 ms round trip,   44.2 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   43.5 ms,   42.8 ms for echo
4892018-07-07 22:03:33,258 total_time=474650, total_pixels=8214265
4902018-07-07 22:03:33,301 packet decoding sequence 3 for window 6: 244x180 took 2.0ms
4912018-07-07 22:03:33,301 record_latency: took   51.0 ms round trip,   50.9 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in   48.9 ms,   48.8 ms for echo
4922018-07-07 22:03:33,356 total_time=476687, total_pixels=8258185
4932018-07-07 22:03:33,394 packet decoding sequence 4 for window 6: 244x180 took 1.3ms
4942018-07-07 22:03:33,394 record_latency: took   47.9 ms round trip,   47.8 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   46.5 ms,   46.4 ms for echo
4952018-07-07 22:03:33,434 packet decoding sequence 5 for window 6: 244x180 took 1.2ms
4962018-07-07 22:03:33,435 record_latency: took    4.8 ms round trip,    4.0 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.5 ms,    2.8 ms for echo
4972018-07-07 22:03:33,456 total_time=479231, total_pixels=8346025
4982018-07-07 22:03:33,502 packet decoding sequence 6 for window 6: 244x180 took 2.3ms
4992018-07-07 22:03:33,502 record_latency: took   39.3 ms round trip,   39.2 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in   37.0 ms,   36.9 ms for echo
5002018-07-07 22:03:33,528 packet decoding sequence 7 for window 6: 244x180 took 2.0ms
5012018-07-07 22:03:33,528 record_latency: took    4.7 ms round trip,    4.6 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.7 ms,    2.6 ms for echo
5022018-07-07 22:03:33,561 total_time=483580, total_pixels=8433865
5032018-07-07 22:03:33,661 total_time=483580, total_pixels=8433865
5042018-07-07 22:03:33,728 packet decoding sequence 8 for window 6: 244x180 took 1.3ms
5052018-07-07 22:03:33,728 record_latency: took    4.9 ms round trip,    4.9 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.6 ms,    3.6 ms for echo
5062018-07-07 22:03:33,770 total_time=484873, total_pixels=8477785
5072018-07-07 22:03:33,811 packet decoding sequence 9 for window 6: 244x180 took 1.3ms
5082018-07-07 22:03:33,811 record_latency: took   48.7 ms round trip,   48.6 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   47.4 ms,   47.4 ms for echo
5092018-07-07 22:03:33,814 calculate_batch_delay for wid=1 current batch delay=23, last update 22 seconds ago
5102018-07-07 22:03:33,815 calculate_batch_delay for wid=1, 2047171 pixels updated since the last update
5112018-07-07 22:03:33,815 calculate_batch_delay for wid=1, skipping - only 43 bytes sent since the last update
5122018-07-07 22:03:33,816 calculate_batch_delay for wid=6 current batch delay=22, last update 416878 seconds ago
5132018-07-07 22:03:33,816 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
5142018-07-07 22:03:33,817 update_batch_delay: client-decode-speed          : 0.97,0.00  {'avg': 36, 'recent': 35}
5152018-07-07 22:03:33,817 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 83}
5162018-07-07 22:03:33,817 update_batch_delay: client-latency               : 1.12,0.15  {'target': 6, 'weight_multiplier': 427, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1254, 'div': 1000, 'avg': 21, 'recent': 26}
5172018-07-07 22:03:33,818 update_batch_delay: client-ping-latency          : 0.90,0.06  {'target': 6, 'weight_multiplier': 179, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 805, 'div': 1000, 'avg': 7, 'recent': 9}
5182018-07-07 22:03:33,818 update_batch_delay: server-ping-latency          : 0.81,0.07  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 650, 'div': 1000, 'avg': 4, 'recent': 6}
5192018-07-07 22:03:33,818 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}
5202018-07-07 22:03:33,820 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
5212018-07-07 22:03:33,824 update_batch_delay: compression-work-queue       : 0.00,0.04  {'target': 1000, 'weight_multiplier': 39, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 1, 'recent': 0}
5222018-07-07 22:03:33,824 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
5232018-07-07 22:03:33,825 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
5242018-07-07 22:03:33,827 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
5252018-07-07 22:03:33,828 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
5262018-07-07 22:03:33,828 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
5272018-07-07 22:03:33,828 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
5282018-07-07 22:03:33,829 update_batch_delay: delay=16
5292018-07-07 22:03:33,864 total_time=486129, total_pixels=8521705
5302018-07-07 22:03:33,902 packet decoding sequence 10 for window 6: 244x180 took 1.4ms
5312018-07-07 22:03:33,903 record_latency: took   47.8 ms round trip,   47.4 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   46.5 ms,   46.1 ms for echo
5322018-07-07 22:03:33,903 may_recalculate(6, 43920) total 43920 pixels, scheduling recalculate work item
5332018-07-07 22:03:33,960 total_time=487485, total_pixels=8565625
5342018-07-07 22:03:34,084 total_time=487485, total_pixels=8565625
5352018-07-07 22:03:34,168 total_time=487485, total_pixels=8565625
5362018-07-07 22:03:34,212 packet decoding sequence 1 for window 7: 237x156 took 3.9ms
5372018-07-07 22:03:34,212 record_latency: took  112.9 ms round trip,  112.5 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in  109.0 ms,  108.5 ms for echo
5382018-07-07 22:03:34,213 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
5392018-07-07 22:03:34,215 packet decoding sequence 45 for window 1: 121x25 took 5.1ms
5402018-07-07 22:03:34,215 record_latency: took   43.3 ms round trip,   43.2 for echo,    5.0 for decoding of     3025 pixels,       57 bytes sent over the network in   38.2 ms,   38.1 ms for echo
5412018-07-07 22:03:34,215 may_recalculate(1, 3025) total 3025 pixels, scheduling recalculate work item
5422018-07-07 22:03:34,269 total_time=496512, total_pixels=8605622
5432018-07-07 22:03:34,375 total_time=496512, total_pixels=8605622
5442018-07-07 22:03:34,475 total_time=496512, total_pixels=8605622
5452018-07-07 22:03:34,524 packet decoding sequence 1 for window 8: 248x181 took 1.7ms
5462018-07-07 22:03:34,524 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.5 ms,    2.4 ms for echo
5472018-07-07 22:03:34,525 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
5482018-07-07 22:03:34,577 total_time=498181, total_pixels=8650510
5492018-07-07 22:03:34,620 packet decoding sequence 46 for window 1: 76x25 took 2.6ms
5502018-07-07 22:03:34,620 record_latency: took   28.9 ms round trip,   28.8 for echo,    2.0 for decoding of     1900 pixels,       57 bytes sent over the network in   26.3 ms,   26.3 ms for echo
5512018-07-07 22:03:34,674 total_time=500748, total_pixels=8652410
5522018-07-07 22:03:34,714 packet decoding sequence 2 for window 8: 248x181 took 1.4ms
5532018-07-07 22:03:34,714 record_latency: took   27.2 ms round trip,   27.1 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   25.8 ms,   25.7 ms for echo
5542018-07-07 22:03:34,772 total_time=502176, total_pixels=8697298
5552018-07-07 22:03:34,815 calculate_batch_delay for wid=1 current batch delay=23, last update 23 seconds ago
5562018-07-07 22:03:34,815 calculate_batch_delay for wid=1, 2052096 pixels updated since the last update
5572018-07-07 22:03:34,815 calculate_batch_delay for wid=1, skipping - only 45 bytes sent since the last update
5582018-07-07 22:03:34,816 calculate_batch_delay for wid=8 current batch delay=25, last update 416879 seconds ago
5592018-07-07 22:03:34,816 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
5602018-07-07 22:03:34,817 update_batch_delay: client-decode-speed          : 0.99,0.00  {'avg': 35, 'recent': 35}
5612018-07-07 22:03:34,817 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 143, 'elapsed': 177}
5622018-07-07 22:03:34,817 update_batch_delay: client-latency               : 1.14,0.16  {'target': 6, 'weight_multiplier': 427, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1290, 'div': 1000, 'avg': 23, 'recent': 29}
5632018-07-07 22:03:34,817 update_batch_delay: client-ping-latency          : 0.73,0.09  {'target': 6, 'weight_multiplier': 179, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 533, 'div': 1000, 'avg': 6, 'recent': 6}
5642018-07-07 22:03:34,817 update_batch_delay: server-ping-latency          : 0.52,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 271, 'div': 1000, 'avg': 3, 'recent': 2}
5652018-07-07 22:03:34,818 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}
5662018-07-07 22:03:34,818 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}
5672018-07-07 22:03:34,818 update_batch_delay: compression-work-queue       : 0.00,0.03  {'target': 1000, 'weight_multiplier': 34, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 1, 'recent': 0}
5682018-07-07 22:03:34,818 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}
5692018-07-07 22:03:34,818 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
5702018-07-07 22:03:34,818 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
5712018-07-07 22:03:34,819 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
5722018-07-07 22:03:34,819 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
5732018-07-07 22:03:34,819 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
5742018-07-07 22:03:34,819 update_batch_delay: delay=18
5752018-07-07 22:03:34,821 packet decoding sequence 3 for window 8: 248x181 took 1.2ms
5762018-07-07 22:03:34,822 record_latency: took   11.9 ms round trip,   11.6 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   10.7 ms,   10.4 ms for echo
5772018-07-07 22:03:34,823 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
5782018-07-07 22:03:34,874 total_time=503385, total_pixels=8742186
5792018-07-07 22:03:34,978 total_time=503385, total_pixels=8742186
5802018-07-07 22:03:35,021 packet decoding sequence 4 for window 8: 248x181 took 1.5ms
5812018-07-07 22:03:35,021 record_latency: took   62.8 ms round trip,   62.4 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   61.3 ms,   60.9 ms for echo
5822018-07-07 22:03:35,073 total_time=504886, total_pixels=8787074
5832018-07-07 22:03:35,181 total_time=504886, total_pixels=8787074
5842018-07-07 22:03:35,286 total_time=504886, total_pixels=8787074
5852018-07-07 22:03:35,346 packet decoding sequence 5 for window 8: 248x181 took 1.7ms
5862018-07-07 22:03:35,347 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.6 ms,    2.6 ms for echo
5872018-07-07 22:03:35,379 total_time=506571, total_pixels=8831962
5882018-07-07 22:03:35,480 total_time=506571, total_pixels=8831962
5892018-07-07 22:03:35,583 total_time=506571, total_pixels=8831962
5902018-07-07 22:03:35,677 total_time=506571, total_pixels=8831962
5912018-07-07 22:03:35,784 total_time=506571, total_pixels=8831962
5922018-07-07 22:03:35,883 total_time=506571, total_pixels=8831962
5932018-07-07 22:03:35,935 packet decoding sequence 47 for window 1: 363x45 took 1.3ms
5942018-07-07 22:03:35,935 record_latency: took   28.9 ms round trip,   28.9 for echo,    1.0 for decoding of    16335 pixels,       61 bytes sent over the network in   27.6 ms,   27.6 ms for echo
5952018-07-07 22:03:35,936 may_recalculate(1, 16335) total 16335 pixels, scheduling recalculate work item
5962018-07-07 22:03:35,985 total_time=507890, total_pixels=8848297
5972018-07-07 22:03:36,053 packet decoding sequence 48 for window 1: 12x20 took 1.3ms
5982018-07-07 22:03:36,054 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.9 ms,    2.8 ms for echo
5992018-07-07 22:03:36,085 total_time=509177, total_pixels=8848537
6002018-07-07 22:03:36,143 packet decoding sequence 49 for window 1: 12x20 took 1.0ms
6012018-07-07 22:03:36,143 record_latency: took   43.3 ms round trip,   43.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   42.3 ms,   42.2 ms for echo
6022018-07-07 22:03:36,145 packet decoding sequence 50 for window 1: 12x20 took 3.3ms
6032018-07-07 22:03:36,146 record_latency: took   15.0 ms round trip,   15.0 for echo,    3.0 for decoding of      240 pixels,       55 bytes sent over the network in   11.7 ms,   11.7 ms for echo
6042018-07-07 22:03:36,183 total_time=513547, total_pixels=8849017
6052018-07-07 22:03:36,230 packet decoding sequence 51 for window 1: 12x20 took 1.1ms
6062018-07-07 22:03:36,231 record_latency: took   40.0 ms round trip,   39.8 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   38.9 ms,   38.6 ms for echo
6072018-07-07 22:03:36,251 packet decoding sequence 52 for window 1: 12x20 took 1.2ms
6082018-07-07 22:03:36,251 record_latency: took   10.7 ms round trip,   10.6 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    9.5 ms,    9.4 ms for echo
6092018-07-07 22:03:36,290 total_time=515882, total_pixels=8849497
6102018-07-07 22:03:36,332 packet decoding sequence 53 for window 1: 12x20 took 1.0ms
6112018-07-07 22:03:36,332 record_latency: took    3.0 ms round trip,    2.6 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.1 ms,    1.6 ms for echo
6122018-07-07 22:03:36,387 total_time=516844, total_pixels=8849737
6132018-07-07 22:03:36,408 packet decoding sequence 54 for window 1: 12x20 took 1.1ms
6142018-07-07 22:03:36,408 record_latency: took   30.8 ms round trip,   30.5 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   29.7 ms,   29.4 ms for echo
6152018-07-07 22:03:36,488 total_time=517942, total_pixels=8849977
6162018-07-07 22:03:36,549 packet decoding sequence 55 for window 1: 12x20 took 1.0ms
6172018-07-07 22:03:36,549 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    3.3 ms,    3.2 ms for echo
6182018-07-07 22:03:36,588 total_time=518945, total_pixels=8850217
6192018-07-07 22:03:36,639 packet decoding sequence 56 for window 1: 12x20 took 1.0ms
6202018-07-07 22:03:36,639 record_latency: took    3.4 ms round trip,    3.4 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.5 ms,    2.4 ms for echo
6212018-07-07 22:03:36,692 total_time=519899, total_pixels=8850457
6222018-07-07 22:03:36,792 total_time=519899, total_pixels=8850457
6232018-07-07 22:03:36,815 calculate_batch_delay for wid=1 current batch delay=23, last update 25 seconds ago
6242018-07-07 22:03:36,816 calculate_batch_delay for wid=1, 2070591 pixels updated since the last update
6252018-07-07 22:03:36,816 calculate_batch_delay for wid=1, skipping - only 55 bytes sent since the last update
6262018-07-07 22:03:36,893 total_time=519899, total_pixels=8850457
6272018-07-07 22:03:36,994 total_time=519899, total_pixels=8850457
6282018-07-07 22:03:37,095 total_time=519899, total_pixels=8850457
6292018-07-07 22:03:37,195 total_time=519899, total_pixels=8850457
6302018-07-07 22:03:37,297 total_time=519899, total_pixels=8850457
6312018-07-07 22:03:37,402 total_time=519899, total_pixels=8850457
6322018-07-07 22:03:37,506 total_time=519899, total_pixels=8850457
6332018-07-07 22:03:37,596 total_time=519899, total_pixels=8850457
6342018-07-07 22:03:37,700 total_time=519899, total_pixels=8850457
6352018-07-07 22:03:37,803 total_time=519899, total_pixels=8850457
6362018-07-07 22:03:37,903 total_time=519899, total_pixels=8850457
6372018-07-07 22:03:38,002 total_time=519899, total_pixels=8850457
6382018-07-07 22:03:38,105 total_time=519899, total_pixels=8850457
6392018-07-07 22:03:38,206 total_time=519899, total_pixels=8850457
6402018-07-07 22:03:38,306 total_time=519899, total_pixels=8850457
6412018-07-07 22:03:38,406 total_time=519899, total_pixels=8850457
6422018-07-07 22:03:38,506 total_time=519899, total_pixels=8850457
6432018-07-07 22:03:38,603 total_time=519899, total_pixels=8850457
6442018-07-07 22:03:38,709 total_time=519899, total_pixels=8850457
6452018-07-07 22:03:38,809 total_time=519899, total_pixels=8850457
6462018-07-07 22:03:38,913 total_time=519899, total_pixels=8850457
6472018-07-07 22:03:39,011 total_time=519899, total_pixels=8850457
6482018-07-07 22:03:41,669 xpra client 1 disconnected.
6492018-07-07 22:03:41,681 sound source stopping
6502018-07-07 22:05:13,034 New unix-domain connection received on /run/user/1000/xpra/beelink-1
6512018-07-07 22:05:13,041 Handshake complete; enabling connection
6522018-07-07 22:05:13,048  automatic picture encoding enabled, also available:
6532018-07-07 22:05:13,048   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
6542018-07-07 22:05:13,050  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.rVpPxx.mmap
6552018-07-07 22:05:13,051 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
6562018-07-07 22:05:13,051  connected from 'beelink' as 'steved' - 'Steve Dee'
6572018-07-07 22:05:13,054  client root window size is 2880x900 with 1 display:
6582018-07-07 22:05:13,055   :0.0 (761x237 mm - DPI: 96x96)
6592018-07-07 22:05:13,055     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
6602018-07-07 22:05:13,056     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
6612018-07-07 22:05:13,064 best resolution matching 2880x900 is unchanged: 2880x960
6622018-07-07 22:05:13,070 setting key repeat rate from client: 500ms delay / 50ms interval
6632018-07-07 22:05:13,073 keyboard mapping already configured (skipped)
6642018-07-07 22:05:13,117 client @03.920 Xpra X11 server version 2.3.2-r19729 64-bit
6652018-07-07 22:05:13,125 client @03.921  running on Linux Ubuntu 18.04 bionic
6662018-07-07 22:05:13,143 client @03.929 Attached to :1
6672018-07-07 22:05:13,154 client @03.930  (press Control-C to detach)
6682018-07-07 22:05:13,187 total_time=0, total_pixels=0
6692018-07-07 22:05:13,238 client @04.042 server does not support xi input devices
6702018-07-07 22:05:13,238 client @04.042  server uses: xtest
6712018-07-07 22:05:13,432 New unix-domain connection received on /run/user/1000/xpra/beelink-1
6722018-07-07 22:05:13,432 New unix-domain connection received on /home/steved/.xpra/beelink-1
6732018-07-07 22:05:15,032 using pulseaudio device:
6742018-07-07 22:05:15,032  'Monitor of Xpra Speaker'
6752018-07-07 22:05:17,457 sound source using 'opus' audio codec
6762018-07-07 22:05:36,315 packet decoding sequence 1 for window 9: 734x459 took 8.4ms
6772018-07-07 22:05:36,315 record_latency: took   26.0 ms round trip,   26.0 for echo,    8.0 for decoding of   336906 pixels,       57 bytes sent over the network in   17.6 ms,   17.5 ms for echo
6782018-07-07 22:05:36,315 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
6792018-07-07 22:05:36,316 calculate_batch_delay for wid=9 current batch delay=29, last update 417001 seconds ago
6802018-07-07 22:05:36,317 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
6812018-07-07 22:05:36,317 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 26, 'recent': 26}
6822018-07-07 22:05:36,318 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 32}
6832018-07-07 22:05:36,318 update_batch_delay: client-latency               : 0.87,0.40  {'target': 22, 'weight_multiplier': 1089, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 750, 'div': 1000, 'avg': 17, 'recent': 17}
6842018-07-07 22:05:36,318 update_batch_delay: client-ping-latency          : 0.60,0.13  {'target': 7, 'weight_multiplier': 198, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 360, 'div': 1000, 'avg': 2, 'recent': 2}
6852018-07-07 22:05:36,320 update_batch_delay: server-ping-latency          : 0.58,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 332, 'div': 1000, 'avg': 5, 'recent': 3}
6862018-07-07 22:05:36,320 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}
6872018-07-07 22:05:36,320 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}
6882018-07-07 22:05:36,321 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}
6892018-07-07 22:05:36,322 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}
6902018-07-07 22:05:36,322 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
6912018-07-07 22:05:36,322 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
6922018-07-07 22:05:36,322 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
6932018-07-07 22:05:36,322 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
6942018-07-07 22:05:36,322 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
6952018-07-07 22:05:36,323 update_batch_delay: delay=23
6962018-07-07 22:05:36,544 packet decoding sequence 2 for window 9: 12x20 took 2.2ms
6972018-07-07 22:05:36,545 record_latency: took    7.2 ms round trip,    7.1 for echo,    2.0 for decoding of      240 pixels,       59 bytes sent over the network in    5.0 ms,    4.8 ms for echo
6982018-07-07 22:05:36,870 packet decoding sequence 3 for window 9: 734x459 took 3.8ms
6992018-07-07 22:05:36,870 record_latency: took    7.5 ms round trip,    7.3 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.7 ms,    3.5 ms for echo
7002018-07-07 22:05:36,871 may_recalculate(9, 336906) total 337146 pixels, scheduling recalculate work item
7012018-07-07 22:05:36,916 packet decoding sequence 4 for window 9: 12x20 took 1.6ms
7022018-07-07 22:05:36,916 record_latency: took    4.3 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.7 ms,    2.4 ms for echo
7032018-07-07 22:05:36,949 packet decoding sequence 5 for window 9: 12x20 took 2.2ms
7042018-07-07 22:05:36,949 record_latency: took    8.0 ms round trip,    7.3 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.8 ms,    5.0 ms for echo
7052018-07-07 22:05:36,986 packet decoding sequence 6 for window 9: 12x20 took 1.5ms
7062018-07-07 22:05:36,987 record_latency: took    5.5 ms round trip,    5.4 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.0 ms,    3.9 ms for echo
7072018-07-07 22:05:36,992 packet decoding sequence 7 for window 9: 12x20 took 1.2ms
7082018-07-07 22:05:36,992 record_latency: took    7.8 ms round trip,    7.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.6 ms,    6.0 ms for echo
7092018-07-07 22:05:36,994 packet decoding sequence 8 for window 9: 12x20 took 3.4ms
7102018-07-07 22:05:36,994 record_latency: took    8.8 ms round trip,    8.4 for echo,    3.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.4 ms,    5.0 ms for echo
7112018-07-07 22:05:37,316 calculate_batch_delay for wid=9 current batch delay=23, last update 1 seconds ago
7122018-07-07 22:05:37,317 calculate_batch_delay for wid=9, 338346 pixels updated since the last update
7132018-07-07 22:05:37,317 calculate_batch_delay for wid=9, skipping - only 7 bytes sent since the last update
7142018-07-07 22:05:37,879 packet decoding sequence 9 for window 9: 12x20 took 4.3ms
7152018-07-07 22:05:37,879 record_latency: took  205.2 ms round trip,  205.1 for echo,    4.0 for decoding of      240 pixels,       56 bytes sent over the network in  200.9 ms,  200.8 ms for echo
7162018-07-07 22:05:37,954 packet decoding sequence 10 for window 9: 734x459 took 6.1ms
7172018-07-07 22:05:37,955 record_latency: took    9.4 ms round trip,    9.2 for echo,    6.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.3 ms,    3.1 ms for echo
7182018-07-07 22:05:37,955 may_recalculate(9, 336906) total 337146 pixels, scheduling recalculate work item
7192018-07-07 22:05:37,998 packet decoding sequence 11 for window 9: 12x20 took 1.4ms
7202018-07-07 22:05:37,999 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    2.8 ms,    2.7 ms for echo
7212018-07-07 22:05:38,165 packet decoding sequence 12 for window 9: 734x459 took 3.7ms
7222018-07-07 22:05:38,165 record_latency: took    6.7 ms round trip,    6.6 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.9 ms,    2.8 ms for echo
7232018-07-07 22:05:38,316 calculate_batch_delay for wid=9 current batch delay=39, last update 2 seconds ago
7242018-07-07 22:05:38,317 calculate_batch_delay for wid=9, 1012638 pixels updated since the last update
7252018-07-07 22:05:38,317 calculate_batch_delay for wid=9, skipping - only 11 bytes sent since the last update
7262018-07-07 22:05:38,555 packet decoding sequence 1 for window 10: 438x131 took 2.6ms
7272018-07-07 22:05:38,555 record_latency: took   47.5 ms round trip,   47.3 for echo,    2.0 for decoding of    57378 pixels,       60 bytes sent over the network in   44.9 ms,   44.8 ms for echo
7282018-07-07 22:05:38,555 may_recalculate(10, 57378) total 57378 pixels, scheduling recalculate work item
7292018-07-07 22:05:38,558 packet decoding sequence 2 for window 10: 438x131 took 5.6ms
7302018-07-07 22:05:38,558 record_latency: took   38.7 ms round trip,   38.5 for echo,    5.0 for decoding of    57378 pixels,       60 bytes sent over the network in   33.1 ms,   33.0 ms for echo
7312018-07-07 22:05:38,637 packet decoding sequence 13 for window 9: 734x459 took 5.9ms
7322018-07-07 22:05:38,638 record_latency: took    9.1 ms round trip,    9.1 for echo,    5.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.2 ms,    3.1 ms for echo
7332018-07-07 22:05:38,638 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
7342018-07-07 22:05:39,317 calculate_batch_delay for wid=9 current batch delay=39, last update 3 seconds ago
7352018-07-07 22:05:39,318 calculate_batch_delay for wid=9, 1349544 pixels updated since the last update
7362018-07-07 22:05:39,318 calculate_batch_delay for wid=9, skipping - only 12 bytes sent since the last update
7372018-07-07 22:05:39,319 calculate_batch_delay for wid=10 current batch delay=16, last update 417004 seconds ago
7382018-07-07 22:05:39,319 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
7392018-07-07 22:05:39,320 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 64, 'recent': 64}
7402018-07-07 22:05:39,320 update_batch_delay: damage-rate                  : 0.38,0.79  {'max_latency': 100, 'elapsed': 818}
7412018-07-07 22:05:39,321 update_batch_delay: client-latency               : 1.07,0.13  {'target': 7, 'weight_multiplier': 470, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1152, 'div': 1000, 'avg': 25, 'recent': 27}
7422018-07-07 22:05:39,321 update_batch_delay: client-ping-latency          : 0.76,0.10  {'target': 7, 'weight_multiplier': 198, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 576, 'div': 1000, 'avg': 3, 'recent': 5}
7432018-07-07 22:05:39,321 update_batch_delay: server-ping-latency          : 0.50,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 249, 'div': 1000, 'avg': 3, 'recent': 2}
7442018-07-07 22:05:39,321 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}
7452018-07-07 22:05:39,322 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
7462018-07-07 22:05:39,322 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}
7472018-07-07 22:05:39,322 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
7482018-07-07 22:05:39,322 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
7492018-07-07 22:05:39,324 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
7502018-07-07 22:05:39,324 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
7512018-07-07 22:05:39,325 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
7522018-07-07 22:05:39,326 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
7532018-07-07 22:05:39,326 update_batch_delay: delay=11
7542018-07-07 22:05:39,704 packet decoding sequence 14 for window 9: 734x459 took 3.8ms
7552018-07-07 22:05:39,705 record_latency: took    8.5 ms round trip,    8.4 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    4.7 ms,    4.6 ms for echo
7562018-07-07 22:05:39,705 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
7572018-07-07 22:05:39,750 packet decoding sequence 15 for window 9: 12x20 took 1.7ms
7582018-07-07 22:05:39,750 record_latency: took    4.2 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.6 ms,    2.5 ms for echo
7592018-07-07 22:05:39,805 packet decoding sequence 16 for window 9: 12x20 took 1.8ms
7602018-07-07 22:05:39,806 record_latency: took    8.1 ms round trip,    7.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.2 ms,    6.0 ms for echo
7612018-07-07 22:05:39,853 packet decoding sequence 17 for window 9: 12x20 took 1.7ms
7622018-07-07 22:05:39,854 record_latency: took    5.2 ms round trip,    5.1 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.5 ms,    3.4 ms for echo
7632018-07-07 22:05:40,317 calculate_batch_delay for wid=9 current batch delay=39, last update 4 seconds ago
7642018-07-07 22:05:40,318 calculate_batch_delay for wid=9, 1687170 pixels updated since the last update
7652018-07-07 22:05:40,318 calculate_batch_delay for wid=9, skipping - only 16 bytes sent since the last update
7662018-07-07 22:05:41,179 packet decoding sequence 18 for window 9: 734x459 took 3.0ms
7672018-07-07 22:05:41,180 record_latency: took    5.8 ms round trip,    5.7 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.8 ms,    2.7 ms for echo
7682018-07-07 22:05:41,180 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
7692018-07-07 22:05:41,318 calculate_batch_delay for wid=9 current batch delay=39, last update 5 seconds ago
7702018-07-07 22:05:41,319 calculate_batch_delay for wid=9, 2024076 pixels updated since the last update
7712018-07-07 22:05:41,319 calculate_batch_delay for wid=9, skipping - only 17 bytes sent since the last update
7722018-07-07 22:05:44,134 total_time=65834, total_pixels=2475738
7732018-07-07 22:05:44,431 packet decoding sequence 19 for window 9: 263x17 took 4.2ms
7742018-07-07 22:05:44,432 record_latency: took    8.0 ms round trip,    7.9 for echo,    4.0 for decoding of     4471 pixels,       57 bytes sent over the network in    3.8 ms,    3.7 ms for echo
7752018-07-07 22:05:44,432 may_recalculate(9, 4471) total 4471 pixels, scheduling recalculate work item
7762018-07-07 22:05:44,433 calculate_batch_delay for wid=9 current batch delay=39, last update 8 seconds ago
7772018-07-07 22:05:44,433 calculate_batch_delay for wid=9, 2028547 pixels updated since the last update
7782018-07-07 22:05:44,433 calculate_batch_delay for wid=9, skipping - only 18 bytes sent since the last update
7792018-07-07 22:05:44,459 packet decoding sequence 20 for window 9: 12x20 took 1.5ms
7802018-07-07 22:05:44,460 record_latency: took    5.3 ms round trip,    5.2 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    3.7 ms,    3.7 ms for echo
7812018-07-07 22:05:44,916 packet decoding sequence 21 for window 9: 12x20 took 1.1ms
7822018-07-07 22:05:44,917 record_latency: took    3.1 ms round trip,    2.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.0 ms,    1.8 ms for echo
7832018-07-07 22:05:44,952 packet decoding sequence 22 for window 9: 12x20 took 1.3ms
7842018-07-07 22:05:44,953 record_latency: took    4.3 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.0 ms,    2.7 ms for echo
7852018-07-07 22:05:45,006 packet decoding sequence 23 for window 9: 12x20 took 1.8ms
7862018-07-07 22:05:45,007 record_latency: took    7.3 ms round trip,    7.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.5 ms,    5.4 ms for echo
7872018-07-07 22:05:45,973 total_time=75812, total_pixels=2481169
7882018-07-07 22:05:46,075 total_time=75812, total_pixels=2481169
7892018-07-07 22:05:46,176 total_time=75812, total_pixels=2481169
7902018-07-07 22:05:46,281 total_time=75812, total_pixels=2481169
7912018-07-07 22:05:46,376 total_time=75812, total_pixels=2481169
7922018-07-07 22:05:46,482 total_time=75812, total_pixels=2481169
7932018-07-07 22:05:46,578 total_time=75812, total_pixels=2481169
7942018-07-07 22:05:46,683 total_time=75812, total_pixels=2481169
7952018-07-07 22:05:46,782 total_time=75812, total_pixels=2481169
7962018-07-07 22:05:46,884 total_time=75812, total_pixels=2481169
7972018-07-07 22:05:46,983 total_time=75812, total_pixels=2481169
7982018-07-07 22:05:47,078 total_time=75812, total_pixels=2481169
7992018-07-07 22:05:47,186 total_time=75812, total_pixels=2481169
8002018-07-07 22:05:47,285 total_time=75812, total_pixels=2481169
8012018-07-07 22:05:47,385 total_time=75812, total_pixels=2481169
8022018-07-07 22:05:47,489 total_time=75812, total_pixels=2481169
8032018-07-07 22:05:47,589 total_time=75812, total_pixels=2481169
8042018-07-07 22:05:47,688 total_time=75812, total_pixels=2481169
8052018-07-07 22:05:47,787 total_time=75812, total_pixels=2481169
8062018-07-07 22:05:47,891 total_time=75812, total_pixels=2481169
8072018-07-07 22:05:47,993 total_time=75812, total_pixels=2481169
8082018-07-07 22:05:48,091 total_time=75812, total_pixels=2481169
8092018-07-07 22:05:48,192 total_time=75812, total_pixels=2481169
8102018-07-07 22:05:48,293 total_time=75812, total_pixels=2481169
8112018-07-07 22:05:48,392 total_time=75812, total_pixels=2481169
8122018-07-07 22:05:48,494 total_time=75812, total_pixels=2481169
8132018-07-07 22:05:48,595 total_time=75812, total_pixels=2481169
8142018-07-07 22:05:48,695 total_time=75812, total_pixels=2481169
8152018-07-07 22:05:48,797 total_time=75812, total_pixels=2481169
8162018-07-07 22:05:48,896 total_time=75812, total_pixels=2481169
8172018-07-07 22:05:49,000 total_time=75812, total_pixels=2481169
8182018-07-07 22:05:49,095 total_time=75812, total_pixels=2481169
8192018-07-07 22:05:49,200 total_time=75812, total_pixels=2481169
8202018-07-07 22:05:49,302 total_time=75812, total_pixels=2481169
8212018-07-07 22:05:49,400 total_time=75812, total_pixels=2481169
8222018-07-07 22:05:49,498 total_time=75812, total_pixels=2481169
8232018-07-07 22:05:49,602 total_time=75812, total_pixels=2481169
8242018-07-07 22:05:49,705 total_time=75812, total_pixels=2481169
8252018-07-07 22:05:49,804 total_time=75812, total_pixels=2481169
8262018-07-07 22:05:49,904 total_time=75812, total_pixels=2481169
8272018-07-07 22:05:50,005 total_time=75812, total_pixels=2481169
8282018-07-07 22:05:50,105 total_time=75812, total_pixels=2481169
8292018-07-07 22:05:50,209 total_time=75812, total_pixels=2481169
8302018-07-07 22:05:50,304 total_time=75812, total_pixels=2481169
8312018-07-07 22:05:50,405 total_time=75812, total_pixels=2481169
8322018-07-07 22:05:50,512 total_time=75812, total_pixels=2481169
8332018-07-07 22:05:50,608 total_time=75812, total_pixels=2481169
8342018-07-07 22:05:50,712 total_time=75812, total_pixels=2481169
8352018-07-07 22:05:50,809 total_time=75812, total_pixels=2481169
8362018-07-07 22:05:50,909 total_time=75812, total_pixels=2481169
8372018-07-07 22:05:51,013 total_time=75812, total_pixels=2481169
8382018-07-07 22:05:51,070 packet decoding sequence 24 for window 9: 336x20 took 3.5ms
8392018-07-07 22:05:51,070 record_latency: took    6.2 ms round trip,    6.2 for echo,    3.0 for decoding of     6720 pixels,       57 bytes sent over the network in    2.8 ms,    2.7 ms for echo
8402018-07-07 22:05:51,071 may_recalculate(9, 6720) total 7680 pixels, scheduling recalculate work item
8412018-07-07 22:05:51,071 calculate_batch_delay for wid=9 current batch delay=39, last update 14 seconds ago
8422018-07-07 22:05:51,072 calculate_batch_delay for wid=9, 2036227 pixels updated since the last update
8432018-07-07 22:05:51,074 calculate_batch_delay for wid=9, skipping - only 23 bytes sent since the last update
8442018-07-07 22:05:51,112 total_time=79289, total_pixels=2487889
8452018-07-07 22:05:51,165 packet decoding sequence 25 for window 9: 12x20 took 2.1ms
8462018-07-07 22:05:51,166 record_latency: took    5.3 ms round trip,    5.2 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.2 ms,    3.1 ms for echo
8472018-07-07 22:05:51,211 total_time=81345, total_pixels=2488129
8482018-07-07 22:05:51,246 packet decoding sequence 26 for window 9: 12x20 took 1.2ms
8492018-07-07 22:05:51,247 record_latency: took   34.3 ms round trip,   34.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   33.0 ms,   33.0 ms for echo
8502018-07-07 22:05:51,265 packet decoding sequence 27 for window 9: 12x20 took 2.8ms
8512018-07-07 22:05:51,265 record_latency: took    6.5 ms round trip,    5.2 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.6 ms,    2.4 ms for echo
8522018-07-07 22:05:51,316 total_time=85432, total_pixels=2488609
8532018-07-07 22:05:51,346 packet decoding sequence 28 for window 9: 12x20 took 1.7ms
8542018-07-07 22:05:51,347 record_latency: took   28.8 ms round trip,   28.5 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   27.1 ms,   26.8 ms for echo
8552018-07-07 22:05:51,373 packet decoding sequence 29 for window 9: 12x20 took 1.1ms
8562018-07-07 22:05:51,373 record_latency: took    4.2 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.0 ms,    2.9 ms for echo
8572018-07-07 22:05:51,414 total_time=88238, total_pixels=2489089
8582018-07-07 22:05:51,517 total_time=88238, total_pixels=2489089
8592018-07-07 22:05:51,636 total_time=88238, total_pixels=2489089
8602018-07-07 22:05:51,647 packet decoding sequence 1 for window 11: 438x131 took 3.5ms
8612018-07-07 22:05:51,648 record_latency: took   27.5 ms round trip,   27.4 for echo,    3.0 for decoding of    57378 pixels,       60 bytes sent over the network in   24.0 ms,   23.9 ms for echo
8622018-07-07 22:05:51,649 may_recalculate(11, 57378) total 57378 pixels, scheduling recalculate work item
8632018-07-07 22:05:51,705 packet decoding sequence 30 for window 9: 734x459 took 6.4ms
8642018-07-07 22:05:51,706 record_latency: took    9.4 ms round trip,    9.3 for echo,    6.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.0 ms,    2.9 ms for echo
8652018-07-07 22:05:51,706 may_recalculate(9, 336906) total 338106 pixels, scheduling recalculate work item
8662018-07-07 22:05:51,738 total_time=98120, total_pixels=2883373
8672018-07-07 22:05:51,840 total_time=98120, total_pixels=2883373
8682018-07-07 22:05:51,940 total_time=98120, total_pixels=2883373
8692018-07-07 22:05:52,039 total_time=98120, total_pixels=2883373
8702018-07-07 22:05:52,072 calculate_batch_delay for wid=9 current batch delay=39, last update 15 seconds ago
8712018-07-07 22:05:52,072 calculate_batch_delay for wid=9, 2374333 pixels updated since the last update
8722018-07-07 22:05:52,072 calculate_batch_delay for wid=9, skipping - only 29 bytes sent since the last update
8732018-07-07 22:05:52,073 calculate_batch_delay for wid=11 current batch delay=45, last update 417016 seconds ago
8742018-07-07 22:05:52,073 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
8752018-07-07 22:05:52,073 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 63, 'recent': 63}
8762018-07-07 22:05:52,073 update_batch_delay: damage-rate                  : 0.70,0.55  {'max_latency': 100, 'elapsed': 501}
8772018-07-07 22:05:52,074 update_batch_delay: client-latency               : 0.88,0.15  {'target': 6, 'weight_multiplier': 431, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 780, 'div': 1000, 'avg': 12, 'recent': 12}
8782018-07-07 22:05:52,074 update_batch_delay: client-ping-latency          : 0.74,0.10  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 543, 'div': 1000, 'avg': 3, 'recent': 4}
8792018-07-07 22:05:52,074 update_batch_delay: server-ping-latency          : 0.59,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 346, 'div': 1000, 'avg': 3, 'recent': 2}
8802018-07-07 22:05:52,074 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}
8812018-07-07 22:05:52,074 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
8822018-07-07 22:05:52,074 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}
8832018-07-07 22:05:52,074 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
8842018-07-07 22:05:52,075 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
8852018-07-07 22:05:52,075 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
8862018-07-07 22:05:52,075 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
8872018-07-07 22:05:52,075 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
8882018-07-07 22:05:52,075 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
8892018-07-07 22:05:52,075 update_batch_delay: delay=33
8902018-07-07 22:05:52,155 total_time=98120, total_pixels=2883373
8912018-07-07 22:05:52,256 total_time=98120, total_pixels=2883373
8922018-07-07 22:05:52,289 packet decoding sequence 31 for window 9: 234x25 took 1.4ms
8932018-07-07 22:05:52,289 record_latency: took    3.2 ms round trip,    3.2 for echo,    1.0 for decoding of     5850 pixels,       57 bytes sent over the network in    1.9 ms,    1.8 ms for echo
8942018-07-07 22:05:52,289 may_recalculate(9, 5850) total 5850 pixels, scheduling recalculate work item
8952018-07-07 22:05:52,382 total_time=99482, total_pixels=2889223
8962018-07-07 22:05:52,458 total_time=99482, total_pixels=2889223
8972018-07-07 22:05:52,516 packet decoding sequence 32 for window 9: 190x25 took 1.9ms
8982018-07-07 22:05:52,517 record_latency: took    5.4 ms round trip,    5.4 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    3.5 ms,    3.4 ms for echo
8992018-07-07 22:05:52,520 packet decoding sequence 1 for window 13: 244x180 took 2.0ms
9002018-07-07 22:05:52,521 record_latency: took    8.1 ms round trip,    8.0 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    6.1 ms,    6.1 ms for echo
9012018-07-07 22:05:52,521 may_recalculate(13, 43920) total 43920 pixels, scheduling recalculate work item
9022018-07-07 22:05:52,563 total_time=103391, total_pixels=2937893
9032018-07-07 22:05:52,665 total_time=103391, total_pixels=2937893
9042018-07-07 22:05:52,764 total_time=103391, total_pixels=2937893
9052018-07-07 22:05:52,866 total_time=103391, total_pixels=2937893
9062018-07-07 22:05:52,965 total_time=103391, total_pixels=2937893
9072018-07-07 22:05:53,095 calculate_batch_delay for wid=9 current batch delay=39, last update 16 seconds ago
9082018-07-07 22:05:53,096 calculate_batch_delay for wid=9, 2384933 pixels updated since the last update
9092018-07-07 22:05:53,099 calculate_batch_delay for wid=9, skipping - only 31 bytes sent since the last update
9102018-07-07 22:05:53,107 total_time=103391, total_pixels=2937893
9112018-07-07 22:05:53,194 total_time=103391, total_pixels=2937893
9122018-07-07 22:05:53,327 total_time=103391, total_pixels=2937893
9132018-07-07 22:05:53,397 total_time=103391, total_pixels=2937893
9142018-07-07 22:05:53,406 packet decoding sequence 33 for window 9: 121x25 took 97.6ms
9152018-07-07 22:05:53,406 record_latency: took  176.8 ms round trip,  176.7 for echo,   97.0 for decoding of     3025 pixels,       56 bytes sent over the network in   79.2 ms,   79.1 ms for echo
9162018-07-07 22:05:53,406 may_recalculate(9, 3025) total 3025 pixels, scheduling recalculate work item
9172018-07-07 22:05:53,477 packet decoding sequence 34 for window 9: 190x25 took 1.4ms
9182018-07-07 22:05:53,478 record_latency: took    4.1 ms round trip,    4.1 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    2.8 ms,    2.7 ms for echo
9192018-07-07 22:05:53,498 total_time=202382, total_pixels=2945668
9202018-07-07 22:05:53,511 packet decoding sequence 1 for window 16: 312x259 took 2.0ms
9212018-07-07 22:05:53,511 record_latency: took   35.4 ms round trip,   35.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   33.4 ms,   33.3 ms for echo
9222018-07-07 22:05:53,512 may_recalculate(16, 80808) total 80808 pixels, scheduling recalculate work item
9232018-07-07 22:05:53,599 total_time=204419, total_pixels=3026476
9242018-07-07 22:05:53,719 total_time=204419, total_pixels=3026476
9252018-07-07 22:05:53,815 total_time=204419, total_pixels=3026476
9262018-07-07 22:05:53,863 packet decoding sequence 1 for window 17: 244x180 took 1.8ms
9272018-07-07 22:05:53,864 record_latency: took    5.6 ms round trip,    5.5 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.8 ms,    3.7 ms for echo
9282018-07-07 22:05:53,864 may_recalculate(17, 43920) total 43920 pixels, scheduling recalculate work item
9292018-07-07 22:05:53,868 packet decoding sequence 35 for window 9: 190x25 took 1.3ms
9302018-07-07 22:05:53,868 record_latency: took    3.8 ms round trip,    3.7 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    2.5 ms,    2.4 ms for echo
9312018-07-07 22:05:53,916 total_time=207544, total_pixels=3075146
9322018-07-07 22:05:54,018 total_time=207544, total_pixels=3075146
9332018-07-07 22:05:54,094 calculate_batch_delay for wid=17 current batch delay=120, last update 417018 seconds ago
9342018-07-07 22:05:54,095 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
9352018-07-07 22:05:54,095 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 44, 'recent': 44}
9362018-07-07 22:05:54,095 update_batch_delay: damage-rate                  : 0.85,0.39  {'max_latency': 100, 'elapsed': 353}
9372018-07-07 22:05:54,095 update_batch_delay: client-latency               : 0.86,0.16  {'target': 6, 'weight_multiplier': 426, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 731, 'div': 1000, 'avg': 15, 'recent': 13}
9382018-07-07 22:05:54,096 update_batch_delay: client-ping-latency          : 0.60,0.12  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 354, 'div': 1000, 'avg': 3, 'recent': 2}
9392018-07-07 22:05:54,096 update_batch_delay: server-ping-latency          : 0.91,0.06  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 827, 'div': 1000, 'avg': 5, 'recent': 8}
9402018-07-07 22:05:54,096 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}
9412018-07-07 22:05:54,096 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
9422018-07-07 22:05:54,097 update_batch_delay: compression-work-queue       : 0.05,0.24  {'target': 1000, 'weight_multiplier': 250, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 38, 'div': 1000, 'avg': 59, 'recent': 62}
9432018-07-07 22:05:54,097 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
9442018-07-07 22:05:54,097 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
9452018-07-07 22:05:54,097 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
9462018-07-07 22:05:54,098 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
9472018-07-07 22:05:54,098 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
9482018-07-07 22:05:54,098 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
9492018-07-07 22:05:54,098 update_batch_delay: delay=86
9502018-07-07 22:05:54,101 calculate_batch_delay for wid=9 current batch delay=39, last update 17 seconds ago
9512018-07-07 22:05:54,109 calculate_batch_delay for wid=9, 2397458 pixels updated since the last update
9522018-07-07 22:05:54,109 calculate_batch_delay for wid=9, skipping - only 34 bytes sent since the last update
9532018-07-07 22:05:54,119 total_time=207544, total_pixels=3075146
9542018-07-07 22:05:54,220 total_time=207544, total_pixels=3075146
9552018-07-07 22:05:54,319 total_time=207544, total_pixels=3075146
9562018-07-07 22:05:54,423 total_time=207544, total_pixels=3075146
9572018-07-07 22:05:54,463 packet decoding sequence 2 for window 17: 244x180 took 2.3ms
9582018-07-07 22:05:54,463 record_latency: took    4.5 ms round trip,    4.1 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.2 ms,    1.8 ms for echo
9592018-07-07 22:05:54,464 may_recalculate(17, 43920) total 43920 pixels, scheduling recalculate work item
9602018-07-07 22:05:54,518 total_time=209831, total_pixels=3119066
9612018-07-07 22:05:54,574 packet decoding sequence 3 for window 17: 244x180 took 1.4ms
9622018-07-07 22:05:54,574 record_latency: took    3.6 ms round trip,    3.1 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.1 ms,    1.7 ms for echo
9632018-07-07 22:05:54,619 total_time=211270, total_pixels=3162986
9642018-07-07 22:05:54,685 packet decoding sequence 4 for window 17: 244x180 took 3.0ms
9652018-07-07 22:05:54,685 record_latency: took    5.9 ms round trip,    5.4 for echo,    3.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.8 ms,    2.3 ms for echo
9662018-07-07 22:05:54,719 total_time=214309, total_pixels=3206906
9672018-07-07 22:05:54,790 packet decoding sequence 5 for window 17: 244x180 took 1.7ms
9682018-07-07 22:05:54,791 record_latency: took    4.1 ms round trip,    4.1 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.5 ms,    2.4 ms for echo
9692018-07-07 22:05:54,821 total_time=215983, total_pixels=3250826
9702018-07-07 22:05:54,921 total_time=215983, total_pixels=3250826
9712018-07-07 22:05:54,962 packet decoding sequence 6 for window 17: 244x180 took 1.4ms
9722018-07-07 22:05:54,962 record_latency: took    5.5 ms round trip,    5.4 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
9732018-07-07 22:05:55,023 total_time=217377, total_pixels=3294746
9742018-07-07 22:05:55,095 calculate_batch_delay for wid=17 current batch delay=86, last update 1 seconds ago
9752018-07-07 22:05:55,095 calculate_batch_delay for wid=17, skipping - only 5 events since the last update
9762018-07-07 22:05:55,120 total_time=217377, total_pixels=3294746
9772018-07-07 22:05:55,203 packet decoding sequence 7 for window 17: 244x180 took 1.8ms
9782018-07-07 22:05:55,203 record_latency: took    6.1 ms round trip,    6.1 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    4.3 ms,    4.2 ms for echo
9792018-07-07 22:05:55,203 may_recalculate(17, 43920) total 43920 pixels, scheduling recalculate work item
9802018-07-07 22:05:55,221 total_time=219222, total_pixels=3338666
9812018-07-07 22:05:55,293 packet decoding sequence 8 for window 17: 244x180 took 1.5ms
9822018-07-07 22:05:55,293 record_latency: took    5.1 ms round trip,    4.6 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.6 ms,    3.1 ms for echo
9832018-07-07 22:05:55,331 total_time=220724, total_pixels=3382586
9842018-07-07 22:05:55,390 packet decoding sequence 9 for window 17: 244x180 took 1.6ms
9852018-07-07 22:05:55,391 record_latency: took    5.6 ms round trip,    5.5 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    4.0 ms,    3.9 ms for echo
9862018-07-07 22:05:55,427 total_time=222336, total_pixels=3426506
9872018-07-07 22:05:55,499 packet decoding sequence 10 for window 17: 244x180 took 1.5ms
9882018-07-07 22:05:55,499 record_latency: took   10.9 ms round trip,   10.8 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    9.3 ms,    9.3 ms for echo
9892018-07-07 22:05:55,529 total_time=223859, total_pixels=3470426
9902018-07-07 22:05:55,656 total_time=223859, total_pixels=3470426
9912018-07-07 22:05:55,736 packet decoding sequence 36 for window 9: 121x25 took 1.2ms
9922018-07-07 22:05:55,736 record_latency: took   24.0 ms round trip,   23.9 for echo,    1.0 for decoding of     3025 pixels,       56 bytes sent over the network in   22.7 ms,   22.7 ms for echo
9932018-07-07 22:05:55,737 may_recalculate(9, 3025) total 3025 pixels, scheduling recalculate work item
9942018-07-07 22:05:55,754 total_time=225096, total_pixels=3473451
9952018-07-07 22:05:55,856 total_time=225096, total_pixels=3473451
9962018-07-07 22:05:55,871 packet decoding sequence 37 for window 9: 76x25 took 1.5ms
9972018-07-07 22:05:55,872 record_latency: took   19.7 ms round trip,   19.6 for echo,    1.0 for decoding of     1900 pixels,       56 bytes sent over the network in   18.2 ms,   18.1 ms for echo
9982018-07-07 22:05:55,956 total_time=226588, total_pixels=3475351
9992018-07-07 22:05:56,059 total_time=226588, total_pixels=3475351
10002018-07-07 22:05:56,095 calculate_batch_delay for wid=9 current batch delay=39, last update 19 seconds ago
10012018-07-07 22:05:56,096 calculate_batch_delay for wid=9, 2402383 pixels updated since the last update
10022018-07-07 22:05:56,096 calculate_batch_delay for wid=9, skipping - only 36 bytes sent since the last update
10032018-07-07 22:05:56,165 total_time=226588, total_pixels=3475351
10042018-07-07 22:05:56,210 packet decoding sequence 1 for window 19: 248x181 took 1.7ms
10052018-07-07 22:05:56,210 record_latency: took   52.4 ms round trip,   52.2 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   50.6 ms,   50.5 ms for echo
10062018-07-07 22:05:56,211 may_recalculate(19, 44888) total 44888 pixels, scheduling recalculate work item
10072018-07-07 22:05:56,259 total_time=228292, total_pixels=3520239
10082018-07-07 22:05:56,364 total_time=228292, total_pixels=3520239
10092018-07-07 22:05:56,505 total_time=228292, total_pixels=3520239
10102018-07-07 22:05:56,595 total_time=228292, total_pixels=3520239
10112018-07-07 22:05:56,691 total_time=228292, total_pixels=3520239
10122018-07-07 22:05:56,736 packet decoding sequence 38 for window 9: 121x25 took 194.5ms
10132018-07-07 22:05:56,736 record_latency: took  210.9 ms round trip,  210.9 for echo,  194.0 for decoding of     3025 pixels,       56 bytes sent over the network in   16.4 ms,   16.3 ms for echo
10142018-07-07 22:05:56,736 may_recalculate(9, 3025) total 3025 pixels, scheduling recalculate work item
10152018-07-07 22:05:56,815 total_time=422833, total_pixels=3523264
10162018-07-07 22:05:56,856 packet decoding sequence 39 for window 9: 234x25 took 1.7ms
10172018-07-07 22:05:56,857 record_latency: took   24.3 ms round trip,   24.3 for echo,    1.0 for decoding of     5850 pixels,       57 bytes sent over the network in   22.6 ms,   22.6 ms for echo
10182018-07-07 22:05:56,917 total_time=424510, total_pixels=3529114
10192018-07-07 22:05:57,014 total_time=424510, total_pixels=3529114
10202018-07-07 22:05:57,096 calculate_batch_delay for wid=9 current batch delay=39, last update 20 seconds ago
10212018-07-07 22:05:57,097 calculate_batch_delay for wid=9, 2411258 pixels updated since the last update
10222018-07-07 22:05:57,097 calculate_batch_delay for wid=9, skipping - only 38 bytes sent since the last update
10232018-07-07 22:05:57,117 total_time=424510, total_pixels=3529114
10242018-07-07 22:05:57,224 total_time=424510, total_pixels=3529114
10252018-07-07 22:05:57,342 total_time=424510, total_pixels=3529114
10262018-07-07 22:05:57,449 total_time=424510, total_pixels=3529114
10272018-07-07 22:05:57,501 packet decoding sequence 40 for window 9: 279x25 took 1.7ms
10282018-07-07 22:05:57,501 record_latency: took   38.2 ms round trip,   38.1 for echo,    1.0 for decoding of     6975 pixels,       57 bytes sent over the network in   36.6 ms,   36.4 ms for echo
10292018-07-07 22:05:57,501 may_recalculate(9, 6975) total 6975 pixels, scheduling recalculate work item
10302018-07-07 22:05:57,517 packet decoding sequence 1 for window 23: 130x54 took 1.4ms
10312018-07-07 22:05:57,517 record_latency: took    3.9 ms round trip,    3.8 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    2.4 ms,    2.4 ms for echo
10322018-07-07 22:05:57,517 may_recalculate(23, 7020) total 7020 pixels, scheduling recalculate work item
10332018-07-07 22:05:57,548 total_time=427607, total_pixels=3543109
10342018-07-07 22:05:57,648 total_time=427607, total_pixels=3543109
10352018-07-07 22:05:57,709 packet decoding sequence 2 for window 23: 130x54 took 1.1ms
10362018-07-07 22:05:57,709 record_latency: took    4.0 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
10372018-07-07 22:05:57,749 total_time=428711, total_pixels=3550129
10382018-07-07 22:05:57,854 total_time=428711, total_pixels=3550129
10392018-07-07 22:05:57,951 total_time=428711, total_pixels=3550129
10402018-07-07 22:05:58,052 total_time=428711, total_pixels=3550129
10412018-07-07 22:05:58,097 calculate_batch_delay for wid=23 current batch delay=164, last update 417022 seconds ago
10422018-07-07 22:05:58,097 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
10432018-07-07 22:05:58,099 update_batch_delay: client-decode-speed          : 0.98,0.02  {'avg': 184, 'recent': 180}
10442018-07-07 22:05:58,100 update_batch_delay: damage-rate                  : 0.59,0.64  {'max_latency': 142, 'elapsed': 699}
10452018-07-07 22:05:58,100 update_batch_delay: client-latency               : 0.88,0.15  {'target': 6, 'weight_multiplier': 426, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 767, 'div': 1000, 'avg': 14, 'recent': 13}
10462018-07-07 22:05:58,100 update_batch_delay: client-ping-latency          : 1.04,0.04  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1082, 'div': 1000, 'avg': 12, 'recent': 18}
10472018-07-07 22:05:58,100 update_batch_delay: server-ping-latency          : 0.51,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 262, 'div': 1000, 'avg': 3, 'recent': 2}
10482018-07-07 22:05:58,101 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}
10492018-07-07 22:05:58,101 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}
10502018-07-07 22:05:58,101 update_batch_delay: compression-work-queue       : 0.05,0.23  {'target': 1000, 'weight_multiplier': 238, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 34, 'div': 1000, 'avg': 38, 'recent': 56}
10512018-07-07 22:05:58,102 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}
10522018-07-07 22:05:58,102 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
10532018-07-07 22:05:58,102 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
10542018-07-07 22:05:58,103 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
10552018-07-07 22:05:58,103 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
10562018-07-07 22:05:58,103 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
10572018-07-07 22:05:58,103 update_batch_delay: delay=116
10582018-07-07 22:05:58,104 calculate_batch_delay for wid=9 current batch delay=39, last update 21 seconds ago
10592018-07-07 22:05:58,105 calculate_batch_delay for wid=9, 2418233 pixels updated since the last update
10602018-07-07 22:05:58,105 calculate_batch_delay for wid=9, skipping - only 39 bytes sent since the last update
10612018-07-07 22:05:58,150 total_time=428711, total_pixels=3550129
10622018-07-07 22:05:58,255 total_time=428711, total_pixels=3550129
10632018-07-07 22:05:58,354 total_time=428711, total_pixels=3550129
10642018-07-07 22:05:58,454 total_time=428711, total_pixels=3550129
10652018-07-07 22:05:58,556 total_time=428711, total_pixels=3550129
10662018-07-07 22:05:58,655 total_time=428711, total_pixels=3550129
10672018-07-07 22:05:58,753 total_time=428711, total_pixels=3550129
10682018-07-07 22:05:58,857 total_time=428711, total_pixels=3550129
10692018-07-07 22:05:58,927 packet decoding sequence 41 for window 9: 734x459 took 6.3ms
10702018-07-07 22:05:58,927 record_latency: took    8.5 ms round trip,    8.4 for echo,    6.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.2 ms,    2.1 ms for echo
10712018-07-07 22:05:58,927 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
10722018-07-07 22:05:58,958 total_time=435021, total_pixels=3887035
10732018-07-07 22:05:59,014 packet decoding sequence 42 for window 9: 12x20 took 1.1ms
10742018-07-07 22:05:59,015 record_latency: took   27.9 ms round trip,   27.8 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   26.8 ms,   26.8 ms for echo
10752018-07-07 22:05:59,060 total_time=436084, total_pixels=3887275
10762018-07-07 22:05:59,097 calculate_batch_delay for wid=9 current batch delay=39, last update 22 seconds ago
10772018-07-07 22:05:59,097 calculate_batch_delay for wid=9, 2755379 pixels updated since the last update
10782018-07-07 22:05:59,097 calculate_batch_delay for wid=9, skipping - only 41 bytes sent since the last update
10792018-07-07 22:05:59,123 packet decoding sequence 43 for window 9: 12x20 took 1.0ms
10802018-07-07 22:05:59,124 record_latency: took   25.0 ms round trip,   24.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   23.9 ms,   23.9 ms for echo
10812018-07-07 22:05:59,131 packet decoding sequence 44 for window 9: 12x20 took 1.1ms
10822018-07-07 22:05:59,131 record_latency: took    3.1 ms round trip,    2.9 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.0 ms,    1.8 ms for echo
10832018-07-07 22:05:59,157 total_time=438187, total_pixels=3887755
10842018-07-07 22:05:59,263 total_time=438187, total_pixels=3887755
10852018-07-07 22:05:59,291 packet decoding sequence 45 for window 9: 12x20 took 1.1ms
10862018-07-07 22:05:59,292 record_latency: took   52.8 ms round trip,   52.7 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   51.7 ms,   51.6 ms for echo
10872018-07-07 22:05:59,362 total_time=439291, total_pixels=3887995
10882018-07-07 22:05:59,481 total_time=439291, total_pixels=3887995
10892018-07-07 22:05:59,512 packet decoding sequence 46 for window 9: 734x459 took 2.8ms
10902018-07-07 22:05:59,513 record_latency: took   49.1 ms round trip,   43.0 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   46.3 ms,   40.2 ms for echo
10912018-07-07 22:05:59,513 may_recalculate(9, 336906) total 337626 pixels, scheduling recalculate work item
10922018-07-07 22:05:59,577 total_time=442041, total_pixels=4224901
10932018-07-07 22:05:59,679 total_time=442041, total_pixels=4224901
10942018-07-07 22:05:59,779 total_time=442041, total_pixels=4224901
10952018-07-07 22:05:59,849 packet decoding sequence 1 for window 24: 312x259 took 3.2ms
10962018-07-07 22:05:59,849 record_latency: took    5.5 ms round trip,    5.5 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.3 ms for echo
10972018-07-07 22:05:59,849 may_recalculate(24, 80808) total 80808 pixels, scheduling recalculate work item
10982018-07-07 22:05:59,880 total_time=445201, total_pixels=4305709
10992018-07-07 22:05:59,981 total_time=445201, total_pixels=4305709
11002018-07-07 22:06:00,081 total_time=445201, total_pixels=4305709
11012018-07-07 22:06:00,097 calculate_batch_delay for wid=24 current batch delay=4697, last update 417024 seconds ago
11022018-07-07 22:06:00,098 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
11032018-07-07 22:06:00,098 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 41, 'recent': 41}
11042018-07-07 22:06:00,098 update_batch_delay: damage-rate                  : 0.96,0.20  {'max_latency': 100, 'elapsed': 240}
11052018-07-07 22:06:00,099 update_batch_delay: client-latency               : 0.97,0.08  {'target': 6, 'weight_multiplier': 426, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 932, 'div': 1000, 'avg': 17, 'recent': 17}
11062018-07-07 22:06:00,099 update_batch_delay: client-ping-latency          : 1.06,0.04  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1116, 'div': 1000, 'avg': 11, 'recent': 17}
11072018-07-07 22:06:00,099 update_batch_delay: server-ping-latency          : 0.72,0.10  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 515, 'div': 1000, 'avg': 4, 'recent': 4}
11082018-07-07 22:06:00,099 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}
11092018-07-07 22:06:00,099 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}
11102018-07-07 22:06:00,100 update_batch_delay: compression-work-queue       : 0.01,0.16  {'target': 1000, 'weight_multiplier': 158, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 5, 'div': 1000, 'avg': 24, 'recent': 8}
11112018-07-07 22:06:00,100 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}
11122018-07-07 22:06:00,101 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
11132018-07-07 22:06:00,101 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
11142018-07-07 22:06:00,102 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
11152018-07-07 22:06:00,102 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
11162018-07-07 22:06:00,102 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
11172018-07-07 22:06:00,103 update_batch_delay: delay=362
11182018-07-07 22:06:00,104 calculate_batch_delay for wid=9 current batch delay=39, last update 23 seconds ago
11192018-07-07 22:06:00,104 calculate_batch_delay for wid=9, 3093005 pixels updated since the last update
11202018-07-07 22:06:00,105 calculate_batch_delay for wid=9, skipping - only 45 bytes sent since the last update
11212018-07-07 22:06:00,178 total_time=445201, total_pixels=4305709
11222018-07-07 22:06:00,285 total_time=445201, total_pixels=4305709
11232018-07-07 22:06:00,367 packet decoding sequence 2 for window 24: 312x259 took 2.8ms
11242018-07-07 22:06:00,367 record_latency: took    5.7 ms round trip,    5.6 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.8 ms for echo
11252018-07-07 22:06:00,369 may_recalculate(24, 80808) total 80808 pixels, scheduling recalculate work item
11262018-07-07 22:06:00,389 total_time=448040, total_pixels=4386517
11272018-07-07 22:06:00,488 total_time=448040, total_pixels=4386517
11282018-07-07 22:06:00,589 total_time=448040, total_pixels=4386517
11292018-07-07 22:06:00,690 total_time=448040, total_pixels=4386517
11302018-07-07 22:06:00,792 total_time=448040, total_pixels=4386517
11312018-07-07 22:06:00,856 packet decoding sequence 3 for window 24: 312x259 took 2.1ms
11322018-07-07 22:06:00,856 record_latency: took   10.3 ms round trip,   10.2 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    8.2 ms,    8.2 ms for echo
11332018-07-07 22:06:00,889 total_time=450094, total_pixels=4467325
11342018-07-07 22:06:00,988 total_time=450094, total_pixels=4467325
11352018-07-07 22:06:01,093 total_time=450094, total_pixels=4467325
11362018-07-07 22:06:01,144 packet decoding sequence 47 for window 9: 190x45 took 2.4ms
11372018-07-07 22:06:01,144 record_latency: took    4.5 ms round trip,    4.4 for echo,    2.0 for decoding of     8550 pixels,       61 bytes sent over the network in    2.1 ms,    2.0 ms for echo
11382018-07-07 22:06:01,144 may_recalculate(9, 8550) total 8550 pixels, scheduling recalculate work item
11392018-07-07 22:06:01,190 total_time=452497, total_pixels=4475875
11402018-07-07 22:06:01,291 total_time=452497, total_pixels=4475875
11412018-07-07 22:06:01,393 total_time=452497, total_pixels=4475875
11422018-07-07 22:06:01,493 total_time=452497, total_pixels=4475875
11432018-07-07 22:06:01,594 total_time=452497, total_pixels=4475875
11442018-07-07 22:06:01,699 total_time=452497, total_pixels=4475875
11452018-07-07 22:06:01,777 packet decoding sequence 48 for window 9: 12x20 took 1.3ms
11462018-07-07 22:06:01,778 record_latency: took    4.1 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.8 ms,    2.7 ms for echo
11472018-07-07 22:06:01,798 total_time=453840, total_pixels=4476115
11482018-07-07 22:06:01,897 total_time=453840, total_pixels=4476115
11492018-07-07 22:06:01,998 total_time=453840, total_pixels=4476115
11502018-07-07 22:06:02,100 calculate_batch_delay for wid=9 current batch delay=39, last update 25 seconds ago
11512018-07-07 22:06:02,100 total_time=453840, total_pixels=4476115
11522018-07-07 22:06:02,103 calculate_batch_delay for wid=9, 3101795 pixels updated since the last update
11532018-07-07 22:06:02,103 calculate_batch_delay for wid=9, skipping - only 47 bytes sent since the last update
11542018-07-07 22:06:02,200 total_time=453840, total_pixels=4476115
11552018-07-07 22:06:02,298 total_time=453840, total_pixels=4476115
11562018-07-07 22:06:02,400 total_time=453840, total_pixels=4476115
11572018-07-07 22:06:02,501 total_time=453840, total_pixels=4476115
11582018-07-07 22:06:02,605 total_time=453840, total_pixels=4476115
11592018-07-07 22:06:02,706 total_time=453840, total_pixels=4476115
11602018-07-07 22:06:02,804 total_time=453840, total_pixels=4476115
11612018-07-07 22:06:02,904 total_time=453840, total_pixels=4476115
11622018-07-07 22:06:03,007 total_time=453840, total_pixels=4476115
11632018-07-07 22:06:03,107 total_time=453840, total_pixels=4476115
11642018-07-07 22:06:03,205 total_time=453840, total_pixels=4476115
11652018-07-07 22:06:03,311 total_time=453840, total_pixels=4476115
11662018-07-07 22:06:03,410 total_time=453840, total_pixels=4476115
11672018-07-07 22:06:03,510 total_time=453840, total_pixels=4476115
11682018-07-07 22:06:03,612 total_time=453840, total_pixels=4476115
11692018-07-07 22:06:03,712 total_time=453840, total_pixels=4476115
11702018-07-07 22:06:03,813 total_time=453840, total_pixels=4476115
11712018-07-07 22:06:03,915 total_time=453840, total_pixels=4476115
11722018-07-07 22:06:04,017 total_time=453840, total_pixels=4476115
11732018-07-07 22:06:04,115 total_time=453840, total_pixels=4476115
11742018-07-07 22:06:04,215 total_time=453840, total_pixels=4476115
11752018-07-07 22:06:04,324 total_time=453840, total_pixels=4476115
11762018-07-07 22:06:04,421 total_time=453840, total_pixels=4476115
11772018-07-07 22:06:04,517 total_time=453840, total_pixels=4476115
11782018-07-07 22:06:04,617 total_time=453840, total_pixels=4476115
11792018-07-07 22:06:04,719 total_time=453840, total_pixels=4476115
11802018-07-07 22:06:04,822 total_time=453840, total_pixels=4476115
11812018-07-07 22:06:04,918 total_time=453840, total_pixels=4476115
11822018-07-07 22:06:05,022 total_time=453840, total_pixels=4476115
11832018-07-07 22:06:05,121 total_time=453840, total_pixels=4476115
11842018-07-07 22:06:05,220 total_time=453840, total_pixels=4476115
11852018-07-07 22:06:05,324 total_time=453840, total_pixels=4476115
11862018-07-07 22:06:05,425 total_time=453840, total_pixels=4476115
11872018-07-07 22:06:05,527 total_time=453840, total_pixels=4476115
11882018-07-07 22:06:05,627 total_time=453840, total_pixels=4476115
11892018-07-07 22:06:05,727 total_time=453840, total_pixels=4476115
11902018-07-07 22:06:05,828 total_time=453840, total_pixels=4476115
11912018-07-07 22:06:05,927 total_time=453840, total_pixels=4476115
11922018-07-07 22:06:06,030 total_time=453840, total_pixels=4476115
11932018-07-07 22:06:06,131 total_time=453840, total_pixels=4476115
11942018-07-07 22:06:06,231 total_time=453840, total_pixels=4476115
11952018-07-07 22:06:06,331 total_time=453840, total_pixels=4476115
11962018-07-07 22:06:06,431 total_time=453840, total_pixels=4476115
11972018-07-07 22:06:06,533 total_time=453840, total_pixels=4476115
11982018-07-07 22:06:06,633 total_time=453840, total_pixels=4476115
11992018-07-07 22:06:06,737 total_time=453840, total_pixels=4476115
12002018-07-07 22:06:06,835 total_time=453840, total_pixels=4476115
12012018-07-07 22:06:06,936 total_time=453840, total_pixels=4476115
12022018-07-07 22:06:07,041 total_time=453840, total_pixels=4476115
12032018-07-07 22:06:07,138 total_time=453840, total_pixels=4476115
12042018-07-07 22:06:07,236 total_time=453840, total_pixels=4476115
12052018-07-07 22:06:07,338 total_time=453840, total_pixels=4476115
12062018-07-07 22:06:07,437 total_time=453840, total_pixels=4476115
12072018-07-07 22:06:07,540 total_time=453840, total_pixels=4476115
12082018-07-07 22:06:07,648 total_time=453840, total_pixels=4476115
12092018-07-07 22:06:07,745 total_time=453840, total_pixels=4476115
12102018-07-07 22:06:07,843 total_time=453840, total_pixels=4476115
12112018-07-07 22:06:07,944 total_time=453840, total_pixels=4476115
12122018-07-07 22:06:08,044 total_time=453840, total_pixels=4476115
12132018-07-07 22:06:08,145 total_time=453840, total_pixels=4476115
12142018-07-07 22:06:08,245 total_time=453840, total_pixels=4476115
12152018-07-07 22:06:08,348 total_time=453840, total_pixels=4476115
12162018-07-07 22:06:08,447 total_time=453840, total_pixels=4476115
12172018-07-07 22:06:08,547 total_time=453840, total_pixels=4476115
12182018-07-07 22:06:08,647 total_time=453840, total_pixels=4476115
12192018-07-07 22:06:08,750 total_time=453840, total_pixels=4476115
12202018-07-07 22:06:08,852 total_time=453840, total_pixels=4476115
12212018-07-07 22:06:08,951 total_time=453840, total_pixels=4476115
12222018-07-07 22:06:09,052 total_time=453840, total_pixels=4476115
12232018-07-07 22:06:09,153 total_time=453840, total_pixels=4476115
12242018-07-07 22:06:09,252 total_time=453840, total_pixels=4476115
12252018-07-07 22:06:09,355 total_time=453840, total_pixels=4476115
12262018-07-07 22:06:09,455 total_time=453840, total_pixels=4476115
12272018-07-07 22:06:09,560 total_time=453840, total_pixels=4476115
12282018-07-07 22:06:09,663 total_time=453840, total_pixels=4476115
12292018-07-07 22:06:09,732 packet decoding sequence 49 for window 9: 12x20 took 1.7ms
12302018-07-07 22:06:09,732 record_latency: took  165.1 ms round trip,  165.0 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in  163.4 ms,  163.3 ms for echo
12312018-07-07 22:06:09,734 packet decoding sequence 50 for window 9: 12x20 took 3.9ms
12322018-07-07 22:06:09,734 record_latency: took   78.6 ms round trip,   78.4 for echo,    3.0 for decoding of      240 pixels,       55 bytes sent over the network in   74.7 ms,   74.5 ms for echo
12332018-07-07 22:06:09,761 total_time=459470, total_pixels=4476595
12342018-07-07 22:06:09,863 total_time=459470, total_pixels=4476595
12352018-07-07 22:06:09,945 packet decoding sequence 51 for window 9: 12x20 took 2.3ms
12362018-07-07 22:06:09,946 record_latency: took   91.7 ms round trip,   91.6 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in   89.4 ms,   89.4 ms for echo
12372018-07-07 22:06:09,962 total_time=461726, total_pixels=4476835
12382018-07-07 22:06:10,066 total_time=461726, total_pixels=4476835
12392018-07-07 22:06:10,167 total_time=461726, total_pixels=4476835
12402018-07-07 22:06:10,266 total_time=461726, total_pixels=4476835
12412018-07-07 22:06:10,369 total_time=461726, total_pixels=4476835
12422018-07-07 22:06:10,468 total_time=461726, total_pixels=4476835
12432018-07-07 22:06:10,569 total_time=461726, total_pixels=4476835
12442018-07-07 22:06:10,671 total_time=461726, total_pixels=4476835
12452018-07-07 22:06:10,772 total_time=461726, total_pixels=4476835
12462018-07-07 22:06:10,872 total_time=461726, total_pixels=4476835
12472018-07-07 22:06:10,972 total_time=461726, total_pixels=4476835
12482018-07-07 22:06:11,072 total_time=461726, total_pixels=4476835
12492018-07-07 22:06:11,174 total_time=461726, total_pixels=4476835
12502018-07-07 22:06:11,274 total_time=461726, total_pixels=4476835
12512018-07-07 22:06:11,376 total_time=461726, total_pixels=4476835
12522018-07-07 22:06:11,477 total_time=461726, total_pixels=4476835
12532018-07-07 22:06:11,577 total_time=461726, total_pixels=4476835
12542018-07-07 22:06:11,677 total_time=461726, total_pixels=4476835
12552018-07-07 22:06:11,776 total_time=461726, total_pixels=4476835
12562018-07-07 22:06:11,877 total_time=461726, total_pixels=4476835
12572018-07-07 22:06:11,976 total_time=461726, total_pixels=4476835
12582018-07-07 22:06:12,080 total_time=461726, total_pixels=4476835
12592018-07-07 22:06:12,178 total_time=461726, total_pixels=4476835
12602018-07-07 22:06:12,282 total_time=461726, total_pixels=4476835
12612018-07-07 22:06:12,380 total_time=461726, total_pixels=4476835
12622018-07-07 22:06:12,490 total_time=461726, total_pixels=4476835
12632018-07-07 22:06:12,594 total_time=461726, total_pixels=4476835
12642018-07-07 22:06:12,663 packet decoding sequence 52 for window 9: 12x20 took 1.1ms
12652018-07-07 22:06:12,664 record_latency: took   70.1 ms round trip,   70.0 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   69.0 ms,   68.9 ms for echo
12662018-07-07 22:06:12,691 total_time=462857, total_pixels=4477075
12672018-07-07 22:06:12,735 packet decoding sequence 53 for window 9: 12x20 took 1.0ms
12682018-07-07 22:06:12,736 record_latency: took   48.4 ms round trip,   48.2 for echo,    1.0 for decoding of      240 pixels,       62 bytes sent over the network in   47.4 ms,   47.2 ms for echo
12692018-07-07 22:06:12,737 packet decoding sequence 54 for window 9: 12x20 took 2.5ms
12702018-07-07 22:06:12,737 record_latency: took   48.4 ms round trip,   47.6 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in   45.8 ms,   45.1 ms for echo
12712018-07-07 22:06:12,790 total_time=466446, total_pixels=4477555
12722018-07-07 22:06:12,865 packet decoding sequence 55 for window 9: 12x20 took 3.0ms
12732018-07-07 22:06:12,865 record_latency: took   71.2 ms round trip,   70.7 for echo,    3.0 for decoding of      240 pixels,       55 bytes sent over the network in   68.2 ms,   67.6 ms for echo
12742018-07-07 22:06:12,891 total_time=469463, total_pixels=4477795
12752018-07-07 22:06:12,993 total_time=469463, total_pixels=4477795
12762018-07-07 22:06:13,064 packet decoding sequence 56 for window 9: 12x20 took 1.6ms
12772018-07-07 22:06:13,064 record_latency: took   77.5 ms round trip,   77.1 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   75.9 ms,   75.5 ms for echo
12782018-07-07 22:06:13,092 total_time=471098, total_pixels=4478035
12792018-07-07 22:06:13,192 total_time=471098, total_pixels=4478035
12802018-07-07 22:06:13,296 total_time=471098, total_pixels=4478035
12812018-07-07 22:06:13,393 total_time=471098, total_pixels=4478035
12822018-07-07 22:06:13,497 total_time=471098, total_pixels=4478035
12832018-07-07 22:06:13,612 total_time=471098, total_pixels=4478035
12842018-07-07 22:06:13,694 total_time=471098, total_pixels=4478035
12852018-07-07 22:06:13,814 total_time=471098, total_pixels=4478035
12862018-07-07 22:06:13,863 packet decoding sequence 57 for window 9: 734x459 took 5.1ms
12872018-07-07 22:06:13,863 record_latency: took    7.0 ms round trip,    6.9 for echo,    5.0 for decoding of   336906 pixels,       62 bytes sent over the network in    1.8 ms,    1.8 ms for echo
12882018-07-07 22:06:13,863 may_recalculate(9, 336906) total 338826 pixels, scheduling recalculate work item
12892018-07-07 22:06:13,864 calculate_batch_delay for wid=9 current batch delay=39, last update 37 seconds ago
12902018-07-07 22:06:13,864 calculate_batch_delay for wid=9, 3440621 pixels updated since the last update
12912018-07-07 22:06:13,864 calculate_batch_delay for wid=9, skipping - only 56 bytes sent since the last update
12922018-07-07 22:06:13,916 total_time=476229, total_pixels=4814941
12932018-07-07 22:06:14,016 total_time=476229, total_pixels=4814941
12942018-07-07 22:06:14,119 total_time=476229, total_pixels=4814941
12952018-07-07 22:06:14,176 packet decoding sequence 1 for window 25: 312x259 took 3.8ms
12962018-07-07 22:06:14,176 record_latency: took    6.7 ms round trip,    6.6 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.8 ms for echo
12972018-07-07 22:06:14,177 may_recalculate(25, 80808) total 80808 pixels, scheduling recalculate work item
12982018-07-07 22:06:14,219 total_time=480052, total_pixels=4895749
12992018-07-07 22:06:14,318 total_time=480052, total_pixels=4895749
13002018-07-07 22:06:14,422 total_time=480052, total_pixels=4895749
13012018-07-07 22:06:14,522 total_time=480052, total_pixels=4895749
13022018-07-07 22:06:14,622 total_time=480052, total_pixels=4895749
13032018-07-07 22:06:14,725 total_time=480052, total_pixels=4895749
13042018-07-07 22:06:14,823 total_time=480052, total_pixels=4895749
13052018-07-07 22:06:14,864 calculate_batch_delay for wid=25 current batch delay=1273, last update 417039 seconds ago
13062018-07-07 22:06:14,865 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
13072018-07-07 22:06:14,865 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 49, 'recent': 49}
13082018-07-07 22:06:14,866 update_batch_delay: damage-rate                  : 0.00,1.03  {'max_latency': 100, 'elapsed': 1270}
13092018-07-07 22:06:14,866 update_batch_delay: client-latency               : 0.94,0.10  {'target': 6, 'weight_multiplier': 423, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 886, 'div': 1000, 'avg': 32, 'recent': 23}
13102018-07-07 22:06:14,866 update_batch_delay: client-ping-latency          : 0.91,0.05  {'target': 6, 'weight_multiplier': 179, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 823, 'div': 1000, 'avg': 9, 'recent': 11}
13112018-07-07 22:06:14,866 update_batch_delay: server-ping-latency          : 0.51,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 259, 'div': 1000, 'avg': 2, 'recent': 2}
13122018-07-07 22:06:14,866 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}
13132018-07-07 22:06:14,867 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}
13142018-07-07 22:06:14,867 update_batch_delay: compression-work-queue       : 0.00,0.10  {'target': 1000, 'weight_multiplier': 97, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 9, 'recent': 0}
13152018-07-07 22:06:14,867 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}
13162018-07-07 22:06:14,867 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
13172018-07-07 22:06:14,867 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
13182018-07-07 22:06:14,868 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
13192018-07-07 22:06:14,868 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
13202018-07-07 22:06:14,868 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
13212018-07-07 22:06:14,868 update_batch_delay: delay=281
13222018-07-07 22:06:14,922 total_time=480052, total_pixels=4895749
13232018-07-07 22:06:15,024 total_time=480052, total_pixels=4895749
13242018-07-07 22:06:15,124 total_time=480052, total_pixels=4895749
13252018-07-07 22:06:15,226 total_time=480052, total_pixels=4895749
13262018-07-07 22:06:15,326 total_time=480052, total_pixels=4895749
13272018-07-07 22:06:15,427 total_time=480052, total_pixels=4895749
13282018-07-07 22:06:15,526 total_time=480052, total_pixels=4895749
13292018-07-07 22:06:15,627 total_time=480052, total_pixels=4895749
13302018-07-07 22:06:15,728 total_time=480052, total_pixels=4895749
13312018-07-07 22:06:15,829 total_time=480052, total_pixels=4895749
13322018-07-07 22:06:15,929 total_time=480052, total_pixels=4895749
13332018-07-07 22:06:16,035 total_time=480052, total_pixels=4895749
13342018-07-07 22:06:16,131 total_time=480052, total_pixels=4895749
13352018-07-07 22:06:16,233 total_time=480052, total_pixels=4895749
13362018-07-07 22:06:16,330 total_time=480052, total_pixels=4895749
13372018-07-07 22:06:16,434 total_time=480052, total_pixels=4895749
13382018-07-07 22:06:16,537 total_time=480052, total_pixels=4895749
13392018-07-07 22:06:16,635 total_time=480052, total_pixels=4895749
13402018-07-07 22:06:16,737 total_time=480052, total_pixels=4895749
13412018-07-07 22:06:16,835 total_time=480052, total_pixels=4895749
13422018-07-07 22:06:16,934 total_time=480052, total_pixels=4895749
13432018-07-07 22:06:17,054 total_time=480052, total_pixels=4895749
13442018-07-07 22:06:17,181 total_time=480052, total_pixels=4895749
13452018-07-07 22:06:17,280 total_time=480052, total_pixels=4895749
13462018-07-07 22:06:17,411 total_time=480052, total_pixels=4895749
13472018-07-07 22:06:17,459 packet decoding sequence 58 for window 9: 234x25 took 1.3ms
13482018-07-07 22:06:17,459 record_latency: took  300.2 ms round trip,  300.1 for echo,    1.0 for decoding of     5850 pixels,       58 bytes sent over the network in  298.9 ms,  298.8 ms for echo
13492018-07-07 22:06:17,460 may_recalculate(9, 5850) total 5850 pixels, scheduling recalculate work item
13502018-07-07 22:06:17,460 calculate_batch_delay for wid=9 current batch delay=39, last update 41 seconds ago
13512018-07-07 22:06:17,461 calculate_batch_delay for wid=9, 3446471 pixels updated since the last update
13522018-07-07 22:06:17,462 calculate_batch_delay for wid=9, skipping - only 57 bytes sent since the last update
13532018-07-07 22:06:17,517 total_time=481330, total_pixels=4901599
13542018-07-07 22:06:17,591 packet decoding sequence 1 for window 26: 438x131 took 2.6ms
13552018-07-07 22:06:17,591 record_latency: took   48.5 ms round trip,   48.4 for echo,    2.0 for decoding of    57378 pixels,       60 bytes sent over the network in   45.8 ms,   45.8 ms for echo
13562018-07-07 22:06:17,591 may_recalculate(26, 57378) total 57378 pixels, scheduling recalculate work item
13572018-07-07 22:06:17,614 total_time=483970, total_pixels=4958977
13582018-07-07 22:06:17,719 total_time=483970, total_pixels=4958977
13592018-07-07 22:06:17,818 total_time=483970, total_pixels=4958977
13602018-07-07 22:06:17,913 total_time=483970, total_pixels=4958977
13612018-07-07 22:06:18,019 total_time=483970, total_pixels=4958977
13622018-07-07 22:06:18,120 total_time=483970, total_pixels=4958977
13632018-07-07 22:06:18,217 total_time=483970, total_pixels=4958977
13642018-07-07 22:06:18,321 total_time=483970, total_pixels=4958977
13652018-07-07 22:06:18,417 total_time=483970, total_pixels=4958977
13662018-07-07 22:06:18,460 calculate_batch_delay for wid=26 current batch delay=832, last update 417043 seconds ago
13672018-07-07 22:06:18,461 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
13682018-07-07 22:06:18,462 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 48, 'recent': 48}
13692018-07-07 22:06:18,462 update_batch_delay: damage-rate                  : 0.00,1.11  {'max_latency': 100, 'elapsed': 1423}
13702018-07-07 22:06:18,462 update_batch_delay: client-latency               : 2.01,0.43  {'target': 6, 'weight_multiplier': 423, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4053, 'div': 1000, 'avg': 58, 'recent': 137}
13712018-07-07 22:06:18,463 update_batch_delay: client-ping-latency          : 0.47,0.13  {'target': 6, 'weight_multiplier': 176, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 222, 'div': 1000, 'avg': 5, 'recent': 2}
13722018-07-07 22:06:18,463 update_batch_delay: server-ping-latency          : 0.73,0.10  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 526, 'div': 1000, 'avg': 3, 'recent': 4}
13732018-07-07 22:06:18,463 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}
13742018-07-07 22:06:18,464 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
13752018-07-07 22:06:18,464 update_batch_delay: compression-work-queue       : 0.00,0.11  {'target': 1000, 'weight_multiplier': 106, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 11, 'recent': 1}
13762018-07-07 22:06:18,464 update_batch_delay: congestion                   : 1.08,0.79  {}
13772018-07-07 22:06:18,464 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
13782018-07-07 22:06:18,465 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
13792018-07-07 22:06:18,465 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
13802018-07-07 22:06:18,465 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
13812018-07-07 22:06:18,465 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
13822018-07-07 22:06:18,465 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
13832018-07-07 22:06:18,465 update_batch_delay: delay=326
13842018-07-07 22:06:18,517 total_time=483970, total_pixels=4958977
13852018-07-07 22:06:18,625 total_time=483970, total_pixels=4958977
13862018-07-07 22:06:18,720 total_time=483970, total_pixels=4958977
13872018-07-07 22:06:18,801 packet decoding sequence 59 for window 9: 234x45 took 2.4ms
13882018-07-07 22:06:18,801 record_latency: took   55.1 ms round trip,   55.0 for echo,    2.0 for decoding of    10530 pixels,       61 bytes sent over the network in   52.7 ms,   52.6 ms for echo
13892018-07-07 22:06:18,801 may_recalculate(9, 10530) total 10530 pixels, scheduling recalculate work item
13902018-07-07 22:06:18,819 total_time=486374, total_pixels=4969507
13912018-07-07 22:06:18,899 packet decoding sequence 60 for window 9: 12x20 took 1.0ms
13922018-07-07 22:06:18,899 record_latency: took   84.5 ms round trip,   84.5 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   83.5 ms,   83.4 ms for echo
13932018-07-07 22:06:18,920 total_time=487399, total_pixels=4969747
13942018-07-07 22:06:19,000 packet decoding sequence 61 for window 9: 12x20 took 1.3ms
13952018-07-07 22:06:19,001 record_latency: took   35.4 ms round trip,   35.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   34.1 ms,   34.0 ms for echo
13962018-07-07 22:06:19,027 total_time=488678, total_pixels=4969987
13972018-07-07 22:06:19,098 packet decoding sequence 62 for window 9: 12x20 took 1.2ms
13982018-07-07 22:06:19,099 record_latency: took   80.0 ms round trip,   79.8 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   78.8 ms,   78.6 ms for echo
13992018-07-07 22:06:19,126 total_time=489905, total_pixels=4970227
14002018-07-07 22:06:19,226 total_time=489905, total_pixels=4970227
14012018-07-07 22:06:19,325 total_time=489905, total_pixels=4970227
14022018-07-07 22:06:19,423 total_time=489905, total_pixels=4970227
14032018-07-07 22:06:19,461 calculate_batch_delay for wid=9 current batch delay=39, last update 43 seconds ago
14042018-07-07 22:06:19,462 calculate_batch_delay for wid=9, 3457721 pixels updated since the last update
14052018-07-07 22:06:19,462 calculate_batch_delay for wid=9, skipping - only 61 bytes sent since the last update
14062018-07-07 22:06:19,527 total_time=489905, total_pixels=4970227
14072018-07-07 22:06:19,627 total_time=489905, total_pixels=4970227
14082018-07-07 22:06:19,727 total_time=489905, total_pixels=4970227
14092018-07-07 22:06:19,829 total_time=489905, total_pixels=4970227
14102018-07-07 22:06:19,930 total_time=489905, total_pixels=4970227
14112018-07-07 22:06:20,039 total_time=489905, total_pixels=4970227
14122018-07-07 22:06:20,153 total_time=489905, total_pixels=4970227
14132018-07-07 22:06:20,255 total_time=489905, total_pixels=4970227
14142018-07-07 22:06:20,356 total_time=489905, total_pixels=4970227
14152018-07-07 22:06:20,457 total_time=489905, total_pixels=4970227
14162018-07-07 22:06:20,558 total_time=489905, total_pixels=4970227
14172018-07-07 22:06:20,657 total_time=489905, total_pixels=4970227
14182018-07-07 22:06:20,761 total_time=489905, total_pixels=4970227
14192018-07-07 22:06:20,860 total_time=489905, total_pixels=4970227
14202018-07-07 22:06:20,958 total_time=489905, total_pixels=4970227
14212018-07-07 22:06:21,065 total_time=489905, total_pixels=4970227
14222018-07-07 22:06:21,180 total_time=489905, total_pixels=4970227
14232018-07-07 22:06:21,276 total_time=489905, total_pixels=4970227
14242018-07-07 22:06:21,375 total_time=489905, total_pixels=4970227
14252018-07-07 22:06:21,476 total_time=489905, total_pixels=4970227
14262018-07-07 22:06:21,579 total_time=489905, total_pixels=4970227
14272018-07-07 22:06:21,677 total_time=489905, total_pixels=4970227
14282018-07-07 22:06:21,781 total_time=489905, total_pixels=4970227
14292018-07-07 22:06:21,877 total_time=489905, total_pixels=4970227
14302018-07-07 22:06:21,977 total_time=489905, total_pixels=4970227
14312018-07-07 22:06:22,077 total_time=489905, total_pixels=4970227
14322018-07-07 22:06:22,181 total_time=489905, total_pixels=4970227
14332018-07-07 22:06:22,282 total_time=489905, total_pixels=4970227
14342018-07-07 22:06:22,382 total_time=489905, total_pixels=4970227
14352018-07-07 22:06:22,485 total_time=489905, total_pixels=4970227
14362018-07-07 22:06:22,586 total_time=489905, total_pixels=4970227
14372018-07-07 22:06:22,686 total_time=489905, total_pixels=4970227
14382018-07-07 22:06:22,787 total_time=489905, total_pixels=4970227
14392018-07-07 22:06:22,841 client @13.644 Warning: the opus sound sink has stopped
14402018-07-07 22:06:22,847 sound source stopping
14412018-07-07 22:06:22,847 xpra client 2 disconnected.
14422018-07-07 22:13:00,364 New unix-domain connection received on /run/user/1000/xpra/beelink-1
14432018-07-07 22:13:00,365 New unix-domain connection received on /home/steved/.xpra/beelink-1
14442018-07-07 22:14:17,588 New unix-domain connection received on /run/user/1000/xpra/beelink-1
14452018-07-07 22:14:17,596 Handshake complete; enabling connection
14462018-07-07 22:14:17,604  automatic picture encoding enabled, also available:
14472018-07-07 22:14:17,605   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
14482018-07-07 22:14:17,606  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.EKy4y4.mmap
14492018-07-07 22:14:17,607 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
14502018-07-07 22:14:17,607  connected from 'beelink' as 'steved' - 'Steve Dee'
14512018-07-07 22:14:17,612  client root window size is 2880x900 with 1 display:
14522018-07-07 22:14:17,613   :0.0 (761x237 mm - DPI: 96x96)
14532018-07-07 22:14:17,613     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
14542018-07-07 22:14:17,613     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
14552018-07-07 22:14:17,623 best resolution matching 2880x900 is unchanged: 2880x960
14562018-07-07 22:14:17,631 setting key repeat rate from client: 500ms delay / 50ms interval
14572018-07-07 22:14:17,634 keyboard mapping already configured (skipped)
14582018-07-07 22:14:17,674 client @04.479 Xpra X11 server version 2.3.2-r19729 64-bit
14592018-07-07 22:14:17,679 client @04.480  running on Linux Ubuntu 18.04 bionic
14602018-07-07 22:14:17,686 client @04.488 Attached to :1
14612018-07-07 22:14:17,687 client @04.490  (press Control-C to detach)
14622018-07-07 22:14:17,719 total_time=0, total_pixels=0
14632018-07-07 22:14:17,796 client @04.600 server does not support xi input devices
14642018-07-07 22:14:17,797 client @04.601  server uses: xtest
14652018-07-07 22:14:17,980 New unix-domain connection received on /run/user/1000/xpra/beelink-1
14662018-07-07 22:14:17,982 New unix-domain connection received on /home/steved/.xpra/beelink-1
14672018-07-07 22:14:19,612 using pulseaudio device:
14682018-07-07 22:14:19,612  'Monitor of Xpra Speaker'
14692018-07-07 22:14:21,967 sound source using 'opus' audio codec
14702018-07-07 22:14:28,085 total_time=0, total_pixels=0