Ticket #1911: xpra-local-congestion.txt

2018-07-08 10:04:38,157 cannot access python uinput module:
2018-07-08 10:04:38,157  No module named uinput
_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
_XSERVTransMakeAllCOTSServerListeners: server already running
Failed to rename log file "/run/user/1000/xpra/Xorg.S6077.log" to "/run/user/1000/xpra/Xorg.S6077.log": No such file or directory

X.Org X Server 1.19.6
Release Date: 2017-12-20
X Protocol Version 11, Revision 0
Build Operating System: Linux 4.4.0-119-generic x86_64 Ubuntu
Current Operating System: Linux beelink 4.15.0-23-generic #25-Ubuntu SMP Wed May 23 18:02:16 UTC 2018 x86_64
Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-23-generic root=/dev/mapper/xubuntu--vg-root ro noresume
Build Date: 13 April 2018  08:07:36PM
xorg-server 2:1.19.6-1ubuntu4 (For technical support please see http://www.ubuntu.com/support) 
Current version of pixman: 0.34.0
Before reporting problems, check http://wiki.x.org
to make sure that you have the latest version.
Markers: (--) probed, (**) from config file, (==) default setting,
(++) from command line, (!!) notice, (II) informational,
(WW) warning, (EE) error, (NI) not implemented, (??) unknown.
(++) Log file: "/run/user/1000/xpra/Xorg.S6077.log", Time: Sun Jul  8 10:04:38 2018
(++) Using config file: "/etc/xpra/xorg.conf"
(==) Using system config directory "/usr/share/X11/xorg.conf.d"
2018-07-08 10:04:44,763 created unix domain socket: /run/user/1000/xpra/beelink-1
2018-07-08 10:04:44,764 created unix domain socket: /home/steved/.xpra/beelink-1
2018-07-08 10:04:44,764 cannot create group socket '/run/xpra/beelink-1'
2018-07-08 10:04:44,764  [Errno 13] Permission denied
2018-07-08 10:04:44,898 Warning: the python netifaces package is missing
2018-07-08 10:04:44,927 pointer device emulation using XTest
2018-07-08 10:04:47,208 serving html content from: /usr/share/xpra/www
2018-07-08 10:04:47,425 D-Bus notification forwarding is available
2018-07-08 10:04:47,677 Warning: webcam forwarding is disabled
2018-07-08 10:04:47,677  the virtual video directory '/sys/devices/virtual/video4linux' was not found
2018-07-08 10:04:47,678  make sure that the 'v4l2loopback' kernel module is installed and loaded
2018-07-08 10:04:47,678 found 0 virtual video devices for webcam forwarding
2018-07-08 10:04:47,849 pulseaudio server started with pid 6156
2018-07-08 10:04:47,849  private server socket path:
2018-07-08 10:04:47,850  '/run/user/1000/xpra/pulse-:1/pulse/native'
2018-07-08 10:04:50,146 GStreamer version 1.14.1 for Python 2.7.15 64-bit
2018-07-08 10:04:50,282 Warning: failed to load the mdns avahi publisher:
2018-07-08 10:04:50,283  No module named avahi
2018-07-08 10:04:50,283  either fix your installation or use the 'mdns=no' option
2018-07-08 10:04:50,283 xpra X11 version 2.3.2-r19729 64-bit
2018-07-08 10:04:50,284  uid=1000 (steved), gid=1000 (steved)
2018-07-08 10:04:50,284  running with pid 6109 on Linux Ubuntu 18.04 bionic
2018-07-08 10:04:50,284  connected to X11 display :1 with 24 bit colors
2018-07-08 10:04:50,533 xpra is ready.
2018-07-08 10:04:54,048 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-08 10:04:54,058 Handshake complete; enabling connection
2018-07-08 10:04:54,063 get_client_bandwidth_limit(Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1))=0
2018-07-08 10:04:54,085  automatic picture encoding enabled, also available:
2018-07-08 10:04:54,085   png, png/P, png/L, webp, rgb24, rgb32, jpeg
2018-07-08 10:04:54,087  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.6j1Q3G.mmap
2018-07-08 10:04:54,088 server bandwidth-limit=0, client bandwidth-limit=0, value=0
2018-07-08 10:04:54,088 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
2018-07-08 10:04:54,089  connected from 'beelink' as 'steved' - 'Steve Dee'
2018-07-08 10:04:54,093  client root window size is 2880x900 with 1 display:
2018-07-08 10:04:54,094   :0.0 (762x238 mm - DPI: 96x96)
2018-07-08 10:04:54,094     monitor 1 1440x900 (160x90 mm - DPI: 228x254)
2018-07-08 10:04:54,094     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
2018-07-08 10:04:54,346 server virtual display now set to 2880x960 (best match for 2880x900)
2018-07-08 10:04:54,357 setting key repeat rate from client: 500ms delay / 50ms interval
2018-07-08 10:04:54,360 setting keymap: rules=evdev, model=pc105, layout=gb
2018-07-08 10:04:54,401 setting keyboard layout to 'gb'
2018-07-08 10:04:54,497 DPI set to 34 x 22 (wanted 96 x 96)
2018-07-08 10:04:54,498  you may experience scaling problems, such as huge or small fonts, etc
2018-07-08 10:04:54,498  to fix this issue, try the dpi switch, or use a patched Xorg dummy driver
2018-07-08 10:04:54,519 New unix-domain connection received on /home/steved/.xpra/beelink-1
2018-07-08 10:04:54,521 client @04.790 Xpra X11 server version 2.3.2-r19729 64-bit
2018-07-08 10:04:54,522 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-08 10:04:54,524 client @04.790  running on Linux Ubuntu 18.04 bionic
2018-07-08 10:04:54,543 client @04.797 Attached to :1
2018-07-08 10:04:54,544 client @04.797  (press Control-C to detach)
2018-07-08 10:04:54,640 client @04.911 server does not support xi input devices
2018-07-08 10:04:54,640 client @04.911  server uses: xtest
2018-07-08 10:04:56,426 using pulseaudio device:
2018-07-08 10:04:56,427  'Monitor of Xpra Speaker'
2018-07-08 10:04:58,809 sound source using 'opus' audio codec
2018-07-08 10:05:02,638 printer forwarding enabled using postscript and pdf
2018-07-08 10:05:02,643 total_time=0, total_pixels=0
2018-07-08 10:05:02,675 3.7GB of system memory
Failed to connect to session manager: Failed to connect to the session manager: SESSION_MANAGER environment variable not defined
2018-07-08 10:05:03,257 packet decoding sequence 1 for window 1: 734x459 took 8.3ms
2018-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
2018-07-08 10:05:03,257 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:03,258 calculate_batch_delay for wid=1 current batch delay=29, last update 422414 seconds ago
2018-07-08 10:05:03,259 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-08 10:05:03,259 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 25, 'recent': 25}
2018-07-08 10:05:03,260 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 24}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:03,261 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:03,261 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:03,261 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:03,261 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:03,261 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:03,262 update_batch_delay: delay=20
2018-07-08 10:05:03,276 packet decoding sequence 2 for window 1: 734x459 took 3.8ms
2018-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
2018-07-08 10:05:03,276 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:03,465 packet decoding sequence 3 for window 1: 734x459 took 4.2ms
2018-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
2018-07-08 10:05:04,259 calculate_batch_delay for wid=1 current batch delay=20, last update 1 seconds ago
2018-07-08 10:05:04,260 calculate_batch_delay for wid=1, skipping - only 2 events since the last update
2018-07-08 10:05:04,806 packet decoding sequence 4 for window 1: 12x18 took 1.9ms
2018-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
2018-07-08 10:05:04,809 packet decoding sequence 5 for window 1: 723x20 took 1.8ms
2018-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
2018-07-08 10:05:04,810 may_recalculate(1, 14460) total 14676 pixels, scheduling recalculate work item
2018-07-08 10:05:04,859 packet decoding sequence 6 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:04,885 packet decoding sequence 7 for window 1: 12x20 took 1.0ms
2018-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
2018-07-08 10:05:04,925 packet decoding sequence 8 for window 1: 12x20 took 1.1ms
2018-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
2018-07-08 10:05:04,947 packet decoding sequence 9 for window 1: 12x20 took 1.9ms
2018-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
2018-07-08 10:05:04,977 packet decoding sequence 10 for window 1: 12x20 took 1.0ms
2018-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
2018-07-08 10:05:05,017 packet decoding sequence 11 for window 1: 12x20 took 1.1ms
2018-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
2018-07-08 10:05:05,057 packet decoding sequence 12 for window 1: 12x20 took 2.2ms
2018-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
2018-07-08 10:05:05,086 packet decoding sequence 13 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:05,260 calculate_batch_delay for wid=1 current batch delay=20, last update 2 seconds ago
2018-07-08 10:05:05,261 calculate_batch_delay for wid=1, 690408 pixels updated since the last update
2018-07-08 10:05:05,261 calculate_batch_delay for wid=1, skipping - only 12 bytes sent since the last update
2018-07-08 10:05:05,517 packet decoding sequence 14 for window 1: 12x20 took 3.1ms
2018-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
2018-07-08 10:05:05,568 packet decoding sequence 15 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:05,585 packet decoding sequence 16 for window 1: 12x20 took 1.4ms
2018-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
2018-07-08 10:05:05,634 packet decoding sequence 17 for window 1: 12x20 took 2.5ms
2018-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
2018-07-08 10:05:05,654 packet decoding sequence 18 for window 1: 12x20 took 1.0ms
2018-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
2018-07-08 10:05:05,709 packet decoding sequence 19 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:05,722 packet decoding sequence 20 for window 1: 12x20 took 3.0ms
2018-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
2018-07-08 10:05:05,775 packet decoding sequence 21 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:05,788 packet decoding sequence 22 for window 1: 12x20 took 1.0ms
2018-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
2018-07-08 10:05:05,789 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
2018-07-08 10:05:05,851 packet decoding sequence 23 for window 1: 12x20 took 2.6ms
2018-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
2018-07-08 10:05:05,862 packet decoding sequence 24 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:05,919 packet decoding sequence 25 for window 1: 12x20 took 2.0ms
2018-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
2018-07-08 10:05:05,942 packet decoding sequence 26 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:05,987 packet decoding sequence 27 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:06,003 packet decoding sequence 28 for window 1: 12x20 took 1.4ms
2018-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
2018-07-08 10:05:06,052 packet decoding sequence 29 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:06,068 packet decoding sequence 30 for window 1: 12x20 took 1.4ms
2018-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
2018-07-08 10:05:06,102 packet decoding sequence 31 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:06,260 calculate_batch_delay for wid=1 current batch delay=20, last update 3 seconds ago
2018-07-08 10:05:06,261 calculate_batch_delay for wid=1, 694728 pixels updated since the last update
2018-07-08 10:05:06,261 calculate_batch_delay for wid=1, skipping - only 30 bytes sent since the last update
2018-07-08 10:05:06,393 packet decoding sequence 32 for window 1: 12x20 took 1.5ms
2018-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
2018-07-08 10:05:06,441 packet decoding sequence 33 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:06,464 packet decoding sequence 34 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:06,497 packet decoding sequence 35 for window 1: 12x20 took 2.5ms
2018-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
2018-07-08 10:05:06,524 packet decoding sequence 36 for window 1: 12x20 took 1.3ms
2018-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
2018-07-08 10:05:08,501 packet decoding sequence 1 for window 2: 312x259 took 5.6ms
2018-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
2018-07-08 10:05:08,502 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:08,502 calculate_batch_delay for wid=2 current batch delay=16, last update 422419 seconds ago
2018-07-08 10:05:08,503 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-08 10:05:08,503 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 73, 'recent': 73}
2018-07-08 10:05:08,504 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 126, 'elapsed': 127}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:08,505 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:08,505 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:08,506 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:08,506 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:08,507 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:08,507 update_batch_delay: delay=11
2018-07-08 10:05:08,514 packet decoding sequence 2 for window 2: 312x259 took 2.2ms
2018-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
2018-07-08 10:05:08,515 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:08,597 packet decoding sequence 37 for window 1: 734x459 took 5.2ms
2018-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
2018-07-08 10:05:08,597 may_recalculate(1, 336906) total 338106 pixels, scheduling recalculate work item
2018-07-08 10:05:08,974 packet decoding sequence 3 for window 2: 312x259 took 2.0ms
2018-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
2018-07-08 10:05:09,004 packet decoding sequence 4 for window 2: 312x259 took 2.3ms
2018-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
2018-07-08 10:05:09,011 packet decoding sequence 5 for window 2: 312x259 took 1.9ms
2018-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
2018-07-08 10:05:09,014 packet decoding sequence 6 for window 2: 312x259 took 3.8ms
2018-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
2018-07-08 10:05:09,073 packet decoding sequence 7 for window 2: 312x259 took 2.0ms
2018-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
2018-07-08 10:05:09,161 packet decoding sequence 8 for window 2: 312x259 took 2.2ms
2018-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
2018-07-08 10:05:09,188 packet decoding sequence 9 for window 2: 312x259 took 1.6ms
2018-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
2018-07-08 10:05:09,259 packet decoding sequence 10 for window 2: 312x259 took 1.9ms
2018-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
2018-07-08 10:05:09,379 packet decoding sequence 11 for window 2: 312x259 took 1.9ms
2018-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
2018-07-08 10:05:09,503 calculate_batch_delay for wid=1 current batch delay=20, last update 6 seconds ago
2018-07-08 10:05:09,503 calculate_batch_delay for wid=1, 1032834 pixels updated since the last update
2018-07-08 10:05:09,504 calculate_batch_delay for wid=1, skipping - only 36 bytes sent since the last update
2018-07-08 10:05:09,504 calculate_batch_delay for wid=2 current batch delay=11, last update 1 seconds ago
2018-07-08 10:05:09,505 calculate_batch_delay for wid=2, 808080 pixels updated since the last update
2018-07-08 10:05:09,505 calculate_batch_delay for wid=2, skipping - only 10 bytes sent since the last update
2018-07-08 10:05:09,520 packet decoding sequence 12 for window 2: 312x259 took 1.5ms
2018-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
2018-07-08 10:05:09,521 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:09,663 packet decoding sequence 13 for window 2: 312x259 took 3.6ms
2018-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
2018-07-08 10:05:09,844 packet decoding sequence 14 for window 2: 312x259 took 2.1ms
2018-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
2018-07-08 10:05:10,503 calculate_batch_delay for wid=2 current batch delay=11, last update 2 seconds ago
2018-07-08 10:05:10,503 calculate_batch_delay for wid=2, 1050504 pixels updated since the last update
2018-07-08 10:05:10,504 calculate_batch_delay for wid=2, skipping - only 13 bytes sent since the last update
2018-07-08 10:05:10,769 packet decoding sequence 15 for window 2: 312x259 took 2.3ms
2018-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
2018-07-08 10:05:10,770 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:10,784 packet decoding sequence 16 for window 2: 312x259 took 2.2ms
2018-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
2018-07-08 10:05:10,801 packet decoding sequence 17 for window 2: 312x259 took 1.6ms
2018-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
2018-07-08 10:05:10,913 packet decoding sequence 18 for window 2: 312x259 took 4.0ms
2018-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
2018-07-08 10:05:10,970 packet decoding sequence 19 for window 2: 312x259 took 1.9ms
2018-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
2018-07-08 10:05:11,024 packet decoding sequence 20 for window 2: 312x259 took 2.3ms
2018-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
2018-07-08 10:05:11,044 packet decoding sequence 21 for window 2: 312x25 took 1.8ms
2018-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
2018-07-08 10:05:11,062 packet decoding sequence 22 for window 2: 312x259 took 2.3ms
2018-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
2018-07-08 10:05:11,125 packet decoding sequence 23 for window 2: 312x25 took 1.3ms
2018-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
2018-07-08 10:05:11,149 packet decoding sequence 24 for window 2: 312x259 took 4.0ms
2018-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
2018-07-08 10:05:11,325 packet decoding sequence 1 for window 3: 130x54 took 2.6ms
2018-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
2018-07-08 10:05:11,325 may_recalculate(3, 7020) total 7020 pixels, scheduling recalculate work item
2018-07-08 10:05:11,327 packet decoding sequence 2 for window 3: 130x54 took 4.6ms
2018-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
2018-07-08 10:05:11,394 packet decoding sequence 38 for window 1: 279x25 took 2.1ms
2018-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
2018-07-08 10:05:11,394 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
2018-07-08 10:05:11,503 calculate_batch_delay for wid=1 current batch delay=20, last update 8 seconds ago
2018-07-08 10:05:11,503 calculate_batch_delay for wid=1, 1039809 pixels updated since the last update
2018-07-08 10:05:11,504 calculate_batch_delay for wid=1, skipping - only 37 bytes sent since the last update
2018-07-08 10:05:11,504 calculate_batch_delay for wid=3 current batch delay=7, last update 422422 seconds ago
2018-07-08 10:05:11,505 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
2018-07-08 10:05:11,505 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 490, 'recent': 491}
2018-07-08 10:05:11,505 update_batch_delay: damage-rate                  : 0.97,0.18  {'max_latency': 100, 'elapsed': 233}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:11,506 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:11,506 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:11,506 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:11,507 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:11,507 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:11,507 update_batch_delay: delay=7
2018-07-08 10:05:11,793 packet decoding sequence 1 for window 4: 462x131 took 2.9ms
2018-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
2018-07-08 10:05:11,794 may_recalculate(4, 60522) total 60522 pixels, scheduling recalculate work item
2018-07-08 10:05:11,885 packet decoding sequence 39 for window 1: 279x25 took 10.7ms
2018-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
2018-07-08 10:05:11,886 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
2018-07-08 10:05:11,935 packet decoding sequence 1 for window 5: 312x259 took 3.1ms
2018-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
2018-07-08 10:05:11,936 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:12,009 packet decoding sequence 40 for window 1: 234x25 took 1.5ms
2018-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
2018-07-08 10:05:12,057 packet decoding sequence 1 for window 6: 244x180 took 1.7ms
2018-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
2018-07-08 10:05:12,058 may_recalculate(6, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-08 10:05:12,157 packet decoding sequence 41 for window 1: 190x25 took 1.4ms
2018-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
2018-07-08 10:05:12,160 packet decoding sequence 1 for window 7: 237x156 took 4.4ms
2018-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
2018-07-08 10:05:12,161 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:12,213 packet decoding sequence 42 for window 1: 121x25 took 1.7ms
2018-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
2018-07-08 10:05:12,504 calculate_batch_delay for wid=1 current batch delay=20, last update 9 seconds ago
2018-07-08 10:05:12,504 calculate_batch_delay for wid=1, 1060409 pixels updated since the last update
2018-07-08 10:05:12,505 calculate_batch_delay for wid=1, skipping - only 41 bytes sent since the last update
2018-07-08 10:05:12,505 calculate_batch_delay for wid=7 current batch delay=22, last update 422423 seconds ago
2018-07-08 10:05:12,506 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
2018-07-08 10:05:12,507 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 124, 'recent': 124}
2018-07-08 10:05:12,507 update_batch_delay: damage-rate                  : 0.80,0.44  {'max_latency': 100, 'elapsed': 397}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:12,510 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:12,510 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:12,510 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:12,511 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:12,511 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:12,511 update_batch_delay: delay=16
2018-07-08 10:05:13,215 packet decoding sequence 2 for window 7: 237x156 took 1.8ms
2018-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
2018-07-08 10:05:13,216 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:13,321 packet decoding sequence 3 for window 7: 237x156 took 1.7ms
2018-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
2018-07-08 10:05:13,480 packet decoding sequence 4 for window 7: 237x156 took 1.3ms
2018-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
2018-07-08 10:05:13,504 calculate_batch_delay for wid=7 current batch delay=16, last update 0 seconds ago
2018-07-08 10:05:13,505 calculate_batch_delay for wid=7, skipping - only 3 events since the last update
2018-07-08 10:05:13,533 packet decoding sequence 5 for window 7: 237x156 took 1.8ms
2018-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
2018-07-08 10:05:13,534 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:13,565 packet decoding sequence 6 for window 7: 237x156 took 1.9ms
2018-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
2018-07-08 10:05:13,823 packet decoding sequence 7 for window 7: 237x156 took 1.5ms
2018-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
2018-07-08 10:05:14,115 packet decoding sequence 8 for window 7: 237x156 took 2.0ms
2018-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
2018-07-08 10:05:14,202 packet decoding sequence 9 for window 7: 237x156 took 2.1ms
2018-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
2018-07-08 10:05:14,504 calculate_batch_delay for wid=7 current batch delay=16, last update 1 seconds ago
2018-07-08 10:05:14,505 calculate_batch_delay for wid=7, 295776 pixels updated since the last update
2018-07-08 10:05:14,505 calculate_batch_delay for wid=7, skipping - only 8 bytes sent since the last update
2018-07-08 10:05:14,625 packet decoding sequence 10 for window 7: 237x156 took 1.2ms
2018-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
2018-07-08 10:05:14,626 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:14,737 packet decoding sequence 11 for window 7: 237x156 took 3.3ms
2018-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
2018-07-08 10:05:14,804 packet decoding sequence 12 for window 7: 237x156 took 1.5ms
2018-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
2018-07-08 10:05:14,995 packet decoding sequence 1 for window 8: 248x181 took 2.3ms
2018-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
2018-07-08 10:05:14,995 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-08 10:05:15,083 packet decoding sequence 43 for window 1: 76x25 took 1.3ms
2018-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
2018-07-08 10:05:15,506 calculate_batch_delay for wid=8 current batch delay=22, last update 422426 seconds ago
2018-07-08 10:05:15,507 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-08 10:05:15,508 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 53, 'recent': 53}
2018-07-08 10:05:15,508 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 1}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:15,510 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:15,510 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:15,511 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:15,512 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:15,512 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:15,513 update_batch_delay: delay=16
2018-07-08 10:05:15,537 packet decoding sequence 2 for window 8: 248x181 took 2.4ms
2018-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
2018-07-08 10:05:15,538 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-08 10:05:15,584 packet decoding sequence 3 for window 8: 248x181 took 1.2ms
2018-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
2018-07-08 10:05:15,636 packet decoding sequence 4 for window 8: 248x181 took 3.1ms
2018-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
2018-07-08 10:05:15,695 packet decoding sequence 5 for window 8: 248x181 took 4.2ms
2018-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
2018-07-08 10:05:15,749 packet decoding sequence 6 for window 8: 248x181 took 1.7ms
2018-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
2018-07-08 10:05:15,823 packet decoding sequence 7 for window 8: 248x181 took 1.6ms
2018-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
2018-07-08 10:05:16,173 packet decoding sequence 8 for window 8: 248x181 took 2.0ms
2018-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
2018-07-08 10:05:16,208 packet decoding sequence 9 for window 8: 248x181 took 1.6ms
2018-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
2018-07-08 10:05:16,281 packet decoding sequence 10 for window 8: 248x181 took 1.9ms
2018-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
2018-07-08 10:05:16,507 calculate_batch_delay for wid=8 current batch delay=16, last update 1 seconds ago
2018-07-08 10:05:16,508 calculate_batch_delay for wid=8, 403992 pixels updated since the last update
2018-07-08 10:05:16,508 calculate_batch_delay for wid=8, skipping - only 9 bytes sent since the last update
2018-07-08 10:05:16,911 packet decoding sequence 44 for window 1: 734x459 took 5.0ms
2018-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
2018-07-08 10:05:16,912 may_recalculate(1, 336906) total 338806 pixels, scheduling recalculate work item
2018-07-08 10:05:16,958 packet decoding sequence 45 for window 1: 12x20 took 1.9ms
2018-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
2018-07-08 10:05:17,007 packet decoding sequence 46 for window 1: 12x20 took 1.1ms
2018-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
2018-07-08 10:05:17,038 packet decoding sequence 47 for window 1: 12x20 took 1.0ms
2018-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
2018-07-08 10:05:17,074 packet decoding sequence 48 for window 1: 12x20 took 1.6ms
2018-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
2018-07-08 10:05:17,508 calculate_batch_delay for wid=1 current batch delay=20, last update 14 seconds ago
2018-07-08 10:05:17,508 calculate_batch_delay for wid=1, 1400175 pixels updated since the last update
2018-07-08 10:05:17,509 calculate_batch_delay for wid=1, skipping - only 47 bytes sent since the last update
2018-07-08 10:05:18,564 packet decoding sequence 49 for window 1: 734x459 took 4.7ms
2018-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
2018-07-08 10:05:18,564 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:18,565 calculate_batch_delay for wid=1 current batch delay=20, last update 15 seconds ago
2018-07-08 10:05:18,565 calculate_batch_delay for wid=1, 1737081 pixels updated since the last update
2018-07-08 10:05:18,566 calculate_batch_delay for wid=1, skipping - only 48 bytes sent since the last update
2018-07-08 10:05:22,426 total_time=228311, total_pixels=5040005
2018-07-08 10:05:22,603 packet decoding sequence 50 for window 1: 263x17 took 4.1ms
2018-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
2018-07-08 10:05:22,604 may_recalculate(1, 4471) total 4471 pixels, scheduling recalculate work item
2018-07-08 10:05:22,605 calculate_batch_delay for wid=1 current batch delay=20, last update 19 seconds ago
2018-07-08 10:05:22,606 calculate_batch_delay for wid=1, 1741552 pixels updated since the last update
2018-07-08 10:05:22,606 calculate_batch_delay for wid=1, skipping - only 49 bytes sent since the last update
2018-07-08 10:05:22,641 packet decoding sequence 51 for window 1: 12x20 took 1.7ms
2018-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
2018-07-08 10:05:23,307 packet decoding sequence 52 for window 1: 12x20 took 1.4ms
2018-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
2018-07-08 10:05:23,343 packet decoding sequence 53 for window 1: 12x20 took 0.9ms
2018-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
2018-07-08 10:05:23,856 total_time=236311, total_pixels=5045196
2018-07-08 10:05:23,956 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,055 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,156 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,255 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,355 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,458 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,558 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,658 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,760 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,861 total_time=236311, total_pixels=5045196
2018-07-08 10:05:24,963 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,065 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,165 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,268 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,368 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,468 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,568 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,668 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,766 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,866 total_time=236311, total_pixels=5045196
2018-07-08 10:05:25,969 total_time=236311, total_pixels=5045196
2018-07-08 10:05:26,070 total_time=236311, total_pixels=5045196
2018-07-08 10:05:26,170 total_time=236311, total_pixels=5045196
2018-07-08 10:05:26,199 packet decoding sequence 54 for window 1: 12x20 took 1.1ms
2018-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
2018-07-08 10:05:26,200 packet decoding sequence 55 for window 1: 12x20 took 2.8ms
2018-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
2018-07-08 10:05:26,246 packet decoding sequence 56 for window 1: 12x20 took 0.9ms
2018-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
2018-07-08 10:05:26,276 packet decoding sequence 57 for window 1: 12x20 took 1.4ms
2018-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
2018-07-08 10:05:26,281 total_time=242576, total_pixels=5046156
2018-07-08 10:05:26,304 packet decoding sequence 58 for window 1: 12x20 took 0.9ms
2018-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
2018-07-08 10:05:26,330 packet decoding sequence 59 for window 1: 12x20 took 1.5ms
2018-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
2018-07-08 10:05:26,332 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
2018-07-08 10:05:26,333 calculate_batch_delay for wid=1 current batch delay=20, last update 23 seconds ago
2018-07-08 10:05:26,334 calculate_batch_delay for wid=1, 1743712 pixels updated since the last update
2018-07-08 10:05:26,334 calculate_batch_delay for wid=1, skipping - only 58 bytes sent since the last update
2018-07-08 10:05:26,374 total_time=245007, total_pixels=5046636
2018-07-08 10:05:26,478 total_time=245007, total_pixels=5046636
2018-07-08 10:05:26,574 total_time=245007, total_pixels=5046636
2018-07-08 10:05:26,673 total_time=245007, total_pixels=5046636
2018-07-08 10:05:26,774 total_time=245007, total_pixels=5046636
2018-07-08 10:05:26,872 total_time=245007, total_pixels=5046636
2018-07-08 10:05:26,990 total_time=245007, total_pixels=5046636
2018-07-08 10:05:27,007 packet decoding sequence 1 for window 9: 462x131 took 2.5ms
2018-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
2018-07-08 10:05:27,009 may_recalculate(9, 60522) total 60522 pixels, scheduling recalculate work item
2018-07-08 10:05:27,012 packet decoding sequence 2 for window 9: 462x131 took 9.4ms
2018-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
2018-07-08 10:05:27,037 packet decoding sequence 60 for window 1: 734x459 took 5.6ms
2018-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
2018-07-08 10:05:27,037 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:27,085 total_time=262427, total_pixels=5504586
2018-07-08 10:05:27,215 total_time=262427, total_pixels=5504586
2018-07-08 10:05:27,245 packet decoding sequence 1 for window 10: 312x259 took 2.6ms
2018-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
2018-07-08 10:05:27,246 may_recalculate(10, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:27,250 packet decoding sequence 2 for window 10: 312x259 took 7.0ms
2018-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
2018-07-08 10:05:27,257 packet decoding sequence 61 for window 1: 234x25 took 5.2ms
2018-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
2018-07-08 10:05:27,320 total_time=277222, total_pixels=5672052
2018-07-08 10:05:27,334 calculate_batch_delay for wid=1 current batch delay=20, last update 24 seconds ago
2018-07-08 10:05:27,335 calculate_batch_delay for wid=1, 2086468 pixels updated since the last update
2018-07-08 10:05:27,335 calculate_batch_delay for wid=1, skipping - only 60 bytes sent since the last update
2018-07-08 10:05:27,336 calculate_batch_delay for wid=10 current batch delay=16, last update 422438 seconds ago
2018-07-08 10:05:27,337 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-08 10:05:27,338 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 49, 'recent': 49}
2018-07-08 10:05:27,338 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 196}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:27,340 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:27,340 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:27,340 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:27,340 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:27,341 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:27,341 update_batch_delay: delay=12
2018-07-08 10:05:27,423 total_time=277222, total_pixels=5672052
2018-07-08 10:05:27,492 packet decoding sequence 3 for window 10: 312x259 took 2.2ms
2018-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
2018-07-08 10:05:27,493 may_recalculate(10, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:27,526 total_time=279373, total_pixels=5752860
2018-07-08 10:05:27,552 packet decoding sequence 4 for window 10: 312x259 took 2.1ms
2018-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
2018-07-08 10:05:27,578 packet decoding sequence 5 for window 10: 312x259 took 3.8ms
2018-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
2018-07-08 10:05:27,621 total_time=285290, total_pixels=5914476
2018-07-08 10:05:27,672 packet decoding sequence 6 for window 10: 312x259 took 2.1ms
2018-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
2018-07-08 10:05:27,692 packet decoding sequence 7 for window 10: 312x259 took 1.8ms
2018-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
2018-07-08 10:05:27,720 total_time=289226, total_pixels=6076092
2018-07-08 10:05:27,757 packet decoding sequence 8 for window 10: 312x259 took 3.0ms
2018-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
2018-07-08 10:05:27,794 packet decoding sequence 9 for window 10: 312x259 took 1.6ms
2018-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
2018-07-08 10:05:27,821 total_time=293827, total_pixels=6237708
2018-07-08 10:05:27,934 total_time=293827, total_pixels=6237708
2018-07-08 10:05:27,952 packet decoding sequence 10 for window 10: 312x259 took 1.6ms
2018-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
2018-07-08 10:05:27,962 packet decoding sequence 11 for window 10: 312x259 took 2.6ms
2018-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
2018-07-08 10:05:27,971 packet decoding sequence 12 for window 10: 312x25 took 1.6ms
2018-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
2018-07-08 10:05:27,998 packet decoding sequence 13 for window 10: 312x259 took 2.1ms
2018-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
2018-07-08 10:05:28,023 total_time=301672, total_pixels=6487932
2018-07-08 10:05:28,058 packet decoding sequence 14 for window 10: 312x259 took 1.6ms
2018-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
2018-07-08 10:05:28,101 packet decoding sequence 15 for window 10: 312x259 took 2.0ms
2018-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
2018-07-08 10:05:28,129 total_time=305245, total_pixels=6649548
2018-07-08 10:05:28,156 packet decoding sequence 16 for window 10: 312x259 took 2.0ms
2018-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
2018-07-08 10:05:28,230 total_time=307196, total_pixels=6730356
2018-07-08 10:05:28,351 total_time=307196, total_pixels=6730356
2018-07-08 10:05:28,410 packet decoding sequence 1 for window 11: 244x180 took 1.9ms
2018-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
2018-07-08 10:05:28,410 may_recalculate(11, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-08 10:05:28,427 total_time=309108, total_pixels=6774276
2018-07-08 10:05:28,484 packet decoding sequence 62 for window 1: 190x25 took 2.2ms
2018-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
2018-07-08 10:05:28,484 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
2018-07-08 10:05:28,540 total_time=311275, total_pixels=6779026
2018-07-08 10:05:28,569 packet decoding sequence 1 for window 12: 237x156 took 1.7ms
2018-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
2018-07-08 10:05:28,569 may_recalculate(12, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:28,573 packet decoding sequence 2 for window 12: 237x156 took 5.3ms
2018-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
2018-07-08 10:05:28,587 packet decoding sequence 63 for window 1: 121x25 took 1.3ms
2018-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
2018-07-08 10:05:28,639 total_time=319606, total_pixels=6855995
2018-07-08 10:05:28,742 total_time=319606, total_pixels=6855995
2018-07-08 10:05:28,841 total_time=319606, total_pixels=6855995
2018-07-08 10:05:28,947 total_time=319606, total_pixels=6855995
2018-07-08 10:05:29,045 total_time=319606, total_pixels=6855995
2018-07-08 10:05:29,078 packet decoding sequence 3 for window 12: 237x156 took 3.7ms
2018-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
2018-07-08 10:05:29,148 total_time=323283, total_pixels=6892967
2018-07-08 10:05:29,179 packet decoding sequence 4 for window 12: 237x156 took 1.9ms
2018-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
2018-07-08 10:05:29,247 total_time=325170, total_pixels=6929939
2018-07-08 10:05:29,316 packet decoding sequence 5 for window 12: 237x156 took 2.7ms
2018-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
2018-07-08 10:05:29,341 calculate_batch_delay for wid=1 current batch delay=20, last update 26 seconds ago
2018-07-08 10:05:29,342 calculate_batch_delay for wid=1, 2094243 pixels updated since the last update
2018-07-08 10:05:29,342 calculate_batch_delay for wid=1, skipping - only 62 bytes sent since the last update
2018-07-08 10:05:29,343 calculate_batch_delay for wid=12 current batch delay=11, last update 422440 seconds ago
2018-07-08 10:05:29,344 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 41}
2018-07-08 10:05:29,345 update_batch_delay: client-decode-speed          : 0.99,0.00  {'avg': 72, 'recent': 71}
2018-07-08 10:05:29,345 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 143, 'elapsed': 164}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:29,347 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:29,347 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:29,347 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:29,347 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:29,347 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:29,348 update_batch_delay: delay=8
2018-07-08 10:05:29,358 total_time=327874, total_pixels=6966911
2018-07-08 10:05:29,380 packet decoding sequence 6 for window 12: 237x156 took 2.2ms
2018-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
2018-07-08 10:05:29,381 may_recalculate(12, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:29,448 total_time=330073, total_pixels=7003883
2018-07-08 10:05:29,548 total_time=330073, total_pixels=7003883
2018-07-08 10:05:29,656 total_time=330073, total_pixels=7003883
2018-07-08 10:05:29,679 packet decoding sequence 7 for window 12: 237x156 took 1.3ms
2018-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
2018-07-08 10:05:29,682 packet decoding sequence 8 for window 12: 237x156 took 3.9ms
2018-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
2018-07-08 10:05:29,686 packet decoding sequence 9 for window 12: 237x156 took 1.1ms
2018-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
2018-07-08 10:05:29,748 total_time=336376, total_pixels=7114799
2018-07-08 10:05:29,863 total_time=336376, total_pixels=7114799
2018-07-08 10:05:29,956 total_time=336376, total_pixels=7114799
2018-07-08 10:05:29,964 packet decoding sequence 1 for window 13: 312x259 took 1.9ms
2018-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
2018-07-08 10:05:29,965 may_recalculate(13, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-08 10:05:29,989 packet decoding sequence 64 for window 1: 190x25 took 1.1ms
2018-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
2018-07-08 10:05:29,990 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
2018-07-08 10:05:30,053 total_time=339377, total_pixels=7200357
2018-07-08 10:05:30,164 total_time=339377, total_pixels=7200357
2018-07-08 10:05:30,265 packet decoding sequence 65 for window 1: 234x25 took 4.8ms
2018-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
2018-07-08 10:05:30,284 total_time=347992, total_pixels=7266729
2018-07-08 10:05:30,340 calculate_batch_delay for wid=1 current batch delay=20, last update 27 seconds ago
2018-07-08 10:05:30,341 calculate_batch_delay for wid=1, 2104843 pixels updated since the last update
2018-07-08 10:05:30,341 calculate_batch_delay for wid=1, skipping - only 64 bytes sent since the last update
2018-07-08 10:05:30,381 total_time=347992, total_pixels=7266729
2018-07-08 10:05:30,423 packet decoding sequence 1 for window 15: 130x54 took 118.5ms
2018-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
2018-07-08 10:05:30,424 may_recalculate(15, 7020) total 7020 pixels, scheduling recalculate work item
2018-07-08 10:05:30,427 packet decoding sequence 2 for window 15: 130x54 took 121.2ms
2018-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
2018-07-08 10:05:30,432 packet decoding sequence 66 for window 1: 279x25 took 2.4ms
2018-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
2018-07-08 10:05:30,432 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
2018-07-08 10:05:30,435 packet decoding sequence 3 for window 15: 130x54 took 6.3ms
2018-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
2018-07-08 10:05:30,480 total_time=596277, total_pixels=7294764
2018-07-08 10:05:30,520 packet decoding sequence 4 for window 15: 130x54 took 1.1ms
2018-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
2018-07-08 10:05:30,585 total_time=597409, total_pixels=7301784
2018-07-08 10:05:30,682 total_time=597409, total_pixels=7301784
2018-07-08 10:05:30,718 packet decoding sequence 5 for window 15: 130x54 took 1.5ms
2018-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
2018-07-08 10:05:30,837 total_time=599908, total_pixels=7315824
2018-07-08 10:05:31,004 packet decoding sequence 67 for window 1: 279x25 took 8.6ms
2018-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
2018-07-08 10:05:31,006 packet decoding sequence 68 for window 1: 234x25 took 10.7ms
2018-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
2018-07-08 10:05:31,039 total_time=629721, total_pixels=7490265
2018-07-08 10:05:31,141 total_time=629721, total_pixels=7490265
2018-07-08 10:05:31,257 total_time=881106, total_pixels=7578105
2018-07-08 10:05:31,294 packet decoding sequence 69 for window 1: 190x25 took 106.0ms
2018-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
2018-07-08 10:05:31,341 calculate_batch_delay for wid=1 current batch delay=20, last update 28 seconds ago
2018-07-08 10:05:31,342 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-08 10:05:31,342 update_batch_delay: client-decode-speed          : 1.25,0.02  {'avg': 16, 'recent': 22}
2018-07-08 10:05:31,342 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 119}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:31,345 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:31,345 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:31,345 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:31,346 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:31,347 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:31,347 update_batch_delay: delay=17
2018-07-08 10:05:31,383 total_time=1207068, total_pixels=7656799
2018-07-08 10:05:31,425 packet decoding sequence 70 for window 1: 121x25 took 112.9ms
2018-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
2018-07-08 10:05:31,426 may_recalculate(1, 3025) total 3025 pixels, scheduling recalculate work item
2018-07-08 10:05:31,427 packet decoding sequence 1 for window 20: 248x181 took 115.6ms
2018-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
2018-07-08 10:05:31,428 may_recalculate(20, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-08 10:05:31,432 packet decoding sequence 2 for window 20: 248x181 took 118.8ms
2018-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
2018-07-08 10:05:31,438 packet decoding sequence 71 for window 1: 76x25 took 2.9ms
2018-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
2018-07-08 10:05:31,464 packet decoding sequence 3 for window 20: 248x181 took 1.4ms
2018-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
2018-07-08 10:05:31,485 total_time=1558672, total_pixels=7796388
2018-07-08 10:05:31,590 total_time=1558672, total_pixels=7796388
2018-07-08 10:05:31,686 total_time=1558672, total_pixels=7796388
2018-07-08 10:05:31,729 packet decoding sequence 4 for window 20: 248x181 took 1.4ms
2018-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
2018-07-08 10:05:31,767 packet decoding sequence 5 for window 20: 248x181 took 1.1ms
2018-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
2018-07-08 10:05:31,784 total_time=1561236, total_pixels=7886164
2018-07-08 10:05:31,891 total_time=1561236, total_pixels=7886164
2018-07-08 10:05:31,989 total_time=1561236, total_pixels=7886164
2018-07-08 10:05:32,093 total_time=1561236, total_pixels=7886164
2018-07-08 10:05:32,194 total_time=1561236, total_pixels=7886164
2018-07-08 10:05:32,235 packet decoding sequence 6 for window 20: 248x181 took 1.4ms
2018-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
2018-07-08 10:05:32,294 total_time=1562595, total_pixels=7931052
2018-07-08 10:05:32,333 packet decoding sequence 7 for window 20: 248x181 took 1.6ms
2018-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
2018-07-08 10:05:32,341 calculate_batch_delay for wid=1 current batch delay=17, last update 1 seconds ago
2018-07-08 10:05:32,342 calculate_batch_delay for wid=1, skipping - only 1 events since the last update
2018-07-08 10:05:32,342 calculate_batch_delay for wid=20 current batch delay=12, last update 422443 seconds ago
2018-07-08 10:05:32,343 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 43, 'recent': 44}
2018-07-08 10:05:32,343 update_batch_delay: client-decode-speed          : 0.91,0.01  {'avg': 41, 'recent': 35}
2018-07-08 10:05:32,343 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 86}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:32,345 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:32,345 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:32,345 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:32,345 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:32,345 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:32,346 update_batch_delay: delay=9
2018-07-08 10:05:32,392 total_time=1564162, total_pixels=7975940
2018-07-08 10:05:32,495 total_time=1564162, total_pixels=7975940
2018-07-08 10:05:32,598 total_time=1564162, total_pixels=7975940
2018-07-08 10:05:32,637 packet decoding sequence 8 for window 20: 248x181 took 1.3ms
2018-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
2018-07-08 10:05:32,638 may_recalculate(20, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-08 10:05:32,717 total_time=1565461, total_pixels=8020828
2018-07-08 10:05:32,807 total_time=1565461, total_pixels=8020828
2018-07-08 10:05:32,896 packet decoding sequence 72 for window 1: 234x25 took 4.4ms
2018-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
2018-07-08 10:05:32,896 may_recalculate(1, 5850) total 5850 pixels, scheduling recalculate work item
2018-07-08 10:05:32,920 total_time=1571762, total_pixels=8087200
2018-07-08 10:05:32,991 packet decoding sequence 1 for window 22: 130x54 took 2.5ms
2018-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
2018-07-08 10:05:32,992 may_recalculate(22, 7020) total 7020 pixels, scheduling recalculate work item
2018-07-08 10:05:33,007 total_time=1574275, total_pixels=8094220
2018-07-08 10:05:33,059 packet decoding sequence 2 for window 22: 130x54 took 70.7ms
2018-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
2018-07-08 10:05:33,062 packet decoding sequence 73 for window 1: 279x25 took 73.0ms
2018-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
2018-07-08 10:05:33,084 packet decoding sequence 3 for window 22: 130x54 took 1.1ms
2018-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
2018-07-08 10:05:33,110 total_time=1719062, total_pixels=8115235
2018-07-08 10:05:33,193 packet decoding sequence 4 for window 22: 130x54 took 1.4ms
2018-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
2018-07-08 10:05:33,215 total_time=1720454, total_pixels=8122255
2018-07-08 10:05:33,313 total_time=1720454, total_pixels=8122255
2018-07-08 10:05:33,342 calculate_batch_delay for wid=1 current batch delay=17, last update 2 seconds ago
2018-07-08 10:05:33,343 calculate_batch_delay for wid=1, skipping - only 3 events since the last update
2018-07-08 10:05:33,343 calculate_batch_delay for wid=22 current batch delay=7, last update 422444 seconds ago
2018-07-08 10:05:33,344 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 41}
2018-07-08 10:05:33,344 update_batch_delay: client-decode-speed          : 0.97,0.14  {'avg': 287, 'recent': 273}
2018-07-08 10:05:33,345 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 164}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:33,347 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:33,347 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:33,347 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:33,347 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:33,347 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:33,347 update_batch_delay: delay=6
2018-07-08 10:05:33,413 total_time=1720454, total_pixels=8122255
2018-07-08 10:05:33,522 total_time=1720454, total_pixels=8122255
2018-07-08 10:05:33,621 total_time=1720454, total_pixels=8122255
2018-07-08 10:05:33,677 packet decoding sequence 74 for window 1: 279x25 took 7.6ms
2018-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
2018-07-08 10:05:33,677 may_recalculate(1, 6975) total 6975 pixels, scheduling recalculate work item
2018-07-08 10:05:33,743 total_time=1731546, total_pixels=8210038
2018-07-08 10:05:33,832 total_time=1731546, total_pixels=8210038
2018-07-08 10:05:33,884 packet decoding sequence 1 for window 25: 237x156 took 110.8ms
2018-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
2018-07-08 10:05:33,886 may_recalculate(25, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:33,887 packet decoding sequence 75 for window 1: 190x25 took 1.2ms
2018-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
2018-07-08 10:05:33,936 total_time=1843525, total_pixels=8251760
2018-07-08 10:05:34,037 total_time=1843525, total_pixels=8251760
2018-07-08 10:05:34,142 total_time=1843525, total_pixels=8251760
2018-07-08 10:05:34,260 total_time=1843525, total_pixels=8251760
2018-07-08 10:05:34,314 packet decoding sequence 76 for window 1: 76x25 took 124.4ms
2018-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
2018-07-08 10:05:34,318 packet decoding sequence 1 for window 27: 237x156 took 128.4ms
2018-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
2018-07-08 10:05:34,318 may_recalculate(27, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:34,322 packet decoding sequence 77 for window 1: 76x25 took 1.9ms
2018-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
2018-07-08 10:05:34,352 calculate_batch_delay for wid=1 current batch delay=17, last update 3 seconds ago
2018-07-08 10:05:34,352 calculate_batch_delay for wid=1, skipping - only 30250 pixels updated since the last update
2018-07-08 10:05:34,354 calculate_batch_delay for wid=27 current batch delay=18, last update 422445 seconds ago
2018-07-08 10:05:34,355 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-08 10:05:34,357 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 3642, 'recent': 3642}
2018-07-08 10:05:34,358 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 17}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:34,365 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:34,366 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:34,366 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:34,367 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:34,368 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:34,368 update_batch_delay: delay=14
2018-07-08 10:05:34,380 total_time=2098296, total_pixels=8292532
2018-07-08 10:05:34,419 packet decoding sequence 2 for window 27: 237x156 took 1.9ms
2018-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
2018-07-08 10:05:34,419 may_recalculate(27, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-08 10:05:34,463 total_time=2100237, total_pixels=8329504
2018-07-08 10:05:34,518 packet decoding sequence 3 for window 27: 237x156 took 1.2ms
2018-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
2018-07-08 10:05:34,566 total_time=2101437, total_pixels=8366476
2018-07-08 10:05:34,613 packet decoding sequence 4 for window 27: 237x156 took 1.6ms
2018-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
2018-07-08 10:05:34,669 total_time=2102987, total_pixels=8403448
2018-07-08 10:05:34,719 packet decoding sequence 5 for window 27: 237x156 took 1.3ms
2018-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
2018-07-08 10:05:34,772 total_time=2104251, total_pixels=8440420
2018-07-08 10:05:34,821 packet decoding sequence 6 for window 27: 237x156 took 1.5ms
2018-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
2018-07-08 10:05:34,867 total_time=2105729, total_pixels=8477392
2018-07-08 10:05:34,972 total_time=2105729, total_pixels=8477392
2018-07-08 10:05:35,071 total_time=2105729, total_pixels=8477392
2018-07-08 10:05:35,171 total_time=2105729, total_pixels=8477392
2018-07-08 10:05:35,275 total_time=2105729, total_pixels=8477392
2018-07-08 10:05:35,376 total_time=2105729, total_pixels=8477392
2018-07-08 10:05:35,443 packet decoding sequence 78 for window 1: 734x459 took 4.6ms
2018-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
2018-07-08 10:05:35,444 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:35,473 total_time=2110342, total_pixels=8814298
2018-07-08 10:05:35,530 packet decoding sequence 79 for window 1: 12x20 took 1.6ms
2018-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
2018-07-08 10:05:35,550 packet decoding sequence 80 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:35,567 total_time=2113168, total_pixels=8814778
2018-07-08 10:05:35,625 packet decoding sequence 81 for window 1: 12x20 took 0.9ms
2018-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
2018-07-08 10:05:35,677 total_time=2114112, total_pixels=8815018
2018-07-08 10:05:35,772 total_time=2114112, total_pixels=8815018
2018-07-08 10:05:35,826 packet decoding sequence 82 for window 1: 12x20 took 1.1ms
2018-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
2018-07-08 10:05:35,876 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:35,974 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,076 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,175 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,274 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,344 calculate_batch_delay for wid=1 current batch delay=17, last update 5 seconds ago
2018-07-08 10:05:36,345 calculate_batch_delay for wid=1, 368116 pixels updated since the last update
2018-07-08 10:05:36,345 calculate_batch_delay for wid=1, skipping - only 12 bytes sent since the last update
2018-07-08 10:05:36,375 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,477 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,581 total_time=2115241, total_pixels=8815258
2018-07-08 10:05:36,638 packet decoding sequence 83 for window 1: 723x38 took 1.6ms
2018-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
2018-07-08 10:05:36,638 may_recalculate(1, 27474) total 27474 pixels, scheduling recalculate work item
2018-07-08 10:05:36,681 total_time=2116850, total_pixels=8842732
2018-07-08 10:05:36,740 packet decoding sequence 84 for window 1: 723x38 took 2.3ms
2018-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
2018-07-08 10:05:36,778 total_time=2119117, total_pixels=8870206
2018-07-08 10:05:36,807 packet decoding sequence 85 for window 1: 723x38 took 1.1ms
2018-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
2018-07-08 10:05:36,879 total_time=2120202, total_pixels=8897680
2018-07-08 10:05:36,907 packet decoding sequence 86 for window 1: 723x38 took 1.3ms
2018-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
2018-07-08 10:05:36,981 total_time=2121546, total_pixels=8925154
2018-07-08 10:05:37,042 packet decoding sequence 87 for window 1: 723x38 took 2.0ms
2018-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
2018-07-08 10:05:37,080 total_time=2123570, total_pixels=8952628
2018-07-08 10:05:37,106 packet decoding sequence 88 for window 1: 723x38 took 1.1ms
2018-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
2018-07-08 10:05:37,182 total_time=2124697, total_pixels=8980102
2018-07-08 10:05:37,211 packet decoding sequence 89 for window 1: 734x459 took 3.8ms
2018-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
2018-07-08 10:05:37,279 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,345 calculate_batch_delay for wid=1 current batch delay=17, last update 6 seconds ago
2018-07-08 10:05:37,346 calculate_batch_delay for wid=1, 869866 pixels updated since the last update
2018-07-08 10:05:37,346 calculate_batch_delay for wid=1, skipping - only 19 bytes sent since the last update
2018-07-08 10:05:37,381 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,482 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,580 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,685 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,782 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,892 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:37,988 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:38,096 total_time=2128476, total_pixels=9317008
2018-07-08 10:05:38,127 packet decoding sequence 90 for window 1: 734x459 took 2.5ms
2018-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
2018-07-08 10:05:38,128 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:38,188 total_time=2130970, total_pixels=9653914
2018-07-08 10:05:38,293 total_time=2130970, total_pixels=9653914
2018-07-08 10:05:38,346 calculate_batch_delay for wid=1 current batch delay=17, last update 7 seconds ago
2018-07-08 10:05:38,346 calculate_batch_delay for wid=1, 1206772 pixels updated since the last update
2018-07-08 10:05:38,346 calculate_batch_delay for wid=1, skipping - only 20 bytes sent since the last update
2018-07-08 10:05:38,386 total_time=2130970, total_pixels=9653914
2018-07-08 10:05:38,495 total_time=2130970, total_pixels=9653914
2018-07-08 10:05:38,556 packet decoding sequence 91 for window 1: 734x459 took 3.3ms
2018-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
2018-07-08 10:05:38,556 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:38,588 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:38,689 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:38,789 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:38,889 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:38,990 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:39,092 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:39,190 total_time=2134284, total_pixels=9990820
2018-07-08 10:05:39,251 packet decoding sequence 92 for window 1: 734x459 took 2.9ms
2018-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
2018-07-08 10:05:39,294 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,346 calculate_batch_delay for wid=1 current batch delay=17, last update 8 seconds ago
2018-07-08 10:05:39,346 calculate_batch_delay for wid=1, 1880584 pixels updated since the last update
2018-07-08 10:05:39,346 calculate_batch_delay for wid=1, skipping - only 22 bytes sent since the last update
2018-07-08 10:05:39,397 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,499 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,599 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,698 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,799 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,898 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:39,998 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:40,100 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:40,203 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:40,314 total_time=2137164, total_pixels=10327726
2018-07-08 10:05:40,345 packet decoding sequence 93 for window 1: 734x459 took 2.4ms
2018-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
2018-07-08 10:05:40,347 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:40,348 calculate_batch_delay for wid=1 current batch delay=17, last update 9 seconds ago
2018-07-08 10:05:40,349 calculate_batch_delay for wid=1, 2217490 pixels updated since the last update
2018-07-08 10:05:40,350 calculate_batch_delay for wid=1, skipping - only 23 bytes sent since the last update
2018-07-08 10:05:40,401 total_time=2139606, total_pixels=10664632
2018-07-08 10:05:40,443 packet decoding sequence 94 for window 1: 734x459 took 2.4ms
2018-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
2018-07-08 10:05:40,443 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:40,502 total_time=2141965, total_pixels=11001538
2018-07-08 10:05:40,604 total_time=2141965, total_pixels=11001538
2018-07-08 10:05:40,703 total_time=2141965, total_pixels=11001538
2018-07-08 10:05:40,805 total_time=2141965, total_pixels=11001538
2018-07-08 10:05:40,910 total_time=2141965, total_pixels=11001538
2018-07-08 10:05:40,957 packet decoding sequence 95 for window 1: 734x459 took 2.6ms
2018-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
2018-07-08 10:05:41,009 total_time=2144522, total_pixels=11338444
2018-07-08 10:05:41,051 packet decoding sequence 96 for window 1: 734x459 took 2.4ms
2018-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
2018-07-08 10:05:41,107 total_time=2146931, total_pixels=11675350
2018-07-08 10:05:41,212 total_time=2146931, total_pixels=11675350
2018-07-08 10:05:41,308 total_time=2146931, total_pixels=11675350
2018-07-08 10:05:41,348 calculate_batch_delay for wid=1 current batch delay=17, last update 10 seconds ago
2018-07-08 10:05:41,348 calculate_batch_delay for wid=1, 3565114 pixels updated since the last update
2018-07-08 10:05:41,349 calculate_batch_delay for wid=1, skipping - only 27 bytes sent since the last update
2018-07-08 10:05:41,354 packet decoding sequence 97 for window 1: 734x459 took 2.5ms
2018-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
2018-07-08 10:05:41,355 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:41,383 packet decoding sequence 98 for window 1: 734x459 took 3.2ms
2018-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
2018-07-08 10:05:41,411 total_time=2152665, total_pixels=12349162
2018-07-08 10:05:41,510 total_time=2152665, total_pixels=12349162
2018-07-08 10:05:41,576 packet decoding sequence 99 for window 1: 13x434 took 2.0ms
2018-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
2018-07-08 10:05:41,609 packet decoding sequence 100 for window 1: 734x459 took 35.3ms
2018-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
2018-07-08 10:05:41,652 total_time=2189962, total_pixels=12691710
2018-07-08 10:05:41,684 packet decoding sequence 101 for window 1: 734x459 took 2.6ms
2018-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
2018-07-08 10:05:41,718 packet decoding sequence 102 for window 1: 734x459 took 33.9ms
2018-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
2018-07-08 10:05:41,735 total_time=2226483, total_pixels=13365522
2018-07-08 10:05:41,783 packet decoding sequence 103 for window 1: 734x459 took 2.4ms
2018-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
2018-07-08 10:05:41,815 packet decoding sequence 104 for window 1: 734x459 took 34.6ms
2018-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
2018-07-08 10:05:41,820 packet decoding sequence 105 for window 1: 734x459 took 3.1ms
2018-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
2018-07-08 10:05:41,838 total_time=2266593, total_pixels=14376240
2018-07-08 10:05:41,937 total_time=2266593, total_pixels=14376240
2018-07-08 10:05:41,986 packet decoding sequence 106 for window 1: 13x434 took 1.1ms
2018-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
2018-07-08 10:05:42,018 packet decoding sequence 107 for window 1: 734x459 took 33.9ms
2018-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
2018-07-08 10:05:42,023 packet decoding sequence 108 for window 1: 734x459 took 2.6ms
2018-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
2018-07-08 10:05:42,039 total_time=2304178, total_pixels=15055694
2018-07-08 10:05:42,096 packet decoding sequence 109 for window 1: 734x459 took 2.3ms
2018-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
2018-07-08 10:05:42,181 total_time=2306499, total_pixels=15392600
2018-07-08 10:05:42,208 packet decoding sequence 110 for window 1: 13x434 took 1.1ms
2018-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
2018-07-08 10:05:42,229 packet decoding sequence 111 for window 1: 734x459 took 2.5ms
2018-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
2018-07-08 10:05:42,307 packet decoding sequence 112 for window 1: 734x459 took 6.8ms
2018-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
2018-07-08 10:05:42,369 total_time=2316852, total_pixels=16072054
2018-07-08 10:05:42,376 calculate_batch_delay for wid=1 current batch delay=17, last update 11 seconds ago
2018-07-08 10:05:42,384 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-08 10:05:42,386 update_batch_delay: client-decode-speed          : 1.03,0.00  {'avg': 11, 'recent': 11}
2018-07-08 10:05:42,386 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 34}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:42,407 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:42,407 packet decoding sequence 113 for window 1: 734x459 took 2.7ms
2018-07-08 10:05:42,407 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-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
2018-07-08 10:05:42,411 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:42,412 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:42,412 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:42,417 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
2018-07-08 10:05:42,420 update_batch_delay: delay=14
2018-07-08 10:05:42,436 packet decoding sequence 114 for window 1: 734x459 took 33.3ms
2018-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
2018-07-08 10:05:42,523 packet decoding sequence 115 for window 1: 734x459 took 2.7ms
2018-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
2018-07-08 10:05:42,545 packet decoding sequence 116 for window 1: 734x459 took 28.3ms
2018-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
2018-07-08 10:05:42,601 total_time=2383869, total_pixels=17419678
2018-07-08 10:05:42,786 total_time=2383869, total_pixels=17419678
2018-07-08 10:05:42,864 packet decoding sequence 117 for window 1: 734x459 took 4.8ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:42,867 packet decoding sequence 118 for window 1: 734x459 took 8.0ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:42,873 packet decoding sequence 119 for window 1: 734x459 took 12.1ms
2018-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
2018-07-08 10:05:42,876 packet decoding sequence 120 for window 1: 734x459 took 16.1ms
2018-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
2018-07-08 10:05:42,886 packet decoding sequence 121 for window 1: 734x459 took 26.3ms
2018-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
2018-07-08 10:05:42,892 packet decoding sequence 122 for window 1: 734x459 took 29.8ms
2018-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
2018-07-08 10:05:42,915 total_time=2480905, total_pixels=19441114
2018-07-08 10:05:43,045 total_time=2480905, total_pixels=19441114
2018-07-08 10:05:43,133 total_time=2480905, total_pixels=19441114
2018-07-08 10:05:43,180 packet decoding sequence 123 for window 1: 12x20 took 2.4ms
2018-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
2018-07-08 10:05:43,218 packet decoding sequence 124 for window 1: 734x459 took 40.6ms
2018-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
2018-07-08 10:05:43,224 packet decoding sequence 125 for window 1: 734x459 took 45.6ms
2018-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
2018-07-08 10:05:43,233 packet decoding sequence 126 for window 1: 734x459 took 54.2ms
2018-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
2018-07-08 10:05:43,255 total_time=2623695, total_pixels=20452072

(xfce4-terminal:6398): Gdk-CRITICAL **: 10:05:43.359: gdk_window_get_window_type: assertion 'GDK_IS_WINDOW (window)' failed
2018-07-08 10:05:43,369 calculate_batch_delay for wid=1 current batch delay=14, last update 0 seconds ago
2018-07-08 10:05:43,375 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
2018-07-08 10:05:43,380 update_batch_delay: client-decode-speed          : 1.59,0.08  {'avg': 17, 'recent': 34}
2018-07-08 10:05:43,385 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:43,390 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 57}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:43,398 update_batch_delay: congestion                   : 2.75,17.48  {}
2018-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}
2018-07-08 10:05:43,400 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:43,400 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:43,401 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:43,421 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:43,422 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
2018-07-08 10:05:43,423 update_batch_delay: delay=33
2018-07-08 10:05:43,589 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:43,705 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:43,796 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:43,909 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:44,028 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:44,152 total_time=2623695, total_pixels=20452072
2018-07-08 10:05:44,200 packet decoding sequence 127 for window 1: 734x459 took 641.8ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,202 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:44,235 packet decoding sequence 128 for window 1: 734x459 took 674.2ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,238 packet decoding sequence 1 for window 29: 164x284 took 669.4ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,238 may_recalculate(29, 46576) total 46576 pixels, scheduling recalculate work item
2018-07-08 10:05:44,242 packet decoding sequence 129 for window 1: 734x459 took 673.4ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,262 total_time=5282577, total_pixels=21509366
2018-07-08 10:05:44,324 packet decoding sequence 130 for window 1: 734x459 took 76.9ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,348 networksend_congestion_event(soft-expire limit: 64ms, 5/5, 1000, 0) 418Kbps (average=418Kbps)
2018-07-08 10:05:44,348 record_congestion_event(soft-expire limit: 64ms, 5/5, 1000, 428504)
2018-07-08 10:05:44,358 packet decoding sequence 131 for window 1: 734x459 took 113.2ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,368 packet decoding sequence 132 for window 1: 734x459 took 123.3ms
2018-07-08 10:05:44,369 calculate_batch_delay for wid=1 current batch delay=33, last update 0 seconds ago
2018-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
2018-07-08 10:05:44,370 calculate_batch_delay for wid=1, skipping - only 5 events since the last update
2018-07-08 10:05:44,371 calculate_batch_delay for wid=29 current batch delay=21, last update 422455 seconds ago
2018-07-08 10:05:44,370 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:44,372 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 43, 'recent': 43}
2018-07-08 10:05:44,395 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 15071, 'recent': 15071}
2018-07-08 10:05:44,396 update_batch_delay: damage-rate                  : 0.27,0.85  {'max_latency': 100, 'elapsed': 925}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:44,398 update_batch_delay: congestion                   : 7.28,62.77  {}
2018-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}
2018-07-08 10:05:44,399 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:44,399 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:44,400 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:44,400 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:44,400 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:44,400 update_batch_delay: delay=105
2018-07-08 10:05:44,407 total_time=5595946, total_pixels=22520084
2018-07-08 10:05:44,503 total_time=5595946, total_pixels=22520084
2018-07-08 10:05:44,581 packet decoding sequence 133 for window 1: 734x459 took 3.8ms
2018-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
2018-07-08 10:05:44,606 total_time=5599765, total_pixels=22856990
2018-07-08 10:05:44,704 total_time=5599765, total_pixels=22856990
2018-07-08 10:05:44,829 total_time=5599765, total_pixels=22856990
2018-07-08 10:05:44,893 packet decoding sequence 134 for window 1: 734x459 took 2.9ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:44,904 packet decoding sequence 135 for window 1: 734x459 took 27.9ms
2018-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
2018-07-08 10:05:44,935 total_time=5630596, total_pixels=23530802
2018-07-08 10:05:45,091 packet decoding sequence 136 for window 1: 734x459 took 2.5ms
2018-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
2018-07-08 10:05:45,153 total_time=5633133, total_pixels=23867708
2018-07-08 10:05:45,256 total_time=5633133, total_pixels=23867708
2018-07-08 10:05:45,359 total_time=5633133, total_pixels=23867708
2018-07-08 10:05:45,386 calculate_batch_delay for wid=1 current batch delay=33, last update 2 seconds ago
2018-07-08 10:05:45,396 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 42, 'recent': 43}
2018-07-08 10:05:45,397 update_batch_delay: client-decode-speed          : 0.89,0.01  {'avg': 18, 'recent': 15}
2018-07-08 10:05:45,398 packet decoding sequence 137 for window 1: 734x459 took 70.8ms
2018-07-08 10:05:45,398 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 230, 'elapsed': 84}
2018-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
2018-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}
2018-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)
2018-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}
2018-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)
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:45,406 update_batch_delay: congestion                   : 2.47,14.73  {}
2018-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}
2018-07-08 10:05:45,407 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:45,408 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:45,410 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:45,411 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:45,412 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
2018-07-08 10:05:45,413 update_batch_delay: delay=77
2018-07-08 10:05:45,415 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:45,431 packet decoding sequence 138 for window 1: 734x459 took 103.0ms
2018-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
2018-07-08 10:05:45,519 packet decoding sequence 139 for window 1: 734x459 took 73.6ms
2018-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
2018-07-08 10:05:45,530 packet decoding sequence 140 for window 1: 734x459 took 3.4ms
2018-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
2018-07-08 10:05:45,584 total_time=5883873, total_pixels=25215332
2018-07-08 10:05:45,648 packet decoding sequence 141 for window 1: 734x459 took 2.5ms
2018-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
2018-07-08 10:05:45,731 packet decoding sequence 142 for window 1: 734x459 took 3.1ms
2018-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
2018-07-08 10:05:45,777 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:45,905 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,100 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,202 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,299 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,387 calculate_batch_delay for wid=1 current batch delay=77, last update 1 seconds ago
2018-07-08 10:05:46,388 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 42, 'recent': 42}
2018-07-08 10:05:46,388 update_batch_delay: client-decode-speed          : 0.95,0.00  {'avg': 17, 'recent': 16}
2018-07-08 10:05:46,388 update_batch_delay: damage-rate                  : 0.94,0.25  {'max_latency': 127, 'elapsed': 319}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:46,389 update_batch_delay: congestion                   : 2.12,11.22  {}
2018-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}
2018-07-08 10:05:46,390 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:46,390 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:46,390 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:46,390 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:46,396 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
2018-07-08 10:05:46,396 update_batch_delay: delay=134
2018-07-08 10:05:46,415 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,501 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,604 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,750 total_time=5889471, total_pixels=25889144
2018-07-08 10:05:46,820 packet decoding sequence 143 for window 1: 734x459 took 748.3ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:46,821 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:46,851 packet decoding sequence 1 for window 31: 164x284 took 768.5ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:46,853 may_recalculate(31, 46576) total 46576 pixels, scheduling recalculate work item
2018-07-08 10:05:46,878 total_time=7406293, total_pixels=26272626
2018-07-08 10:05:46,937 packet decoding sequence 144 for window 1: 734x459 took 81.2ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:46,969 packet decoding sequence 145 for window 1: 734x459 took 114.1ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:47,001 total_time=7601634, total_pixels=26946438
2018-07-08 10:05:47,061 packet decoding sequence 146 for window 1: 734x459 took 4.1ms
2018-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
2018-07-08 10:05:47,113 total_time=7605737, total_pixels=27283344
2018-07-08 10:05:47,205 total_time=7605737, total_pixels=27283344
2018-07-08 10:05:47,307 total_time=7605737, total_pixels=27283344
2018-07-08 10:05:47,360 packet decoding sequence 147 for window 1: 734x459 took 2.6ms
2018-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
2018-07-08 10:05:47,388 calculate_batch_delay for wid=1 current batch delay=134, last update 1 seconds ago
2018-07-08 10:05:47,389 update_batch_delay: damage-network-delay         : 1.02,0.52  {'avg': 42, 'recent': 43}
2018-07-08 10:05:47,392 update_batch_delay: client-decode-speed          : 0.88,0.01  {'avg': 18, 'recent': 15}
2018-07-08 10:05:47,415 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 113, 'elapsed': 137}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:47,425 update_batch_delay: congestion                   : 4.68,36.75  {}
2018-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}
2018-07-08 10:05:47,426 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:47,427 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:47,427 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:47,427 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:47,428 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:47,428 update_batch_delay: delay=330
2018-07-08 10:05:47,429 calculate_batch_delay for wid=31 current batch delay=49, last update 422458 seconds ago
2018-07-08 10:05:47,430 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-08 10:05:47,430 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 17302, 'recent': 17302}
2018-07-08 10:05:47,430 update_batch_delay: damage-rate                  : 0.00,1.13  {'max_latency': 100, 'elapsed': 1484}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:47,435 update_batch_delay: congestion                   : 4.68,36.75  {}
2018-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}
2018-07-08 10:05:47,437 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:47,438 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-08 10:05:47,438 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:47,439 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:47,442 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:47,443 update_batch_delay: delay=178
2018-07-08 10:05:47,451 total_time=7608314, total_pixels=27620250
2018-07-08 10:05:47,571 packet decoding sequence 148 for window 1: 734x459 took 2.6ms
2018-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
2018-07-08 10:05:47,572 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:47,619 total_time=7610936, total_pixels=27957156
2018-07-08 10:05:47,731 total_time=7610936, total_pixels=27957156
2018-07-08 10:05:47,846 total_time=7610936, total_pixels=27957156
2018-07-08 10:05:47,971 total_time=7610936, total_pixels=27957156
2018-07-08 10:05:48,052 total_time=7610936, total_pixels=27957156
2018-07-08 10:05:48,109 packet decoding sequence 149 for window 1: 734x459 took 2.8ms
2018-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
2018-07-08 10:05:48,169 total_time=7613692, total_pixels=28294062
2018-07-08 10:05:48,354 total_time=7613692, total_pixels=28294062
2018-07-08 10:05:48,395 calculate_batch_delay for wid=1 current batch delay=330, last update 1 seconds ago
2018-07-08 10:05:48,404 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 42, 'recent': 42}
2018-07-08 10:05:48,405 update_batch_delay: client-decode-speed          : 0.75,0.01  {'avg': 15, 'recent': 10}
2018-07-08 10:05:48,406 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 109, 'elapsed': 34}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:48,410 update_batch_delay: congestion                   : 1.39,3.94  {}
2018-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}
2018-07-08 10:05:48,412 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:48,413 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:48,414 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:48,415 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:48,415 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:48,416 update_batch_delay: delay=303
2018-07-08 10:05:48,456 total_time=7613692, total_pixels=28294062
2018-07-08 10:05:48,567 total_time=7613692, total_pixels=28294062
2018-07-08 10:05:48,626 packet decoding sequence 150 for window 1: 734x459 took 3.0ms
2018-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
2018-07-08 10:05:48,628 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:48,672 total_time=7616708, total_pixels=28630968
2018-07-08 10:05:48,804 total_time=7616708, total_pixels=28630968
2018-07-08 10:05:48,942 total_time=7616708, total_pixels=28630968
2018-07-08 10:05:49,057 packet decoding sequence 151 for window 1: 734x459 took 4.3ms
2018-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
2018-07-08 10:05:49,107 total_time=7621014, total_pixels=28967874
2018-07-08 10:05:49,217 total_time=7621014, total_pixels=28967874
2018-07-08 10:05:49,374 total_time=7621014, total_pixels=28967874
2018-07-08 10:05:49,399 calculate_batch_delay for wid=1 current batch delay=303, last update 1 seconds ago
2018-07-08 10:05:49,402 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 42, 'recent': 41}
2018-07-08 10:05:49,403 update_batch_delay: client-decode-speed          : 0.87,0.01  {'avg': 15, 'recent': 12}
2018-07-08 10:05:49,404 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 176, 'elapsed': 332}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:49,409 update_batch_delay: congestion                   : 1.13,1.30  {}
2018-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}
2018-07-08 10:05:49,409 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:49,410 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:49,410 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:49,410 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:49,410 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
2018-07-08 10:05:49,411 update_batch_delay: delay=255
2018-07-08 10:05:49,591 total_time=7621014, total_pixels=28967874
2018-07-08 10:05:49,688 total_time=7621014, total_pixels=28967874
2018-07-08 10:05:49,867 packet decoding sequence 152 for window 1: 734x459 took 3.6ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:49,876 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:49,926 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,138 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,261 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,335 congestion_notification_callback(16777217, ignore)
2018-07-08 10:05:50,353 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,399 calculate_batch_delay for wid=1 current batch delay=255, last update 0 seconds ago
2018-07-08 10:05:50,400 update_batch_delay: damage-network-delay         : 1.03,0.53  {'avg': 42, 'recent': 44}
2018-07-08 10:05:50,400 update_batch_delay: client-decode-speed          : 0.86,0.01  {'avg': 15, 'recent': 12}
2018-07-08 10:05:50,400 update_batch_delay: damage-rate                  : 0.65,0.59  {'max_latency': 174, 'elapsed': 696}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:50,401 update_batch_delay: congestion                   : 1.94,9.42  {}
2018-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}
2018-07-08 10:05:50,402 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:50,402 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:50,402 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:50,403 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:50,403 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
2018-07-08 10:05:50,403 update_batch_delay: delay=366
2018-07-08 10:05:50,461 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,589 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,691 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,799 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:50,911 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:51,037 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:51,152 total_time=7624616, total_pixels=29304780
2018-07-08 10:05:51,220 packet decoding sequence 153 for window 1: 734x459 took 3.3ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:51,221 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:51,264 packet decoding sequence 154 for window 1: 734x459 took 47.5ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:51,283 total_time=7675429, total_pixels=29978592
2018-07-08 10:05:51,351 packet decoding sequence 155 for window 1: 734x459 took 2.4ms
2018-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
2018-07-08 10:05:51,382 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:51,400 calculate_batch_delay for wid=1 current batch delay=366, last update 1 seconds ago
2018-07-08 10:05:51,401 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 42, 'recent': 41}
2018-07-08 10:05:51,401 update_batch_delay: client-decode-speed          : 0.89,0.01  {'avg': 14, 'recent': 12}
2018-07-08 10:05:51,401 update_batch_delay: damage-rate                  : 0.32,0.82  {'max_latency': 115, 'elapsed': 912}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:51,402 update_batch_delay: congestion                   : 2.87,18.70  {}
2018-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}
2018-07-08 10:05:51,402 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:51,402 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:51,402 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:51,403 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:51,403 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:51,403 update_batch_delay: delay=401
2018-07-08 10:05:51,484 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:51,584 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:51,706 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:51,788 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:51,891 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:52,005 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:52,099 total_time=7677808, total_pixels=30315498
2018-07-08 10:05:52,213 packet decoding sequence 156 for window 1: 734x459 took 3.0ms
2018-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
2018-07-08 10:05:52,214 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:52,234 total_time=7680783, total_pixels=30652404
2018-07-08 10:05:52,337 total_time=7680783, total_pixels=30652404
2018-07-08 10:05:52,401 calculate_batch_delay for wid=1 current batch delay=401, last update 1 seconds ago
2018-07-08 10:05:52,402 update_batch_delay: damage-network-delay         : 1.02,0.52  {'avg': 42, 'recent': 43}
2018-07-08 10:05:52,402 update_batch_delay: client-decode-speed          : 0.78,0.01  {'avg': 14, 'recent': 10}
2018-07-08 10:05:52,402 update_batch_delay: damage-rate                  : 0.75,0.50  {'max_latency': 113, 'elapsed': 474}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:52,403 update_batch_delay: congestion                   : 1.21,2.10  {}
2018-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}
2018-07-08 10:05:52,404 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:52,404 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:52,404 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:52,404 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:52,404 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:52,405 update_batch_delay: delay=359
2018-07-08 10:05:52,438 total_time=7680783, total_pixels=30652404
2018-07-08 10:05:52,515 packet decoding sequence 157 for window 1: 734x459 took 3.1ms
2018-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
2018-07-08 10:05:52,516 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:52,555 total_time=7683924, total_pixels=30989310
2018-07-08 10:05:52,662 total_time=7683924, total_pixels=30989310
2018-07-08 10:05:52,857 total_time=7683924, total_pixels=30989310
2018-07-08 10:05:52,928 packet decoding sequence 158 for window 1: 734x459 took 2.6ms
2018-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
2018-07-08 10:05:52,971 total_time=7686503, total_pixels=31326216
2018-07-08 10:05:53,104 total_time=7686503, total_pixels=31326216
2018-07-08 10:05:53,268 total_time=7686503, total_pixels=31326216
2018-07-08 10:05:53,390 total_time=7686503, total_pixels=31326216
2018-07-08 10:05:53,408 calculate_batch_delay for wid=1 current batch delay=359, last update 1 seconds ago
2018-07-08 10:05:53,412 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 42, 'recent': 41}
2018-07-08 10:05:53,413 update_batch_delay: client-decode-speed          : 0.77,0.01  {'avg': 13, 'recent': 9}
2018-07-08 10:05:53,413 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 107, 'elapsed': 181}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:53,417 update_batch_delay: congestion                   : 1.07,0.72  {}
2018-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}
2018-07-08 10:05:53,418 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:53,418 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:53,418 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:53,419 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:53,419 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:53,420 update_batch_delay: delay=302
2018-07-08 10:05:53,454 packet decoding sequence 159 for window 1: 734x459 took 4.6ms
2018-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
2018-07-08 10:05:53,454 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-08 10:05:53,493 total_time=7691136, total_pixels=31663122
2018-07-08 10:05:53,597 total_time=7691136, total_pixels=31663122
2018-07-08 10:05:53,698 total_time=7691136, total_pixels=31663122
2018-07-08 10:05:53,755 packet decoding sequence 160 for window 1: 734x459 took 3.5ms
2018-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
2018-07-08 10:05:53,796 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:53,896 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,001 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,098 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,199 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,297 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,400 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,408 calculate_batch_delay for wid=1 current batch delay=302, last update 0 seconds ago
2018-07-08 10:05:54,410 update_batch_delay: damage-network-delay         : 0.99,0.52  {'avg': 42, 'recent': 41}
2018-07-08 10:05:54,411 update_batch_delay: client-decode-speed          : 0.88,0.01  {'avg': 13, 'recent': 11}
2018-07-08 10:05:54,412 update_batch_delay: damage-rate                  : 0.92,0.29  {'max_latency': 123, 'elapsed': 331}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:54,416 update_batch_delay: congestion                   : 1.03,0.34  {}
2018-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}
2018-07-08 10:05:54,421 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-08 10:05:54,421 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:54,422 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:54,422 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:54,423 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:54,423 update_batch_delay: delay=266
2018-07-08 10:05:54,518 total_time=7694601, total_pixels=32000028
2018-07-08 10:05:54,595 packet decoding sequence 161 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:54,614 total_time=7695774, total_pixels=32000268
2018-07-08 10:05:54,713 total_time=7695774, total_pixels=32000268
2018-07-08 10:05:54,818 total_time=7695774, total_pixels=32000268
2018-07-08 10:05:54,888 packet decoding sequence 162 for window 1: 12x20 took 1.2ms
2018-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
2018-07-08 10:05:54,889 packet decoding sequence 163 for window 1: 12x20 took 1.9ms
2018-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
2018-07-08 10:05:54,919 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,017 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,121 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,219 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,321 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,422 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,524 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,624 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,724 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,826 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:55,927 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,026 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,128 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,226 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,326 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,428 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,528 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,629 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,775 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,869 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:56,966 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:57,072 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:57,167 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:57,271 total_time=7698909, total_pixels=32000748
2018-07-08 10:05:57,329 packet decoding sequence 164 for window 1: 12x20 took 1.1ms
2018-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
2018-07-08 10:05:57,370 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:57,473 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:57,576 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:57,671 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:57,776 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:57,878 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:57,988 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:58,088 total_time=7700002, total_pixels=32000988
2018-07-08 10:05:58,169 packet decoding sequence 165 for window 1: 363x20 took 2.6ms
2018-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
2018-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)
2018-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)
2018-07-08 10:05:58,171 may_recalculate(1, 7260) total 8220 pixels, scheduling recalculate work item
2018-07-08 10:05:58,172 packet decoding sequence 166 for window 1: 12x20 took 3.6ms
2018-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
2018-07-08 10:05:58,174 calculate_batch_delay for wid=1 current batch delay=266, last update 3 seconds ago
2018-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)
2018-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)
2018-07-08 10:05:58,177 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
2018-07-08 10:05:58,178 update_batch_delay: client-decode-speed          : 0.95,0.00  {'avg': 14, 'recent': 13}
2018-07-08 10:05:58,179 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 104, 'elapsed': 6}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-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}
2018-07-08 10:05:58,186 update_batch_delay: congestion                   : 1.88,8.80  {}
2018-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}
2018-07-08 10:05:58,186 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-08 10:05:58,186 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-08 10:05:58,187 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-08 10:05:58,187 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-08 10:05:58,187 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-08 10:05:58,187 update_batch_delay: delay=372
2018-07-08 10:05:58,198 total_time=7706227, total_pixels=32008488
2018-07-08 10:05:58,292 total_time=7706227, total_pixels=32008488
2018-07-08 10:05:58,392 total_time=7706227, total_pixels=32008488
2018-07-08 10:05:58,492 total_time=7706227, total_pixels=32008488
2018-07-08 10:05:58,542 packet decoding sequence 167 for window 1: 12x20 took 0.9ms
2018-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
2018-07-08 10:05:58,592 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:58,692 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:58,796 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:58,897 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:58,997 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,097 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,196 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,299 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,398 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,498 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,601 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,701 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,802 total_time=7707137, total_pixels=32008728
2018-07-08 10:05:59,903 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,002 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,103 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,202 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,305 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,407 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,509 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,614 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,708 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,806 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:00,909 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,007 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,115 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,208 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,315 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,415 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,516 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,614 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,718 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,815 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:01,918 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,018 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,117 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,218 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,319 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,424 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,522 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,627 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,721 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,823 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:02,923 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:03,024 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:03,124 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:03,228 total_time=7707137, total_pixels=32008728
2018-07-08 10:06:03,328 total_time=7695037, total_pixels=31334916
2018-07-08 10:06:03,427 total_time=7695037, total_pixels=31334916
2018-07-08 10:06:03,527 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:03,627 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:03,728 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:03,827 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:03,930 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,030 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,135 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,233 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,333 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,434 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,535 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,636 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,735 total_time=7690879, total_pixels=30998010
2018-07-08 10:06:04,835 total_time=7687215, total_pixels=30983334
2018-07-08 10:06:04,936 total_time=7683850, total_pixels=30982614
2018-07-08 10:06:05,036 total_time=7679823, total_pixels=30981894
2018-07-08 10:06:05,138 total_time=7676384, total_pixels=30981414
2018-07-08 10:06:05,238 total_time=7676384, total_pixels=30981414
2018-07-08 10:06:05,339 total_time=7676384, total_pixels=30981414
2018-07-08 10:06:05,439 total_time=7676384, total_pixels=30981414
2018-07-08 10:06:05,542 total_time=7673332, total_pixels=30981174
2018-07-08 10:06:05,642 total_time=7668100, total_pixels=30980454
2018-07-08 10:06:05,748 total_time=7662952, total_pixels=30979734
2018-07-08 10:06:05,843 total_time=7660751, total_pixels=30979254
2018-07-08 10:06:05,944 total_time=7653826, total_pixels=30978294
2018-07-08 10:06:06,046 total_time=7651176, total_pixels=30977814
2018-07-08 10:06:06,145 total_time=7647374, total_pixels=30977094
2018-07-08 10:06:06,248 total_time=7647374, total_pixels=30977094
2018-07-08 10:06:06,351 total_time=7647374, total_pixels=30977094
2018-07-08 10:06:06,448 total_time=7644714, total_pixels=30976614
2018-07-08 10:06:06,547 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:06,648 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:06,748 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:06,853 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:06,949 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,050 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,149 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,252 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,351 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,451 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,554 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,656 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,758 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,858 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:07,957 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:08,057 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:08,158 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:08,256 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:08,359 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:08,461 total_time=7639640, total_pixels=30975894
2018-07-08 10:06:08,563 total_time=7631847, total_pixels=30814278
2018-07-08 10:06:08,661 total_time=7626632, total_pixels=30477372
2018-07-08 10:06:08,765 total_time=7626632, total_pixels=30477372
2018-07-08 10:06:08,835 client @19.107 Warning: the opus sound sink has stopped
2018-07-08 10:06:08,840 sound source stopping
2018-07-08 10:06:08,849 xpra client 1 disconnected.
2018-07-08 10:06:12,835 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-08 10:06:12,839 Handshake complete; enabling connection
2018-07-08 10:06:12,841 get_client_bandwidth_limit(Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1))=0
2018-07-08 10:06:12,845 server bandwidth-limit=0, client bandwidth-limit=0, value=0
2018-07-08 10:06:12,847 Python2/GObject client version 2.3.2-r19729 64-bit
2018-07-08 10:06:12,847  connected from 'beelink' as 'steved' - 'Steve Dee'
2018-07-08 10:06:12,851 Shutting down in response to client request
2018-07-08 10:06:12,852 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/beelink-1):
2018-07-08 10:06:12,852  server shutdown
2018-07-08 10:06:12,854 xpra client 2 disconnected.
2018-07-08 10:06:13,233 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-08 10:06:13,234 New unix-domain connection received on /home/steved/.xpra/beelink-1
2018-07-08 10:06:13,361 stopping pulseaudio with pid 6156
2018-07-08 10:06:13,901 killing xvfb with pid 6110
2018-07-08 10:06:13,903 removing socket /run/user/1000/xpra/beelink-1
2018-07-08 10:06:13,903 removing socket /home/steved/.xpra/beelink-1
(II) Server terminated successfully (0). Closing log file.