xpra icon
Bug tracker and wiki

Ticket #1911: xpra-local-congestion.txt

File xpra-local-congestion.txt, 195.3 KB (added by tc424, 19 months ago)

log for local connection with congestion logging on

Line 
12018-07-08 10:04:38,157 cannot access python uinput module:
22018-07-08 10:04:38,157  No module named uinput
3_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
4_XSERVTransMakeAllCOTSServerListeners: server already running
5Failed to rename log file "/run/user/1000/xpra/Xorg.S6077.log" to "/run/user/1000/xpra/Xorg.S6077.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.S6077.log", Time: Sun Jul  8 10:04:38 2018
22(++) Using config file: "/etc/xpra/xorg.conf"
23(==) Using system config directory "/usr/share/X11/xorg.conf.d"
242018-07-08 10:04:44,763 created unix domain socket: /run/user/1000/xpra/beelink-1
252018-07-08 10:04:44,764 created unix domain socket: /home/steved/.xpra/beelink-1
262018-07-08 10:04:44,764 cannot create group socket '/run/xpra/beelink-1'
272018-07-08 10:04:44,764  [Errno 13] Permission denied
282018-07-08 10:04:44,898 Warning: the python netifaces package is missing
292018-07-08 10:04:44,927 pointer device emulation using XTest
302018-07-08 10:04:47,208 serving html content from: /usr/share/xpra/www
312018-07-08 10:04:47,425 D-Bus notification forwarding is available
322018-07-08 10:04:47,677 Warning: webcam forwarding is disabled
332018-07-08 10:04:47,677  the virtual video directory '/sys/devices/virtual/video4linux' was not found
342018-07-08 10:04:47,678  make sure that the 'v4l2loopback' kernel module is installed and loaded
352018-07-08 10:04:47,678 found 0 virtual video devices for webcam forwarding
362018-07-08 10:04:47,849 pulseaudio server started with pid 6156
372018-07-08 10:04:47,849  private server socket path:
382018-07-08 10:04:47,850  '/run/user/1000/xpra/pulse-:1/pulse/native'
392018-07-08 10:04:50,146 GStreamer version 1.14.1 for Python 2.7.15 64-bit
402018-07-08 10:04:50,282 Warning: failed to load the mdns avahi publisher:
412018-07-08 10:04:50,283  No module named avahi
422018-07-08 10:04:50,283  either fix your installation or use the 'mdns=no' option
432018-07-08 10:04:50,283 xpra X11 version 2.3.2-r19729 64-bit
442018-07-08 10:04:50,284  uid=1000 (steved), gid=1000 (steved)
452018-07-08 10:04:50,284  running with pid 6109 on Linux Ubuntu 18.04 bionic
462018-07-08 10:04:50,284  connected to X11 display :1 with 24 bit colors
472018-07-08 10:04:50,533 xpra is ready.
482018-07-08 10:04:54,048 New unix-domain connection received on /run/user/1000/xpra/beelink-1
492018-07-08 10:04:54,058 Handshake complete; enabling connection
502018-07-08 10:04:54,063 get_client_bandwidth_limit(Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1))=0
512018-07-08 10:04:54,085  automatic picture encoding enabled, also available:
522018-07-08 10:04:54,085   png, png/P, png/L, webp, rgb24, rgb32, jpeg
532018-07-08 10:04:54,087  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.6j1Q3G.mmap
542018-07-08 10:04:54,088 server bandwidth-limit=0, client bandwidth-limit=0, value=0
552018-07-08 10:04:54,088 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
562018-07-08 10:04:54,089  connected from 'beelink' as 'steved' - 'Steve Dee'
572018-07-08 10:04:54,093  client root window size is 2880x900 with 1 display:
582018-07-08 10:04:54,094   :0.0 (762x238 mm - DPI: 96x96)
592018-07-08 10:04:54,094     monitor 1 1440x900 (160x90 mm - DPI: 228x254)
602018-07-08 10:04:54,094     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
612018-07-08 10:04:54,346 server virtual display now set to 2880x960 (best match for 2880x900)
622018-07-08 10:04:54,357 setting key repeat rate from client: 500ms delay / 50ms interval
632018-07-08 10:04:54,360 setting keymap: rules=evdev, model=pc105, layout=gb
642018-07-08 10:04:54,401 setting keyboard layout to 'gb'
652018-07-08 10:04:54,497 DPI set to 34 x 22 (wanted 96 x 96)
662018-07-08 10:04:54,498  you may experience scaling problems, such as huge or small fonts, etc
672018-07-08 10:04:54,498  to fix this issue, try the dpi switch, or use a patched Xorg dummy driver
682018-07-08 10:04:54,519 New unix-domain connection received on /home/steved/.xpra/beelink-1
692018-07-08 10:04:54,521 client @04.790 Xpra X11 server version 2.3.2-r19729 64-bit
702018-07-08 10:04:54,522 New unix-domain connection received on /run/user/1000/xpra/beelink-1
712018-07-08 10:04:54,524 client @04.790  running on Linux Ubuntu 18.04 bionic
722018-07-08 10:04:54,543 client @04.797 Attached to :1
732018-07-08 10:04:54,544 client @04.797  (press Control-C to detach)
742018-07-08 10:04:54,640 client @04.911 server does not support xi input devices
752018-07-08 10:04:54,640 client @04.911  server uses: xtest
762018-07-08 10:04:56,426 using pulseaudio device:
772018-07-08 10:04:56,427  'Monitor of Xpra Speaker'
782018-07-08 10:04:58,809 sound source using 'opus' audio codec
792018-07-08 10:05:02,638 printer forwarding enabled using postscript and pdf
802018-07-08 10:05:02,643 total_time=0, total_pixels=0
812018-07-08 10:05:02,675 3.7GB of system memory
82Failed to connect to session manager: Failed to connect to the session manager: SESSION_MANAGER environment variable not defined
832018-07-08 10:05:03,257 packet decoding sequence 1 for window 1: 734x459 took 8.3ms
842018-07-08 10:05:03,257 record_latency: took   17.0 ms round trip,   16.7 for echo,    8.0 for decoding of   336906 pixels,       57 bytes sent over the network in    8.7 ms,    8.3 ms for echo
852018-07-08 10:05:03,257 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
862018-07-08 10:05:03,258 calculate_batch_delay for wid=1 current batch delay=29, last update 422414 seconds ago
872018-07-08 10:05:03,259 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
882018-07-08 10:05:03,259 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 25, 'recent': 25}
892018-07-08 10:05:03,260 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 24}
902018-07-08 10:05:03,260 update_batch_delay: client-latency               : 0.77,0.36  {'target': 13, 'weight_multiplier': 751, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 600, 'div': 1000, 'avg': 8, 'recent': 8}
912018-07-08 10:05:03,260 update_batch_delay: client-ping-latency          : 0.59,0.12  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 351, 'div': 1000, 'avg': 2, 'recent': 2}
922018-07-08 10:05:03,260 update_batch_delay: server-ping-latency          : 0.59,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 353, 'div': 1000, 'avg': 9, 'recent': 4}
932018-07-08 10:05:03,260 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}
942018-07-08 10:05:03,260 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}
952018-07-08 10:05:03,260 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}
962018-07-08 10:05:03,261 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}
972018-07-08 10:05:03,261 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
982018-07-08 10:05:03,261 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
992018-07-08 10:05:03,261 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
1002018-07-08 10:05:03,261 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
1012018-07-08 10:05:03,261 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
1022018-07-08 10:05:03,262 update_batch_delay: delay=20
1032018-07-08 10:05:03,276 packet decoding sequence 2 for window 1: 734x459 took 3.8ms
1042018-07-08 10:05:03,276 record_latency: took    6.3 ms round trip,    6.2 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.5 ms,    2.4 ms for echo
1052018-07-08 10:05:03,276 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
1062018-07-08 10:05:03,465 packet decoding sequence 3 for window 1: 734x459 took 4.2ms
1072018-07-08 10:05:03,466 record_latency: took    7.2 ms round trip,    7.1 for echo,    4.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.0 ms,    3.0 ms for echo
1082018-07-08 10:05:04,259 calculate_batch_delay for wid=1 current batch delay=20, last update 1 seconds ago
1092018-07-08 10:05:04,260 calculate_batch_delay for wid=1, skipping - only 2 events since the last update
1102018-07-08 10:05:04,806 packet decoding sequence 4 for window 1: 12x18 took 1.9ms
1112018-07-08 10:05:04,806 record_latency: took    4.5 ms round trip,    2.9 for echo,    1.0 for decoding of      216 pixels,       61 bytes sent over the network in    2.7 ms,    1.1 ms for echo
1122018-07-08 10:05:04,809 packet decoding sequence 5 for window 1: 723x20 took 1.8ms
1132018-07-08 10:05:04,810 record_latency: took    4.5 ms round trip,    4.3 for echo,    1.0 for decoding of    14460 pixels,       59 bytes sent over the network in    2.7 ms,    2.5 ms for echo
1142018-07-08 10:05:04,810 may_recalculate(1, 14460) total 14676 pixels, scheduling recalculate work item
1152018-07-08 10:05:04,859 packet decoding sequence 6 for window 1: 12x20 took 1.3ms
1162018-07-08 10:05:04,860 record_latency: took    6.1 ms round trip,    6.1 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.8 ms,    4.8 ms for echo
1172018-07-08 10:05:04,885 packet decoding sequence 7 for window 1: 12x20 took 1.0ms
1182018-07-08 10:05:04,885 record_latency: took   15.3 ms round trip,   15.3 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in   14.4 ms,   14.3 ms for echo
1192018-07-08 10:05:04,925 packet decoding sequence 8 for window 1: 12x20 took 1.1ms
1202018-07-08 10:05:04,925 record_latency: took    3.9 ms round trip,    3.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.8 ms,    2.8 ms for echo
1212018-07-08 10:05:04,947 packet decoding sequence 9 for window 1: 12x20 took 1.9ms
1222018-07-08 10:05:04,948 record_latency: took   11.9 ms round trip,   11.8 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   10.0 ms,    9.9 ms for echo
1232018-07-08 10:05:04,977 packet decoding sequence 10 for window 1: 12x20 took 1.0ms
1242018-07-08 10:05:04,978 record_latency: took    7.2 ms round trip,    7.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.1 ms,    5.9 ms for echo
1252018-07-08 10:05:05,017 packet decoding sequence 11 for window 1: 12x20 took 1.1ms
1262018-07-08 10:05:05,018 record_latency: took    3.8 ms round trip,    3.7 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.7 ms,    2.7 ms for echo
1272018-07-08 10:05:05,057 packet decoding sequence 12 for window 1: 12x20 took 2.2ms
1282018-07-08 10:05:05,057 record_latency: took    4.7 ms round trip,    4.5 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.5 ms,    2.3 ms for echo
1292018-07-08 10:05:05,086 packet decoding sequence 13 for window 1: 12x20 took 1.3ms
1302018-07-08 10:05:05,087 record_latency: took    4.5 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.2 ms,    2.9 ms for echo
1312018-07-08 10:05:05,260 calculate_batch_delay for wid=1 current batch delay=20, last update 2 seconds ago
1322018-07-08 10:05:05,261 calculate_batch_delay for wid=1, 690408 pixels updated since the last update
1332018-07-08 10:05:05,261 calculate_batch_delay for wid=1, skipping - only 12 bytes sent since the last update
1342018-07-08 10:05:05,517 packet decoding sequence 14 for window 1: 12x20 took 3.1ms
1352018-07-08 10:05:05,517 record_latency: took    6.5 ms round trip,    6.4 for echo,    3.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.5 ms,    3.4 ms for echo
1362018-07-08 10:05:05,568 packet decoding sequence 15 for window 1: 12x20 took 1.3ms
1372018-07-08 10:05:05,569 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.9 ms,    3.8 ms for echo
1382018-07-08 10:05:05,585 packet decoding sequence 16 for window 1: 12x20 took 1.4ms
1392018-07-08 10:05:05,586 record_latency: took    8.3 ms round trip,    7.1 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.9 ms,    5.7 ms for echo
1402018-07-08 10:05:05,634 packet decoding sequence 17 for window 1: 12x20 took 2.5ms
1412018-07-08 10:05:05,635 record_latency: took    4.9 ms round trip,    4.7 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.3 ms,    2.2 ms for echo
1422018-07-08 10:05:05,654 packet decoding sequence 18 for window 1: 12x20 took 1.0ms
1432018-07-08 10:05:05,654 record_latency: took    8.4 ms round trip,    8.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    7.4 ms,    7.3 ms for echo
1442018-07-08 10:05:05,709 packet decoding sequence 19 for window 1: 12x20 took 1.2ms
1452018-07-08 10:05:05,709 record_latency: took    4.8 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.6 ms,    3.1 ms for echo
1462018-07-08 10:05:05,722 packet decoding sequence 20 for window 1: 12x20 took 3.0ms
1472018-07-08 10:05:05,722 record_latency: took    5.5 ms round trip,    5.4 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.5 ms,    2.4 ms for echo
1482018-07-08 10:05:05,775 packet decoding sequence 21 for window 1: 12x20 took 1.2ms
1492018-07-08 10:05:05,776 record_latency: took    5.5 ms round trip,    4.6 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.3 ms,    3.4 ms for echo
1502018-07-08 10:05:05,788 packet decoding sequence 22 for window 1: 12x20 took 1.0ms
1512018-07-08 10:05:05,789 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
1522018-07-08 10:05:05,789 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
1532018-07-08 10:05:05,851 packet decoding sequence 23 for window 1: 12x20 took 2.6ms
1542018-07-08 10:05:05,852 record_latency: took    7.1 ms round trip,    7.0 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.5 ms,    4.4 ms for echo
1552018-07-08 10:05:05,862 packet decoding sequence 24 for window 1: 12x20 took 1.2ms
1562018-07-08 10:05:05,862 record_latency: took    4.7 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.5 ms,    3.2 ms for echo
1572018-07-08 10:05:05,919 packet decoding sequence 25 for window 1: 12x20 took 2.0ms
1582018-07-08 10:05:05,919 record_latency: took    8.9 ms round trip,    8.7 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.9 ms,    6.7 ms for echo
1592018-07-08 10:05:05,942 packet decoding sequence 26 for window 1: 12x20 took 1.2ms
1602018-07-08 10:05:05,943 record_latency: took   11.1 ms round trip,   11.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    9.9 ms,    9.8 ms for echo
1612018-07-08 10:05:05,987 packet decoding sequence 27 for window 1: 12x20 took 1.3ms
1622018-07-08 10:05:05,988 record_latency: took    4.9 ms round trip,    4.6 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.6 ms,    3.3 ms for echo
1632018-07-08 10:05:06,003 packet decoding sequence 28 for window 1: 12x20 took 1.4ms
1642018-07-08 10:05:06,004 record_latency: took    3.8 ms round trip,    3.6 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.5 ms,    2.2 ms for echo
1652018-07-08 10:05:06,052 packet decoding sequence 29 for window 1: 12x20 took 1.3ms
1662018-07-08 10:05:06,053 record_latency: took    4.7 ms round trip,    4.7 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.5 ms,    3.4 ms for echo
1672018-07-08 10:05:06,068 packet decoding sequence 30 for window 1: 12x20 took 1.4ms
1682018-07-08 10:05:06,069 record_latency: took    4.3 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.0 ms,    2.9 ms for echo
1692018-07-08 10:05:06,102 packet decoding sequence 31 for window 1: 12x20 took 1.2ms
1702018-07-08 10:05:06,102 record_latency: took    3.7 ms round trip,    3.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.5 ms,    2.1 ms for echo
1712018-07-08 10:05:06,260 calculate_batch_delay for wid=1 current batch delay=20, last update 3 seconds ago
1722018-07-08 10:05:06,261 calculate_batch_delay for wid=1, 694728 pixels updated since the last update
1732018-07-08 10:05:06,261 calculate_batch_delay for wid=1, skipping - only 30 bytes sent since the last update
1742018-07-08 10:05:06,393 packet decoding sequence 32 for window 1: 12x20 took 1.5ms
1752018-07-08 10:05:06,394 record_latency: took    4.5 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.0 ms,    2.8 ms for echo
1762018-07-08 10:05:06,441 packet decoding sequence 33 for window 1: 12x20 took 1.2ms
1772018-07-08 10:05:06,442 record_latency: took    3.7 ms round trip,    3.5 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.5 ms,    2.3 ms for echo
1782018-07-08 10:05:06,464 packet decoding sequence 34 for window 1: 12x20 took 1.3ms
1792018-07-08 10:05:06,464 record_latency: took    4.4 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.1 ms,    3.0 ms for echo
1802018-07-08 10:05:06,497 packet decoding sequence 35 for window 1: 12x20 took 2.5ms
1812018-07-08 10:05:06,497 record_latency: took    5.2 ms round trip,    5.1 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.7 ms,    2.6 ms for echo
1822018-07-08 10:05:06,524 packet decoding sequence 36 for window 1: 12x20 took 1.3ms
1832018-07-08 10:05:06,525 record_latency: took    4.1 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.8 ms,    2.7 ms for echo
1842018-07-08 10:05:08,501 packet decoding sequence 1 for window 2: 312x259 took 5.6ms
1852018-07-08 10:05:08,502 record_latency: took    8.3 ms round trip,    8.2 for echo,    5.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.7 ms,    2.6 ms for echo
1862018-07-08 10:05:08,502 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
1872018-07-08 10:05:08,502 calculate_batch_delay for wid=2 current batch delay=16, last update 422419 seconds ago
1882018-07-08 10:05:08,503 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
1892018-07-08 10:05:08,503 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 73, 'recent': 73}
1902018-07-08 10:05:08,504 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 126, 'elapsed': 127}
1912018-07-08 10:05:08,504 update_batch_delay: client-latency               : 0.57,0.29  {'target': 7, 'weight_multiplier': 447, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 324, 'div': 1000, 'avg': 4, 'recent': 3}
1922018-07-08 10:05:08,504 update_batch_delay: client-ping-latency          : 0.58,0.12  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 336, 'div': 1000, 'avg': 2, 'recent': 2}
1932018-07-08 10:05:08,504 update_batch_delay: server-ping-latency          : 0.54,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 290, 'div': 1000, 'avg': 5, 'recent': 3}
1942018-07-08 10:05:08,504 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}
1952018-07-08 10:05:08,504 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}
1962018-07-08 10:05:08,505 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}
1972018-07-08 10:05:08,505 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}
1982018-07-08 10:05:08,505 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
1992018-07-08 10:05:08,505 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2002018-07-08 10:05:08,506 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2012018-07-08 10:05:08,506 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2022018-07-08 10:05:08,507 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2032018-07-08 10:05:08,507 update_batch_delay: delay=11
2042018-07-08 10:05:08,514 packet decoding sequence 2 for window 2: 312x259 took 2.2ms
2052018-07-08 10:05:08,514 record_latency: took    5.1 ms round trip,    4.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.2 ms for echo
2062018-07-08 10:05:08,515 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2072018-07-08 10:05:08,597 packet decoding sequence 37 for window 1: 734x459 took 5.2ms
2082018-07-08 10:05:08,597 record_latency: took    9.0 ms round trip,    8.8 for echo,    5.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.8 ms,    3.6 ms for echo
2092018-07-08 10:05:08,597 may_recalculate(1, 336906) total 338106 pixels, scheduling recalculate work item
2102018-07-08 10:05:08,974 packet decoding sequence 3 for window 2: 312x259 took 2.0ms
2112018-07-08 10:05:08,975 record_latency: took    7.2 ms round trip,    7.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    5.2 ms,    5.1 ms for echo
2122018-07-08 10:05:09,004 packet decoding sequence 4 for window 2: 312x259 took 2.3ms
2132018-07-08 10:05:09,005 record_latency: took   13.9 ms round trip,   13.8 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   11.7 ms,   11.5 ms for echo
2142018-07-08 10:05:09,011 packet decoding sequence 5 for window 2: 312x259 took 1.9ms
2152018-07-08 10:05:09,012 record_latency: took    8.2 ms round trip,    8.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.3 ms,    6.2 ms for echo
2162018-07-08 10:05:09,014 packet decoding sequence 6 for window 2: 312x259 took 3.8ms
2172018-07-08 10:05:09,014 record_latency: took    6.4 ms round trip,    6.3 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.6 ms,    2.5 ms for echo
2182018-07-08 10:05:09,073 packet decoding sequence 7 for window 2: 312x259 took 2.0ms
2192018-07-08 10:05:09,073 record_latency: took    6.1 ms round trip,    6.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
2202018-07-08 10:05:09,161 packet decoding sequence 8 for window 2: 312x259 took 2.2ms
2212018-07-08 10:05:09,161 record_latency: took    5.1 ms round trip,    5.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    2.9 ms for echo
2222018-07-08 10:05:09,188 packet decoding sequence 9 for window 2: 312x259 took 1.6ms
2232018-07-08 10:05:09,188 record_latency: took   12.6 ms round trip,   12.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   11.0 ms,   10.9 ms for echo
2242018-07-08 10:05:09,259 packet decoding sequence 10 for window 2: 312x259 took 1.9ms
2252018-07-08 10:05:09,260 record_latency: took    5.1 ms round trip,    5.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.2 ms,    3.1 ms for echo
2262018-07-08 10:05:09,379 packet decoding sequence 11 for window 2: 312x259 took 1.9ms
2272018-07-08 10:05:09,380 record_latency: took    4.6 ms round trip,    4.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.7 ms,    2.6 ms for echo
2282018-07-08 10:05:09,503 calculate_batch_delay for wid=1 current batch delay=20, last update 6 seconds ago
2292018-07-08 10:05:09,503 calculate_batch_delay for wid=1, 1032834 pixels updated since the last update
2302018-07-08 10:05:09,504 calculate_batch_delay for wid=1, skipping - only 36 bytes sent since the last update
2312018-07-08 10:05:09,504 calculate_batch_delay for wid=2 current batch delay=11, last update 1 seconds ago
2322018-07-08 10:05:09,505 calculate_batch_delay for wid=2, 808080 pixels updated since the last update
2332018-07-08 10:05:09,505 calculate_batch_delay for wid=2, skipping - only 10 bytes sent since the last update
2342018-07-08 10:05:09,520 packet decoding sequence 12 for window 2: 312x259 took 1.5ms
2352018-07-08 10:05:09,521 record_latency: took    3.9 ms round trip,    3.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.4 ms for echo
2362018-07-08 10:05:09,521 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2372018-07-08 10:05:09,663 packet decoding sequence 13 for window 2: 312x259 took 3.6ms
2382018-07-08 10:05:09,663 record_latency: took    5.9 ms round trip,    5.8 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.3 ms,    2.3 ms for echo
2392018-07-08 10:05:09,844 packet decoding sequence 14 for window 2: 312x259 took 2.1ms
2402018-07-08 10:05:09,844 record_latency: took    4.7 ms round trip,    4.6 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.6 ms,    2.5 ms for echo
2412018-07-08 10:05:10,503 calculate_batch_delay for wid=2 current batch delay=11, last update 2 seconds ago
2422018-07-08 10:05:10,503 calculate_batch_delay for wid=2, 1050504 pixels updated since the last update
2432018-07-08 10:05:10,504 calculate_batch_delay for wid=2, skipping - only 13 bytes sent since the last update
2442018-07-08 10:05:10,769 packet decoding sequence 15 for window 2: 312x259 took 2.3ms
2452018-07-08 10:05:10,769 record_latency: took    7.0 ms round trip,    6.6 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.6 ms,    4.3 ms for echo
2462018-07-08 10:05:10,770 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2472018-07-08 10:05:10,784 packet decoding sequence 16 for window 2: 312x259 took 2.2ms
2482018-07-08 10:05:10,785 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.4 ms,    2.2 ms for echo
2492018-07-08 10:05:10,801 packet decoding sequence 17 for window 2: 312x259 took 1.6ms
2502018-07-08 10:05:10,802 record_latency: took    3.5 ms round trip,    2.4 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.0 ms,    0.8 ms for echo
2512018-07-08 10:05:10,913 packet decoding sequence 18 for window 2: 312x259 took 4.0ms
2522018-07-08 10:05:10,914 record_latency: took    7.3 ms round trip,    7.3 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.4 ms,    3.3 ms for echo
2532018-07-08 10:05:10,970 packet decoding sequence 19 for window 2: 312x259 took 1.9ms
2542018-07-08 10:05:10,972 record_latency: took    5.7 ms round trip,    5.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.7 ms,    3.7 ms for echo
2552018-07-08 10:05:11,024 packet decoding sequence 20 for window 2: 312x259 took 2.3ms
2562018-07-08 10:05:11,024 record_latency: took    6.7 ms round trip,    6.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.4 ms,    4.2 ms for echo
2572018-07-08 10:05:11,044 packet decoding sequence 21 for window 2: 312x25 took 1.8ms
2582018-07-08 10:05:11,045 record_latency: took    8.0 ms round trip,    7.9 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    6.2 ms,    6.1 ms for echo
2592018-07-08 10:05:11,062 packet decoding sequence 22 for window 2: 312x259 took 2.3ms
2602018-07-08 10:05:11,062 record_latency: took    8.5 ms round trip,    8.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.2 ms,    6.2 ms for echo
2612018-07-08 10:05:11,125 packet decoding sequence 23 for window 2: 312x25 took 1.3ms
2622018-07-08 10:05:11,125 record_latency: took    4.6 ms round trip,    4.5 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    3.3 ms,    3.2 ms for echo
2632018-07-08 10:05:11,149 packet decoding sequence 24 for window 2: 312x259 took 4.0ms
2642018-07-08 10:05:11,150 record_latency: took    8.8 ms round trip,    8.3 for echo,    4.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.8 ms,    4.3 ms for echo
2652018-07-08 10:05:11,325 packet decoding sequence 1 for window 3: 130x54 took 2.6ms
2662018-07-08 10:05:11,325 record_latency: took   44.2 ms round trip,   44.2 for echo,    2.0 for decoding of     7020 pixels,       57 bytes sent over the network in   41.7 ms,   41.6 ms for echo
2672018-07-08 10:05:11,325 may_recalculate(3, 7020) total 7020 pixels, scheduling recalculate work item
2682018-07-08 10:05:11,327 packet decoding sequence 2 for window 3: 130x54 took 4.6ms
2692018-07-08 10:05:11,328 record_latency: took   46.5 ms round trip,   46.5 for echo,    4.0 for decoding of     7020 pixels,       57 bytes sent over the network in   41.9 ms,   41.9 ms for echo
2702018-07-08 10:05:11,394 packet decoding sequence 38 for window 1: 279x25 took 2.1ms
2712018-07-08 10:05:11,394 record_latency: took    5.6 ms round trip,    5.5 for echo,    2.0 for decoding of     6975 pixels,       57 bytes sent over the network in    3.5 ms,    3.4 ms for echo
2722018-07-08 10:05:11,394 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
2732018-07-08 10:05:11,503 calculate_batch_delay for wid=1 current batch delay=20, last update 8 seconds ago
2742018-07-08 10:05:11,503 calculate_batch_delay for wid=1, 1039809 pixels updated since the last update
2752018-07-08 10:05:11,504 calculate_batch_delay for wid=1, skipping - only 37 bytes sent since the last update
2762018-07-08 10:05:11,504 calculate_batch_delay for wid=3 current batch delay=7, last update 422422 seconds ago
2772018-07-08 10:05:11,505 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
2782018-07-08 10:05:11,505 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 490, 'recent': 491}
2792018-07-08 10:05:11,505 update_batch_delay: damage-rate                  : 0.97,0.18  {'max_latency': 100, 'elapsed': 233}
2802018-07-08 10:05:11,505 update_batch_delay: client-latency               : 1.07,0.12  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1153, 'div': 1000, 'avg': 7, 'recent': 14}
2812018-07-08 10:05:11,505 update_batch_delay: client-ping-latency          : 0.59,0.12  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 350, 'div': 1000, 'avg': 2, 'recent': 2}
2822018-07-08 10:05:11,505 update_batch_delay: server-ping-latency          : 0.49,0.14  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 238, 'div': 1000, 'avg': 3, 'recent': 2}
2832018-07-08 10:05:11,506 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}
2842018-07-08 10:05:11,506 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}
2852018-07-08 10:05:11,506 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}
2862018-07-08 10:05:11,506 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}
2872018-07-08 10:05:11,506 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2882018-07-08 10:05:11,506 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2892018-07-08 10:05:11,506 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2902018-07-08 10:05:11,507 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2912018-07-08 10:05:11,507 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2922018-07-08 10:05:11,507 update_batch_delay: delay=7
2932018-07-08 10:05:11,793 packet decoding sequence 1 for window 4: 462x131 took 2.9ms
2942018-07-08 10:05:11,794 record_latency: took   19.2 ms round trip,   19.1 for echo,    2.0 for decoding of    60522 pixels,       60 bytes sent over the network in   16.3 ms,   16.2 ms for echo
2952018-07-08 10:05:11,794 may_recalculate(4, 60522) total 60522 pixels, scheduling recalculate work item
2962018-07-08 10:05:11,885 packet decoding sequence 39 for window 1: 279x25 took 10.7ms
2972018-07-08 10:05:11,886 record_latency: took   37.4 ms round trip,   37.3 for echo,   10.0 for decoding of     6975 pixels,       57 bytes sent over the network in   26.7 ms,   26.6 ms for echo
2982018-07-08 10:05:11,886 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
2992018-07-08 10:05:11,935 packet decoding sequence 1 for window 5: 312x259 took 3.1ms
3002018-07-08 10:05:11,936 record_latency: took   19.1 ms round trip,   18.7 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in   16.0 ms,   15.6 ms for echo
3012018-07-08 10:05:11,936 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
3022018-07-08 10:05:12,009 packet decoding sequence 40 for window 1: 234x25 took 1.5ms
3032018-07-08 10:05:12,009 record_latency: took   29.4 ms round trip,   29.3 for echo,    1.0 for decoding of     5850 pixels,       57 bytes sent over the network in   27.9 ms,   27.8 ms for echo
3042018-07-08 10:05:12,057 packet decoding sequence 1 for window 6: 244x180 took 1.7ms
3052018-07-08 10:05:12,057 record_latency: took   21.3 ms round trip,   21.3 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   19.7 ms,   19.6 ms for echo
3062018-07-08 10:05:12,058 may_recalculate(6, 43920) total 43920 pixels, scheduling recalculate work item
3072018-07-08 10:05:12,157 packet decoding sequence 41 for window 1: 190x25 took 1.4ms
3082018-07-08 10:05:12,157 record_latency: took   43.2 ms round trip,   43.1 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in   41.8 ms,   41.7 ms for echo
3092018-07-08 10:05:12,160 packet decoding sequence 1 for window 7: 237x156 took 4.4ms
3102018-07-08 10:05:12,161 record_latency: took   24.3 ms round trip,   24.2 for echo,    4.0 for decoding of    36972 pixels,       60 bytes sent over the network in   19.9 ms,   19.8 ms for echo
3112018-07-08 10:05:12,161 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
3122018-07-08 10:05:12,213 packet decoding sequence 42 for window 1: 121x25 took 1.7ms
3132018-07-08 10:05:12,213 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of     3025 pixels,       56 bytes sent over the network in    2.6 ms,    2.5 ms for echo
3142018-07-08 10:05:12,504 calculate_batch_delay for wid=1 current batch delay=20, last update 9 seconds ago
3152018-07-08 10:05:12,504 calculate_batch_delay for wid=1, 1060409 pixels updated since the last update
3162018-07-08 10:05:12,505 calculate_batch_delay for wid=1, skipping - only 41 bytes sent since the last update
3172018-07-08 10:05:12,505 calculate_batch_delay for wid=7 current batch delay=22, last update 422423 seconds ago
3182018-07-08 10:05:12,506 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
3192018-07-08 10:05:12,507 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 124, 'recent': 124}
3202018-07-08 10:05:12,507 update_batch_delay: damage-rate                  : 0.80,0.44  {'max_latency': 100, 'elapsed': 397}
3212018-07-08 10:05:12,507 update_batch_delay: client-latency               : 1.11,0.14  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1224, 'div': 1000, 'avg': 11, 'recent': 18}
3222018-07-08 10:05:12,507 update_batch_delay: client-ping-latency          : 0.59,0.12  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 349, 'div': 1000, 'avg': 2, 'recent': 2}
3232018-07-08 10:05:12,508 update_batch_delay: server-ping-latency          : 0.50,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 252, 'div': 1000, 'avg': 4, 'recent': 2}
3242018-07-08 10:05:12,508 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}
3252018-07-08 10:05:12,508 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
3262018-07-08 10:05:12,509 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}
3272018-07-08 10:05:12,509 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
3282018-07-08 10:05:12,510 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
3292018-07-08 10:05:12,510 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
3302018-07-08 10:05:12,510 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
3312018-07-08 10:05:12,511 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
3322018-07-08 10:05:12,511 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
3332018-07-08 10:05:12,511 update_batch_delay: delay=16
3342018-07-08 10:05:13,215 packet decoding sequence 2 for window 7: 237x156 took 1.8ms
3352018-07-08 10:05:13,216 record_latency: took    5.9 ms round trip,    5.9 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
3362018-07-08 10:05:13,216 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
3372018-07-08 10:05:13,321 packet decoding sequence 3 for window 7: 237x156 took 1.7ms
3382018-07-08 10:05:13,321 record_latency: took    6.3 ms round trip,    6.2 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.6 ms,    4.5 ms for echo
3392018-07-08 10:05:13,480 packet decoding sequence 4 for window 7: 237x156 took 1.3ms
3402018-07-08 10:05:13,481 record_latency: took    3.4 ms round trip,    3.4 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    2.1 ms,    2.1 ms for echo
3412018-07-08 10:05:13,504 calculate_batch_delay for wid=7 current batch delay=16, last update 0 seconds ago
3422018-07-08 10:05:13,505 calculate_batch_delay for wid=7, skipping - only 3 events since the last update
3432018-07-08 10:05:13,533 packet decoding sequence 5 for window 7: 237x156 took 1.8ms
3442018-07-08 10:05:13,534 record_latency: took    4.4 ms round trip,    4.3 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    2.6 ms,    2.5 ms for echo
3452018-07-08 10:05:13,534 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
3462018-07-08 10:05:13,565 packet decoding sequence 6 for window 7: 237x156 took 1.9ms
3472018-07-08 10:05:13,565 record_latency: took    5.0 ms round trip,    4.8 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    3.1 ms,    3.0 ms for echo
3482018-07-08 10:05:13,823 packet decoding sequence 7 for window 7: 237x156 took 1.5ms
3492018-07-08 10:05:13,823 record_latency: took    6.2 ms round trip,    6.1 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.7 ms,    4.6 ms for echo
3502018-07-08 10:05:14,115 packet decoding sequence 8 for window 7: 237x156 took 2.0ms
3512018-07-08 10:05:14,116 record_latency: took    6.3 ms round trip,    6.2 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.3 ms,    4.2 ms for echo
3522018-07-08 10:05:14,202 packet decoding sequence 9 for window 7: 237x156 took 2.1ms
3532018-07-08 10:05:14,202 record_latency: took    5.2 ms round trip,    5.1 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in    3.2 ms,    3.1 ms for echo
3542018-07-08 10:05:14,504 calculate_batch_delay for wid=7 current batch delay=16, last update 1 seconds ago
3552018-07-08 10:05:14,505 calculate_batch_delay for wid=7, 295776 pixels updated since the last update
3562018-07-08 10:05:14,505 calculate_batch_delay for wid=7, skipping - only 8 bytes sent since the last update
3572018-07-08 10:05:14,625 packet decoding sequence 10 for window 7: 237x156 took 1.2ms
3582018-07-08 10:05:14,625 record_latency: took    3.2 ms round trip,    3.1 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    2.0 ms,    2.0 ms for echo
3592018-07-08 10:05:14,626 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
3602018-07-08 10:05:14,737 packet decoding sequence 11 for window 7: 237x156 took 3.3ms
3612018-07-08 10:05:14,737 record_latency: took    7.3 ms round trip,    7.2 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in    4.1 ms,    3.9 ms for echo
3622018-07-08 10:05:14,804 packet decoding sequence 12 for window 7: 237x156 took 1.5ms
3632018-07-08 10:05:14,805 record_latency: took    5.3 ms round trip,    5.3 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    3.9 ms,    3.8 ms for echo
3642018-07-08 10:05:14,995 packet decoding sequence 1 for window 8: 248x181 took 2.3ms
3652018-07-08 10:05:14,995 record_latency: took   29.8 ms round trip,   29.6 for echo,    2.0 for decoding of    44888 pixels,       60 bytes sent over the network in   27.5 ms,   27.3 ms for echo
3662018-07-08 10:05:14,995 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
3672018-07-08 10:05:15,083 packet decoding sequence 43 for window 1: 76x25 took 1.3ms
3682018-07-08 10:05:15,084 record_latency: took    3.6 ms round trip,    3.5 for echo,    1.0 for decoding of     1900 pixels,       56 bytes sent over the network in    2.2 ms,    2.2 ms for echo
3692018-07-08 10:05:15,506 calculate_batch_delay for wid=8 current batch delay=22, last update 422426 seconds ago
3702018-07-08 10:05:15,507 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
3712018-07-08 10:05:15,508 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 53, 'recent': 53}
3722018-07-08 10:05:15,508 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 1}
3732018-07-08 10:05:15,508 update_batch_delay: client-latency               : 0.83,0.18  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 685, 'div': 1000, 'avg': 7, 'recent': 8}
3742018-07-08 10:05:15,508 update_batch_delay: client-ping-latency          : 0.59,0.12  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 347, 'div': 1000, 'avg': 2, 'recent': 2}
3752018-07-08 10:05:15,509 update_batch_delay: server-ping-latency          : 0.65,0.11  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 422, 'div': 1000, 'avg': 4, 'recent': 3}
3762018-07-08 10:05:15,509 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}
3772018-07-08 10:05:15,509 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}
3782018-07-08 10:05:15,510 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}
3792018-07-08 10:05:15,510 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}
3802018-07-08 10:05:15,510 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
3812018-07-08 10:05:15,510 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
3822018-07-08 10:05:15,511 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
3832018-07-08 10:05:15,512 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
3842018-07-08 10:05:15,512 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
3852018-07-08 10:05:15,513 update_batch_delay: delay=16
3862018-07-08 10:05:15,537 packet decoding sequence 2 for window 8: 248x181 took 2.4ms
3872018-07-08 10:05:15,537 record_latency: took    5.3 ms round trip,    5.2 for echo,    2.0 for decoding of    44888 pixels,       60 bytes sent over the network in    3.0 ms,    2.8 ms for echo
3882018-07-08 10:05:15,538 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
3892018-07-08 10:05:15,584 packet decoding sequence 3 for window 8: 248x181 took 1.2ms
3902018-07-08 10:05:15,585 record_latency: took    4.4 ms round trip,    4.3 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    3.1 ms,    3.1 ms for echo
3912018-07-08 10:05:15,636 packet decoding sequence 4 for window 8: 248x181 took 3.1ms
3922018-07-08 10:05:15,637 record_latency: took    5.8 ms round trip,    5.7 for echo,    3.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.7 ms,    2.6 ms for echo
3932018-07-08 10:05:15,695 packet decoding sequence 5 for window 8: 248x181 took 4.2ms
3942018-07-08 10:05:15,696 record_latency: took    7.2 ms round trip,    7.1 for echo,    4.0 for decoding of    44888 pixels,       60 bytes sent over the network in    3.0 ms,    2.9 ms for echo
3952018-07-08 10:05:15,749 packet decoding sequence 6 for window 8: 248x181 took 1.7ms
3962018-07-08 10:05:15,749 record_latency: took    5.7 ms round trip,    5.5 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    4.0 ms,    3.8 ms for echo
3972018-07-08 10:05:15,823 packet decoding sequence 7 for window 8: 248x181 took 1.6ms
3982018-07-08 10:05:15,823 record_latency: took    4.1 ms round trip,    4.0 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.5 ms,    2.5 ms for echo
3992018-07-08 10:05:16,173 packet decoding sequence 8 for window 8: 248x181 took 2.0ms
4002018-07-08 10:05:16,173 record_latency: took    6.5 ms round trip,    6.3 for echo,    2.0 for decoding of    44888 pixels,       60 bytes sent over the network in    4.5 ms,    4.3 ms for echo
4012018-07-08 10:05:16,208 packet decoding sequence 9 for window 8: 248x181 took 1.6ms
4022018-07-08 10:05:16,208 record_latency: took    4.1 ms round trip,    3.8 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.5 ms,    2.3 ms for echo
4032018-07-08 10:05:16,281 packet decoding sequence 10 for window 8: 248x181 took 1.9ms
4042018-07-08 10:05:16,282 record_latency: took    4.7 ms round trip,    4.6 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.8 ms,    2.7 ms for echo
4052018-07-08 10:05:16,507 calculate_batch_delay for wid=8 current batch delay=16, last update 1 seconds ago
4062018-07-08 10:05:16,508 calculate_batch_delay for wid=8, 403992 pixels updated since the last update
4072018-07-08 10:05:16,508 calculate_batch_delay for wid=8, skipping - only 9 bytes sent since the last update
4082018-07-08 10:05:16,911 packet decoding sequence 44 for window 1: 734x459 took 5.0ms
4092018-07-08 10:05:16,911 record_latency: took    7.6 ms round trip,    7.5 for echo,    4.0 for decoding of   336906 pixels,       62 bytes sent over the network in    2.6 ms,    2.5 ms for echo
4102018-07-08 10:05:16,912 may_recalculate(1, 336906) total 338806 pixels, scheduling recalculate work item
4112018-07-08 10:05:16,958 packet decoding sequence 45 for window 1: 12x20 took 1.9ms
4122018-07-08 10:05:16,959 record_latency: took    5.3 ms round trip,    5.2 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    3.3 ms,    3.3 ms for echo
4132018-07-08 10:05:17,007 packet decoding sequence 46 for window 1: 12x20 took 1.1ms
4142018-07-08 10:05:17,007 record_latency: took    3.6 ms round trip,    3.5 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.5 ms,    2.4 ms for echo
4152018-07-08 10:05:17,038 packet decoding sequence 47 for window 1: 12x20 took 1.0ms
4162018-07-08 10:05:17,039 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
4172018-07-08 10:05:17,074 packet decoding sequence 48 for window 1: 12x20 took 1.6ms
4182018-07-08 10:05:17,075 record_latency: took    5.5 ms round trip,    5.4 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    3.9 ms,    3.8 ms for echo
4192018-07-08 10:05:17,508 calculate_batch_delay for wid=1 current batch delay=20, last update 14 seconds ago
4202018-07-08 10:05:17,508 calculate_batch_delay for wid=1, 1400175 pixels updated since the last update
4212018-07-08 10:05:17,509 calculate_batch_delay for wid=1, skipping - only 47 bytes sent since the last update
4222018-07-08 10:05:18,564 packet decoding sequence 49 for window 1: 734x459 took 4.7ms
4232018-07-08 10:05:18,564 record_latency: took    7.7 ms round trip,    7.4 for echo,    4.0 for decoding of   336906 pixels,       62 bytes sent over the network in    3.0 ms,    2.7 ms for echo
4242018-07-08 10:05:18,564 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
4252018-07-08 10:05:18,565 calculate_batch_delay for wid=1 current batch delay=20, last update 15 seconds ago
4262018-07-08 10:05:18,565 calculate_batch_delay for wid=1, 1737081 pixels updated since the last update
4272018-07-08 10:05:18,566 calculate_batch_delay for wid=1, skipping - only 48 bytes sent since the last update
4282018-07-08 10:05:22,426 total_time=228311, total_pixels=5040005
4292018-07-08 10:05:22,603 packet decoding sequence 50 for window 1: 263x17 took 4.1ms
4302018-07-08 10:05:22,604 record_latency: took    7.6 ms round trip,    7.6 for echo,    4.0 for decoding of     4471 pixels,       58 bytes sent over the network in    3.6 ms,    3.5 ms for echo
4312018-07-08 10:05:22,604 may_recalculate(1, 4471) total 4471 pixels, scheduling recalculate work item
4322018-07-08 10:05:22,605 calculate_batch_delay for wid=1 current batch delay=20, last update 19 seconds ago
4332018-07-08 10:05:22,606 calculate_batch_delay for wid=1, 1741552 pixels updated since the last update
4342018-07-08 10:05:22,606 calculate_batch_delay for wid=1, skipping - only 49 bytes sent since the last update
4352018-07-08 10:05:22,641 packet decoding sequence 51 for window 1: 12x20 took 1.7ms
4362018-07-08 10:05:22,642 record_latency: took    4.8 ms round trip,    4.7 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.1 ms,    3.1 ms for echo
4372018-07-08 10:05:23,307 packet decoding sequence 52 for window 1: 12x20 took 1.4ms
4382018-07-08 10:05:23,308 record_latency: took    4.5 ms round trip,    4.5 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    3.1 ms,    3.1 ms for echo
4392018-07-08 10:05:23,343 packet decoding sequence 53 for window 1: 12x20 took 0.9ms
4402018-07-08 10:05:23,343 record_latency: took    3.5 ms round trip,    3.4 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.6 ms,    2.6 ms for echo
4412018-07-08 10:05:23,856 total_time=236311, total_pixels=5045196
4422018-07-08 10:05:23,956 total_time=236311, total_pixels=5045196
4432018-07-08 10:05:24,055 total_time=236311, total_pixels=5045196
4442018-07-08 10:05:24,156 total_time=236311, total_pixels=5045196
4452018-07-08 10:05:24,255 total_time=236311, total_pixels=5045196
4462018-07-08 10:05:24,355 total_time=236311, total_pixels=5045196
4472018-07-08 10:05:24,458 total_time=236311, total_pixels=5045196
4482018-07-08 10:05:24,558 total_time=236311, total_pixels=5045196
4492018-07-08 10:05:24,658 total_time=236311, total_pixels=5045196
4502018-07-08 10:05:24,760 total_time=236311, total_pixels=5045196
4512018-07-08 10:05:24,861 total_time=236311, total_pixels=5045196
4522018-07-08 10:05:24,963 total_time=236311, total_pixels=5045196
4532018-07-08 10:05:25,065 total_time=236311, total_pixels=5045196
4542018-07-08 10:05:25,165 total_time=236311, total_pixels=5045196
4552018-07-08 10:05:25,268 total_time=236311, total_pixels=5045196
4562018-07-08 10:05:25,368 total_time=236311, total_pixels=5045196
4572018-07-08 10:05:25,468 total_time=236311, total_pixels=5045196
4582018-07-08 10:05:25,568 total_time=236311, total_pixels=5045196
4592018-07-08 10:05:25,668 total_time=236311, total_pixels=5045196
4602018-07-08 10:05:25,766 total_time=236311, total_pixels=5045196
4612018-07-08 10:05:25,866 total_time=236311, total_pixels=5045196
4622018-07-08 10:05:25,969 total_time=236311, total_pixels=5045196
4632018-07-08 10:05:26,070 total_time=236311, total_pixels=5045196
4642018-07-08 10:05:26,170 total_time=236311, total_pixels=5045196
4652018-07-08 10:05:26,199 packet decoding sequence 54 for window 1: 12x20 took 1.1ms
4662018-07-08 10:05:26,199 record_latency: took    4.3 ms round trip,    3.9 for echo,    1.0 for decoding of      240 pixels,       64 bytes sent over the network in    3.2 ms,    2.8 ms for echo
4672018-07-08 10:05:26,200 packet decoding sequence 55 for window 1: 12x20 took 2.8ms
4682018-07-08 10:05:26,200 record_latency: took    4.9 ms round trip,    4.8 for echo,    2.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.1 ms,    2.0 ms for echo
4692018-07-08 10:05:26,246 packet decoding sequence 56 for window 1: 12x20 took 0.9ms
4702018-07-08 10:05:26,246 record_latency: took    3.4 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.5 ms,    2.2 ms for echo
4712018-07-08 10:05:26,276 packet decoding sequence 57 for window 1: 12x20 took 1.4ms
4722018-07-08 10:05:26,276 record_latency: took    4.3 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.9 ms,    2.8 ms for echo
4732018-07-08 10:05:26,281 total_time=242576, total_pixels=5046156
4742018-07-08 10:05:26,304 packet decoding sequence 58 for window 1: 12x20 took 0.9ms
4752018-07-08 10:05:26,304 record_latency: took    5.0 ms round trip,    4.9 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    4.1 ms,    4.0 ms for echo
4762018-07-08 10:05:26,330 packet decoding sequence 59 for window 1: 12x20 took 1.5ms
4772018-07-08 10:05:26,331 record_latency: took    4.4 ms round trip,    4.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.9 ms,    2.8 ms for echo
4782018-07-08 10:05:26,332 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
4792018-07-08 10:05:26,333 calculate_batch_delay for wid=1 current batch delay=20, last update 23 seconds ago
4802018-07-08 10:05:26,334 calculate_batch_delay for wid=1, 1743712 pixels updated since the last update
4812018-07-08 10:05:26,334 calculate_batch_delay for wid=1, skipping - only 58 bytes sent since the last update
4822018-07-08 10:05:26,374 total_time=245007, total_pixels=5046636
4832018-07-08 10:05:26,478 total_time=245007, total_pixels=5046636
4842018-07-08 10:05:26,574 total_time=245007, total_pixels=5046636
4852018-07-08 10:05:26,673 total_time=245007, total_pixels=5046636
4862018-07-08 10:05:26,774 total_time=245007, total_pixels=5046636
4872018-07-08 10:05:26,872 total_time=245007, total_pixels=5046636
4882018-07-08 10:05:26,990 total_time=245007, total_pixels=5046636
4892018-07-08 10:05:27,007 packet decoding sequence 1 for window 9: 462x131 took 2.5ms
4902018-07-08 10:05:27,008 record_latency: took   79.6 ms round trip,   78.9 for echo,    2.0 for decoding of    60522 pixels,       60 bytes sent over the network in   77.1 ms,   76.4 ms for echo
4912018-07-08 10:05:27,009 may_recalculate(9, 60522) total 60522 pixels, scheduling recalculate work item
4922018-07-08 10:05:27,012 packet decoding sequence 2 for window 9: 462x131 took 9.4ms
4932018-07-08 10:05:27,012 record_latency: took   73.8 ms round trip,   73.7 for echo,    9.0 for decoding of    60522 pixels,       60 bytes sent over the network in   64.4 ms,   64.3 ms for echo
4942018-07-08 10:05:27,037 packet decoding sequence 60 for window 1: 734x459 took 5.6ms
4952018-07-08 10:05:27,037 record_latency: took    7.7 ms round trip,    7.6 for echo,    5.0 for decoding of   336906 pixels,       62 bytes sent over the network in    2.1 ms,    2.0 ms for echo
4962018-07-08 10:05:27,037 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
4972018-07-08 10:05:27,085 total_time=262427, total_pixels=5504586
4982018-07-08 10:05:27,215 total_time=262427, total_pixels=5504586
4992018-07-08 10:05:27,245 packet decoding sequence 1 for window 10: 312x259 took 2.6ms
5002018-07-08 10:05:27,246 record_latency: took   95.9 ms round trip,   95.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   93.3 ms,   93.2 ms for echo
5012018-07-08 10:05:27,246 may_recalculate(10, 80808) total 80808 pixels, scheduling recalculate work item
5022018-07-08 10:05:27,250 packet decoding sequence 2 for window 10: 312x259 took 7.0ms
5032018-07-08 10:05:27,251 record_latency: took   90.1 ms round trip,   90.0 for echo,    6.0 for decoding of    80808 pixels,       60 bytes sent over the network in   83.1 ms,   83.0 ms for echo
5042018-07-08 10:05:27,257 packet decoding sequence 61 for window 1: 234x25 took 5.2ms
5052018-07-08 10:05:27,257 record_latency: took   16.0 ms round trip,   15.9 for echo,    5.0 for decoding of     5850 pixels,       58 bytes sent over the network in   10.8 ms,   10.7 ms for echo
5062018-07-08 10:05:27,320 total_time=277222, total_pixels=5672052
5072018-07-08 10:05:27,334 calculate_batch_delay for wid=1 current batch delay=20, last update 24 seconds ago
5082018-07-08 10:05:27,335 calculate_batch_delay for wid=1, 2086468 pixels updated since the last update
5092018-07-08 10:05:27,335 calculate_batch_delay for wid=1, skipping - only 60 bytes sent since the last update
5102018-07-08 10:05:27,336 calculate_batch_delay for wid=10 current batch delay=16, last update 422438 seconds ago
5112018-07-08 10:05:27,337 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
5122018-07-08 10:05:27,338 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 49, 'recent': 49}
5132018-07-08 10:05:27,338 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 196}
5142018-07-08 10:05:27,338 update_batch_delay: client-latency               : 1.52,0.31  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2311, 'div': 1000, 'avg': 22, 'recent': 50}
5152018-07-08 10:05:27,338 update_batch_delay: client-ping-latency          : 0.57,0.12  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 323, 'div': 1000, 'avg': 2, 'recent': 2}
5162018-07-08 10:05:27,338 update_batch_delay: server-ping-latency          : 0.59,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 351, 'div': 1000, 'avg': 3, 'recent': 2}
5172018-07-08 10:05:27,339 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}
5182018-07-08 10:05:27,339 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}
5192018-07-08 10:05:27,339 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}
5202018-07-08 10:05:27,339 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}
5212018-07-08 10:05:27,340 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
5222018-07-08 10:05:27,340 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
5232018-07-08 10:05:27,340 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
5242018-07-08 10:05:27,340 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
5252018-07-08 10:05:27,341 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
5262018-07-08 10:05:27,341 update_batch_delay: delay=12
5272018-07-08 10:05:27,423 total_time=277222, total_pixels=5672052
5282018-07-08 10:05:27,492 packet decoding sequence 3 for window 10: 312x259 took 2.2ms
5292018-07-08 10:05:27,492 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.5 ms,    4.4 ms for echo
5302018-07-08 10:05:27,493 may_recalculate(10, 80808) total 80808 pixels, scheduling recalculate work item
5312018-07-08 10:05:27,526 total_time=279373, total_pixels=5752860
5322018-07-08 10:05:27,552 packet decoding sequence 4 for window 10: 312x259 took 2.1ms
5332018-07-08 10:05:27,552 record_latency: took   30.3 ms round trip,   30.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   28.2 ms,   28.1 ms for echo
5342018-07-08 10:05:27,578 packet decoding sequence 5 for window 10: 312x259 took 3.8ms
5352018-07-08 10:05:27,578 record_latency: took    6.9 ms round trip,    6.5 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.2 ms,    2.7 ms for echo
5362018-07-08 10:05:27,621 total_time=285290, total_pixels=5914476
5372018-07-08 10:05:27,672 packet decoding sequence 6 for window 10: 312x259 took 2.1ms
5382018-07-08 10:05:27,672 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.5 ms,    3.4 ms for echo
5392018-07-08 10:05:27,692 packet decoding sequence 7 for window 10: 312x259 took 1.8ms
5402018-07-08 10:05:27,693 record_latency: took    4.5 ms round trip,    4.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.7 ms,    2.4 ms for echo
5412018-07-08 10:05:27,720 total_time=289226, total_pixels=6076092
5422018-07-08 10:05:27,757 packet decoding sequence 8 for window 10: 312x259 took 3.0ms
5432018-07-08 10:05:27,757 record_latency: took   54.9 ms round trip,   54.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   52.0 ms,   51.9 ms for echo
5442018-07-08 10:05:27,794 packet decoding sequence 9 for window 10: 312x259 took 1.6ms
5452018-07-08 10:05:27,795 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.6 ms,    2.5 ms for echo
5462018-07-08 10:05:27,821 total_time=293827, total_pixels=6237708
5472018-07-08 10:05:27,934 total_time=293827, total_pixels=6237708
5482018-07-08 10:05:27,952 packet decoding sequence 10 for window 10: 312x259 took 1.6ms
5492018-07-08 10:05:27,952 record_latency: took   23.8 ms round trip,   22.7 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   22.2 ms,   21.1 ms for echo
5502018-07-08 10:05:27,962 packet decoding sequence 11 for window 10: 312x259 took 2.6ms
5512018-07-08 10:05:27,962 record_latency: took   11.8 ms round trip,   10.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    9.3 ms,    8.0 ms for echo
5522018-07-08 10:05:27,971 packet decoding sequence 12 for window 10: 312x25 took 1.6ms
5532018-07-08 10:05:27,972 record_latency: took    6.2 ms round trip,    6.1 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    4.6 ms,    4.5 ms for echo
5542018-07-08 10:05:27,998 packet decoding sequence 13 for window 10: 312x259 took 2.1ms
5552018-07-08 10:05:27,998 record_latency: took    5.0 ms round trip,    4.6 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    2.5 ms for echo
5562018-07-08 10:05:28,023 total_time=301672, total_pixels=6487932
5572018-07-08 10:05:28,058 packet decoding sequence 14 for window 10: 312x259 took 1.6ms
5582018-07-08 10:05:28,059 record_latency: took   52.4 ms round trip,   52.3 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   50.8 ms,   50.7 ms for echo
5592018-07-08 10:05:28,101 packet decoding sequence 15 for window 10: 312x259 took 2.0ms
5602018-07-08 10:05:28,101 record_latency: took    6.2 ms round trip,    6.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.2 ms,    4.2 ms for echo
5612018-07-08 10:05:28,129 total_time=305245, total_pixels=6649548
5622018-07-08 10:05:28,156 packet decoding sequence 16 for window 10: 312x259 took 2.0ms
5632018-07-08 10:05:28,157 record_latency: took   19.7 ms round trip,   19.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   17.7 ms,   17.7 ms for echo
5642018-07-08 10:05:28,230 total_time=307196, total_pixels=6730356
5652018-07-08 10:05:28,351 total_time=307196, total_pixels=6730356
5662018-07-08 10:05:28,410 packet decoding sequence 1 for window 11: 244x180 took 1.9ms
5672018-07-08 10:05:28,410 record_latency: took   53.0 ms round trip,   52.7 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   51.1 ms,   50.7 ms for echo
5682018-07-08 10:05:28,410 may_recalculate(11, 43920) total 43920 pixels, scheduling recalculate work item
5692018-07-08 10:05:28,427 total_time=309108, total_pixels=6774276
5702018-07-08 10:05:28,484 packet decoding sequence 62 for window 1: 190x25 took 2.2ms
5712018-07-08 10:05:28,484 record_latency: took   51.1 ms round trip,   51.1 for echo,    2.0 for decoding of     4750 pixels,       58 bytes sent over the network in   49.0 ms,   49.0 ms for echo
5722018-07-08 10:05:28,484 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
5732018-07-08 10:05:28,540 total_time=311275, total_pixels=6779026
5742018-07-08 10:05:28,569 packet decoding sequence 1 for window 12: 237x156 took 1.7ms
5752018-07-08 10:05:28,569 record_latency: took   81.3 ms round trip,   81.3 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   79.6 ms,   79.6 ms for echo
5762018-07-08 10:05:28,569 may_recalculate(12, 36972) total 36972 pixels, scheduling recalculate work item
5772018-07-08 10:05:28,573 packet decoding sequence 2 for window 12: 237x156 took 5.3ms
5782018-07-08 10:05:28,574 record_latency: took   78.2 ms round trip,   78.1 for echo,    5.0 for decoding of    36972 pixels,       60 bytes sent over the network in   72.9 ms,   72.8 ms for echo
5792018-07-08 10:05:28,587 packet decoding sequence 63 for window 1: 121x25 took 1.3ms
5802018-07-08 10:05:28,588 record_latency: took    5.9 ms round trip,    5.9 for echo,    1.0 for decoding of     3025 pixels,       57 bytes sent over the network in    4.7 ms,    4.6 ms for echo
5812018-07-08 10:05:28,639 total_time=319606, total_pixels=6855995
5822018-07-08 10:05:28,742 total_time=319606, total_pixels=6855995
5832018-07-08 10:05:28,841 total_time=319606, total_pixels=6855995
5842018-07-08 10:05:28,947 total_time=319606, total_pixels=6855995
5852018-07-08 10:05:29,045 total_time=319606, total_pixels=6855995
5862018-07-08 10:05:29,078 packet decoding sequence 3 for window 12: 237x156 took 3.7ms
5872018-07-08 10:05:29,078 record_latency: took   14.2 ms round trip,   14.1 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in   10.5 ms,   10.5 ms for echo
5882018-07-08 10:05:29,148 total_time=323283, total_pixels=6892967
5892018-07-08 10:05:29,179 packet decoding sequence 4 for window 12: 237x156 took 1.9ms
5902018-07-08 10:05:29,179 record_latency: took    9.1 ms round trip,    8.2 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    7.2 ms,    6.3 ms for echo
5912018-07-08 10:05:29,247 total_time=325170, total_pixels=6929939
5922018-07-08 10:05:29,316 packet decoding sequence 5 for window 12: 237x156 took 2.7ms
5932018-07-08 10:05:29,316 record_latency: took    5.6 ms round trip,    5.4 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in    2.9 ms,    2.7 ms for echo
5942018-07-08 10:05:29,341 calculate_batch_delay for wid=1 current batch delay=20, last update 26 seconds ago
5952018-07-08 10:05:29,342 calculate_batch_delay for wid=1, 2094243 pixels updated since the last update
5962018-07-08 10:05:29,342 calculate_batch_delay for wid=1, skipping - only 62 bytes sent since the last update
5972018-07-08 10:05:29,343 calculate_batch_delay for wid=12 current batch delay=11, last update 422440 seconds ago
5982018-07-08 10:05:29,344 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 41}
5992018-07-08 10:05:29,345 update_batch_delay: client-decode-speed          : 0.99,0.00  {'avg': 72, 'recent': 71}
6002018-07-08 10:05:29,345 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 143, 'elapsed': 164}
6012018-07-08 10:05:29,345 update_batch_delay: client-latency               : 0.92,0.13  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 837, 'div': 1000, 'avg': 19, 'recent': 16}
6022018-07-08 10:05:29,346 update_batch_delay: client-ping-latency          : 1.01,0.02  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1023, 'div': 1000, 'avg': 6, 'recent': 11}
6032018-07-08 10:05:29,346 update_batch_delay: server-ping-latency          : 0.72,0.10  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 520, 'div': 1000, 'avg': 3, 'recent': 4}
6042018-07-08 10:05:29,346 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}
6052018-07-08 10:05:29,346 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
6062018-07-08 10:05:29,346 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}
6072018-07-08 10:05:29,346 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
6082018-07-08 10:05:29,347 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
6092018-07-08 10:05:29,347 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
6102018-07-08 10:05:29,347 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
6112018-07-08 10:05:29,347 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
6122018-07-08 10:05:29,347 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
6132018-07-08 10:05:29,348 update_batch_delay: delay=8
6142018-07-08 10:05:29,358 total_time=327874, total_pixels=6966911
6152018-07-08 10:05:29,380 packet decoding sequence 6 for window 12: 237x156 took 2.2ms
6162018-07-08 10:05:29,380 record_latency: took   29.3 ms round trip,   29.2 for echo,    2.0 for decoding of    36972 pixels,       60 bytes sent over the network in   27.1 ms,   27.0 ms for echo
6172018-07-08 10:05:29,381 may_recalculate(12, 36972) total 36972 pixels, scheduling recalculate work item
6182018-07-08 10:05:29,448 total_time=330073, total_pixels=7003883
6192018-07-08 10:05:29,548 total_time=330073, total_pixels=7003883
6202018-07-08 10:05:29,656 total_time=330073, total_pixels=7003883
6212018-07-08 10:05:29,679 packet decoding sequence 7 for window 12: 237x156 took 1.3ms
6222018-07-08 10:05:29,679 record_latency: took   32.0 ms round trip,   29.2 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   30.6 ms,   27.9 ms for echo
6232018-07-08 10:05:29,682 packet decoding sequence 8 for window 12: 237x156 took 3.9ms
6242018-07-08 10:05:29,683 record_latency: took   17.5 ms round trip,   17.4 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in   13.7 ms,   13.6 ms for echo
6252018-07-08 10:05:29,686 packet decoding sequence 9 for window 12: 237x156 took 1.1ms
6262018-07-08 10:05:29,687 record_latency: took    2.9 ms round trip,    2.8 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in    1.8 ms,    1.7 ms for echo
6272018-07-08 10:05:29,748 total_time=336376, total_pixels=7114799
6282018-07-08 10:05:29,863 total_time=336376, total_pixels=7114799
6292018-07-08 10:05:29,956 total_time=336376, total_pixels=7114799
6302018-07-08 10:05:29,964 packet decoding sequence 1 for window 13: 312x259 took 1.9ms
6312018-07-08 10:05:29,965 record_latency: took    4.3 ms round trip,    4.3 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.3 ms for echo
6322018-07-08 10:05:29,965 may_recalculate(13, 80808) total 80808 pixels, scheduling recalculate work item
6332018-07-08 10:05:29,989 packet decoding sequence 64 for window 1: 190x25 took 1.1ms
6342018-07-08 10:05:29,990 record_latency: took    4.2 ms round trip,    4.2 for echo,    1.0 for decoding of     4750 pixels,       58 bytes sent over the network in    3.1 ms,    3.1 ms for echo
6352018-07-08 10:05:29,990 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
6362018-07-08 10:05:30,053 total_time=339377, total_pixels=7200357
6372018-07-08 10:05:30,164 total_time=339377, total_pixels=7200357
6382018-07-08 10:05:30,265 packet decoding sequence 65 for window 1: 234x25 took 4.8ms
6392018-07-08 10:05:30,265 record_latency: took   38.7 ms round trip,   38.6 for echo,    4.0 for decoding of     5850 pixels,       58 bytes sent over the network in   33.9 ms,   33.8 ms for echo
6402018-07-08 10:05:30,284 total_time=347992, total_pixels=7266729
6412018-07-08 10:05:30,340 calculate_batch_delay for wid=1 current batch delay=20, last update 27 seconds ago
6422018-07-08 10:05:30,341 calculate_batch_delay for wid=1, 2104843 pixels updated since the last update
6432018-07-08 10:05:30,341 calculate_batch_delay for wid=1, skipping - only 64 bytes sent since the last update
6442018-07-08 10:05:30,381 total_time=347992, total_pixels=7266729
6452018-07-08 10:05:30,423 packet decoding sequence 1 for window 15: 130x54 took 118.5ms
6462018-07-08 10:05:30,423 record_latency: took  148.5 ms round trip,  148.3 for echo,  118.0 for decoding of     7020 pixels,       57 bytes sent over the network in   30.0 ms,   29.8 ms for echo
6472018-07-08 10:05:30,424 may_recalculate(15, 7020) total 7020 pixels, scheduling recalculate work item
6482018-07-08 10:05:30,427 packet decoding sequence 2 for window 15: 130x54 took 121.2ms
6492018-07-08 10:05:30,427 record_latency: took  150.3 ms round trip,  150.1 for echo,  121.0 for decoding of     7020 pixels,       57 bytes sent over the network in   29.1 ms,   29.0 ms for echo
6502018-07-08 10:05:30,432 packet decoding sequence 66 for window 1: 279x25 took 2.4ms
6512018-07-08 10:05:30,432 record_latency: took   61.4 ms round trip,   57.3 for echo,    2.0 for decoding of     6975 pixels,       58 bytes sent over the network in   59.0 ms,   54.9 ms for echo
6522018-07-08 10:05:30,432 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
6532018-07-08 10:05:30,435 packet decoding sequence 3 for window 15: 130x54 took 6.3ms
6542018-07-08 10:05:30,436 record_latency: took   26.9 ms round trip,   26.8 for echo,    6.0 for decoding of     7020 pixels,       57 bytes sent over the network in   20.6 ms,   20.5 ms for echo
6552018-07-08 10:05:30,480 total_time=596277, total_pixels=7294764
6562018-07-08 10:05:30,520 packet decoding sequence 4 for window 15: 130x54 took 1.1ms
6572018-07-08 10:05:30,520 record_latency: took   50.3 ms round trip,   49.9 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in   49.2 ms,   48.8 ms for echo
6582018-07-08 10:05:30,585 total_time=597409, total_pixels=7301784
6592018-07-08 10:05:30,682 total_time=597409, total_pixels=7301784
6602018-07-08 10:05:30,718 packet decoding sequence 5 for window 15: 130x54 took 1.5ms
6612018-07-08 10:05:30,718 record_latency: took   44.6 ms round trip,   43.8 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in   43.1 ms,   42.3 ms for echo
6622018-07-08 10:05:30,837 total_time=599908, total_pixels=7315824
6632018-07-08 10:05:31,004 packet decoding sequence 67 for window 1: 279x25 took 8.6ms
6642018-07-08 10:05:31,005 record_latency: took  120.2 ms round trip,  119.8 for echo,    8.0 for decoding of     6975 pixels,       58 bytes sent over the network in  111.6 ms,  111.2 ms for echo
6652018-07-08 10:05:31,006 packet decoding sequence 68 for window 1: 234x25 took 10.7ms
6662018-07-08 10:05:31,006 record_latency: took   72.1 ms round trip,   72.0 for echo,   10.0 for decoding of     5850 pixels,       58 bytes sent over the network in   61.4 ms,   61.3 ms for echo
6672018-07-08 10:05:31,039 total_time=629721, total_pixels=7490265
6682018-07-08 10:05:31,141 total_time=629721, total_pixels=7490265
6692018-07-08 10:05:31,257 total_time=881106, total_pixels=7578105
6702018-07-08 10:05:31,294 packet decoding sequence 69 for window 1: 190x25 took 106.0ms
6712018-07-08 10:05:31,295 record_latency: took  187.8 ms round trip,  187.4 for echo,  106.0 for decoding of     4750 pixels,       58 bytes sent over the network in   81.8 ms,   81.4 ms for echo
6722018-07-08 10:05:31,341 calculate_batch_delay for wid=1 current batch delay=20, last update 28 seconds ago
6732018-07-08 10:05:31,342 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
6742018-07-08 10:05:31,342 update_batch_delay: client-decode-speed          : 1.25,0.02  {'avg': 16, 'recent': 22}
6752018-07-08 10:05:31,342 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 119}
6762018-07-08 10:05:31,342 update_batch_delay: client-latency               : 1.59,0.32  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2520, 'div': 1000, 'avg': 28, 'recent': 61}
6772018-07-08 10:05:31,342 update_batch_delay: client-ping-latency          : 1.01,0.02  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1023, 'div': 1000, 'avg': 9, 'recent': 14}
6782018-07-08 10:05:31,343 update_batch_delay: server-ping-latency          : 0.59,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 344, 'div': 1000, 'avg': 3, 'recent': 2}
6792018-07-08 10:05:31,343 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}
6802018-07-08 10:05:31,343 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}
6812018-07-08 10:05:31,344 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}
6822018-07-08 10:05:31,344 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}
6832018-07-08 10:05:31,345 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
6842018-07-08 10:05:31,345 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
6852018-07-08 10:05:31,345 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
6862018-07-08 10:05:31,346 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
6872018-07-08 10:05:31,347 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
6882018-07-08 10:05:31,347 update_batch_delay: delay=17
6892018-07-08 10:05:31,383 total_time=1207068, total_pixels=7656799
6902018-07-08 10:05:31,425 packet decoding sequence 70 for window 1: 121x25 took 112.9ms
6912018-07-08 10:05:31,425 record_latency: took  203.0 ms round trip,  202.5 for echo,  112.0 for decoding of     3025 pixels,       57 bytes sent over the network in   90.1 ms,   89.6 ms for echo
6922018-07-08 10:05:31,426 may_recalculate(1, 3025) total 3025 pixels, scheduling recalculate work item
6932018-07-08 10:05:31,427 packet decoding sequence 1 for window 20: 248x181 took 115.6ms
6942018-07-08 10:05:31,428 record_latency: took  192.8 ms round trip,  192.7 for echo,  115.0 for decoding of    44888 pixels,       60 bytes sent over the network in   77.2 ms,   77.0 ms for echo
6952018-07-08 10:05:31,428 may_recalculate(20, 44888) total 44888 pixels, scheduling recalculate work item
6962018-07-08 10:05:31,432 packet decoding sequence 2 for window 20: 248x181 took 118.8ms
6972018-07-08 10:05:31,432 record_latency: took  193.8 ms round trip,  193.6 for echo,  118.0 for decoding of    44888 pixels,       60 bytes sent over the network in   75.0 ms,   74.9 ms for echo
6982018-07-08 10:05:31,438 packet decoding sequence 71 for window 1: 76x25 took 2.9ms
6992018-07-08 10:05:31,438 record_latency: took   91.1 ms round trip,   90.9 for echo,    2.0 for decoding of     1900 pixels,       57 bytes sent over the network in   88.2 ms,   88.0 ms for echo
7002018-07-08 10:05:31,464 packet decoding sequence 3 for window 20: 248x181 took 1.4ms
7012018-07-08 10:05:31,465 record_latency: took    3.5 ms round trip,    3.5 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.2 ms,    2.1 ms for echo
7022018-07-08 10:05:31,485 total_time=1558672, total_pixels=7796388
7032018-07-08 10:05:31,590 total_time=1558672, total_pixels=7796388
7042018-07-08 10:05:31,686 total_time=1558672, total_pixels=7796388
7052018-07-08 10:05:31,729 packet decoding sequence 4 for window 20: 248x181 took 1.4ms
7062018-07-08 10:05:31,729 record_latency: took   52.6 ms round trip,   52.4 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   51.1 ms,   51.0 ms for echo
7072018-07-08 10:05:31,767 packet decoding sequence 5 for window 20: 248x181 took 1.1ms
7082018-07-08 10:05:31,768 record_latency: took    3.7 ms round trip,    3.7 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.6 ms,    2.5 ms for echo
7092018-07-08 10:05:31,784 total_time=1561236, total_pixels=7886164
7102018-07-08 10:05:31,891 total_time=1561236, total_pixels=7886164
7112018-07-08 10:05:31,989 total_time=1561236, total_pixels=7886164
7122018-07-08 10:05:32,093 total_time=1561236, total_pixels=7886164
7132018-07-08 10:05:32,194 total_time=1561236, total_pixels=7886164
7142018-07-08 10:05:32,235 packet decoding sequence 6 for window 20: 248x181 took 1.4ms
7152018-07-08 10:05:32,235 record_latency: took   53.1 ms round trip,   52.9 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   51.7 ms,   51.5 ms for echo
7162018-07-08 10:05:32,294 total_time=1562595, total_pixels=7931052
7172018-07-08 10:05:32,333 packet decoding sequence 7 for window 20: 248x181 took 1.6ms
7182018-07-08 10:05:32,333 record_latency: took   60.5 ms round trip,   59.9 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   59.0 ms,   58.4 ms for echo
7192018-07-08 10:05:32,341 calculate_batch_delay for wid=1 current batch delay=17, last update 1 seconds ago
7202018-07-08 10:05:32,342 calculate_batch_delay for wid=1, skipping - only 1 events since the last update
7212018-07-08 10:05:32,342 calculate_batch_delay for wid=20 current batch delay=12, last update 422443 seconds ago
7222018-07-08 10:05:32,343 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 43, 'recent': 44}
7232018-07-08 10:05:32,343 update_batch_delay: client-decode-speed          : 0.91,0.01  {'avg': 41, 'recent': 35}
7242018-07-08 10:05:32,343 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 86}
7252018-07-08 10:05:32,344 update_batch_delay: client-latency               : 1.40,0.26  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1952, 'div': 1000, 'avg': 32, 'recent': 51}
7262018-07-08 10:05:32,344 update_batch_delay: client-ping-latency          : 0.72,0.10  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 517, 'div': 1000, 'avg': 7, 'recent': 6}
7272018-07-08 10:05:32,344 update_batch_delay: server-ping-latency          : 0.60,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 356, 'div': 1000, 'avg': 3, 'recent': 2}
7282018-07-08 10:05:32,344 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}
7292018-07-08 10:05:32,344 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}
7302018-07-08 10:05:32,344 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}
7312018-07-08 10:05:32,345 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}
7322018-07-08 10:05:32,345 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
7332018-07-08 10:05:32,345 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
7342018-07-08 10:05:32,345 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
7352018-07-08 10:05:32,345 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
7362018-07-08 10:05:32,345 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
7372018-07-08 10:05:32,346 update_batch_delay: delay=9
7382018-07-08 10:05:32,392 total_time=1564162, total_pixels=7975940
7392018-07-08 10:05:32,495 total_time=1564162, total_pixels=7975940
7402018-07-08 10:05:32,598 total_time=1564162, total_pixels=7975940
7412018-07-08 10:05:32,637 packet decoding sequence 8 for window 20: 248x181 took 1.3ms
7422018-07-08 10:05:32,638 record_latency: took   42.4 ms round trip,   42.3 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   41.1 ms,   41.0 ms for echo
7432018-07-08 10:05:32,638 may_recalculate(20, 44888) total 44888 pixels, scheduling recalculate work item
7442018-07-08 10:05:32,717 total_time=1565461, total_pixels=8020828
7452018-07-08 10:05:32,807 total_time=1565461, total_pixels=8020828
7462018-07-08 10:05:32,896 packet decoding sequence 72 for window 1: 234x25 took 4.4ms
7472018-07-08 10:05:32,896 record_latency: took  109.7 ms round trip,  109.5 for echo,    4.0 for decoding of     5850 pixels,       58 bytes sent over the network in  105.3 ms,  105.1 ms for echo
7482018-07-08 10:05:32,896 may_recalculate(1, 5850) total 5850 pixels, scheduling recalculate work item
7492018-07-08 10:05:32,920 total_time=1571762, total_pixels=8087200
7502018-07-08 10:05:32,991 packet decoding sequence 1 for window 22: 130x54 took 2.5ms
7512018-07-08 10:05:32,992 record_latency: took   86.3 ms round trip,   85.6 for echo,    2.0 for decoding of     7020 pixels,       57 bytes sent over the network in   83.8 ms,   83.1 ms for echo
7522018-07-08 10:05:32,992 may_recalculate(22, 7020) total 7020 pixels, scheduling recalculate work item
7532018-07-08 10:05:33,007 total_time=1574275, total_pixels=8094220
7542018-07-08 10:05:33,059 packet decoding sequence 2 for window 22: 130x54 took 70.7ms
7552018-07-08 10:05:33,060 record_latency: took  147.7 ms round trip,  147.5 for echo,   70.0 for decoding of     7020 pixels,       57 bytes sent over the network in   77.0 ms,   76.8 ms for echo
7562018-07-08 10:05:33,062 packet decoding sequence 73 for window 1: 279x25 took 73.0ms
7572018-07-08 10:05:33,062 record_latency: took   75.8 ms round trip,   75.8 for echo,   73.0 for decoding of     6975 pixels,       58 bytes sent over the network in    2.8 ms,    2.7 ms for echo
7582018-07-08 10:05:33,084 packet decoding sequence 3 for window 22: 130x54 took 1.1ms
7592018-07-08 10:05:33,084 record_latency: took    4.9 ms round trip,    4.8 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    3.8 ms,    3.7 ms for echo
7602018-07-08 10:05:33,110 total_time=1719062, total_pixels=8115235
7612018-07-08 10:05:33,193 packet decoding sequence 4 for window 22: 130x54 took 1.4ms
7622018-07-08 10:05:33,193 record_latency: took    4.7 ms round trip,    4.7 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    3.3 ms,    3.3 ms for echo
7632018-07-08 10:05:33,215 total_time=1720454, total_pixels=8122255
7642018-07-08 10:05:33,313 total_time=1720454, total_pixels=8122255
7652018-07-08 10:05:33,342 calculate_batch_delay for wid=1 current batch delay=17, last update 2 seconds ago
7662018-07-08 10:05:33,343 calculate_batch_delay for wid=1, skipping - only 3 events since the last update
7672018-07-08 10:05:33,343 calculate_batch_delay for wid=22 current batch delay=7, last update 422444 seconds ago
7682018-07-08 10:05:33,344 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 41}
7692018-07-08 10:05:33,344 update_batch_delay: client-decode-speed          : 0.97,0.14  {'avg': 287, 'recent': 273}
7702018-07-08 10:05:33,345 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 164}
7712018-07-08 10:05:33,345 update_batch_delay: client-latency               : 1.21,0.19  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1468, 'div': 1000, 'avg': 32, 'recent': 38}
7722018-07-08 10:05:33,345 update_batch_delay: client-ping-latency          : 0.96,0.04  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 918, 'div': 1000, 'avg': 8, 'recent': 12}
7732018-07-08 10:05:33,345 update_batch_delay: server-ping-latency          : 0.91,0.06  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 824, 'div': 1000, 'avg': 4, 'recent': 7}
7742018-07-08 10:05:33,346 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}
7752018-07-08 10:05:33,346 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}
7762018-07-08 10:05:33,346 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}
7772018-07-08 10:05:33,346 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}
7782018-07-08 10:05:33,347 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
7792018-07-08 10:05:33,347 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
7802018-07-08 10:05:33,347 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
7812018-07-08 10:05:33,347 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
7822018-07-08 10:05:33,347 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
7832018-07-08 10:05:33,347 update_batch_delay: delay=6
7842018-07-08 10:05:33,413 total_time=1720454, total_pixels=8122255
7852018-07-08 10:05:33,522 total_time=1720454, total_pixels=8122255
7862018-07-08 10:05:33,621 total_time=1720454, total_pixels=8122255
7872018-07-08 10:05:33,677 packet decoding sequence 74 for window 1: 279x25 took 7.6ms
7882018-07-08 10:05:33,677 record_latency: took   93.1 ms round trip,   93.1 for echo,    7.0 for decoding of     6975 pixels,       58 bytes sent over the network in   85.5 ms,   85.4 ms for echo
7892018-07-08 10:05:33,677 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
7902018-07-08 10:05:33,743 total_time=1731546, total_pixels=8210038
7912018-07-08 10:05:33,832 total_time=1731546, total_pixels=8210038
7922018-07-08 10:05:33,884 packet decoding sequence 1 for window 25: 237x156 took 110.8ms
7932018-07-08 10:05:33,885 record_latency: took  148.9 ms round trip,  148.9 for echo,  110.0 for decoding of    36972 pixels,       60 bytes sent over the network in   38.2 ms,   38.2 ms for echo
7942018-07-08 10:05:33,886 may_recalculate(25, 36972) total 36972 pixels, scheduling recalculate work item
7952018-07-08 10:05:33,887 packet decoding sequence 75 for window 1: 190x25 took 1.2ms
7962018-07-08 10:05:33,888 record_latency: took   76.8 ms round trip,   76.6 for echo,    1.0 for decoding of     4750 pixels,       58 bytes sent over the network in   75.6 ms,   75.4 ms for echo
7972018-07-08 10:05:33,936 total_time=1843525, total_pixels=8251760
7982018-07-08 10:05:34,037 total_time=1843525, total_pixels=8251760
7992018-07-08 10:05:34,142 total_time=1843525, total_pixels=8251760
8002018-07-08 10:05:34,260 total_time=1843525, total_pixels=8251760
8012018-07-08 10:05:34,314 packet decoding sequence 76 for window 1: 76x25 took 124.4ms
8022018-07-08 10:05:34,314 record_latency: took  200.5 ms round trip,  200.2 for echo,  124.0 for decoding of     1900 pixels,       57 bytes sent over the network in   76.1 ms,   75.8 ms for echo
8032018-07-08 10:05:34,318 packet decoding sequence 1 for window 27: 237x156 took 128.4ms
8042018-07-08 10:05:34,318 record_latency: took  183.6 ms round trip,  183.4 for echo,  128.0 for decoding of    36972 pixels,       60 bytes sent over the network in   55.2 ms,   54.9 ms for echo
8052018-07-08 10:05:34,318 may_recalculate(27, 36972) total 36972 pixels, scheduling recalculate work item
8062018-07-08 10:05:34,322 packet decoding sequence 77 for window 1: 76x25 took 1.9ms
8072018-07-08 10:05:34,323 record_latency: took  112.8 ms round trip,  112.8 for echo,    1.0 for decoding of     1900 pixels,       57 bytes sent over the network in  110.9 ms,  110.8 ms for echo
8082018-07-08 10:05:34,352 calculate_batch_delay for wid=1 current batch delay=17, last update 3 seconds ago
8092018-07-08 10:05:34,352 calculate_batch_delay for wid=1, skipping - only 30250 pixels updated since the last update
8102018-07-08 10:05:34,354 calculate_batch_delay for wid=27 current batch delay=18, last update 422445 seconds ago
8112018-07-08 10:05:34,355 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
8122018-07-08 10:05:34,357 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 3642, 'recent': 3642}
8132018-07-08 10:05:34,358 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 17}
8142018-07-08 10:05:34,359 update_batch_delay: client-latency               : 1.57,0.32  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2480, 'div': 1000, 'avg': 40, 'recent': 71}
8152018-07-08 10:05:34,360 update_batch_delay: client-ping-latency          : 1.31,0.10  {'target': 6, 'weight_multiplier': 185, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1721, 'div': 1000, 'avg': 14, 'recent': 32}
8162018-07-08 10:05:34,361 update_batch_delay: server-ping-latency          : 0.62,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 382, 'div': 1000, 'avg': 3, 'recent': 3}
8172018-07-08 10:05:34,363 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}
8182018-07-08 10:05:34,364 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
8192018-07-08 10:05:34,364 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}
8202018-07-08 10:05:34,365 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 36972000, 'avg': 0, 'recent': 0}
8212018-07-08 10:05:34,365 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
8222018-07-08 10:05:34,366 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
8232018-07-08 10:05:34,366 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
8242018-07-08 10:05:34,367 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
8252018-07-08 10:05:34,368 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
8262018-07-08 10:05:34,368 update_batch_delay: delay=14
8272018-07-08 10:05:34,380 total_time=2098296, total_pixels=8292532
8282018-07-08 10:05:34,419 packet decoding sequence 2 for window 27: 237x156 took 1.9ms
8292018-07-08 10:05:34,419 record_latency: took   51.6 ms round trip,   51.6 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   49.7 ms,   49.6 ms for echo
8302018-07-08 10:05:34,419 may_recalculate(27, 36972) total 36972 pixels, scheduling recalculate work item
8312018-07-08 10:05:34,463 total_time=2100237, total_pixels=8329504
8322018-07-08 10:05:34,518 packet decoding sequence 3 for window 27: 237x156 took 1.2ms
8332018-07-08 10:05:34,518 record_latency: took   61.2 ms round trip,   60.8 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   60.0 ms,   59.6 ms for echo
8342018-07-08 10:05:34,566 total_time=2101437, total_pixels=8366476
8352018-07-08 10:05:34,613 packet decoding sequence 4 for window 27: 237x156 took 1.6ms
8362018-07-08 10:05:34,613 record_latency: took   52.2 ms round trip,   50.8 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   50.7 ms,   49.2 ms for echo
8372018-07-08 10:05:34,669 total_time=2102987, total_pixels=8403448
8382018-07-08 10:05:34,719 packet decoding sequence 5 for window 27: 237x156 took 1.3ms
8392018-07-08 10:05:34,720 record_latency: took   59.3 ms round trip,   59.3 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   58.1 ms,   58.0 ms for echo
8402018-07-08 10:05:34,772 total_time=2104251, total_pixels=8440420
8412018-07-08 10:05:34,821 packet decoding sequence 6 for window 27: 237x156 took 1.5ms
8422018-07-08 10:05:34,821 record_latency: took   58.8 ms round trip,   56.6 for echo,    1.0 for decoding of    36972 pixels,       60 bytes sent over the network in   57.3 ms,   55.1 ms for echo
8432018-07-08 10:05:34,867 total_time=2105729, total_pixels=8477392
8442018-07-08 10:05:34,972 total_time=2105729, total_pixels=8477392
8452018-07-08 10:05:35,071 total_time=2105729, total_pixels=8477392
8462018-07-08 10:05:35,171 total_time=2105729, total_pixels=8477392
8472018-07-08 10:05:35,275 total_time=2105729, total_pixels=8477392
8482018-07-08 10:05:35,376 total_time=2105729, total_pixels=8477392
8492018-07-08 10:05:35,443 packet decoding sequence 78 for window 1: 734x459 took 4.6ms
8502018-07-08 10:05:35,443 record_latency: took    9.2 ms round trip,    9.0 for echo,    4.0 for decoding of   336906 pixels,       62 bytes sent over the network in    4.6 ms,    4.4 ms for echo
8512018-07-08 10:05:35,444 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
8522018-07-08 10:05:35,473 total_time=2110342, total_pixels=8814298
8532018-07-08 10:05:35,530 packet decoding sequence 79 for window 1: 12x20 took 1.6ms
8542018-07-08 10:05:35,530 record_latency: took   11.4 ms round trip,   11.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    9.8 ms,    9.7 ms for echo
8552018-07-08 10:05:35,550 packet decoding sequence 80 for window 1: 12x20 took 1.2ms
8562018-07-08 10:05:35,551 record_latency: took    3.5 ms round trip,    3.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.2 ms,    2.1 ms for echo
8572018-07-08 10:05:35,567 total_time=2113168, total_pixels=8814778
8582018-07-08 10:05:35,625 packet decoding sequence 81 for window 1: 12x20 took 0.9ms
8592018-07-08 10:05:35,626 record_latency: took    2.9 ms round trip,    2.8 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.0 ms,    1.9 ms for echo
8602018-07-08 10:05:35,677 total_time=2114112, total_pixels=8815018
8612018-07-08 10:05:35,772 total_time=2114112, total_pixels=8815018
8622018-07-08 10:05:35,826 packet decoding sequence 82 for window 1: 12x20 took 1.1ms
8632018-07-08 10:05:35,826 record_latency: took    3.4 ms round trip,    3.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.3 ms,    2.2 ms for echo
8642018-07-08 10:05:35,876 total_time=2115241, total_pixels=8815258
8652018-07-08 10:05:35,974 total_time=2115241, total_pixels=8815258
8662018-07-08 10:05:36,076 total_time=2115241, total_pixels=8815258
8672018-07-08 10:05:36,175 total_time=2115241, total_pixels=8815258
8682018-07-08 10:05:36,274 total_time=2115241, total_pixels=8815258
8692018-07-08 10:05:36,344 calculate_batch_delay for wid=1 current batch delay=17, last update 5 seconds ago
8702018-07-08 10:05:36,345 calculate_batch_delay for wid=1, 368116 pixels updated since the last update
8712018-07-08 10:05:36,345 calculate_batch_delay for wid=1, skipping - only 12 bytes sent since the last update
8722018-07-08 10:05:36,375 total_time=2115241, total_pixels=8815258
8732018-07-08 10:05:36,477 total_time=2115241, total_pixels=8815258
8742018-07-08 10:05:36,581 total_time=2115241, total_pixels=8815258
8752018-07-08 10:05:36,638 packet decoding sequence 83 for window 1: 723x38 took 1.6ms
8762018-07-08 10:05:36,638 record_latency: took   63.2 ms round trip,   62.8 for echo,    1.0 for decoding of    27474 pixels,       60 bytes sent over the network in   61.6 ms,   61.2 ms for echo
8772018-07-08 10:05:36,638 may_recalculate(1, 27474) total 27474 pixels, scheduling recalculate work item
8782018-07-08 10:05:36,681 total_time=2116850, total_pixels=8842732
8792018-07-08 10:05:36,740 packet decoding sequence 84 for window 1: 723x38 took 2.3ms
8802018-07-08 10:05:36,740 record_latency: took   66.6 ms round trip,   66.5 for echo,    2.0 for decoding of    27474 pixels,       60 bytes sent over the network in   64.4 ms,   64.3 ms for echo
8812018-07-08 10:05:36,778 total_time=2119117, total_pixels=8870206
8822018-07-08 10:05:36,807 packet decoding sequence 85 for window 1: 723x38 took 1.1ms
8832018-07-08 10:05:36,807 record_latency: took   37.5 ms round trip,   37.4 for echo,    1.0 for decoding of    27474 pixels,       60 bytes sent over the network in   36.4 ms,   36.3 ms for echo
8842018-07-08 10:05:36,879 total_time=2120202, total_pixels=8897680
8852018-07-08 10:05:36,907 packet decoding sequence 86 for window 1: 723x38 took 1.3ms
8862018-07-08 10:05:36,907 record_latency: took   34.7 ms round trip,   33.6 for echo,    1.0 for decoding of    27474 pixels,       60 bytes sent over the network in   33.3 ms,   32.3 ms for echo
8872018-07-08 10:05:36,981 total_time=2121546, total_pixels=8925154
8882018-07-08 10:05:37,042 packet decoding sequence 87 for window 1: 723x38 took 2.0ms
8892018-07-08 10:05:37,043 record_latency: took   60.4 ms round trip,   60.3 for echo,    2.0 for decoding of    27474 pixels,       60 bytes sent over the network in   58.4 ms,   58.3 ms for echo
8902018-07-08 10:05:37,080 total_time=2123570, total_pixels=8952628
8912018-07-08 10:05:37,106 packet decoding sequence 88 for window 1: 723x38 took 1.1ms
8922018-07-08 10:05:37,106 record_latency: took   30.3 ms round trip,   29.8 for echo,    1.0 for decoding of    27474 pixels,       60 bytes sent over the network in   29.2 ms,   28.7 ms for echo
8932018-07-08 10:05:37,182 total_time=2124697, total_pixels=8980102
8942018-07-08 10:05:37,211 packet decoding sequence 89 for window 1: 734x459 took 3.8ms
8952018-07-08 10:05:37,212 record_latency: took   39.1 ms round trip,   39.0 for echo,    3.0 for decoding of   336906 pixels,       62 bytes sent over the network in   35.3 ms,   35.3 ms for echo
8962018-07-08 10:05:37,279 total_time=2128476, total_pixels=9317008
8972018-07-08 10:05:37,345 calculate_batch_delay for wid=1 current batch delay=17, last update 6 seconds ago
8982018-07-08 10:05:37,346 calculate_batch_delay for wid=1, 869866 pixels updated since the last update
8992018-07-08 10:05:37,346 calculate_batch_delay for wid=1, skipping - only 19 bytes sent since the last update
9002018-07-08 10:05:37,381 total_time=2128476, total_pixels=9317008
9012018-07-08 10:05:37,482 total_time=2128476, total_pixels=9317008
9022018-07-08 10:05:37,580 total_time=2128476, total_pixels=9317008
9032018-07-08 10:05:37,685 total_time=2128476, total_pixels=9317008
9042018-07-08 10:05:37,782 total_time=2128476, total_pixels=9317008
9052018-07-08 10:05:37,892 total_time=2128476, total_pixels=9317008
9062018-07-08 10:05:37,988 total_time=2128476, total_pixels=9317008
9072018-07-08 10:05:38,096 total_time=2128476, total_pixels=9317008
9082018-07-08 10:05:38,127 packet decoding sequence 90 for window 1: 734x459 took 2.5ms
9092018-07-08 10:05:38,128 record_latency: took   42.2 ms round trip,   42.1 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   39.7 ms,   39.6 ms for echo
9102018-07-08 10:05:38,128 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
9112018-07-08 10:05:38,188 total_time=2130970, total_pixels=9653914
9122018-07-08 10:05:38,293 total_time=2130970, total_pixels=9653914
9132018-07-08 10:05:38,346 calculate_batch_delay for wid=1 current batch delay=17, last update 7 seconds ago
9142018-07-08 10:05:38,346 calculate_batch_delay for wid=1, 1206772 pixels updated since the last update
9152018-07-08 10:05:38,346 calculate_batch_delay for wid=1, skipping - only 20 bytes sent since the last update
9162018-07-08 10:05:38,386 total_time=2130970, total_pixels=9653914
9172018-07-08 10:05:38,495 total_time=2130970, total_pixels=9653914
9182018-07-08 10:05:38,556 packet decoding sequence 91 for window 1: 734x459 took 3.3ms
9192018-07-08 10:05:38,556 record_latency: took   71.4 ms round trip,   71.1 for echo,    3.0 for decoding of   336906 pixels,       62 bytes sent over the network in   68.0 ms,   67.8 ms for echo
9202018-07-08 10:05:38,556 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
9212018-07-08 10:05:38,588 total_time=2134284, total_pixels=9990820
9222018-07-08 10:05:38,689 total_time=2134284, total_pixels=9990820
9232018-07-08 10:05:38,789 total_time=2134284, total_pixels=9990820
9242018-07-08 10:05:38,889 total_time=2134284, total_pixels=9990820
9252018-07-08 10:05:38,990 total_time=2134284, total_pixels=9990820
9262018-07-08 10:05:39,092 total_time=2134284, total_pixels=9990820
9272018-07-08 10:05:39,190 total_time=2134284, total_pixels=9990820
9282018-07-08 10:05:39,251 packet decoding sequence 92 for window 1: 734x459 took 2.9ms
9292018-07-08 10:05:39,252 record_latency: took    5.3 ms round trip,    5.2 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in    2.4 ms,    2.3 ms for echo
9302018-07-08 10:05:39,294 total_time=2137164, total_pixels=10327726
9312018-07-08 10:05:39,346 calculate_batch_delay for wid=1 current batch delay=17, last update 8 seconds ago
9322018-07-08 10:05:39,346 calculate_batch_delay for wid=1, 1880584 pixels updated since the last update
9332018-07-08 10:05:39,346 calculate_batch_delay for wid=1, skipping - only 22 bytes sent since the last update
9342018-07-08 10:05:39,397 total_time=2137164, total_pixels=10327726
9352018-07-08 10:05:39,499 total_time=2137164, total_pixels=10327726
9362018-07-08 10:05:39,599 total_time=2137164, total_pixels=10327726
9372018-07-08 10:05:39,698 total_time=2137164, total_pixels=10327726
9382018-07-08 10:05:39,799 total_time=2137164, total_pixels=10327726
9392018-07-08 10:05:39,898 total_time=2137164, total_pixels=10327726
9402018-07-08 10:05:39,998 total_time=2137164, total_pixels=10327726
9412018-07-08 10:05:40,100 total_time=2137164, total_pixels=10327726
9422018-07-08 10:05:40,203 total_time=2137164, total_pixels=10327726
9432018-07-08 10:05:40,314 total_time=2137164, total_pixels=10327726
9442018-07-08 10:05:40,345 packet decoding sequence 93 for window 1: 734x459 took 2.4ms
9452018-07-08 10:05:40,346 record_latency: took   41.7 ms round trip,   41.6 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   39.2 ms,   39.1 ms for echo
9462018-07-08 10:05:40,347 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
9472018-07-08 10:05:40,348 calculate_batch_delay for wid=1 current batch delay=17, last update 9 seconds ago
9482018-07-08 10:05:40,349 calculate_batch_delay for wid=1, 2217490 pixels updated since the last update
9492018-07-08 10:05:40,350 calculate_batch_delay for wid=1, skipping - only 23 bytes sent since the last update
9502018-07-08 10:05:40,401 total_time=2139606, total_pixels=10664632
9512018-07-08 10:05:40,443 packet decoding sequence 94 for window 1: 734x459 took 2.4ms
9522018-07-08 10:05:40,443 record_latency: took   38.6 ms round trip,   38.1 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   36.3 ms,   35.7 ms for echo
9532018-07-08 10:05:40,443 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
9542018-07-08 10:05:40,502 total_time=2141965, total_pixels=11001538
9552018-07-08 10:05:40,604 total_time=2141965, total_pixels=11001538
9562018-07-08 10:05:40,703 total_time=2141965, total_pixels=11001538
9572018-07-08 10:05:40,805 total_time=2141965, total_pixels=11001538
9582018-07-08 10:05:40,910 total_time=2141965, total_pixels=11001538
9592018-07-08 10:05:40,957 packet decoding sequence 95 for window 1: 734x459 took 2.6ms
9602018-07-08 10:05:40,957 record_latency: took   54.0 ms round trip,   53.8 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   51.5 ms,   51.2 ms for echo
9612018-07-08 10:05:41,009 total_time=2144522, total_pixels=11338444
9622018-07-08 10:05:41,051 packet decoding sequence 96 for window 1: 734x459 took 2.4ms
9632018-07-08 10:05:41,051 record_latency: took   39.3 ms round trip,   39.2 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   36.8 ms,   36.8 ms for echo
9642018-07-08 10:05:41,107 total_time=2146931, total_pixels=11675350
9652018-07-08 10:05:41,212 total_time=2146931, total_pixels=11675350
9662018-07-08 10:05:41,308 total_time=2146931, total_pixels=11675350
9672018-07-08 10:05:41,348 calculate_batch_delay for wid=1 current batch delay=17, last update 10 seconds ago
9682018-07-08 10:05:41,348 calculate_batch_delay for wid=1, 3565114 pixels updated since the last update
9692018-07-08 10:05:41,349 calculate_batch_delay for wid=1, skipping - only 27 bytes sent since the last update
9702018-07-08 10:05:41,354 packet decoding sequence 97 for window 1: 734x459 took 2.5ms
9712018-07-08 10:05:41,354 record_latency: took   37.3 ms round trip,   37.3 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   34.8 ms,   34.7 ms for echo
9722018-07-08 10:05:41,355 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
9732018-07-08 10:05:41,383 packet decoding sequence 98 for window 1: 734x459 took 3.2ms
9742018-07-08 10:05:41,384 record_latency: took   15.5 ms round trip,   15.4 for echo,    3.0 for decoding of   336906 pixels,       62 bytes sent over the network in   12.3 ms,   12.2 ms for echo
9752018-07-08 10:05:41,411 total_time=2152665, total_pixels=12349162
9762018-07-08 10:05:41,510 total_time=2152665, total_pixels=12349162
9772018-07-08 10:05:41,576 packet decoding sequence 99 for window 1: 13x434 took 2.0ms
9782018-07-08 10:05:41,577 record_latency: took   58.5 ms round trip,   57.7 for echo,    1.0 for decoding of     5642 pixels,       59 bytes sent over the network in   56.5 ms,   55.7 ms for echo
9792018-07-08 10:05:41,609 packet decoding sequence 100 for window 1: 734x459 took 35.3ms
9802018-07-08 10:05:41,609 record_latency: took   65.1 ms round trip,   64.5 for echo,   35.0 for decoding of   336906 pixels,       62 bytes sent over the network in   29.7 ms,   29.2 ms for echo
9812018-07-08 10:05:41,652 total_time=2189962, total_pixels=12691710
9822018-07-08 10:05:41,684 packet decoding sequence 101 for window 1: 734x459 took 2.6ms
9832018-07-08 10:05:41,685 record_latency: took  108.3 ms round trip,  107.7 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in  105.8 ms,  105.1 ms for echo
9842018-07-08 10:05:41,718 packet decoding sequence 102 for window 1: 734x459 took 33.9ms
9852018-07-08 10:05:41,718 record_latency: took   67.0 ms round trip,   66.9 for echo,   33.0 for decoding of   336906 pixels,       62 bytes sent over the network in   33.1 ms,   33.0 ms for echo
9862018-07-08 10:05:41,735 total_time=2226483, total_pixels=13365522
9872018-07-08 10:05:41,783 packet decoding sequence 103 for window 1: 734x459 took 2.4ms
9882018-07-08 10:05:41,783 record_latency: took   66.1 ms round trip,   66.1 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   63.7 ms,   63.7 ms for echo
9892018-07-08 10:05:41,815 packet decoding sequence 104 for window 1: 734x459 took 34.6ms
9902018-07-08 10:05:41,815 record_latency: took   47.9 ms round trip,   47.5 for echo,   34.0 for decoding of   336906 pixels,       62 bytes sent over the network in   13.3 ms,   12.9 ms for echo
9912018-07-08 10:05:41,820 packet decoding sequence 105 for window 1: 734x459 took 3.1ms
9922018-07-08 10:05:41,820 record_latency: took   12.6 ms round trip,   12.6 for echo,    3.0 for decoding of   336906 pixels,       62 bytes sent over the network in    9.5 ms,    9.5 ms for echo
9932018-07-08 10:05:41,838 total_time=2266593, total_pixels=14376240
9942018-07-08 10:05:41,937 total_time=2266593, total_pixels=14376240
9952018-07-08 10:05:41,986 packet decoding sequence 106 for window 1: 13x434 took 1.1ms
9962018-07-08 10:05:41,986 record_latency: took   25.2 ms round trip,   25.2 for echo,    1.0 for decoding of     5642 pixels,       59 bytes sent over the network in   24.1 ms,   24.1 ms for echo
9972018-07-08 10:05:42,018 packet decoding sequence 107 for window 1: 734x459 took 33.9ms
9982018-07-08 10:05:42,019 record_latency: took   47.3 ms round trip,   46.9 for echo,   33.0 for decoding of   336906 pixels,       62 bytes sent over the network in   13.4 ms,   13.1 ms for echo
9992018-07-08 10:05:42,023 packet decoding sequence 108 for window 1: 734x459 took 2.6ms
10002018-07-08 10:05:42,023 record_latency: took   11.9 ms round trip,   11.9 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in    9.3 ms,    9.3 ms for echo
10012018-07-08 10:05:42,039 total_time=2304178, total_pixels=15055694
10022018-07-08 10:05:42,096 packet decoding sequence 109 for window 1: 734x459 took 2.3ms
10032018-07-08 10:05:42,096 record_latency: took   37.1 ms round trip,   36.8 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   34.8 ms,   34.5 ms for echo
10042018-07-08 10:05:42,181 total_time=2306499, total_pixels=15392600
10052018-07-08 10:05:42,208 packet decoding sequence 110 for window 1: 13x434 took 1.1ms
10062018-07-08 10:05:42,210 record_latency: took    6.4 ms round trip,    6.2 for echo,    1.0 for decoding of     5642 pixels,       59 bytes sent over the network in    5.3 ms,    5.1 ms for echo
10072018-07-08 10:05:42,229 packet decoding sequence 111 for window 1: 734x459 took 2.5ms
10082018-07-08 10:05:42,231 record_latency: took   17.6 ms round trip,   17.5 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   15.1 ms,   15.0 ms for echo
10092018-07-08 10:05:42,307 packet decoding sequence 112 for window 1: 734x459 took 6.8ms
10102018-07-08 10:05:42,308 record_latency: took   50.8 ms round trip,   50.4 for echo,    6.0 for decoding of   336906 pixels,       62 bytes sent over the network in   44.0 ms,   43.6 ms for echo
10112018-07-08 10:05:42,369 total_time=2316852, total_pixels=16072054
10122018-07-08 10:05:42,376 calculate_batch_delay for wid=1 current batch delay=17, last update 11 seconds ago
10132018-07-08 10:05:42,384 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
10142018-07-08 10:05:42,386 update_batch_delay: client-decode-speed          : 1.03,0.00  {'avg': 11, 'recent': 11}
10152018-07-08 10:05:42,386 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 34}
10162018-07-08 10:05:42,393 update_batch_delay: client-latency               : 1.03,0.08  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1066, 'div': 1000, 'avg': 30, 'recent': 27}
10172018-07-08 10:05:42,396 update_batch_delay: client-ping-latency          : 0.91,0.05  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 835, 'div': 1000, 'avg': 9, 'recent': 11}
10182018-07-08 10:05:42,397 update_batch_delay: server-ping-latency          : 0.91,0.06  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 826, 'div': 1000, 'avg': 4, 'recent': 7}
10192018-07-08 10:05:42,398 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}
10202018-07-08 10:05:42,399 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}
10212018-07-08 10:05:42,400 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}
10222018-07-08 10:05:42,401 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}
10232018-07-08 10:05:42,407 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
10242018-07-08 10:05:42,407 packet decoding sequence 113 for window 1: 734x459 took 2.7ms
10252018-07-08 10:05:42,407 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
10262018-07-08 10:05:42,408 record_latency: took  100.4 ms round trip,  100.2 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   97.7 ms,   97.5 ms for echo
10272018-07-08 10:05:42,411 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
10282018-07-08 10:05:42,412 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
10292018-07-08 10:05:42,412 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
10302018-07-08 10:05:42,417 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
10312018-07-08 10:05:42,420 update_batch_delay: delay=14
10322018-07-08 10:05:42,436 packet decoding sequence 114 for window 1: 734x459 took 33.3ms
10332018-07-08 10:05:42,437 record_latency: took   60.1 ms round trip,   59.4 for echo,   33.0 for decoding of   336906 pixels,       62 bytes sent over the network in   26.8 ms,   26.1 ms for echo
10342018-07-08 10:05:42,523 packet decoding sequence 115 for window 1: 734x459 took 2.7ms
10352018-07-08 10:05:42,524 record_latency: took  107.0 ms round trip,  106.9 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in  104.3 ms,  104.2 ms for echo
10362018-07-08 10:05:42,545 packet decoding sequence 116 for window 1: 734x459 took 28.3ms
10372018-07-08 10:05:42,545 record_latency: took   71.8 ms round trip,   71.7 for echo,   28.0 for decoding of   336906 pixels,       62 bytes sent over the network in   43.4 ms,   43.4 ms for echo
10382018-07-08 10:05:42,601 total_time=2383869, total_pixels=17419678
10392018-07-08 10:05:42,786 total_time=2383869, total_pixels=17419678
10402018-07-08 10:05:42,864 packet decoding sequence 117 for window 1: 734x459 took 4.8ms
10412018-07-08 10:05:42,864 record_latency: took  343.5 ms round trip,  343.4 for echo,    4.0 for decoding of   336906 pixels,       62 bytes sent over the network in  338.8 ms,  338.7 ms for echo
10422018-07-08 10:05:42,865 networksend_congestion_event(late-ack for sequence    117: late by  88ms, target latency=255 ((2, 0, 3, 250)), 33800, 1467) 1Kbps (average=434Kbps)
10432018-07-08 10:05:42,865 record_congestion_event(late-ack for sequence    117: late by  88ms, target latency=255 ((2, 0, 3, 250)), 33800, 1387)
10442018-07-08 10:05:42,867 packet decoding sequence 118 for window 1: 734x459 took 8.0ms
10452018-07-08 10:05:42,868 record_latency: took  273.3 ms round trip,  273.3 for echo,    7.0 for decoding of   336906 pixels,       62 bytes sent over the network in  265.3 ms,  265.3 ms for echo
10462018-07-08 10:05:42,868 networksend_congestion_event(late-ack for sequence    118: late by  18ms, target latency=255 ((2, 0, 3, 250)), 26800, 1850) 1Kbps (average=434Kbps)
10472018-07-08 10:05:42,869 record_congestion_event(late-ack for sequence    118: late by  18ms, target latency=255 ((2, 0, 3, 250)), 26800, 1748)
10482018-07-08 10:05:42,873 packet decoding sequence 119 for window 1: 734x459 took 12.1ms
10492018-07-08 10:05:42,874 record_latency: took  206.1 ms round trip,  203.0 for echo,   12.0 for decoding of   336906 pixels,       62 bytes sent over the network in  194.0 ms,  190.9 ms for echo
10502018-07-08 10:05:42,876 packet decoding sequence 120 for window 1: 734x459 took 16.1ms
10512018-07-08 10:05:42,877 record_latency: took  169.5 ms round trip,  169.4 for echo,   16.0 for decoding of   336906 pixels,       62 bytes sent over the network in  153.3 ms,  153.3 ms for echo
10522018-07-08 10:05:42,886 packet decoding sequence 121 for window 1: 734x459 took 26.3ms
10532018-07-08 10:05:42,886 record_latency: took  108.6 ms round trip,  108.6 for echo,   26.0 for decoding of   336906 pixels,       62 bytes sent over the network in   82.3 ms,   82.3 ms for echo
10542018-07-08 10:05:42,892 packet decoding sequence 122 for window 1: 734x459 took 29.8ms
10552018-07-08 10:05:42,892 record_latency: took   66.9 ms round trip,   66.9 for echo,   29.0 for decoding of   336906 pixels,       62 bytes sent over the network in   37.2 ms,   37.1 ms for echo
10562018-07-08 10:05:42,915 total_time=2480905, total_pixels=19441114
10572018-07-08 10:05:43,045 total_time=2480905, total_pixels=19441114
10582018-07-08 10:05:43,133 total_time=2480905, total_pixels=19441114
10592018-07-08 10:05:43,180 packet decoding sequence 123 for window 1: 12x20 took 2.4ms
10602018-07-08 10:05:43,181 record_latency: took  247.5 ms round trip,  247.4 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in  245.1 ms,  245.1 ms for echo
10612018-07-08 10:05:43,218 packet decoding sequence 124 for window 1: 734x459 took 40.6ms
10622018-07-08 10:05:43,219 record_latency: took  235.0 ms round trip,  235.0 for echo,   40.0 for decoding of   336906 pixels,       62 bytes sent over the network in  194.5 ms,  194.4 ms for echo
10632018-07-08 10:05:43,224 packet decoding sequence 125 for window 1: 734x459 took 45.6ms
10642018-07-08 10:05:43,224 record_latency: took  144.5 ms round trip,  144.5 for echo,   45.0 for decoding of   336906 pixels,       62 bytes sent over the network in   98.9 ms,   98.8 ms for echo
10652018-07-08 10:05:43,233 packet decoding sequence 126 for window 1: 734x459 took 54.2ms
10662018-07-08 10:05:43,233 record_latency: took   72.4 ms round trip,   71.7 for echo,   54.0 for decoding of   336906 pixels,       62 bytes sent over the network in   18.2 ms,   17.6 ms for echo
10672018-07-08 10:05:43,255 total_time=2623695, total_pixels=20452072
1068
1069(xfce4-terminal:6398): Gdk-CRITICAL **: 10:05:43.359: gdk_window_get_window_type: assertion 'GDK_IS_WINDOW (window)' failed
10702018-07-08 10:05:43,369 calculate_batch_delay for wid=1 current batch delay=14, last update 0 seconds ago
10712018-07-08 10:05:43,375 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
10722018-07-08 10:05:43,380 update_batch_delay: client-decode-speed          : 1.59,0.08  {'avg': 17, 'recent': 34}
10732018-07-08 10:05:43,385 total_time=2623695, total_pixels=20452072
10742018-07-08 10:05:43,390 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 57}
10752018-07-08 10:05:43,393 update_batch_delay: client-latency               : 1.89,0.40  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 3554, 'div': 1000, 'avg': 67, 'recent': 127}
10762018-07-08 10:05:43,394 update_batch_delay: client-ping-latency          : 0.51,0.13  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 258, 'div': 1000, 'avg': 7, 'recent': 3}
10772018-07-08 10:05:43,395 update_batch_delay: server-ping-latency          : 1.07,0.05  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1154, 'div': 1000, 'avg': 6, 'recent': 12}
10782018-07-08 10:05:43,396 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}
10792018-07-08 10:05:43,397 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}
10802018-07-08 10:05:43,397 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}
10812018-07-08 10:05:43,398 update_batch_delay: congestion                   : 2.75,17.48  {}
10822018-07-08 10:05:43,399 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}
10832018-07-08 10:05:43,400 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
10842018-07-08 10:05:43,400 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
10852018-07-08 10:05:43,401 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
10862018-07-08 10:05:43,421 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
10872018-07-08 10:05:43,422 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
10882018-07-08 10:05:43,423 update_batch_delay: delay=33
10892018-07-08 10:05:43,589 total_time=2623695, total_pixels=20452072
10902018-07-08 10:05:43,705 total_time=2623695, total_pixels=20452072
10912018-07-08 10:05:43,796 total_time=2623695, total_pixels=20452072
10922018-07-08 10:05:43,909 total_time=2623695, total_pixels=20452072
10932018-07-08 10:05:44,028 total_time=2623695, total_pixels=20452072
10942018-07-08 10:05:44,152 total_time=2623695, total_pixels=20452072
10952018-07-08 10:05:44,200 packet decoding sequence 127 for window 1: 734x459 took 641.8ms
10962018-07-08 10:05:44,201 record_latency: took  882.6 ms round trip,  882.6 for echo,  641.0 for decoding of   336906 pixels,       62 bytes sent over the network in  240.8 ms,  240.8 ms for echo
10972018-07-08 10:05:44,201 networksend_congestion_event(late-ack for sequence    127: late by 627ms, target latency=255 ((2, 0, 3, 250)), 87700, 565) 0Kbps (average=418Kbps)
10982018-07-08 10:05:44,201 record_congestion_event(late-ack for sequence    127: late by 627ms, target latency=255 ((2, 0, 3, 250)), 87700, 526)
10992018-07-08 10:05:44,202 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
11002018-07-08 10:05:44,235 packet decoding sequence 128 for window 1: 734x459 took 674.2ms
11012018-07-08 10:05:44,235 record_latency: took  813.7 ms round trip,  813.6 for echo,  674.0 for decoding of   336906 pixels,       63 bytes sent over the network in  139.5 ms,  139.4 ms for echo
11022018-07-08 10:05:44,235 networksend_congestion_event(late-ack for sequence    128: late by 558ms, target latency=255 ((2, 0, 3, 250)), 80900, 622) 0Kbps (average=418Kbps)
11032018-07-08 10:05:44,236 record_congestion_event(late-ack for sequence    128: late by 558ms, target latency=255 ((2, 0, 3, 250)), 80900, 575)
11042018-07-08 10:05:44,238 packet decoding sequence 1 for window 29: 164x284 took 669.4ms
11052018-07-08 10:05:44,238 record_latency: took  758.2 ms round trip,  757.5 for echo,  669.0 for decoding of    46576 pixels,       60 bytes sent over the network in   88.8 ms,   88.1 ms for echo
11062018-07-08 10:05:44,238 networksend_congestion_event(late-ack for sequence      1: late by 385ms, target latency=372 ((2, 0, 0, 370)), 75600, 634) 0Kbps (average=418Kbps)
11072018-07-08 10:05:44,238 record_congestion_event(late-ack for sequence      1: late by 385ms, target latency=372 ((2, 0, 0, 370)), 75600, 599)
11082018-07-08 10:05:44,238 may_recalculate(29, 46576) total 46576 pixels, scheduling recalculate work item
11092018-07-08 10:05:44,242 packet decoding sequence 129 for window 1: 734x459 took 673.4ms
11102018-07-08 10:05:44,242 record_latency: took  753.7 ms round trip,  753.0 for echo,  673.0 for decoding of   336906 pixels,       63 bytes sent over the network in   80.3 ms,   79.5 ms for echo
11112018-07-08 10:05:44,242 networksend_congestion_event(late-ack for sequence    129: late by 498ms, target latency=255 ((2, 0, 3, 250)), 74800, 673) 0Kbps (average=418Kbps)
11122018-07-08 10:05:44,242 record_congestion_event(late-ack for sequence    129: late by 498ms, target latency=255 ((2, 0, 3, 250)), 74800, 622)
11132018-07-08 10:05:44,262 total_time=5282577, total_pixels=21509366
11142018-07-08 10:05:44,324 packet decoding sequence 130 for window 1: 734x459 took 76.9ms
11152018-07-08 10:05:44,325 record_latency: took  657.2 ms round trip,  657.2 for echo,   76.0 for decoding of   336906 pixels,       63 bytes sent over the network in  580.3 ms,  580.3 ms for echo
11162018-07-08 10:05:44,325 networksend_congestion_event(late-ack for sequence    130: late by 402ms, target latency=255 ((2, 0, 3, 250)), 65200, 773) 0Kbps (average=418Kbps)
11172018-07-08 10:05:44,325 record_congestion_event(late-ack for sequence    130: late by 402ms, target latency=255 ((2, 0, 3, 250)), 65200, 714)
11182018-07-08 10:05:44,348 networksend_congestion_event(soft-expire limit: 64ms, 5/5, 1000, 0) 418Kbps (average=418Kbps)
11192018-07-08 10:05:44,348 record_congestion_event(soft-expire limit: 64ms, 5/5, 1000, 428504)
11202018-07-08 10:05:44,358 packet decoding sequence 131 for window 1: 734x459 took 113.2ms
11212018-07-08 10:05:44,358 record_latency: took  500.0 ms round trip,  500.0 for echo,  113.0 for decoding of   336906 pixels,       63 bytes sent over the network in  386.8 ms,  386.8 ms for echo
11222018-07-08 10:05:44,359 networksend_congestion_event(late-ack for sequence    131: late by 245ms, target latency=255 ((2, 0, 3, 250)), 49500, 1018) 0Kbps (average=418Kbps)
11232018-07-08 10:05:44,360 record_congestion_event(late-ack for sequence    131: late by 245ms, target latency=255 ((2, 0, 3, 250)), 49500, 939)
11242018-07-08 10:05:44,368 packet decoding sequence 132 for window 1: 734x459 took 123.3ms
11252018-07-08 10:05:44,369 calculate_batch_delay for wid=1 current batch delay=33, last update 0 seconds ago
11262018-07-08 10:05:44,369 record_latency: took  235.6 ms round trip,  235.2 for echo,  123.0 for decoding of   336906 pixels,       63 bytes sent over the network in  112.4 ms,  111.9 ms for echo
11272018-07-08 10:05:44,370 calculate_batch_delay for wid=1, skipping - only 5 events since the last update
11282018-07-08 10:05:44,371 calculate_batch_delay for wid=29 current batch delay=21, last update 422455 seconds ago
11292018-07-08 10:05:44,370 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
11302018-07-08 10:05:44,372 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 43, 'recent': 43}
11312018-07-08 10:05:44,395 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 15071, 'recent': 15071}
11322018-07-08 10:05:44,396 update_batch_delay: damage-rate                  : 0.27,0.85  {'max_latency': 100, 'elapsed': 925}
11332018-07-08 10:05:44,397 update_batch_delay: client-latency               : 2.42,0.50  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 5861, 'div': 1000, 'avg': 100, 'recent': 238}
11342018-07-08 10:05:44,397 update_batch_delay: client-ping-latency          : 0.51,0.13  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 258, 'div': 1000, 'avg': 6, 'recent': 3}
11352018-07-08 10:05:44,397 update_batch_delay: server-ping-latency          : 1.38,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1914, 'div': 1000, 'avg': 11, 'recent': 30}
11362018-07-08 10:05:44,397 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}
11372018-07-08 10:05:44,398 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 46576000, 'avg': 0, 'recent': 0}
11382018-07-08 10:05:44,398 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}
11392018-07-08 10:05:44,398 update_batch_delay: congestion                   : 7.28,62.77  {}
11402018-07-08 10:05:44,398 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 46576000, 'avg': 0, 'recent': 0}
11412018-07-08 10:05:44,399 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
11422018-07-08 10:05:44,399 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
11432018-07-08 10:05:44,400 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
11442018-07-08 10:05:44,400 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
11452018-07-08 10:05:44,400 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
11462018-07-08 10:05:44,400 update_batch_delay: delay=105
11472018-07-08 10:05:44,407 total_time=5595946, total_pixels=22520084
11482018-07-08 10:05:44,503 total_time=5595946, total_pixels=22520084
11492018-07-08 10:05:44,581 packet decoding sequence 133 for window 1: 734x459 took 3.8ms
11502018-07-08 10:05:44,582 record_latency: took  129.7 ms round trip,  129.7 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in  125.9 ms,  125.9 ms for echo
11512018-07-08 10:05:44,606 total_time=5599765, total_pixels=22856990
11522018-07-08 10:05:44,704 total_time=5599765, total_pixels=22856990
11532018-07-08 10:05:44,829 total_time=5599765, total_pixels=22856990
11542018-07-08 10:05:44,893 packet decoding sequence 134 for window 1: 734x459 took 2.9ms
11552018-07-08 10:05:44,899 record_latency: took  303.3 ms round trip,  300.0 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in  300.4 ms,  297.1 ms for echo
11562018-07-08 10:05:44,901 networksend_congestion_event(late-ack for sequence    134: late by  46ms, target latency=255 ((2, 0, 3, 250)), 29900, 1685) 1Kbps (average=395Kbps)
11572018-07-08 10:05:44,901 record_congestion_event(late-ack for sequence    134: late by  46ms, target latency=255 ((2, 0, 3, 250)), 29900, 1514)
11582018-07-08 10:05:44,904 packet decoding sequence 135 for window 1: 734x459 took 27.9ms
11592018-07-08 10:05:44,904 record_latency: took  161.7 ms round trip,  160.8 for echo,   27.0 for decoding of   336906 pixels,       63 bytes sent over the network in  133.8 ms,  132.9 ms for echo
11602018-07-08 10:05:44,935 total_time=5630596, total_pixels=23530802
11612018-07-08 10:05:45,091 packet decoding sequence 136 for window 1: 734x459 took 2.5ms
11622018-07-08 10:05:45,092 record_latency: took    4.5 ms round trip,    4.0 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in    1.9 ms,    1.5 ms for echo
11632018-07-08 10:05:45,153 total_time=5633133, total_pixels=23867708
11642018-07-08 10:05:45,256 total_time=5633133, total_pixels=23867708
11652018-07-08 10:05:45,359 total_time=5633133, total_pixels=23867708
11662018-07-08 10:05:45,386 calculate_batch_delay for wid=1 current batch delay=33, last update 2 seconds ago
11672018-07-08 10:05:45,396 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 42, 'recent': 43}
11682018-07-08 10:05:45,397 update_batch_delay: client-decode-speed          : 0.89,0.01  {'avg': 18, 'recent': 15}
11692018-07-08 10:05:45,398 packet decoding sequence 137 for window 1: 734x459 took 70.8ms
11702018-07-08 10:05:45,398 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 230, 'elapsed': 84}
11712018-07-08 10:05:45,399 record_latency: took  254.3 ms round trip,  254.1 for echo,   70.0 for decoding of   336906 pixels,       63 bytes sent over the network in  183.5 ms,  183.3 ms for echo
11722018-07-08 10:05:45,400 update_batch_delay: client-latency               : 1.89,0.40  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 3580, 'div': 1000, 'avg': 88, 'recent': 140}
11732018-07-08 10:05:45,400 networksend_congestion_event(late-ack for sequence    137: late by   0ms, target latency=255 ((2, 0, 3, 250)), 25000, 2016) 1Kbps (average=380Kbps)
11742018-07-08 10:05:45,401 update_batch_delay: client-ping-latency          : 1.21,0.08  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1454, 'div': 1000, 'avg': 12, 'recent': 25}
11752018-07-08 10:05:45,402 record_congestion_event(late-ack for sequence    137: late by   0ms, target latency=255 ((2, 0, 3, 250)), 25000, 1779)
11762018-07-08 10:05:45,403 update_batch_delay: server-ping-latency          : 0.60,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 365, 'div': 1000, 'avg': 6, 'recent': 4}
11772018-07-08 10:05:45,404 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}
11782018-07-08 10:05:45,404 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}
11792018-07-08 10:05:45,406 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}
11802018-07-08 10:05:45,406 update_batch_delay: congestion                   : 2.47,14.73  {}
11812018-07-08 10:05:45,407 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}
11822018-07-08 10:05:45,407 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
11832018-07-08 10:05:45,408 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
11842018-07-08 10:05:45,410 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
11852018-07-08 10:05:45,411 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
11862018-07-08 10:05:45,412 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
11872018-07-08 10:05:45,413 update_batch_delay: delay=77
11882018-07-08 10:05:45,415 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
11892018-07-08 10:05:45,431 packet decoding sequence 138 for window 1: 734x459 took 103.0ms
11902018-07-08 10:05:45,432 record_latency: took  152.9 ms round trip,  151.8 for echo,  102.0 for decoding of   336906 pixels,       63 bytes sent over the network in   50.0 ms,   48.9 ms for echo
11912018-07-08 10:05:45,519 packet decoding sequence 139 for window 1: 734x459 took 73.6ms
11922018-07-08 10:05:45,520 record_latency: took  152.7 ms round trip,  152.0 for echo,   73.0 for decoding of   336906 pixels,       63 bytes sent over the network in   79.1 ms,   78.4 ms for echo
11932018-07-08 10:05:45,530 packet decoding sequence 140 for window 1: 734x459 took 3.4ms
11942018-07-08 10:05:45,531 record_latency: took   70.7 ms round trip,   70.1 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in   67.3 ms,   66.7 ms for echo
11952018-07-08 10:05:45,584 total_time=5883873, total_pixels=25215332
11962018-07-08 10:05:45,648 packet decoding sequence 141 for window 1: 734x459 took 2.5ms
11972018-07-08 10:05:45,648 record_latency: took   24.9 ms round trip,   24.8 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in   22.4 ms,   22.3 ms for echo
11982018-07-08 10:05:45,731 packet decoding sequence 142 for window 1: 734x459 took 3.1ms
11992018-07-08 10:05:45,731 record_latency: took    6.5 ms round trip,    6.0 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in    3.4 ms,    2.9 ms for echo
12002018-07-08 10:05:45,777 total_time=5889471, total_pixels=25889144
12012018-07-08 10:05:45,905 total_time=5889471, total_pixels=25889144
12022018-07-08 10:05:46,100 total_time=5889471, total_pixels=25889144
12032018-07-08 10:05:46,202 total_time=5889471, total_pixels=25889144
12042018-07-08 10:05:46,299 total_time=5889471, total_pixels=25889144
12052018-07-08 10:05:46,387 calculate_batch_delay for wid=1 current batch delay=77, last update 1 seconds ago
12062018-07-08 10:05:46,388 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
12072018-07-08 10:05:46,388 update_batch_delay: client-decode-speed          : 0.95,0.00  {'avg': 17, 'recent': 16}
12082018-07-08 10:05:46,388 update_batch_delay: damage-rate                  : 0.94,0.25  {'max_latency': 127, 'elapsed': 319}
12092018-07-08 10:05:46,388 update_batch_delay: client-latency               : 1.56,0.31  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2421, 'div': 1000, 'avg': 77, 'recent': 90}
12102018-07-08 10:05:46,389 update_batch_delay: client-ping-latency          : 0.69,0.10  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 475, 'div': 1000, 'avg': 9, 'recent': 6}
12112018-07-08 10:05:46,389 update_batch_delay: server-ping-latency          : 0.58,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 338, 'div': 1000, 'avg': 6, 'recent': 3}
12122018-07-08 10:05:46,389 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}
12132018-07-08 10:05:46,389 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}
12142018-07-08 10:05:46,389 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}
12152018-07-08 10:05:46,389 update_batch_delay: congestion                   : 2.12,11.22  {}
12162018-07-08 10:05:46,390 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}
12172018-07-08 10:05:46,390 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
12182018-07-08 10:05:46,390 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
12192018-07-08 10:05:46,390 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
12202018-07-08 10:05:46,390 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
12212018-07-08 10:05:46,396 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
12222018-07-08 10:05:46,396 update_batch_delay: delay=134
12232018-07-08 10:05:46,415 total_time=5889471, total_pixels=25889144
12242018-07-08 10:05:46,501 total_time=5889471, total_pixels=25889144
12252018-07-08 10:05:46,604 total_time=5889471, total_pixels=25889144
12262018-07-08 10:05:46,750 total_time=5889471, total_pixels=25889144
12272018-07-08 10:05:46,820 packet decoding sequence 143 for window 1: 734x459 took 748.3ms
12282018-07-08 10:05:46,821 record_latency: took  925.6 ms round trip,  925.6 for echo,  748.0 for decoding of   336906 pixels,       63 bytes sent over the network in  177.3 ms,  177.3 ms for echo
12292018-07-08 10:05:46,821 networksend_congestion_event(late-ack for sequence    143: late by 670ms, target latency=255 ((2, 0, 3, 250)), 92000, 547) 0Kbps (average=363Kbps)
12302018-07-08 10:05:46,821 record_congestion_event(late-ack for sequence    143: late by 670ms, target latency=255 ((2, 0, 3, 250)), 92000, 475)
12312018-07-08 10:05:46,821 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
12322018-07-08 10:05:46,851 packet decoding sequence 1 for window 31: 164x284 took 768.5ms
12332018-07-08 10:05:46,851 record_latency: took  849.9 ms round trip,  849.7 for echo,  768.0 for decoding of    46576 pixels,       60 bytes sent over the network in   81.4 ms,   81.2 ms for echo
12342018-07-08 10:05:46,852 networksend_congestion_event(late-ack for sequence      1: late by 490ms, target latency=360 ((2, 0, 0, 358)), 84800, 566) 0Kbps (average=363Kbps)
12352018-07-08 10:05:46,853 record_congestion_event(late-ack for sequence      1: late by 490ms, target latency=360 ((2, 0, 0, 358)), 84800, 501)
12362018-07-08 10:05:46,853 may_recalculate(31, 46576) total 46576 pixels, scheduling recalculate work item
12372018-07-08 10:05:46,878 total_time=7406293, total_pixels=26272626
12382018-07-08 10:05:46,937 packet decoding sequence 144 for window 1: 734x459 took 81.2ms
12392018-07-08 10:05:46,938 record_latency: took  799.3 ms round trip,  799.3 for echo,   81.0 for decoding of   336906 pixels,       63 bytes sent over the network in  718.1 ms,  718.0 ms for echo
12402018-07-08 10:05:46,938 networksend_congestion_event(late-ack for sequence    144: late by 544ms, target latency=255 ((2, 0, 3, 250)), 79400, 634) 0Kbps (average=363Kbps)
12412018-07-08 10:05:46,939 record_congestion_event(late-ack for sequence    144: late by 544ms, target latency=255 ((2, 0, 3, 250)), 79400, 550)
12422018-07-08 10:05:46,969 packet decoding sequence 145 for window 1: 734x459 took 114.1ms
12432018-07-08 10:05:46,970 record_latency: took  512.3 ms round trip,  512.2 for echo,  114.0 for decoding of   336906 pixels,       63 bytes sent over the network in  398.2 ms,  398.1 ms for echo
12442018-07-08 10:05:46,970 networksend_congestion_event(late-ack for sequence    145: late by 257ms, target latency=255 ((2, 0, 3, 250)), 50700, 994) 0Kbps (average=363Kbps)
12452018-07-08 10:05:46,970 record_congestion_event(late-ack for sequence    145: late by 257ms, target latency=255 ((2, 0, 3, 250)), 50700, 862)
12462018-07-08 10:05:47,001 total_time=7601634, total_pixels=26946438
12472018-07-08 10:05:47,061 packet decoding sequence 146 for window 1: 734x459 took 4.1ms
12482018-07-08 10:05:47,063 record_latency: took   36.2 ms round trip,   35.6 for echo,    4.0 for decoding of   336906 pixels,       63 bytes sent over the network in   32.1 ms,   31.5 ms for echo
12492018-07-08 10:05:47,113 total_time=7605737, total_pixels=27283344
12502018-07-08 10:05:47,205 total_time=7605737, total_pixels=27283344
12512018-07-08 10:05:47,307 total_time=7605737, total_pixels=27283344
12522018-07-08 10:05:47,360 packet decoding sequence 147 for window 1: 734x459 took 2.6ms
12532018-07-08 10:05:47,361 record_latency: took   28.4 ms round trip,   28.0 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in   25.9 ms,   25.4 ms for echo
12542018-07-08 10:05:47,388 calculate_batch_delay for wid=1 current batch delay=134, last update 1 seconds ago
12552018-07-08 10:05:47,389 update_batch_delay: damage-network-delay         : 1.02,0.52  {'avg': 42, 'recent': 43}
12562018-07-08 10:05:47,392 update_batch_delay: client-decode-speed          : 0.88,0.01  {'avg': 18, 'recent': 15}
12572018-07-08 10:05:47,415 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 113, 'elapsed': 137}
12582018-07-08 10:05:47,416 update_batch_delay: client-latency               : 2.04,0.43  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4164, 'div': 1000, 'avg': 101, 'recent': 169}
12592018-07-08 10:05:47,417 update_batch_delay: client-ping-latency          : 0.54,0.12  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 293, 'div': 1000, 'avg': 8, 'recent': 3}
12602018-07-08 10:05:47,418 update_batch_delay: server-ping-latency          : 0.68,0.11  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 464, 'div': 1000, 'avg': 5, 'recent': 5}
12612018-07-08 10:05:47,422 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}
12622018-07-08 10:05:47,422 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}
12632018-07-08 10:05:47,423 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}
12642018-07-08 10:05:47,425 update_batch_delay: congestion                   : 4.68,36.75  {}
12652018-07-08 10:05:47,426 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}
12662018-07-08 10:05:47,426 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
12672018-07-08 10:05:47,427 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
12682018-07-08 10:05:47,427 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
12692018-07-08 10:05:47,427 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
12702018-07-08 10:05:47,428 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
12712018-07-08 10:05:47,428 update_batch_delay: delay=330
12722018-07-08 10:05:47,429 calculate_batch_delay for wid=31 current batch delay=49, last update 422458 seconds ago
12732018-07-08 10:05:47,430 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
12742018-07-08 10:05:47,430 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 17302, 'recent': 17302}
12752018-07-08 10:05:47,430 update_batch_delay: damage-rate                  : 0.00,1.13  {'max_latency': 100, 'elapsed': 1484}
12762018-07-08 10:05:47,431 update_batch_delay: client-latency               : 2.04,0.43  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4164, 'div': 1000, 'avg': 101, 'recent': 169}
12772018-07-08 10:05:47,432 update_batch_delay: client-ping-latency          : 0.54,0.12  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 293, 'div': 1000, 'avg': 8, 'recent': 3}
12782018-07-08 10:05:47,432 update_batch_delay: server-ping-latency          : 0.68,0.11  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 464, 'div': 1000, 'avg': 5, 'recent': 5}
12792018-07-08 10:05:47,433 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}
12802018-07-08 10:05:47,433 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 46576000, 'avg': 0, 'recent': 0}
12812018-07-08 10:05:47,434 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}
12822018-07-08 10:05:47,435 update_batch_delay: congestion                   : 4.68,36.75  {}
12832018-07-08 10:05:47,436 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 46576000, 'avg': 0, 'recent': 0}
12842018-07-08 10:05:47,437 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
12852018-07-08 10:05:47,438 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
12862018-07-08 10:05:47,438 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
12872018-07-08 10:05:47,439 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
12882018-07-08 10:05:47,442 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
12892018-07-08 10:05:47,443 update_batch_delay: delay=178
12902018-07-08 10:05:47,451 total_time=7608314, total_pixels=27620250
12912018-07-08 10:05:47,571 packet decoding sequence 148 for window 1: 734x459 took 2.6ms
12922018-07-08 10:05:47,571 record_latency: took    5.3 ms round trip,    4.8 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in    2.7 ms,    2.2 ms for echo
12932018-07-08 10:05:47,572 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
12942018-07-08 10:05:47,619 total_time=7610936, total_pixels=27957156
12952018-07-08 10:05:47,731 total_time=7610936, total_pixels=27957156
12962018-07-08 10:05:47,846 total_time=7610936, total_pixels=27957156
12972018-07-08 10:05:47,971 total_time=7610936, total_pixels=27957156
12982018-07-08 10:05:48,052 total_time=7610936, total_pixels=27957156
12992018-07-08 10:05:48,109 packet decoding sequence 149 for window 1: 734x459 took 2.8ms
13002018-07-08 10:05:48,110 record_latency: took   25.7 ms round trip,   25.0 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in   22.9 ms,   22.2 ms for echo
13012018-07-08 10:05:48,169 total_time=7613692, total_pixels=28294062
13022018-07-08 10:05:48,354 total_time=7613692, total_pixels=28294062
13032018-07-08 10:05:48,395 calculate_batch_delay for wid=1 current batch delay=330, last update 1 seconds ago
13042018-07-08 10:05:48,404 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 42, 'recent': 42}
13052018-07-08 10:05:48,405 update_batch_delay: client-decode-speed          : 0.75,0.01  {'avg': 15, 'recent': 10}
13062018-07-08 10:05:48,406 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 109, 'elapsed': 34}
13072018-07-08 10:05:48,406 update_batch_delay: client-latency               : 1.47,0.29  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2154, 'div': 1000, 'avg': 85, 'recent': 83}
13082018-07-08 10:05:48,407 update_batch_delay: client-ping-latency          : 0.44,0.13  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 196, 'div': 1000, 'avg': 7, 'recent': 2}
13092018-07-08 10:05:48,408 update_batch_delay: server-ping-latency          : 0.57,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 321, 'div': 1000, 'avg': 5, 'recent': 3}
13102018-07-08 10:05:48,408 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}
13112018-07-08 10:05:48,410 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}
13122018-07-08 10:05:48,410 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}
13132018-07-08 10:05:48,410 update_batch_delay: congestion                   : 1.39,3.94  {}
13142018-07-08 10:05:48,411 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}
13152018-07-08 10:05:48,412 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
13162018-07-08 10:05:48,413 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
13172018-07-08 10:05:48,414 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
13182018-07-08 10:05:48,415 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
13192018-07-08 10:05:48,415 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
13202018-07-08 10:05:48,416 update_batch_delay: delay=303
13212018-07-08 10:05:48,456 total_time=7613692, total_pixels=28294062
13222018-07-08 10:05:48,567 total_time=7613692, total_pixels=28294062
13232018-07-08 10:05:48,626 packet decoding sequence 150 for window 1: 734x459 took 3.0ms
13242018-07-08 10:05:48,627 record_latency: took  184.3 ms round trip,  184.2 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in  181.2 ms,  181.2 ms for echo
13252018-07-08 10:05:48,628 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
13262018-07-08 10:05:48,672 total_time=7616708, total_pixels=28630968
13272018-07-08 10:05:48,804 total_time=7616708, total_pixels=28630968
13282018-07-08 10:05:48,942 total_time=7616708, total_pixels=28630968
13292018-07-08 10:05:49,057 packet decoding sequence 151 for window 1: 734x459 took 4.3ms
13302018-07-08 10:05:49,058 record_latency: took  153.2 ms round trip,  152.7 for echo,    4.0 for decoding of   336906 pixels,       63 bytes sent over the network in  148.9 ms,  148.4 ms for echo
13312018-07-08 10:05:49,107 total_time=7621014, total_pixels=28967874
13322018-07-08 10:05:49,217 total_time=7621014, total_pixels=28967874
13332018-07-08 10:05:49,374 total_time=7621014, total_pixels=28967874
13342018-07-08 10:05:49,399 calculate_batch_delay for wid=1 current batch delay=303, last update 1 seconds ago
13352018-07-08 10:05:49,402 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 42, 'recent': 41}
13362018-07-08 10:05:49,403 update_batch_delay: client-decode-speed          : 0.87,0.01  {'avg': 15, 'recent': 12}
13372018-07-08 10:05:49,404 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 176, 'elapsed': 332}
13382018-07-08 10:05:49,405 update_batch_delay: client-latency               : 1.90,0.40  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 3596, 'div': 1000, 'avg': 86, 'recent': 139}
13392018-07-08 10:05:49,406 update_batch_delay: client-ping-latency          : 0.60,0.11  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 354, 'div': 1000, 'avg': 7, 'recent': 4}
13402018-07-08 10:05:49,407 update_batch_delay: server-ping-latency          : 0.64,0.11  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 415, 'div': 1000, 'avg': 5, 'recent': 4}
13412018-07-08 10:05:49,407 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}
13422018-07-08 10:05:49,408 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}
13432018-07-08 10:05:49,408 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}
13442018-07-08 10:05:49,409 update_batch_delay: congestion                   : 1.13,1.30  {}
13452018-07-08 10:05:49,409 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}
13462018-07-08 10:05:49,409 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
13472018-07-08 10:05:49,410 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
13482018-07-08 10:05:49,410 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
13492018-07-08 10:05:49,410 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
13502018-07-08 10:05:49,410 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
13512018-07-08 10:05:49,411 update_batch_delay: delay=255
13522018-07-08 10:05:49,591 total_time=7621014, total_pixels=28967874
13532018-07-08 10:05:49,688 total_time=7621014, total_pixels=28967874
13542018-07-08 10:05:49,867 packet decoding sequence 152 for window 1: 734x459 took 3.6ms
13552018-07-08 10:05:49,872 record_latency: took  608.8 ms round trip,  608.6 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in  605.2 ms,  605.0 ms for echo
13562018-07-08 10:05:49,875 networksend_congestion_event(late-ack for sequence    152: late by 356ms, target latency=255 ((2, 0, 3, 250)), 60600, 831) 0Kbps (average=365Kbps)
13572018-07-08 10:05:49,875 record_congestion_event(late-ack for sequence    152: late by 356ms, target latency=255 ((2, 0, 3, 250)), 60600, 723)
13582018-07-08 10:05:49,876 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
13592018-07-08 10:05:49,926 total_time=7624616, total_pixels=29304780
13602018-07-08 10:05:50,138 total_time=7624616, total_pixels=29304780
13612018-07-08 10:05:50,261 total_time=7624616, total_pixels=29304780
13622018-07-08 10:05:50,335 congestion_notification_callback(16777217, ignore)
13632018-07-08 10:05:50,353 total_time=7624616, total_pixels=29304780
13642018-07-08 10:05:50,399 calculate_batch_delay for wid=1 current batch delay=255, last update 0 seconds ago
13652018-07-08 10:05:50,400 update_batch_delay: damage-network-delay         : 1.03,0.53  {'avg': 42, 'recent': 44}
13662018-07-08 10:05:50,400 update_batch_delay: client-decode-speed          : 0.86,0.01  {'avg': 15, 'recent': 12}
13672018-07-08 10:05:50,400 update_batch_delay: damage-rate                  : 0.65,0.59  {'max_latency': 174, 'elapsed': 696}
13682018-07-08 10:05:50,401 update_batch_delay: client-latency               : 2.92,0.58  {'target': 6, 'weight_multiplier': 420, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 8527, 'div': 1000, 'avg': 101, 'recent': 348}
13692018-07-08 10:05:50,401 update_batch_delay: client-ping-latency          : 0.63,0.11  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 398, 'div': 1000, 'avg': 10, 'recent': 6}
13702018-07-08 10:05:50,401 update_batch_delay: server-ping-latency          : 0.82,0.08  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 676, 'div': 1000, 'avg': 8, 'recent': 9}
13712018-07-08 10:05:50,401 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}
13722018-07-08 10:05:50,401 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}
13732018-07-08 10:05:50,401 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}
13742018-07-08 10:05:50,401 update_batch_delay: congestion                   : 1.94,9.42  {}
13752018-07-08 10:05:50,402 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}
13762018-07-08 10:05:50,402 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
13772018-07-08 10:05:50,402 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
13782018-07-08 10:05:50,402 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
13792018-07-08 10:05:50,403 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
13802018-07-08 10:05:50,403 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
13812018-07-08 10:05:50,403 update_batch_delay: delay=366
13822018-07-08 10:05:50,461 total_time=7624616, total_pixels=29304780
13832018-07-08 10:05:50,589 total_time=7624616, total_pixels=29304780
13842018-07-08 10:05:50,691 total_time=7624616, total_pixels=29304780
13852018-07-08 10:05:50,799 total_time=7624616, total_pixels=29304780
13862018-07-08 10:05:50,911 total_time=7624616, total_pixels=29304780
13872018-07-08 10:05:51,037 total_time=7624616, total_pixels=29304780
13882018-07-08 10:05:51,152 total_time=7624616, total_pixels=29304780
13892018-07-08 10:05:51,220 packet decoding sequence 153 for window 1: 734x459 took 3.3ms
13902018-07-08 10:05:51,220 record_latency: took 1331.2 ms round trip, 1329.9 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in 1327.8 ms, 1326.6 ms for echo
13912018-07-08 10:05:51,220 networksend_congestion_event(late-ack for sequence    153: late by 1075ms, target latency=255 ((2, 0, 3, 250)), 132600, 380) 0Kbps (average=366Kbps)
13922018-07-08 10:05:51,221 record_congestion_event(late-ack for sequence    153: late by 1075ms, target latency=255 ((2, 0, 3, 250)), 132600, 331)
13932018-07-08 10:05:51,221 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
13942018-07-08 10:05:51,264 packet decoding sequence 154 for window 1: 734x459 took 47.5ms
13952018-07-08 10:05:51,264 record_latency: took  654.3 ms round trip,  653.8 for echo,   47.0 for decoding of   336906 pixels,       63 bytes sent over the network in  606.8 ms,  606.3 ms for echo
13962018-07-08 10:05:51,265 networksend_congestion_event(late-ack for sequence    154: late by 398ms, target latency=255 ((2, 0, 3, 250)), 64900, 776) 0Kbps (average=366Kbps)
13972018-07-08 10:05:51,265 record_congestion_event(late-ack for sequence    154: late by 398ms, target latency=255 ((2, 0, 3, 250)), 64900, 676)
13982018-07-08 10:05:51,283 total_time=7675429, total_pixels=29978592
13992018-07-08 10:05:51,351 packet decoding sequence 155 for window 1: 734x459 took 2.4ms
14002018-07-08 10:05:51,351 record_latency: took    4.1 ms round trip,    4.0 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in    1.7 ms,    1.7 ms for echo
14012018-07-08 10:05:51,382 total_time=7677808, total_pixels=30315498
14022018-07-08 10:05:51,400 calculate_batch_delay for wid=1 current batch delay=366, last update 1 seconds ago
14032018-07-08 10:05:51,401 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 42, 'recent': 41}
14042018-07-08 10:05:51,401 update_batch_delay: client-decode-speed          : 0.89,0.01  {'avg': 14, 'recent': 12}
14052018-07-08 10:05:51,401 update_batch_delay: damage-rate                  : 0.32,0.82  {'max_latency': 115, 'elapsed': 912}
14062018-07-08 10:05:51,401 update_batch_delay: client-latency               : 3.47,0.66  {'target': 6, 'weight_multiplier': 417, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 12054, 'div': 1000, 'avg': 171, 'recent': 557}
14072018-07-08 10:05:51,401 update_batch_delay: client-ping-latency          : 0.82,0.08  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 672, 'div': 1000, 'avg': 10, 'recent': 10}
14082018-07-08 10:05:51,401 update_batch_delay: server-ping-latency          : 0.88,0.06  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 778, 'div': 1000, 'avg': 7, 'recent': 10}
14092018-07-08 10:05:51,402 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}
14102018-07-08 10:05:51,402 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}
14112018-07-08 10:05:51,402 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}
14122018-07-08 10:05:51,402 update_batch_delay: congestion                   : 2.87,18.70  {}
14132018-07-08 10:05:51,402 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}
14142018-07-08 10:05:51,402 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
14152018-07-08 10:05:51,402 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
14162018-07-08 10:05:51,402 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
14172018-07-08 10:05:51,403 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
14182018-07-08 10:05:51,403 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
14192018-07-08 10:05:51,403 update_batch_delay: delay=401
14202018-07-08 10:05:51,484 total_time=7677808, total_pixels=30315498
14212018-07-08 10:05:51,584 total_time=7677808, total_pixels=30315498
14222018-07-08 10:05:51,706 total_time=7677808, total_pixels=30315498
14232018-07-08 10:05:51,788 total_time=7677808, total_pixels=30315498
14242018-07-08 10:05:51,891 total_time=7677808, total_pixels=30315498
14252018-07-08 10:05:52,005 total_time=7677808, total_pixels=30315498
14262018-07-08 10:05:52,099 total_time=7677808, total_pixels=30315498
14272018-07-08 10:05:52,213 packet decoding sequence 156 for window 1: 734x459 took 3.0ms
14282018-07-08 10:05:52,213 record_latency: took  213.6 ms round trip,  213.6 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in  210.6 ms,  210.6 ms for echo
14292018-07-08 10:05:52,214 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
14302018-07-08 10:05:52,234 total_time=7680783, total_pixels=30652404
14312018-07-08 10:05:52,337 total_time=7680783, total_pixels=30652404
14322018-07-08 10:05:52,401 calculate_batch_delay for wid=1 current batch delay=401, last update 1 seconds ago
14332018-07-08 10:05:52,402 update_batch_delay: damage-network-delay         : 1.02,0.52  {'avg': 42, 'recent': 43}
14342018-07-08 10:05:52,402 update_batch_delay: client-decode-speed          : 0.78,0.01  {'avg': 14, 'recent': 10}
14352018-07-08 10:05:52,402 update_batch_delay: damage-rate                  : 0.75,0.50  {'max_latency': 113, 'elapsed': 474}
14362018-07-08 10:05:52,402 update_batch_delay: client-latency               : 2.52,0.51  {'target': 6, 'weight_multiplier': 417, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 6339, 'div': 1000, 'avg': 140, 'recent': 280}
14372018-07-08 10:05:52,403 update_batch_delay: client-ping-latency          : 0.55,0.12  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 298, 'div': 1000, 'avg': 10, 'recent': 4}
14382018-07-08 10:05:52,403 update_batch_delay: server-ping-latency          : 0.56,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 309, 'div': 1000, 'avg': 6, 'recent': 3}
14392018-07-08 10:05:52,403 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}
14402018-07-08 10:05:52,403 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}
14412018-07-08 10:05:52,403 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}
14422018-07-08 10:05:52,403 update_batch_delay: congestion                   : 1.21,2.10  {}
14432018-07-08 10:05:52,404 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}
14442018-07-08 10:05:52,404 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
14452018-07-08 10:05:52,404 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
14462018-07-08 10:05:52,404 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
14472018-07-08 10:05:52,404 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
14482018-07-08 10:05:52,404 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
14492018-07-08 10:05:52,405 update_batch_delay: delay=359
14502018-07-08 10:05:52,438 total_time=7680783, total_pixels=30652404
14512018-07-08 10:05:52,515 packet decoding sequence 157 for window 1: 734x459 took 3.1ms
14522018-07-08 10:05:52,515 record_latency: took    6.9 ms round trip,    6.8 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in    3.7 ms,    3.7 ms for echo
14532018-07-08 10:05:52,516 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
14542018-07-08 10:05:52,555 total_time=7683924, total_pixels=30989310
14552018-07-08 10:05:52,662 total_time=7683924, total_pixels=30989310
14562018-07-08 10:05:52,857 total_time=7683924, total_pixels=30989310
14572018-07-08 10:05:52,928 packet decoding sequence 158 for window 1: 734x459 took 2.6ms
14582018-07-08 10:05:52,928 record_latency: took   29.0 ms round trip,   28.9 for echo,    2.0 for decoding of   336906 pixels,       63 bytes sent over the network in   26.5 ms,   26.3 ms for echo
14592018-07-08 10:05:52,971 total_time=7686503, total_pixels=31326216
14602018-07-08 10:05:53,104 total_time=7686503, total_pixels=31326216
14612018-07-08 10:05:53,268 total_time=7686503, total_pixels=31326216
14622018-07-08 10:05:53,390 total_time=7686503, total_pixels=31326216
14632018-07-08 10:05:53,408 calculate_batch_delay for wid=1 current batch delay=359, last update 1 seconds ago
14642018-07-08 10:05:53,412 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 42, 'recent': 41}
14652018-07-08 10:05:53,413 update_batch_delay: client-decode-speed          : 0.77,0.01  {'avg': 13, 'recent': 9}
14662018-07-08 10:05:53,413 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 107, 'elapsed': 181}
14672018-07-08 10:05:53,414 update_batch_delay: client-latency               : 1.68,0.34  {'target': 6, 'weight_multiplier': 417, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2826, 'div': 1000, 'avg': 115, 'recent': 119}
14682018-07-08 10:05:53,415 update_batch_delay: client-ping-latency          : 0.74,0.09  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 549, 'div': 1000, 'avg': 10, 'recent': 8}
14692018-07-08 10:05:53,415 update_batch_delay: server-ping-latency          : 0.66,0.11  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 438, 'div': 1000, 'avg': 6, 'recent': 5}
14702018-07-08 10:05:53,415 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}
14712018-07-08 10:05:53,415 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}
14722018-07-08 10:05:53,416 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}
14732018-07-08 10:05:53,417 update_batch_delay: congestion                   : 1.07,0.72  {}
14742018-07-08 10:05:53,417 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}
14752018-07-08 10:05:53,418 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
14762018-07-08 10:05:53,418 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
14772018-07-08 10:05:53,418 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
14782018-07-08 10:05:53,419 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
14792018-07-08 10:05:53,419 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
14802018-07-08 10:05:53,420 update_batch_delay: delay=302
14812018-07-08 10:05:53,454 packet decoding sequence 159 for window 1: 734x459 took 4.6ms
14822018-07-08 10:05:53,454 record_latency: took  176.6 ms round trip,  175.8 for echo,    4.0 for decoding of   336906 pixels,       63 bytes sent over the network in  172.0 ms,  171.1 ms for echo
14832018-07-08 10:05:53,454 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
14842018-07-08 10:05:53,493 total_time=7691136, total_pixels=31663122
14852018-07-08 10:05:53,597 total_time=7691136, total_pixels=31663122
14862018-07-08 10:05:53,698 total_time=7691136, total_pixels=31663122
14872018-07-08 10:05:53,755 packet decoding sequence 160 for window 1: 734x459 took 3.5ms
14882018-07-08 10:05:53,755 record_latency: took    5.7 ms round trip,    5.5 for echo,    3.0 for decoding of   336906 pixels,       63 bytes sent over the network in    2.2 ms,    2.0 ms for echo
14892018-07-08 10:05:53,796 total_time=7694601, total_pixels=32000028
14902018-07-08 10:05:53,896 total_time=7694601, total_pixels=32000028
14912018-07-08 10:05:54,001 total_time=7694601, total_pixels=32000028
14922018-07-08 10:05:54,098 total_time=7694601, total_pixels=32000028
14932018-07-08 10:05:54,199 total_time=7694601, total_pixels=32000028
14942018-07-08 10:05:54,297 total_time=7694601, total_pixels=32000028
14952018-07-08 10:05:54,400 total_time=7694601, total_pixels=32000028
14962018-07-08 10:05:54,408 calculate_batch_delay for wid=1 current batch delay=302, last update 0 seconds ago
14972018-07-08 10:05:54,410 update_batch_delay: damage-network-delay         : 0.99,0.52  {'avg': 42, 'recent': 41}
14982018-07-08 10:05:54,411 update_batch_delay: client-decode-speed          : 0.88,0.01  {'avg': 13, 'recent': 11}
14992018-07-08 10:05:54,412 update_batch_delay: damage-rate                  : 0.92,0.29  {'max_latency': 123, 'elapsed': 331}
15002018-07-08 10:05:54,413 update_batch_delay: client-latency               : 1.61,0.33  {'target': 6, 'weight_multiplier': 417, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2603, 'div': 1000, 'avg': 105, 'recent': 107}
15012018-07-08 10:05:54,413 update_batch_delay: client-ping-latency          : 0.63,0.11  {'target': 6, 'weight_multiplier': 180, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 402, 'div': 1000, 'avg': 9, 'recent': 5}
15022018-07-08 10:05:54,414 update_batch_delay: server-ping-latency          : 1.19,0.08  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1417, 'div': 1000, 'avg': 9, 'recent': 20}
15032018-07-08 10:05:54,414 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}
15042018-07-08 10:05:54,415 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}
15052018-07-08 10:05:54,415 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}
15062018-07-08 10:05:54,416 update_batch_delay: congestion                   : 1.03,0.34  {}
15072018-07-08 10:05:54,420 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}
15082018-07-08 10:05:54,421 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
15092018-07-08 10:05:54,421 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
15102018-07-08 10:05:54,422 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
15112018-07-08 10:05:54,422 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
15122018-07-08 10:05:54,423 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
15132018-07-08 10:05:54,423 update_batch_delay: delay=266
15142018-07-08 10:05:54,518 total_time=7694601, total_pixels=32000028
15152018-07-08 10:05:54,595 packet decoding sequence 161 for window 1: 12x20 took 1.2ms
15162018-07-08 10:05:54,596 record_latency: took   57.5 ms round trip,   56.4 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in   56.3 ms,   55.2 ms for echo
15172018-07-08 10:05:54,614 total_time=7695774, total_pixels=32000268
15182018-07-08 10:05:54,713 total_time=7695774, total_pixels=32000268
15192018-07-08 10:05:54,818 total_time=7695774, total_pixels=32000268
15202018-07-08 10:05:54,888 packet decoding sequence 162 for window 1: 12x20 took 1.2ms
15212018-07-08 10:05:54,888 record_latency: took    3.6 ms round trip,    3.4 for echo,    1.0 for decoding of      240 pixels,       67 bytes sent over the network in    2.4 ms,    2.2 ms for echo
15222018-07-08 10:05:54,889 packet decoding sequence 163 for window 1: 12x20 took 1.9ms
15232018-07-08 10:05:54,890 record_latency: took    4.8 ms round trip,    4.8 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    2.9 ms,    2.8 ms for echo
15242018-07-08 10:05:54,919 total_time=7698909, total_pixels=32000748
15252018-07-08 10:05:55,017 total_time=7698909, total_pixels=32000748
15262018-07-08 10:05:55,121 total_time=7698909, total_pixels=32000748
15272018-07-08 10:05:55,219 total_time=7698909, total_pixels=32000748
15282018-07-08 10:05:55,321 total_time=7698909, total_pixels=32000748
15292018-07-08 10:05:55,422 total_time=7698909, total_pixels=32000748
15302018-07-08 10:05:55,524 total_time=7698909, total_pixels=32000748
15312018-07-08 10:05:55,624 total_time=7698909, total_pixels=32000748
15322018-07-08 10:05:55,724 total_time=7698909, total_pixels=32000748
15332018-07-08 10:05:55,826 total_time=7698909, total_pixels=32000748
15342018-07-08 10:05:55,927 total_time=7698909, total_pixels=32000748
15352018-07-08 10:05:56,026 total_time=7698909, total_pixels=32000748
15362018-07-08 10:05:56,128 total_time=7698909, total_pixels=32000748
15372018-07-08 10:05:56,226 total_time=7698909, total_pixels=32000748
15382018-07-08 10:05:56,326 total_time=7698909, total_pixels=32000748
15392018-07-08 10:05:56,428 total_time=7698909, total_pixels=32000748
15402018-07-08 10:05:56,528 total_time=7698909, total_pixels=32000748
15412018-07-08 10:05:56,629 total_time=7698909, total_pixels=32000748
15422018-07-08 10:05:56,775 total_time=7698909, total_pixels=32000748
15432018-07-08 10:05:56,869 total_time=7698909, total_pixels=32000748
15442018-07-08 10:05:56,966 total_time=7698909, total_pixels=32000748
15452018-07-08 10:05:57,072 total_time=7698909, total_pixels=32000748
15462018-07-08 10:05:57,167 total_time=7698909, total_pixels=32000748
15472018-07-08 10:05:57,271 total_time=7698909, total_pixels=32000748
15482018-07-08 10:05:57,329 packet decoding sequence 164 for window 1: 12x20 took 1.1ms
15492018-07-08 10:05:57,329 record_latency: took   36.8 ms round trip,   36.8 for echo,    1.0 for decoding of      240 pixels,       60 bytes sent over the network in   35.7 ms,   35.7 ms for echo
15502018-07-08 10:05:57,370 total_time=7700002, total_pixels=32000988
15512018-07-08 10:05:57,473 total_time=7700002, total_pixels=32000988
15522018-07-08 10:05:57,576 total_time=7700002, total_pixels=32000988
15532018-07-08 10:05:57,671 total_time=7700002, total_pixels=32000988
15542018-07-08 10:05:57,776 total_time=7700002, total_pixels=32000988
15552018-07-08 10:05:57,878 total_time=7700002, total_pixels=32000988
15562018-07-08 10:05:57,988 total_time=7700002, total_pixels=32000988
15572018-07-08 10:05:58,088 total_time=7700002, total_pixels=32000988
15582018-07-08 10:05:58,169 packet decoding sequence 165 for window 1: 363x20 took 2.6ms
15592018-07-08 10:05:58,170 record_latency: took  265.6 ms round trip,  265.6 for echo,    2.0 for decoding of     7260 pixels,       68 bytes sent over the network in  263.0 ms,  263.0 ms for echo
15602018-07-08 10:05:58,171 networksend_congestion_event(late-ack for sequence    165: late by  14ms, target latency=252 ((2, 0, 0, 250)), 26400, 2060) 1Kbps (average=391Kbps)
15612018-07-08 10:05:58,171 record_congestion_event(late-ack for sequence    165: late by  14ms, target latency=252 ((2, 0, 0, 250)), 26400, 1782)
15622018-07-08 10:05:58,171 may_recalculate(1, 7260) total 8220 pixels, scheduling recalculate work item
15632018-07-08 10:05:58,172 packet decoding sequence 166 for window 1: 12x20 took 3.6ms
15642018-07-08 10:05:58,173 record_latency: took  267.3 ms round trip,  266.9 for echo,    3.0 for decoding of      240 pixels,       56 bytes sent over the network in  263.7 ms,  263.3 ms for echo
15652018-07-08 10:05:58,174 calculate_batch_delay for wid=1 current batch delay=266, last update 3 seconds ago
15662018-07-08 10:05:58,174 networksend_congestion_event(late-ack for sequence    166: late by  16ms, target latency=252 ((2, 0, 0, 250)), 26600, 0) 391Kbps (average=391Kbps)
15672018-07-08 10:05:58,176 record_congestion_event(late-ack for sequence    166: late by  16ms, target latency=252 ((2, 0, 0, 250)), 26600, 400924)
15682018-07-08 10:05:58,177 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
15692018-07-08 10:05:58,178 update_batch_delay: client-decode-speed          : 0.95,0.00  {'avg': 14, 'recent': 13}
15702018-07-08 10:05:58,179 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 104, 'elapsed': 6}
15712018-07-08 10:05:58,180 update_batch_delay: client-latency               : 2.37,0.49  {'target': 6, 'weight_multiplier': 417, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 5633, 'div': 1000, 'avg': 98, 'recent': 227}
15722018-07-08 10:05:58,180 update_batch_delay: client-ping-latency          : 0.52,0.12  {'target': 6, 'weight_multiplier': 170, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 273, 'div': 1000, 'avg': 6, 'recent': 3}
15732018-07-08 10:05:58,180 update_batch_delay: server-ping-latency          : 0.52,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 269, 'div': 1000, 'avg': 5, 'recent': 2}
15742018-07-08 10:05:58,181 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}
15752018-07-08 10:05:58,181 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}
15762018-07-08 10:05:58,186 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}
15772018-07-08 10:05:58,186 update_batch_delay: congestion                   : 1.88,8.80  {}
15782018-07-08 10:05:58,186 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}
15792018-07-08 10:05:58,186 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
15802018-07-08 10:05:58,186 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
15812018-07-08 10:05:58,187 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
15822018-07-08 10:05:58,187 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
15832018-07-08 10:05:58,187 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
15842018-07-08 10:05:58,187 update_batch_delay: delay=372
15852018-07-08 10:05:58,198 total_time=7706227, total_pixels=32008488
15862018-07-08 10:05:58,292 total_time=7706227, total_pixels=32008488
15872018-07-08 10:05:58,392 total_time=7706227, total_pixels=32008488
15882018-07-08 10:05:58,492 total_time=7706227, total_pixels=32008488
15892018-07-08 10:05:58,542 packet decoding sequence 167 for window 1: 12x20 took 0.9ms
15902018-07-08 10:05:58,543 record_latency: took   27.2 ms round trip,   27.2 for echo,    0.0 for decoding of      240 pixels,       60 bytes sent over the network in   26.3 ms,   26.2 ms for echo
15912018-07-08 10:05:58,592 total_time=7707137, total_pixels=32008728
15922018-07-08 10:05:58,692 total_time=7707137, total_pixels=32008728
15932018-07-08 10:05:58,796 total_time=7707137, total_pixels=32008728
15942018-07-08 10:05:58,897 total_time=7707137, total_pixels=32008728
15952018-07-08 10:05:58,997 total_time=7707137, total_pixels=32008728
15962018-07-08 10:05:59,097 total_time=7707137, total_pixels=32008728
15972018-07-08 10:05:59,196 total_time=7707137, total_pixels=32008728
15982018-07-08 10:05:59,299 total_time=7707137, total_pixels=32008728
15992018-07-08 10:05:59,398 total_time=7707137, total_pixels=32008728
16002018-07-08 10:05:59,498 total_time=7707137, total_pixels=32008728
16012018-07-08 10:05:59,601 total_time=7707137, total_pixels=32008728
16022018-07-08 10:05:59,701 total_time=7707137, total_pixels=32008728
16032018-07-08 10:05:59,802 total_time=7707137, total_pixels=32008728
16042018-07-08 10:05:59,903 total_time=7707137, total_pixels=32008728
16052018-07-08 10:06:00,002 total_time=7707137, total_pixels=32008728
16062018-07-08 10:06:00,103 total_time=7707137, total_pixels=32008728
16072018-07-08 10:06:00,202 total_time=7707137, total_pixels=32008728
16082018-07-08 10:06:00,305 total_time=7707137, total_pixels=32008728
16092018-07-08 10:06:00,407 total_time=7707137, total_pixels=32008728
16102018-07-08 10:06:00,509 total_time=7707137, total_pixels=32008728
16112018-07-08 10:06:00,614 total_time=7707137, total_pixels=32008728
16122018-07-08 10:06:00,708 total_time=7707137, total_pixels=32008728
16132018-07-08 10:06:00,806 total_time=7707137, total_pixels=32008728
16142018-07-08 10:06:00,909 total_time=7707137, total_pixels=32008728
16152018-07-08 10:06:01,007 total_time=7707137, total_pixels=32008728
16162018-07-08 10:06:01,115 total_time=7707137, total_pixels=32008728
16172018-07-08 10:06:01,208 total_time=7707137, total_pixels=32008728
16182018-07-08 10:06:01,315 total_time=7707137, total_pixels=32008728
16192018-07-08 10:06:01,415 total_time=7707137, total_pixels=32008728
16202018-07-08 10:06:01,516 total_time=7707137, total_pixels=32008728
16212018-07-08 10:06:01,614 total_time=7707137, total_pixels=32008728
16222018-07-08 10:06:01,718 total_time=7707137, total_pixels=32008728
16232018-07-08 10:06:01,815 total_time=7707137, total_pixels=32008728
16242018-07-08 10:06:01,918 total_time=7707137, total_pixels=32008728
16252018-07-08 10:06:02,018 total_time=7707137, total_pixels=32008728
16262018-07-08 10:06:02,117 total_time=7707137, total_pixels=32008728
16272018-07-08 10:06:02,218 total_time=7707137, total_pixels=32008728
16282018-07-08 10:06:02,319 total_time=7707137, total_pixels=32008728
16292018-07-08 10:06:02,424 total_time=7707137, total_pixels=32008728
16302018-07-08 10:06:02,522 total_time=7707137, total_pixels=32008728
16312018-07-08 10:06:02,627 total_time=7707137, total_pixels=32008728
16322018-07-08 10:06:02,721 total_time=7707137, total_pixels=32008728
16332018-07-08 10:06:02,823 total_time=7707137, total_pixels=32008728
16342018-07-08 10:06:02,923 total_time=7707137, total_pixels=32008728
16352018-07-08 10:06:03,024 total_time=7707137, total_pixels=32008728
16362018-07-08 10:06:03,124 total_time=7707137, total_pixels=32008728
16372018-07-08 10:06:03,228 total_time=7707137, total_pixels=32008728
16382018-07-08 10:06:03,328 total_time=7695037, total_pixels=31334916
16392018-07-08 10:06:03,427 total_time=7695037, total_pixels=31334916
16402018-07-08 10:06:03,527 total_time=7690879, total_pixels=30998010
16412018-07-08 10:06:03,627 total_time=7690879, total_pixels=30998010
16422018-07-08 10:06:03,728 total_time=7690879, total_pixels=30998010
16432018-07-08 10:06:03,827 total_time=7690879, total_pixels=30998010
16442018-07-08 10:06:03,930 total_time=7690879, total_pixels=30998010
16452018-07-08 10:06:04,030 total_time=7690879, total_pixels=30998010
16462018-07-08 10:06:04,135 total_time=7690879, total_pixels=30998010
16472018-07-08 10:06:04,233 total_time=7690879, total_pixels=30998010
16482018-07-08 10:06:04,333 total_time=7690879, total_pixels=30998010
16492018-07-08 10:06:04,434 total_time=7690879, total_pixels=30998010
16502018-07-08 10:06:04,535 total_time=7690879, total_pixels=30998010
16512018-07-08 10:06:04,636 total_time=7690879, total_pixels=30998010
16522018-07-08 10:06:04,735 total_time=7690879, total_pixels=30998010
16532018-07-08 10:06:04,835 total_time=7687215, total_pixels=30983334
16542018-07-08 10:06:04,936 total_time=7683850, total_pixels=30982614
16552018-07-08 10:06:05,036 total_time=7679823, total_pixels=30981894
16562018-07-08 10:06:05,138 total_time=7676384, total_pixels=30981414
16572018-07-08 10:06:05,238 total_time=7676384, total_pixels=30981414
16582018-07-08 10:06:05,339 total_time=7676384, total_pixels=30981414
16592018-07-08 10:06:05,439 total_time=7676384, total_pixels=30981414
16602018-07-08 10:06:05,542 total_time=7673332, total_pixels=30981174
16612018-07-08 10:06:05,642 total_time=7668100, total_pixels=30980454
16622018-07-08 10:06:05,748 total_time=7662952, total_pixels=30979734
16632018-07-08 10:06:05,843 total_time=7660751, total_pixels=30979254
16642018-07-08 10:06:05,944 total_time=7653826, total_pixels=30978294
16652018-07-08 10:06:06,046 total_time=7651176, total_pixels=30977814
16662018-07-08 10:06:06,145 total_time=7647374, total_pixels=30977094
16672018-07-08 10:06:06,248 total_time=7647374, total_pixels=30977094
16682018-07-08 10:06:06,351 total_time=7647374, total_pixels=30977094
16692018-07-08 10:06:06,448 total_time=7644714, total_pixels=30976614
16702018-07-08 10:06:06,547 total_time=7639640, total_pixels=30975894
16712018-07-08 10:06:06,648 total_time=7639640, total_pixels=30975894
16722018-07-08 10:06:06,748 total_time=7639640, total_pixels=30975894
16732018-07-08 10:06:06,853 total_time=7639640, total_pixels=30975894
16742018-07-08 10:06:06,949 total_time=7639640, total_pixels=30975894
16752018-07-08 10:06:07,050 total_time=7639640, total_pixels=30975894
16762018-07-08 10:06:07,149 total_time=7639640, total_pixels=30975894
16772018-07-08 10:06:07,252 total_time=7639640, total_pixels=30975894
16782018-07-08 10:06:07,351 total_time=7639640, total_pixels=30975894
16792018-07-08 10:06:07,451 total_time=7639640, total_pixels=30975894
16802018-07-08 10:06:07,554 total_time=7639640, total_pixels=30975894
16812018-07-08 10:06:07,656 total_time=7639640, total_pixels=30975894
16822018-07-08 10:06:07,758 total_time=7639640, total_pixels=30975894
16832018-07-08 10:06:07,858 total_time=7639640, total_pixels=30975894
16842018-07-08 10:06:07,957 total_time=7639640, total_pixels=30975894
16852018-07-08 10:06:08,057 total_time=7639640, total_pixels=30975894
16862018-07-08 10:06:08,158 total_time=7639640, total_pixels=30975894
16872018-07-08 10:06:08,256 total_time=7639640, total_pixels=30975894
16882018-07-08 10:06:08,359 total_time=7639640, total_pixels=30975894
16892018-07-08 10:06:08,461 total_time=7639640, total_pixels=30975894
16902018-07-08 10:06:08,563 total_time=7631847, total_pixels=30814278
16912018-07-08 10:06:08,661 total_time=7626632, total_pixels=30477372
16922018-07-08 10:06:08,765 total_time=7626632, total_pixels=30477372
16932018-07-08 10:06:08,835 client @19.107 Warning: the opus sound sink has stopped
16942018-07-08 10:06:08,840 sound source stopping
16952018-07-08 10:06:08,849 xpra client 1 disconnected.
16962018-07-08 10:06:12,835 New unix-domain connection received on /run/user/1000/xpra/beelink-1
16972018-07-08 10:06:12,839 Handshake complete; enabling connection
16982018-07-08 10:06:12,841 get_client_bandwidth_limit(Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1))=0
16992018-07-08 10:06:12,845 server bandwidth-limit=0, client bandwidth-limit=0, value=0
17002018-07-08 10:06:12,847 Python2/GObject client version 2.3.2-r19729 64-bit
17012018-07-08 10:06:12,847  connected from 'beelink' as 'steved' - 'Steve Dee'
17022018-07-08 10:06:12,851 Shutting down in response to client request
17032018-07-08 10:06:12,852 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1):
17042018-07-08 10:06:12,852  server shutdown
17052018-07-08 10:06:12,854 xpra client 2 disconnected.
17062018-07-08 10:06:13,233 New unix-domain connection received on /run/user/1000/xpra/beelink-1
17072018-07-08 10:06:13,234 New unix-domain connection received on /home/steved/.xpra/beelink-1
17082018-07-08 10:06:13,361 stopping pulseaudio with pid 6156
17092018-07-08 10:06:13,901 killing xvfb with pid 6110
17102018-07-08 10:06:13,903 removing socket /run/user/1000/xpra/beelink-1
17112018-07-08 10:06:13,903 removing socket /home/steved/.xpra/beelink-1
1712(II) Server terminated successfully (0). Closing log file.