Ticket #1911: xpra-1804-local.txt

2018-07-07 22:02:45,753 cannot access python uinput module:
2018-07-07 22:02:45,753  No module named uinput
_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
_XSERVTransMakeAllCOTSServerListeners: server already running
Failed to rename log file "/run/user/1000/xpra/Xorg.S28882.log" to "/run/user/1000/xpra/Xorg.S28882.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.S28882.log", Time: Sat Jul  7 22:02:45 2018
(++) Using config file: "/etc/xpra/xorg.conf"
(==) Using system config directory "/usr/share/X11/xorg.conf.d"
2018-07-07 22:02:52,342 created unix domain socket: /run/user/1000/xpra/beelink-1
2018-07-07 22:02:52,343 created unix domain socket: /home/steved/.xpra/beelink-1
2018-07-07 22:02:52,343 cannot create group socket '/run/xpra/beelink-1'
2018-07-07 22:02:52,343  [Errno 13] Permission denied
2018-07-07 22:02:52,630 Warning: the python netifaces package is missing
2018-07-07 22:02:52,661 pointer device emulation using XTest
2018-07-07 22:02:54,929 serving html content from: /usr/share/xpra/www
2018-07-07 22:02:55,149 D-Bus notification forwarding is available
2018-07-07 22:02:55,401 Warning: webcam forwarding is disabled
2018-07-07 22:02:55,402  the virtual video directory '/sys/devices/virtual/video4linux' was not found
2018-07-07 22:02:55,402  make sure that the 'v4l2loopback' kernel module is installed and loaded
2018-07-07 22:02:55,403 found 0 virtual video devices for webcam forwarding
2018-07-07 22:02:55,426 pulseaudio server started with pid 28969
2018-07-07 22:02:55,427  private server socket path:
2018-07-07 22:02:55,427  '/run/user/1000/xpra/pulse-:1/pulse/native'
2018-07-07 22:02:57,379 GStreamer version 1.14.1 for Python 2.7.15 64-bit
2018-07-07 22:02:57,520 Warning: failed to load the mdns avahi publisher:
2018-07-07 22:02:57,520  No module named avahi
2018-07-07 22:02:57,521  either fix your installation or use the 'mdns=no' option
2018-07-07 22:02:57,521 xpra X11 version 2.3.2-r19729 64-bit
2018-07-07 22:02:57,521  uid=1000 (steved), gid=1000 (steved)
2018-07-07 22:02:57,522  running with pid 28914 on Linux Ubuntu 18.04 bionic
2018-07-07 22:02:57,522  connected to X11 display :1 with 24 bit colors
2018-07-07 22:02:57,766 xpra is ready.
2018-07-07 22:03:09,817 printer forwarding enabled using postscript and pdf
2018-07-07 22:03:09,839 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-07 22:03:10,772 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:03:10,781 Handshake complete; enabling connection
2018-07-07 22:03:10,806  automatic picture encoding enabled, also available:
2018-07-07 22:03:10,807   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
2018-07-07 22:03:10,808  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.AuxenV.mmap
2018-07-07 22:03:10,809 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
2018-07-07 22:03:10,810  connected from 'beelink' as 'steved' - 'Steve Dee'
2018-07-07 22:03:10,815  client root window size is 2880x900 with 1 display:
2018-07-07 22:03:10,815   :0.0 (761x237 mm - DPI: 96x96)
2018-07-07 22:03:10,816     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
2018-07-07 22:03:10,816     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
2018-07-07 22:03:11,076 server virtual display now set to 2880x960 (best match for 2880x900)
2018-07-07 22:03:11,093 setting key repeat rate from client: 500ms delay / 50ms interval
2018-07-07 22:03:11,096 setting keymap: rules=evdev, model=pc105, layout=gb
2018-07-07 22:03:11,137 setting keyboard layout to 'gb'
2018-07-07 22:03:11,237 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:03:11,238 New unix-domain connection received on /home/steved/.xpra/beelink-1
2018-07-07 22:03:11,248 client @05.051 Xpra X11 server version 2.3.2-r19729 64-bit
2018-07-07 22:03:11,248 DPI set to 34 x 22 (wanted 96 x 96)
2018-07-07 22:03:11,249  you may experience scaling problems, such as huge or small fonts, etc
2018-07-07 22:03:11,249  to fix this issue, try the dpi switch, or use a patched Xorg dummy driver
2018-07-07 22:03:11,274 client @05.053  running on Linux Ubuntu 18.04 bionic
2018-07-07 22:03:11,275 client @05.068 Attached to :1
2018-07-07 22:03:11,276 client @05.069  (press Control-C to detach)
2018-07-07 22:03:11,402 total_time=0, total_pixels=0
2018-07-07 22:03:11,458 client @05.248 server does not support xi input devices
2018-07-07 22:03:11,458 client @05.250  server uses: xtest
2018-07-07 22:03:11,554 packet decoding sequence 1 for window 1: 734x459 took 11.4ms
2018-07-07 22:03:11,554 record_latency: took   34.0 ms round trip,   34.0 for echo,   11.0 for decoding of   336906 pixels,       57 bytes sent over the network in   22.6 ms,   22.5 ms for echo
2018-07-07 22:03:11,555 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:03:11,556 calculate_batch_delay for wid=1 current batch delay=29, last update 416856 seconds ago
2018-07-07 22:03:11,556 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:03:11,558 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 35, 'recent': 35}
2018-07-07 22:03:11,561 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 25}
2018-07-07 22:03:11,562 update_batch_delay: client-latency               : 0.89,0.41  {'target': 27, 'weight_multiplier': 1249, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 793, 'div': 1000, 'avg': 22, 'recent': 22}
2018-07-07 22:03:11,562 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:11,562 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:11,563 update_batch_delay: compression-work-queue       : 0.28,0.48  {'target': 1000, 'weight_multiplier': 618, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 213, 'div': 1000, 'avg': 353, 'recent': 382}
2018-07-07 22:03:11,563 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:11,563 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 22:03:11,564 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 22:03:11,564 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:03:11,564 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:03:11,564 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
2018-07-07 22:03:11,564 update_batch_delay: delay=23
2018-07-07 22:03:11,568 packet decoding sequence 2 for window 1: 734x459 took 4.0ms
2018-07-07 22:03:11,569 record_latency: took    6.4 ms round trip,    6.2 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.4 ms,    2.2 ms for echo
2018-07-07 22:03:11,569 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:03:11,754 packet decoding sequence 3 for window 1: 734x459 took 2.6ms
2018-07-07 22:03:11,754 record_latency: took    5.2 ms round trip,    5.1 for echo,    2.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.5 ms,    2.5 ms for echo
2018-07-07 22:03:12,556 calculate_batch_delay for wid=1 current batch delay=23, last update 1 seconds ago
2018-07-07 22:03:12,557 calculate_batch_delay for wid=1, skipping - only 2 events since the last update
2018-07-07 22:03:13,211 using pulseaudio device:
2018-07-07 22:03:13,211  'Monitor of Xpra Speaker'
2018-07-07 22:03:13,294 packet decoding sequence 4 for window 1: 12x20 took 1.4ms
2018-07-07 22:03:13,295 record_latency: took   13.2 ms round trip,   13.2 for echo,    1.0 for decoding of      240 pixels,       59 bytes sent over the network in   11.8 ms,   11.7 ms for echo
2018-07-07 22:03:13,328 packet decoding sequence 5 for window 1: 734x459 took 3.6ms
2018-07-07 22:03:13,329 record_latency: took    6.2 ms round trip,    6.1 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.6 ms,    2.6 ms for echo
2018-07-07 22:03:13,329 may_recalculate(1, 336906) total 337146 pixels, scheduling recalculate work item
2018-07-07 22:03:13,355 packet decoding sequence 6 for window 1: 12x20 took 1.7ms
2018-07-07 22:03:13,356 record_latency: took    4.0 ms round trip,    3.9 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.3 ms,    2.3 ms for echo
2018-07-07 22:03:13,389 packet decoding sequence 7 for window 1: 12x20 took 1.1ms
2018-07-07 22:03:13,389 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.1 ms,    3.1 ms for echo
2018-07-07 22:03:13,432 packet decoding sequence 8 for window 1: 12x20 took 2.1ms
2018-07-07 22:03:13,432 record_latency: took    4.5 ms round trip,    4.5 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.4 ms,    2.3 ms for echo
2018-07-07 22:03:13,464 packet decoding sequence 9 for window 1: 12x20 took 1.1ms
2018-07-07 22:03:13,464 record_latency: took    3.1 ms round trip,    3.0 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.0 ms,    2.0 ms for echo
2018-07-07 22:03:13,500 packet decoding sequence 10 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,500 record_latency: took    4.5 ms round trip,    4.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.5 ms,    3.1 ms for echo
2018-07-07 22:03:13,538 packet decoding sequence 11 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:13,538 record_latency: took    4.2 ms round trip,    3.8 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    3.3 ms,    2.9 ms for echo
2018-07-07 22:03:13,558 calculate_batch_delay for wid=1 current batch delay=23, last update 2 seconds ago
2018-07-07 22:03:13,559 calculate_batch_delay for wid=1, 1012398 pixels updated since the last update
2018-07-07 22:03:13,561 calculate_batch_delay for wid=1, skipping - only 10 bytes sent since the last update
2018-07-07 22:03:13,577 packet decoding sequence 12 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,578 record_latency: took   10.0 ms round trip,   10.0 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    9.0 ms,    9.0 ms for echo
2018-07-07 22:03:13,617 packet decoding sequence 13 for window 1: 12x20 took 1.4ms
2018-07-07 22:03:13,617 record_latency: took    9.6 ms round trip,    9.5 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    8.2 ms,    8.1 ms for echo
2018-07-07 22:03:13,647 packet decoding sequence 14 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,647 record_latency: took    3.3 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.3 ms,    2.1 ms for echo
2018-07-07 22:03:13,679 packet decoding sequence 15 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,679 record_latency: took    3.4 ms round trip,    3.3 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.4 ms,    2.3 ms for echo
2018-07-07 22:03:13,715 packet decoding sequence 16 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,716 record_latency: took    3.8 ms round trip,    3.6 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.7 ms,    2.6 ms for echo
2018-07-07 22:03:13,819 packet decoding sequence 17 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:13,819 record_latency: took    3.5 ms round trip,    3.5 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.6 ms,    2.5 ms for echo
2018-07-07 22:03:13,855 packet decoding sequence 18 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:13,856 record_latency: took    5.2 ms round trip,    5.2 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    4.3 ms,    4.2 ms for echo
2018-07-07 22:03:13,888 packet decoding sequence 19 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,888 record_latency: took    3.0 ms round trip,    2.9 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    1.9 ms,    1.9 ms for echo
2018-07-07 22:03:13,924 packet decoding sequence 20 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,924 record_latency: took    3.0 ms round trip,    2.7 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.0 ms,    1.8 ms for echo
2018-07-07 22:03:13,925 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
2018-07-07 22:03:13,959 packet decoding sequence 21 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:13,960 record_latency: took    3.2 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.2 ms,    2.1 ms for echo
2018-07-07 22:03:14,559 calculate_batch_delay for wid=1 current batch delay=23, last update 3 seconds ago
2018-07-07 22:03:14,559 calculate_batch_delay for wid=1, 1014798 pixels updated since the last update
2018-07-07 22:03:14,559 calculate_batch_delay for wid=1, skipping - only 20 bytes sent since the last update
2018-07-07 22:03:14,639 packet decoding sequence 22 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:14,639 record_latency: took    3.3 ms round trip,    3.2 for echo,    1.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.2 ms,    2.2 ms for echo
2018-07-07 22:03:14,675 packet decoding sequence 23 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:14,675 record_latency: took    4.9 ms round trip,    4.8 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    4.0 ms,    3.9 ms for echo
2018-07-07 22:03:14,710 packet decoding sequence 24 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:14,710 record_latency: took    3.2 ms round trip,    3.1 for echo,    0.0 for decoding of      240 pixels,       57 bytes sent over the network in    2.2 ms,    2.2 ms for echo
2018-07-07 22:03:15,295 packet decoding sequence 1 for window 2: 312x259 took 3.0ms
2018-07-07 22:03:15,295 record_latency: took   29.4 ms round trip,   29.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   26.4 ms,   26.4 ms for echo
2018-07-07 22:03:15,296 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:15,301 packet decoding sequence 25 for window 1: 734x459 took 8.9ms
2018-07-07 22:03:15,302 record_latency: took   26.0 ms round trip,   25.9 for echo,    8.0 for decoding of   336906 pixels,       61 bytes sent over the network in   17.1 ms,   17.0 ms for echo
2018-07-07 22:03:15,302 may_recalculate(1, 336906) total 337626 pixels, scheduling recalculate work item
2018-07-07 22:03:15,560 calculate_batch_delay for wid=1 current batch delay=23, last update 4 seconds ago
2018-07-07 22:03:15,560 calculate_batch_delay for wid=1, 1352424 pixels updated since the last update
2018-07-07 22:03:15,560 calculate_batch_delay for wid=1, skipping - only 24 bytes sent since the last update
2018-07-07 22:03:15,561 calculate_batch_delay for wid=2 current batch delay=19, last update 416860 seconds ago
2018-07-07 22:03:15,562 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:03:15,562 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 38, 'recent': 38}
2018-07-07 22:03:15,562 update_batch_delay: damage-rate                  : 0.88,0.34  {'max_latency': 100, 'elapsed': 318}
2018-07-07 22:03:15,563 update_batch_delay: client-latency               : 1.09,0.13  {'target': 6, 'weight_multiplier': 430, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1195, 'div': 1000, 'avg': 6, 'recent': 14}
2018-07-07 22:03:15,563 update_batch_delay: client-ping-latency          : 0.54,0.13  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 292, 'div': 1000, 'avg': 2, 'recent': 2}
2018-07-07 22:03:15,563 update_batch_delay: server-ping-latency          : 0.94,0.23  {'target': 45, 'weight_multiplier': 925, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 884, 'div': 1000, 'avg': 39, 'recent': 39}
2018-07-07 22:03:15,563 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:15,564 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:15,564 update_batch_delay: compression-work-queue       : 0.00,0.14  {'target': 1000, 'weight_multiplier': 139, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 1, 'div': 1000, 'avg': 19, 'recent': 3}
2018-07-07 22:03:15,564 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:15,565 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:03:15,565 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:03:15,565 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:03:15,565 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:03:15,566 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:03:15,566 update_batch_delay: delay=14
2018-07-07 22:03:15,735 packet decoding sequence 2 for window 2: 312x259 took 2.9ms
2018-07-07 22:03:15,736 record_latency: took    7.0 ms round trip,    6.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
2018-07-07 22:03:15,736 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:15,774 packet decoding sequence 3 for window 2: 312x259 took 2.3ms
2018-07-07 22:03:15,775 record_latency: took    6.6 ms round trip,    6.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.3 ms,    4.2 ms for echo
2018-07-07 22:03:15,876 sound source using 'opus' audio codec
2018-07-07 22:03:15,895 packet decoding sequence 4 for window 2: 312x259 took 2.2ms
2018-07-07 22:03:15,896 record_latency: took    5.4 ms round trip,    5.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.1 ms,    2.9 ms for echo
2018-07-07 22:03:15,948 packet decoding sequence 5 for window 2: 312x259 took 2.3ms
2018-07-07 22:03:15,948 record_latency: took    5.9 ms round trip,    5.7 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.7 ms,    3.5 ms for echo
2018-07-07 22:03:15,960 packet decoding sequence 6 for window 2: 312x259 took 1.7ms
2018-07-07 22:03:15,961 record_latency: took    3.8 ms round trip,    3.7 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.0 ms,    2.0 ms for echo
2018-07-07 22:03:16,051 packet decoding sequence 7 for window 2: 312x259 took 2.3ms
2018-07-07 22:03:16,051 record_latency: took    5.4 ms round trip,    5.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.2 ms,    3.0 ms for echo
2018-07-07 22:03:16,192 packet decoding sequence 8 for window 2: 312x259 took 2.6ms
2018-07-07 22:03:16,193 record_latency: took    7.1 ms round trip,    6.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.6 ms,    4.3 ms for echo
2018-07-07 22:03:16,236 packet decoding sequence 9 for window 2: 312x259 took 1.9ms
2018-07-07 22:03:16,236 record_latency: took    4.7 ms round trip,    4.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.6 ms for echo
2018-07-07 22:03:16,273 packet decoding sequence 10 for window 2: 312x259 took 2.6ms
2018-07-07 22:03:16,274 record_latency: took    6.4 ms round trip,    6.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2018-07-07 22:03:16,418 packet decoding sequence 11 for window 2: 312x259 took 1.8ms
2018-07-07 22:03:16,418 record_latency: took    6.2 ms round trip,    6.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.4 ms,    4.2 ms for echo
2018-07-07 22:03:16,473 packet decoding sequence 12 for window 2: 312x259 took 2.2ms
2018-07-07 22:03:16,473 record_latency: took    7.1 ms round trip,    6.8 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.9 ms,    4.6 ms for echo
2018-07-07 22:03:16,478 packet decoding sequence 13 for window 2: 312x259 took 2.8ms
2018-07-07 22:03:16,478 record_latency: took    6.9 ms round trip,    6.8 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
2018-07-07 22:03:16,495 packet decoding sequence 14 for window 2: 312x259 took 1.5ms
2018-07-07 22:03:16,495 record_latency: took    9.5 ms round trip,    9.4 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    8.0 ms,    8.0 ms for echo
2018-07-07 22:03:16,527 packet decoding sequence 15 for window 2: 312x259 took 1.7ms
2018-07-07 22:03:16,528 record_latency: took    9.7 ms round trip,    9.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    8.0 ms,    7.9 ms for echo
2018-07-07 22:03:16,562 calculate_batch_delay for wid=2 current batch delay=14, last update 1 seconds ago
2018-07-07 22:03:16,562 calculate_batch_delay for wid=2, 1131312 pixels updated since the last update
2018-07-07 22:03:16,563 calculate_batch_delay for wid=2, skipping - only 14 bytes sent since the last update
2018-07-07 22:03:16,574 packet decoding sequence 16 for window 2: 312x25 took 1.5ms
2018-07-07 22:03:16,574 record_latency: took    4.4 ms round trip,    4.2 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    2.9 ms,    2.6 ms for echo
2018-07-07 22:03:16,574 may_recalculate(2, 7800) total 7800 pixels, scheduling recalculate work item
2018-07-07 22:03:16,591 packet decoding sequence 17 for window 2: 312x259 took 1.6ms
2018-07-07 22:03:16,591 record_latency: took    3.9 ms round trip,    3.8 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.3 ms,    2.2 ms for echo
2018-07-07 22:03:16,669 packet decoding sequence 18 for window 2: 312x259 took 2.5ms
2018-07-07 22:03:16,670 record_latency: took    5.6 ms round trip,    5.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.1 ms,    3.0 ms for echo
2018-07-07 22:03:16,917 packet decoding sequence 19 for window 2: 312x259 took 2.3ms
2018-07-07 22:03:16,917 record_latency: took    6.1 ms round trip,    5.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.8 ms,    3.6 ms for echo
2018-07-07 22:03:16,966 packet decoding sequence 20 for window 2: 312x259 took 2.3ms
2018-07-07 22:03:16,966 record_latency: took    5.3 ms round trip,    3.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    1.6 ms for echo
2018-07-07 22:03:17,025 packet decoding sequence 21 for window 2: 312x259 took 2.1ms
2018-07-07 22:03:17,026 record_latency: took    6.0 ms round trip,    5.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.9 ms,    3.8 ms for echo
2018-07-07 22:03:17,037 packet decoding sequence 22 for window 2: 312x259 took 2.2ms
2018-07-07 22:03:17,037 record_latency: took    5.0 ms round trip,    4.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    1.8 ms for echo
2018-07-07 22:03:17,077 packet decoding sequence 23 for window 2: 312x259 took 1.8ms
2018-07-07 22:03:17,077 record_latency: took    4.6 ms round trip,    4.6 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2018-07-07 22:03:17,181 packet decoding sequence 24 for window 2: 312x259 took 2.4ms
2018-07-07 22:03:17,181 record_latency: took    5.4 ms round trip,    5.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    3.0 ms for echo
2018-07-07 22:03:17,332 packet decoding sequence 25 for window 2: 312x259 took 2.9ms
2018-07-07 22:03:17,333 record_latency: took    6.7 ms round trip,    6.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2018-07-07 22:03:17,543 packet decoding sequence 26 for window 2: 312x259 took 1.4ms
2018-07-07 22:03:17,543 record_latency: took    3.4 ms round trip,    3.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    1.9 ms,    1.7 ms for echo
2018-07-07 22:03:17,562 calculate_batch_delay for wid=2 current batch delay=14, last update 2 seconds ago
2018-07-07 22:03:17,563 calculate_batch_delay for wid=2, 1947192 pixels updated since the last update
2018-07-07 22:03:17,563 calculate_batch_delay for wid=2, skipping - only 25 bytes sent since the last update
2018-07-07 22:03:17,590 packet decoding sequence 27 for window 2: 312x259 took 1.6ms
2018-07-07 22:03:17,590 record_latency: took    9.1 ms round trip,    9.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    7.4 ms,    7.3 ms for echo
2018-07-07 22:03:17,590 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:17,637 packet decoding sequence 28 for window 2: 312x259 took 2.3ms
2018-07-07 22:03:17,637 record_latency: took    7.1 ms round trip,    7.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.8 ms,    4.7 ms for echo
2018-07-07 22:03:17,690 packet decoding sequence 29 for window 2: 312x259 took 4.0ms
2018-07-07 22:03:17,690 record_latency: took    7.0 ms round trip,    6.9 for echo,    4.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.9 ms,    2.9 ms for echo
2018-07-07 22:03:17,739 packet decoding sequence 30 for window 2: 312x25 took 1.4ms
2018-07-07 22:03:17,739 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of     7800 pixels,       57 bytes sent over the network in    2.9 ms,    2.9 ms for echo
2018-07-07 22:03:17,769 packet decoding sequence 31 for window 2: 312x259 took 10.8ms
2018-07-07 22:03:17,769 record_latency: took   13.0 ms round trip,   12.7 for echo,   10.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.2 ms,    1.9 ms for echo
2018-07-07 22:03:17,965 packet decoding sequence 32 for window 2: 312x259 took 5.2ms
2018-07-07 22:03:17,965 record_latency: took    8.2 ms round trip,    8.0 for echo,    5.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    2.7 ms for echo
2018-07-07 22:03:18,122 packet decoding sequence 33 for window 2: 312x259 took 2.0ms
2018-07-07 22:03:18,123 record_latency: took    6.4 ms round trip,    6.3 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.4 ms,    4.3 ms for echo
2018-07-07 22:03:18,270 packet decoding sequence 34 for window 2: 312x259 took 1.7ms
2018-07-07 22:03:18,270 record_latency: took    5.3 ms round trip,    5.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.6 ms,    3.5 ms for echo
2018-07-07 22:03:18,308 packet decoding sequence 35 for window 2: 312x259 took 2.0ms
2018-07-07 22:03:18,309 record_latency: took    4.5 ms round trip,    4.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.5 ms,    2.4 ms for echo
2018-07-07 22:03:18,328 packet decoding sequence 36 for window 2: 312x259 took 2.0ms
2018-07-07 22:03:18,329 record_latency: took    4.1 ms round trip,    4.1 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.1 ms,    2.1 ms for echo
2018-07-07 22:03:18,346 packet decoding sequence 37 for window 2: 312x259 took 1.7ms
2018-07-07 22:03:18,346 record_latency: took    4.0 ms round trip,    3.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.2 ms,    2.1 ms for echo
2018-07-07 22:03:18,420 packet decoding sequence 38 for window 2: 312x259 took 2.2ms
2018-07-07 22:03:18,421 record_latency: took    7.6 ms round trip,    7.5 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    5.4 ms,    5.2 ms for echo
2018-07-07 22:03:18,505 packet decoding sequence 39 for window 2: 312x259 took 4.5ms
2018-07-07 22:03:18,505 record_latency: took    8.1 ms round trip,    8.0 for echo,    4.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.6 ms,    3.6 ms for echo
2018-07-07 22:03:18,563 calculate_batch_delay for wid=2 current batch delay=14, last update 3 seconds ago
2018-07-07 22:03:18,564 calculate_batch_delay for wid=2, 2924688 pixels updated since the last update
2018-07-07 22:03:18,565 calculate_batch_delay for wid=2, skipping - only 38 bytes sent since the last update
2018-07-07 22:03:18,710 packet decoding sequence 40 for window 2: 312x259 took 2.1ms
2018-07-07 22:03:18,711 record_latency: took    4.8 ms round trip,    4.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.7 ms,    1.9 ms for echo
2018-07-07 22:03:18,711 may_recalculate(2, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:18,735 packet decoding sequence 41 for window 2: 312x259 took 1.9ms
2018-07-07 22:03:18,735 record_latency: took    8.7 ms round trip,    8.5 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.8 ms,    6.6 ms for echo
2018-07-07 22:03:18,749 packet decoding sequence 42 for window 2: 312x259 took 1.7ms
2018-07-07 22:03:18,750 record_latency: took    5.0 ms round trip,    4.8 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.3 ms,    3.1 ms for echo
2018-07-07 22:03:18,783 packet decoding sequence 43 for window 2: 312x259 took 2.6ms
2018-07-07 22:03:18,783 record_latency: took    8.0 ms round trip,    7.9 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    5.3 ms,    5.3 ms for echo
2018-07-07 22:03:18,797 packet decoding sequence 44 for window 2: 312x259 took 1.9ms
2018-07-07 22:03:18,798 record_latency: took    4.7 ms round trip,    4.5 for echo,    1.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.8 ms,    2.6 ms for echo
2018-07-07 22:03:18,871 packet decoding sequence 45 for window 2: 312x259 took 3.3ms
2018-07-07 22:03:18,871 record_latency: took    6.0 ms round trip,    5.9 for echo,    3.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.7 ms,    2.7 ms for echo
2018-07-07 22:03:18,942 packet decoding sequence 46 for window 2: 312x259 took 2.7ms
2018-07-07 22:03:18,943 record_latency: took    7.2 ms round trip,    5.7 for echo,    2.0 for decoding of    80808 pixels,       61 bytes sent over the network in    4.5 ms,    3.1 ms for echo
2018-07-07 22:03:18,947 packet decoding sequence 47 for window 2: 312x259 took 2.0ms
2018-07-07 22:03:18,947 record_latency: took    4.6 ms round trip,    4.0 for echo,    2.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.6 ms,    2.0 ms for echo
2018-07-07 22:03:18,970 packet decoding sequence 48 for window 2: 312x259 took 1.9ms
2018-07-07 22:03:18,970 record_latency: took    4.1 ms round trip,    3.8 for echo,    1.0 for decoding of    80808 pixels,       61 bytes sent over the network in    2.2 ms,    2.0 ms for echo
2018-07-07 22:03:18,977 packet decoding sequence 49 for window 2: 312x51 took 1.7ms
2018-07-07 22:03:18,977 record_latency: took    4.2 ms round trip,    4.0 for echo,    1.0 for decoding of    15912 pixels,       60 bytes sent over the network in    2.5 ms,    2.3 ms for echo
2018-07-07 22:03:19,612 packet decoding sequence 26 for window 1: 734x459 took 5.0ms
2018-07-07 22:03:19,612 record_latency: took    9.6 ms round trip,    9.5 for echo,    4.0 for decoding of   336906 pixels,       61 bytes sent over the network in    4.6 ms,    4.5 ms for echo
2018-07-07 22:03:19,613 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:03:19,840 packet decoding sequence 27 for window 1: 12x20 took 1.5ms
2018-07-07 22:03:19,840 record_latency: took   13.1 ms round trip,   12.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   11.6 ms,   11.4 ms for echo
2018-07-07 22:03:19,885 packet decoding sequence 28 for window 1: 12x20 took 1.1ms
2018-07-07 22:03:19,885 record_latency: took    3.3 ms round trip,    3.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.1 ms,    2.0 ms for echo
2018-07-07 22:03:19,901 packet decoding sequence 29 for window 1: 12x20 took 1.6ms
2018-07-07 22:03:19,901 record_latency: took    5.2 ms round trip,    5.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.7 ms,    3.6 ms for echo
2018-07-07 22:03:20,057 packet decoding sequence 30 for window 1: 12x20 took 1.5ms
2018-07-07 22:03:20,057 record_latency: took    4.6 ms round trip,    4.5 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.1 ms,    3.0 ms for echo
2018-07-07 22:03:20,091 packet decoding sequence 31 for window 1: 12x20 took 1.1ms
2018-07-07 22:03:20,091 record_latency: took    3.2 ms round trip,    3.1 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    2.1 ms,    2.0 ms for echo
2018-07-07 22:03:20,566 calculate_batch_delay for wid=1 current batch delay=23, last update 9 seconds ago
2018-07-07 22:03:20,566 calculate_batch_delay for wid=1, 1690530 pixels updated since the last update
2018-07-07 22:03:20,567 calculate_batch_delay for wid=1, skipping - only 30 bytes sent since the last update
2018-07-07 22:03:25,228 total_time=184690, total_pixels=5776116
2018-07-07 22:03:26,454 total_time=184690, total_pixels=5776116
2018-07-07 22:03:26,557 total_time=184690, total_pixels=5776116
2018-07-07 22:03:26,659 total_time=184690, total_pixels=5776116
2018-07-07 22:03:26,759 total_time=184690, total_pixels=5776116
2018-07-07 22:03:26,856 total_time=184690, total_pixels=5776116
2018-07-07 22:03:26,962 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,063 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,161 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,260 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,362 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,469 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,564 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,663 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,764 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,862 total_time=184690, total_pixels=5776116
2018-07-07 22:03:27,967 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,066 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,165 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,268 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,367 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,469 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,570 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,669 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,774 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,872 total_time=184690, total_pixels=5776116
2018-07-07 22:03:28,973 total_time=184690, total_pixels=5776116
2018-07-07 22:03:29,080 total_time=184690, total_pixels=5776116
2018-07-07 22:03:29,174 total_time=184690, total_pixels=5776116
2018-07-07 22:03:29,275 total_time=184690, total_pixels=5776116
2018-07-07 22:03:29,322 packet decoding sequence 32 for window 1: 12x20 took 2.7ms
2018-07-07 22:03:29,324 record_latency: took    7.8 ms round trip,    7.2 for echo,    2.0 for decoding of      240 pixels,       63 bytes sent over the network in    5.2 ms,    4.6 ms for echo
2018-07-07 22:03:29,327 packet decoding sequence 33 for window 1: 12x20 took 2.3ms
2018-07-07 22:03:29,328 record_latency: took    8.9 ms round trip,    8.8 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.7 ms,    6.5 ms for echo
2018-07-07 22:03:29,355 packet decoding sequence 34 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:29,356 record_latency: took    5.0 ms round trip,    5.0 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.1 ms,    4.1 ms for echo
2018-07-07 22:03:29,376 total_time=190544, total_pixels=5776836
2018-07-07 22:03:29,397 packet decoding sequence 35 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:29,397 record_latency: took    3.1 ms round trip,    3.0 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.2 ms,    2.1 ms for echo
2018-07-07 22:03:29,439 packet decoding sequence 36 for window 1: 12x20 took 1.3ms
2018-07-07 22:03:29,439 record_latency: took    7.7 ms round trip,    7.6 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.4 ms,    6.3 ms for echo
2018-07-07 22:03:29,477 total_time=192710, total_pixels=5777316
2018-07-07 22:03:29,579 total_time=192710, total_pixels=5777316
2018-07-07 22:03:29,657 packet decoding sequence 37 for window 1: 12x20 took 1.7ms
2018-07-07 22:03:29,658 record_latency: took    4.9 ms round trip,    4.8 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.2 ms,    3.1 ms for echo
2018-07-07 22:03:29,677 total_time=194447, total_pixels=5777556
2018-07-07 22:03:29,709 packet decoding sequence 38 for window 1: 12x20 took 0.9ms
2018-07-07 22:03:29,709 record_latency: took    6.8 ms round trip,    6.7 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.9 ms,    5.8 ms for echo
2018-07-07 22:03:29,777 total_time=195364, total_pixels=5777796
2018-07-07 22:03:29,804 packet decoding sequence 39 for window 1: 12x20 took 2.1ms
2018-07-07 22:03:29,804 record_latency: took   46.1 ms round trip,   46.0 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in   44.0 ms,   43.9 ms for echo
2018-07-07 22:03:29,810 packet decoding sequence 40 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:29,811 record_latency: took    3.3 ms round trip,    2.8 for echo,    0.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.3 ms,    1.9 ms for echo
2018-07-07 22:03:29,811 may_recalculate(1, 240) total 2160 pixels, scheduling recalculate work item
2018-07-07 22:03:29,812 calculate_batch_delay for wid=1 current batch delay=23, last update 18 seconds ago
2018-07-07 22:03:29,812 calculate_batch_delay for wid=1, 1692690 pixels updated since the last update
2018-07-07 22:03:29,812 calculate_batch_delay for wid=1, skipping - only 39 bytes sent since the last update
2018-07-07 22:03:29,882 total_time=198404, total_pixels=5778276
2018-07-07 22:03:29,986 total_time=198404, total_pixels=5778276
2018-07-07 22:03:30,084 total_time=198404, total_pixels=5778276
2018-07-07 22:03:30,207 total_time=198404, total_pixels=5778276
2018-07-07 22:03:30,208 packet decoding sequence 1 for window 3: 130x54 took 1.4ms
2018-07-07 22:03:30,209 record_latency: took   65.8 ms round trip,   65.8 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in   64.4 ms,   64.4 ms for echo
2018-07-07 22:03:30,209 may_recalculate(3, 7020) total 7020 pixels, scheduling recalculate work item
2018-07-07 22:03:30,224 packet decoding sequence 2 for window 3: 130x54 took 18.8ms
2018-07-07 22:03:30,224 record_latency: took   76.5 ms round trip,   76.4 for echo,   18.0 for decoding of     7020 pixels,       57 bytes sent over the network in   57.7 ms,   57.6 ms for echo
2018-07-07 22:03:30,255 packet decoding sequence 41 for window 1: 734x459 took 4.3ms
2018-07-07 22:03:30,255 record_latency: took    6.4 ms round trip,    6.4 for echo,    4.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.1 ms,    2.0 ms for echo
2018-07-07 22:03:30,255 may_recalculate(1, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:03:30,306 total_time=222925, total_pixels=6129222
2018-07-07 22:03:30,407 total_time=222925, total_pixels=6129222
2018-07-07 22:03:30,513 total_time=222925, total_pixels=6129222
2018-07-07 22:03:30,626 total_time=225957, total_pixels=6189744
2018-07-07 22:03:30,730 total_time=273713, total_pixels=6250266
2018-07-07 22:03:30,754 packet decoding sequence 42 for window 1: 279x25 took 119.7ms
2018-07-07 22:03:30,754 record_latency: took  161.1 ms round trip,  160.9 for echo,  119.0 for decoding of     6975 pixels,       57 bytes sent over the network in   41.4 ms,   41.2 ms for echo
2018-07-07 22:03:30,757 packet decoding sequence 1 for window 5: 312x259 took 2.4ms
2018-07-07 22:03:30,758 record_latency: took  112.3 ms round trip,  112.2 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in  110.0 ms,  109.9 ms for echo
2018-07-07 22:03:30,758 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:30,761 packet decoding sequence 43 for window 1: 234x25 took 4.3ms
2018-07-07 22:03:30,762 record_latency: took   43.7 ms round trip,   43.6 for echo,    4.0 for decoding of     5850 pixels,       57 bytes sent over the network in   39.3 ms,   39.2 ms for echo
2018-07-07 22:03:30,814 calculate_batch_delay for wid=1 current batch delay=23, last update 19 seconds ago
2018-07-07 22:03:30,814 calculate_batch_delay for wid=1, 2042421 pixels updated since the last update
2018-07-07 22:03:30,815 calculate_batch_delay for wid=1, skipping - only 42 bytes sent since the last update
2018-07-07 22:03:30,815 calculate_batch_delay for wid=5 current batch delay=19, last update 416875 seconds ago
2018-07-07 22:03:30,816 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-07 22:03:30,816 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 30, 'recent': 30}
2018-07-07 22:03:30,816 update_batch_delay: damage-rate                  : 0.99,0.08  {'max_latency': 100, 'elapsed': 205}
2018-07-07 22:03:30,816 update_batch_delay: client-latency               : 1.54,0.32  {'target': 6, 'weight_multiplier': 429, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2376, 'div': 1000, 'avg': 22, 'recent': 52}
2018-07-07 22:03:30,817 update_batch_delay: client-ping-latency          : 1.14,0.07  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1307, 'div': 1000, 'avg': 8, 'recent': 16}
2018-07-07 22:03:30,817 update_batch_delay: server-ping-latency          : 0.49,0.14  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 239, 'div': 1000, 'avg': 3, 'recent': 2}
2018-07-07 22:03:30,817 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:30,817 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:30,817 update_batch_delay: compression-work-queue       : 0.00,0.05  {'target': 1000, 'weight_multiplier': 45, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 2, 'recent': 0}
2018-07-07 22:03:30,818 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:30,818 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:03:30,818 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:03:30,818 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:03:30,819 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:03:30,819 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:03:30,819 update_batch_delay: delay=16
2018-07-07 22:03:30,829 total_time=400127, total_pixels=6343899
2018-07-07 22:03:30,929 total_time=400127, total_pixels=6343899
2018-07-07 22:03:31,025 total_time=400127, total_pixels=6343899
2018-07-07 22:03:31,106 packet decoding sequence 2 for window 5: 312x259 took 2.2ms
2018-07-07 22:03:31,107 record_latency: took    8.2 ms round trip,    8.2 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    6.0 ms,    5.9 ms for echo
2018-07-07 22:03:31,107 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:31,132 total_time=402358, total_pixels=6424707
2018-07-07 22:03:31,165 packet decoding sequence 3 for window 5: 312x259 took 1.6ms
2018-07-07 22:03:31,165 record_latency: took   21.2 ms round trip,   21.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   19.6 ms,   19.6 ms for echo
2018-07-07 22:03:31,203 packet decoding sequence 4 for window 5: 312x259 took 2.7ms
2018-07-07 22:03:31,203 record_latency: took    5.7 ms round trip,    5.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.0 ms,    2.7 ms for echo
2018-07-07 22:03:31,226 total_time=406700, total_pixels=6586323
2018-07-07 22:03:31,267 packet decoding sequence 5 for window 5: 312x259 took 1.4ms
2018-07-07 22:03:31,268 record_latency: took   30.2 ms round trip,   30.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   28.8 ms,   28.7 ms for echo
2018-07-07 22:03:31,306 packet decoding sequence 6 for window 5: 312x259 took 2.6ms
2018-07-07 22:03:31,307 record_latency: took    5.4 ms round trip,    5.4 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.8 ms for echo
2018-07-07 22:03:31,332 total_time=410727, total_pixels=6747939
2018-07-07 22:03:31,357 packet decoding sequence 7 for window 5: 312x259 took 1.5ms
2018-07-07 22:03:31,357 record_latency: took   29.2 ms round trip,   29.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   27.7 ms,   27.6 ms for echo
2018-07-07 22:03:31,408 packet decoding sequence 8 for window 5: 312x259 took 3.8ms
2018-07-07 22:03:31,409 record_latency: took    8.1 ms round trip,    8.0 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    4.2 ms,    4.1 ms for echo
2018-07-07 22:03:31,434 total_time=416063, total_pixels=6909555
2018-07-07 22:03:31,469 packet decoding sequence 9 for window 5: 312x259 took 1.6ms
2018-07-07 22:03:31,469 record_latency: took   23.5 ms round trip,   23.4 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   21.9 ms,   21.8 ms for echo
2018-07-07 22:03:31,533 total_time=417652, total_pixels=6990363
2018-07-07 22:03:31,607 packet decoding sequence 10 for window 5: 312x259 took 2.9ms
2018-07-07 22:03:31,608 record_latency: took    6.1 ms round trip,    6.0 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.1 ms,    3.0 ms for echo
2018-07-07 22:03:31,632 total_time=420597, total_pixels=7071171
2018-07-07 22:03:31,666 packet decoding sequence 11 for window 5: 312x259 took 1.6ms
2018-07-07 22:03:31,666 record_latency: took   22.1 ms round trip,   21.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   20.4 ms,   20.3 ms for echo
2018-07-07 22:03:31,702 packet decoding sequence 12 for window 5: 312x259 took 1.4ms
2018-07-07 22:03:31,702 record_latency: took    3.3 ms round trip,    3.2 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    1.9 ms,    1.8 ms for echo
2018-07-07 22:03:31,731 total_time=423651, total_pixels=7232787
2018-07-07 22:03:31,814 calculate_batch_delay for wid=5 current batch delay=16, last update 0 seconds ago
2018-07-07 22:03:31,815 calculate_batch_delay for wid=5, 888888 pixels updated since the last update
2018-07-07 22:03:31,819 calculate_batch_delay for wid=5, skipping - only 11 bytes sent since the last update
2018-07-07 22:03:31,834 total_time=423651, total_pixels=7232787
2018-07-07 22:03:31,895 packet decoding sequence 13 for window 5: 312x259 took 2.1ms
2018-07-07 22:03:31,896 record_latency: took    4.5 ms round trip,    4.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.1 ms for echo
2018-07-07 22:03:31,896 may_recalculate(5, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:03:31,933 total_time=425768, total_pixels=7313595
2018-07-07 22:03:32,007 packet decoding sequence 14 for window 5: 312x259 took 1.8ms
2018-07-07 22:03:32,007 record_latency: took    5.4 ms round trip,    5.3 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    3.6 ms,    3.6 ms for echo
2018-07-07 22:03:32,036 total_time=427521, total_pixels=7394403
2018-07-07 22:03:32,067 packet decoding sequence 15 for window 5: 312x259 took 1.6ms
2018-07-07 22:03:32,068 record_latency: took   18.1 ms round trip,   18.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   16.5 ms,   16.4 ms for echo
2018-07-07 22:03:32,131 total_time=429101, total_pixels=7475211
2018-07-07 22:03:32,163 packet decoding sequence 16 for window 5: 312x259 took 1.7ms
2018-07-07 22:03:32,163 record_latency: took   53.0 ms round trip,   52.9 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   51.3 ms,   51.2 ms for echo
2018-07-07 22:03:32,169 packet decoding sequence 17 for window 5: 312x259 took 7.6ms
2018-07-07 22:03:32,169 record_latency: took   24.7 ms round trip,   24.7 for echo,    7.0 for decoding of    80808 pixels,       60 bytes sent over the network in   17.1 ms,   17.1 ms for echo
2018-07-07 22:03:32,229 total_time=438406, total_pixels=7636827
2018-07-07 22:03:32,342 total_time=438406, total_pixels=7636827
2018-07-07 22:03:32,361 packet decoding sequence 18 for window 5: 312x259 took 3.1ms
2018-07-07 22:03:32,361 record_latency: took   32.7 ms round trip,   32.5 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in   29.5 ms,   29.4 ms for echo
2018-07-07 22:03:32,369 packet decoding sequence 19 for window 5: 312x259 took 12.6ms
2018-07-07 22:03:32,369 record_latency: took   22.4 ms round trip,   22.3 for echo,   12.0 for decoding of    80808 pixels,       60 bytes sent over the network in    9.8 ms,    9.7 ms for echo
2018-07-07 22:03:32,396 packet decoding sequence 20 for window 5: 312x259 took 1.9ms
2018-07-07 22:03:32,397 record_latency: took    4.3 ms round trip,    4.0 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.1 ms for echo
2018-07-07 22:03:32,439 total_time=456002, total_pixels=7879251
2018-07-07 22:03:32,475 packet decoding sequence 21 for window 5: 312x259 took 1.7ms
2018-07-07 22:03:32,476 record_latency: took   44.9 ms round trip,   44.8 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   43.2 ms,   43.1 ms for echo
2018-07-07 22:03:32,480 packet decoding sequence 22 for window 5: 312x259 took 6.1ms
2018-07-07 22:03:32,480 record_latency: took   34.4 ms round trip,   34.3 for echo,    6.0 for decoding of    80808 pixels,       60 bytes sent over the network in   28.3 ms,   28.3 ms for echo
2018-07-07 22:03:32,533 total_time=463770, total_pixels=8040867
2018-07-07 22:03:32,569 packet decoding sequence 23 for window 5: 312x259 took 1.7ms
2018-07-07 22:03:32,570 record_latency: took   58.2 ms round trip,   58.1 for echo,    1.0 for decoding of    80808 pixels,       60 bytes sent over the network in   56.5 ms,   56.3 ms for echo
2018-07-07 22:03:32,635 total_time=465514, total_pixels=8121675
2018-07-07 22:03:32,743 total_time=465514, total_pixels=8121675
2018-07-07 22:03:32,860 total_time=465514, total_pixels=8121675
2018-07-07 22:03:32,889 packet decoding sequence 1 for window 6: 244x180 took 2.7ms
2018-07-07 22:03:32,890 record_latency: took   95.4 ms round trip,   95.4 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in   92.8 ms,   92.7 ms for echo
2018-07-07 22:03:32,890 may_recalculate(6, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-07 22:03:32,892 packet decoding sequence 44 for window 1: 190x25 took 5.1ms
2018-07-07 22:03:32,892 record_latency: took   15.8 ms round trip,   15.8 for echo,    5.0 for decoding of     4750 pixels,       58 bytes sent over the network in   10.7 ms,   10.7 ms for echo
2018-07-07 22:03:32,893 may_recalculate(1, 4750) total 4750 pixels, scheduling recalculate work item
2018-07-07 22:03:32,955 total_time=473282, total_pixels=8170345
2018-07-07 22:03:33,058 total_time=473282, total_pixels=8170345
2018-07-07 22:03:33,161 total_time=473282, total_pixels=8170345
2018-07-07 22:03:33,197 packet decoding sequence 2 for window 6: 244x180 took 1.4ms
2018-07-07 22:03:33,197 record_latency: took   44.8 ms round trip,   44.2 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   43.5 ms,   42.8 ms for echo
2018-07-07 22:03:33,258 total_time=474650, total_pixels=8214265
2018-07-07 22:03:33,301 packet decoding sequence 3 for window 6: 244x180 took 2.0ms
2018-07-07 22:03:33,301 record_latency: took   51.0 ms round trip,   50.9 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in   48.9 ms,   48.8 ms for echo
2018-07-07 22:03:33,356 total_time=476687, total_pixels=8258185
2018-07-07 22:03:33,394 packet decoding sequence 4 for window 6: 244x180 took 1.3ms
2018-07-07 22:03:33,394 record_latency: took   47.9 ms round trip,   47.8 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   46.5 ms,   46.4 ms for echo
2018-07-07 22:03:33,434 packet decoding sequence 5 for window 6: 244x180 took 1.2ms
2018-07-07 22:03:33,435 record_latency: took    4.8 ms round trip,    4.0 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.5 ms,    2.8 ms for echo
2018-07-07 22:03:33,456 total_time=479231, total_pixels=8346025
2018-07-07 22:03:33,502 packet decoding sequence 6 for window 6: 244x180 took 2.3ms
2018-07-07 22:03:33,502 record_latency: took   39.3 ms round trip,   39.2 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in   37.0 ms,   36.9 ms for echo
2018-07-07 22:03:33,528 packet decoding sequence 7 for window 6: 244x180 took 2.0ms
2018-07-07 22:03:33,528 record_latency: took    4.7 ms round trip,    4.6 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.7 ms,    2.6 ms for echo
2018-07-07 22:03:33,561 total_time=483580, total_pixels=8433865
2018-07-07 22:03:33,661 total_time=483580, total_pixels=8433865
2018-07-07 22:03:33,728 packet decoding sequence 8 for window 6: 244x180 took 1.3ms
2018-07-07 22:03:33,728 record_latency: took    4.9 ms round trip,    4.9 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.6 ms,    3.6 ms for echo
2018-07-07 22:03:33,770 total_time=484873, total_pixels=8477785
2018-07-07 22:03:33,811 packet decoding sequence 9 for window 6: 244x180 took 1.3ms
2018-07-07 22:03:33,811 record_latency: took   48.7 ms round trip,   48.6 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   47.4 ms,   47.4 ms for echo
2018-07-07 22:03:33,814 calculate_batch_delay for wid=1 current batch delay=23, last update 22 seconds ago
2018-07-07 22:03:33,815 calculate_batch_delay for wid=1, 2047171 pixels updated since the last update
2018-07-07 22:03:33,815 calculate_batch_delay for wid=1, skipping - only 43 bytes sent since the last update
2018-07-07 22:03:33,816 calculate_batch_delay for wid=6 current batch delay=22, last update 416878 seconds ago
2018-07-07 22:03:33,816 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-07 22:03:33,817 update_batch_delay: client-decode-speed          : 0.97,0.00  {'avg': 36, 'recent': 35}
2018-07-07 22:03:33,817 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 83}
2018-07-07 22:03:33,817 update_batch_delay: client-latency               : 1.12,0.15  {'target': 6, 'weight_multiplier': 427, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1254, 'div': 1000, 'avg': 21, 'recent': 26}
2018-07-07 22:03:33,818 update_batch_delay: client-ping-latency          : 0.90,0.06  {'target': 6, 'weight_multiplier': 179, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 805, 'div': 1000, 'avg': 7, 'recent': 9}
2018-07-07 22:03:33,818 update_batch_delay: server-ping-latency          : 0.81,0.07  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 650, 'div': 1000, 'avg': 4, 'recent': 6}
2018-07-07 22:03:33,818 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:33,820 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:33,824 update_batch_delay: compression-work-queue       : 0.00,0.04  {'target': 1000, 'weight_multiplier': 39, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 1, 'recent': 0}
2018-07-07 22:03:33,824 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:33,825 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:03:33,827 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:03:33,828 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:03:33,828 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:03:33,828 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:03:33,829 update_batch_delay: delay=16
2018-07-07 22:03:33,864 total_time=486129, total_pixels=8521705
2018-07-07 22:03:33,902 packet decoding sequence 10 for window 6: 244x180 took 1.4ms
2018-07-07 22:03:33,903 record_latency: took   47.8 ms round trip,   47.4 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in   46.5 ms,   46.1 ms for echo
2018-07-07 22:03:33,903 may_recalculate(6, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-07 22:03:33,960 total_time=487485, total_pixels=8565625
2018-07-07 22:03:34,084 total_time=487485, total_pixels=8565625
2018-07-07 22:03:34,168 total_time=487485, total_pixels=8565625
2018-07-07 22:03:34,212 packet decoding sequence 1 for window 7: 237x156 took 3.9ms
2018-07-07 22:03:34,212 record_latency: took  112.9 ms round trip,  112.5 for echo,    3.0 for decoding of    36972 pixels,       60 bytes sent over the network in  109.0 ms,  108.5 ms for echo
2018-07-07 22:03:34,213 may_recalculate(7, 36972) total 36972 pixels, scheduling recalculate work item
2018-07-07 22:03:34,215 packet decoding sequence 45 for window 1: 121x25 took 5.1ms
2018-07-07 22:03:34,215 record_latency: took   43.3 ms round trip,   43.2 for echo,    5.0 for decoding of     3025 pixels,       57 bytes sent over the network in   38.2 ms,   38.1 ms for echo
2018-07-07 22:03:34,215 may_recalculate(1, 3025) total 3025 pixels, scheduling recalculate work item
2018-07-07 22:03:34,269 total_time=496512, total_pixels=8605622
2018-07-07 22:03:34,375 total_time=496512, total_pixels=8605622
2018-07-07 22:03:34,475 total_time=496512, total_pixels=8605622
2018-07-07 22:03:34,524 packet decoding sequence 1 for window 8: 248x181 took 1.7ms
2018-07-07 22:03:34,524 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.5 ms,    2.4 ms for echo
2018-07-07 22:03:34,525 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-07 22:03:34,577 total_time=498181, total_pixels=8650510
2018-07-07 22:03:34,620 packet decoding sequence 46 for window 1: 76x25 took 2.6ms
2018-07-07 22:03:34,620 record_latency: took   28.9 ms round trip,   28.8 for echo,    2.0 for decoding of     1900 pixels,       57 bytes sent over the network in   26.3 ms,   26.3 ms for echo
2018-07-07 22:03:34,674 total_time=500748, total_pixels=8652410
2018-07-07 22:03:34,714 packet decoding sequence 2 for window 8: 248x181 took 1.4ms
2018-07-07 22:03:34,714 record_latency: took   27.2 ms round trip,   27.1 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   25.8 ms,   25.7 ms for echo
2018-07-07 22:03:34,772 total_time=502176, total_pixels=8697298
2018-07-07 22:03:34,815 calculate_batch_delay for wid=1 current batch delay=23, last update 23 seconds ago
2018-07-07 22:03:34,815 calculate_batch_delay for wid=1, 2052096 pixels updated since the last update
2018-07-07 22:03:34,815 calculate_batch_delay for wid=1, skipping - only 45 bytes sent since the last update
2018-07-07 22:03:34,816 calculate_batch_delay for wid=8 current batch delay=25, last update 416879 seconds ago
2018-07-07 22:03:34,816 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-07 22:03:34,817 update_batch_delay: client-decode-speed          : 0.99,0.00  {'avg': 35, 'recent': 35}
2018-07-07 22:03:34,817 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 143, 'elapsed': 177}
2018-07-07 22:03:34,817 update_batch_delay: client-latency               : 1.14,0.16  {'target': 6, 'weight_multiplier': 427, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1290, 'div': 1000, 'avg': 23, 'recent': 29}
2018-07-07 22:03:34,817 update_batch_delay: client-ping-latency          : 0.73,0.09  {'target': 6, 'weight_multiplier': 179, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 533, 'div': 1000, 'avg': 6, 'recent': 6}
2018-07-07 22:03:34,817 update_batch_delay: server-ping-latency          : 0.52,0.11  {'target': 6, 'weight_multiplier': 163, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 271, 'div': 1000, 'avg': 3, 'recent': 2}
2018-07-07 22:03:34,818 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:34,818 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 44888000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:34,818 update_batch_delay: compression-work-queue       : 0.00,0.03  {'target': 1000, 'weight_multiplier': 34, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 1, 'recent': 0}
2018-07-07 22:03:34,818 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 44888000, 'avg': 0, 'recent': 0}
2018-07-07 22:03:34,818 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:03:34,818 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:03:34,819 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:03:34,819 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:03:34,819 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:03:34,819 update_batch_delay: delay=18
2018-07-07 22:03:34,821 packet decoding sequence 3 for window 8: 248x181 took 1.2ms
2018-07-07 22:03:34,822 record_latency: took   11.9 ms round trip,   11.6 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   10.7 ms,   10.4 ms for echo
2018-07-07 22:03:34,823 may_recalculate(8, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-07 22:03:34,874 total_time=503385, total_pixels=8742186
2018-07-07 22:03:34,978 total_time=503385, total_pixels=8742186
2018-07-07 22:03:35,021 packet decoding sequence 4 for window 8: 248x181 took 1.5ms
2018-07-07 22:03:35,021 record_latency: took   62.8 ms round trip,   62.4 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   61.3 ms,   60.9 ms for echo
2018-07-07 22:03:35,073 total_time=504886, total_pixels=8787074
2018-07-07 22:03:35,181 total_time=504886, total_pixels=8787074
2018-07-07 22:03:35,286 total_time=504886, total_pixels=8787074
2018-07-07 22:03:35,346 packet decoding sequence 5 for window 8: 248x181 took 1.7ms
2018-07-07 22:03:35,347 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in    2.6 ms,    2.6 ms for echo
2018-07-07 22:03:35,379 total_time=506571, total_pixels=8831962
2018-07-07 22:03:35,480 total_time=506571, total_pixels=8831962
2018-07-07 22:03:35,583 total_time=506571, total_pixels=8831962
2018-07-07 22:03:35,677 total_time=506571, total_pixels=8831962
2018-07-07 22:03:35,784 total_time=506571, total_pixels=8831962
2018-07-07 22:03:35,883 total_time=506571, total_pixels=8831962
2018-07-07 22:03:35,935 packet decoding sequence 47 for window 1: 363x45 took 1.3ms
2018-07-07 22:03:35,935 record_latency: took   28.9 ms round trip,   28.9 for echo,    1.0 for decoding of    16335 pixels,       61 bytes sent over the network in   27.6 ms,   27.6 ms for echo
2018-07-07 22:03:35,936 may_recalculate(1, 16335) total 16335 pixels, scheduling recalculate work item
2018-07-07 22:03:35,985 total_time=507890, total_pixels=8848297
2018-07-07 22:03:36,053 packet decoding sequence 48 for window 1: 12x20 took 1.3ms
2018-07-07 22:03:36,054 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.9 ms,    2.8 ms for echo
2018-07-07 22:03:36,085 total_time=509177, total_pixels=8848537
2018-07-07 22:03:36,143 packet decoding sequence 49 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:36,143 record_latency: took   43.3 ms round trip,   43.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   42.3 ms,   42.2 ms for echo
2018-07-07 22:03:36,145 packet decoding sequence 50 for window 1: 12x20 took 3.3ms
2018-07-07 22:03:36,146 record_latency: took   15.0 ms round trip,   15.0 for echo,    3.0 for decoding of      240 pixels,       55 bytes sent over the network in   11.7 ms,   11.7 ms for echo
2018-07-07 22:03:36,183 total_time=513547, total_pixels=8849017
2018-07-07 22:03:36,230 packet decoding sequence 51 for window 1: 12x20 took 1.1ms
2018-07-07 22:03:36,231 record_latency: took   40.0 ms round trip,   39.8 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   38.9 ms,   38.6 ms for echo
2018-07-07 22:03:36,251 packet decoding sequence 52 for window 1: 12x20 took 1.2ms
2018-07-07 22:03:36,251 record_latency: took   10.7 ms round trip,   10.6 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    9.5 ms,    9.4 ms for echo
2018-07-07 22:03:36,290 total_time=515882, total_pixels=8849497
2018-07-07 22:03:36,332 packet decoding sequence 53 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:36,332 record_latency: took    3.0 ms round trip,    2.6 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.1 ms,    1.6 ms for echo
2018-07-07 22:03:36,387 total_time=516844, total_pixels=8849737
2018-07-07 22:03:36,408 packet decoding sequence 54 for window 1: 12x20 took 1.1ms
2018-07-07 22:03:36,408 record_latency: took   30.8 ms round trip,   30.5 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   29.7 ms,   29.4 ms for echo
2018-07-07 22:03:36,488 total_time=517942, total_pixels=8849977
2018-07-07 22:03:36,549 packet decoding sequence 55 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:36,549 record_latency: took    4.3 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    3.3 ms,    3.2 ms for echo
2018-07-07 22:03:36,588 total_time=518945, total_pixels=8850217
2018-07-07 22:03:36,639 packet decoding sequence 56 for window 1: 12x20 took 1.0ms
2018-07-07 22:03:36,639 record_latency: took    3.4 ms round trip,    3.4 for echo,    0.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.5 ms,    2.4 ms for echo
2018-07-07 22:03:36,692 total_time=519899, total_pixels=8850457
2018-07-07 22:03:36,792 total_time=519899, total_pixels=8850457
2018-07-07 22:03:36,815 calculate_batch_delay for wid=1 current batch delay=23, last update 25 seconds ago
2018-07-07 22:03:36,816 calculate_batch_delay for wid=1, 2070591 pixels updated since the last update
2018-07-07 22:03:36,816 calculate_batch_delay for wid=1, skipping - only 55 bytes sent since the last update
2018-07-07 22:03:36,893 total_time=519899, total_pixels=8850457
2018-07-07 22:03:36,994 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,095 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,195 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,297 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,402 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,506 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,596 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,700 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,803 total_time=519899, total_pixels=8850457
2018-07-07 22:03:37,903 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,002 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,105 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,206 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,306 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,406 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,506 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,603 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,709 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,809 total_time=519899, total_pixels=8850457
2018-07-07 22:03:38,913 total_time=519899, total_pixels=8850457
2018-07-07 22:03:39,011 total_time=519899, total_pixels=8850457
2018-07-07 22:03:41,669 xpra client 1 disconnected.
2018-07-07 22:03:41,681 sound source stopping
2018-07-07 22:05:13,034 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:05:13,041 Handshake complete; enabling connection
2018-07-07 22:05:13,048  automatic picture encoding enabled, also available:
2018-07-07 22:05:13,048   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
2018-07-07 22:05:13,050  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.rVpPxx.mmap
2018-07-07 22:05:13,051 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
2018-07-07 22:05:13,051  connected from 'beelink' as 'steved' - 'Steve Dee'
2018-07-07 22:05:13,054  client root window size is 2880x900 with 1 display:
2018-07-07 22:05:13,055   :0.0 (761x237 mm - DPI: 96x96)
2018-07-07 22:05:13,055     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
2018-07-07 22:05:13,056     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
2018-07-07 22:05:13,064 best resolution matching 2880x900 is unchanged: 2880x960
2018-07-07 22:05:13,070 setting key repeat rate from client: 500ms delay / 50ms interval
2018-07-07 22:05:13,073 keyboard mapping already configured (skipped)
2018-07-07 22:05:13,117 client @03.920 Xpra X11 server version 2.3.2-r19729 64-bit
2018-07-07 22:05:13,125 client @03.921  running on Linux Ubuntu 18.04 bionic
2018-07-07 22:05:13,143 client @03.929 Attached to :1
2018-07-07 22:05:13,154 client @03.930  (press Control-C to detach)
2018-07-07 22:05:13,187 total_time=0, total_pixels=0
2018-07-07 22:05:13,238 client @04.042 server does not support xi input devices
2018-07-07 22:05:13,238 client @04.042  server uses: xtest
2018-07-07 22:05:13,432 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:05:13,432 New unix-domain connection received on /home/steved/.xpra/beelink-1
2018-07-07 22:05:15,032 using pulseaudio device:
2018-07-07 22:05:15,032  'Monitor of Xpra Speaker'
2018-07-07 22:05:17,457 sound source using 'opus' audio codec
2018-07-07 22:05:36,315 packet decoding sequence 1 for window 9: 734x459 took 8.4ms
2018-07-07 22:05:36,315 record_latency: took   26.0 ms round trip,   26.0 for echo,    8.0 for decoding of   336906 pixels,       57 bytes sent over the network in   17.6 ms,   17.5 ms for echo
2018-07-07 22:05:36,315 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:05:36,316 calculate_batch_delay for wid=9 current batch delay=29, last update 417001 seconds ago
2018-07-07 22:05:36,317 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:05:36,317 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 26, 'recent': 26}
2018-07-07 22:05:36,318 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 32}
2018-07-07 22:05:36,318 update_batch_delay: client-latency               : 0.87,0.40  {'target': 22, 'weight_multiplier': 1089, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 750, 'div': 1000, 'avg': 17, 'recent': 17}
2018-07-07 22:05:36,318 update_batch_delay: client-ping-latency          : 0.60,0.13  {'target': 7, 'weight_multiplier': 198, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 360, 'div': 1000, 'avg': 2, 'recent': 2}
2018-07-07 22:05:36,320 update_batch_delay: server-ping-latency          : 0.58,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 332, 'div': 1000, 'avg': 5, 'recent': 3}
2018-07-07 22:05:36,320 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:36,320 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:36,321 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:36,322 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 336906000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:36,322 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 22:05:36,322 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 22:05:36,322 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:05:36,322 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:05:36,322 update_batch_delay: soft-expired                 : 1.00,1.00  {'count': 1}
2018-07-07 22:05:36,323 update_batch_delay: delay=23
2018-07-07 22:05:36,544 packet decoding sequence 2 for window 9: 12x20 took 2.2ms
2018-07-07 22:05:36,545 record_latency: took    7.2 ms round trip,    7.1 for echo,    2.0 for decoding of      240 pixels,       59 bytes sent over the network in    5.0 ms,    4.8 ms for echo
2018-07-07 22:05:36,870 packet decoding sequence 3 for window 9: 734x459 took 3.8ms
2018-07-07 22:05:36,870 record_latency: took    7.5 ms round trip,    7.3 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.7 ms,    3.5 ms for echo
2018-07-07 22:05:36,871 may_recalculate(9, 336906) total 337146 pixels, scheduling recalculate work item
2018-07-07 22:05:36,916 packet decoding sequence 4 for window 9: 12x20 took 1.6ms
2018-07-07 22:05:36,916 record_latency: took    4.3 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.7 ms,    2.4 ms for echo
2018-07-07 22:05:36,949 packet decoding sequence 5 for window 9: 12x20 took 2.2ms
2018-07-07 22:05:36,949 record_latency: took    8.0 ms round trip,    7.3 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.8 ms,    5.0 ms for echo
2018-07-07 22:05:36,986 packet decoding sequence 6 for window 9: 12x20 took 1.5ms
2018-07-07 22:05:36,987 record_latency: took    5.5 ms round trip,    5.4 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    4.0 ms,    3.9 ms for echo
2018-07-07 22:05:36,992 packet decoding sequence 7 for window 9: 12x20 took 1.2ms
2018-07-07 22:05:36,992 record_latency: took    7.8 ms round trip,    7.3 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.6 ms,    6.0 ms for echo
2018-07-07 22:05:36,994 packet decoding sequence 8 for window 9: 12x20 took 3.4ms
2018-07-07 22:05:36,994 record_latency: took    8.8 ms round trip,    8.4 for echo,    3.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.4 ms,    5.0 ms for echo
2018-07-07 22:05:37,316 calculate_batch_delay for wid=9 current batch delay=23, last update 1 seconds ago
2018-07-07 22:05:37,317 calculate_batch_delay for wid=9, 338346 pixels updated since the last update
2018-07-07 22:05:37,317 calculate_batch_delay for wid=9, skipping - only 7 bytes sent since the last update
2018-07-07 22:05:37,879 packet decoding sequence 9 for window 9: 12x20 took 4.3ms
2018-07-07 22:05:37,879 record_latency: took  205.2 ms round trip,  205.1 for echo,    4.0 for decoding of      240 pixels,       56 bytes sent over the network in  200.9 ms,  200.8 ms for echo
2018-07-07 22:05:37,954 packet decoding sequence 10 for window 9: 734x459 took 6.1ms
2018-07-07 22:05:37,955 record_latency: took    9.4 ms round trip,    9.2 for echo,    6.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.3 ms,    3.1 ms for echo
2018-07-07 22:05:37,955 may_recalculate(9, 336906) total 337146 pixels, scheduling recalculate work item
2018-07-07 22:05:37,998 packet decoding sequence 11 for window 9: 12x20 took 1.4ms
2018-07-07 22:05:37,999 record_latency: took    4.2 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2018-07-07 22:05:38,165 packet decoding sequence 12 for window 9: 734x459 took 3.7ms
2018-07-07 22:05:38,165 record_latency: took    6.7 ms round trip,    6.6 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.9 ms,    2.8 ms for echo
2018-07-07 22:05:38,316 calculate_batch_delay for wid=9 current batch delay=39, last update 2 seconds ago
2018-07-07 22:05:38,317 calculate_batch_delay for wid=9, 1012638 pixels updated since the last update
2018-07-07 22:05:38,317 calculate_batch_delay for wid=9, skipping - only 11 bytes sent since the last update
2018-07-07 22:05:38,555 packet decoding sequence 1 for window 10: 438x131 took 2.6ms
2018-07-07 22:05:38,555 record_latency: took   47.5 ms round trip,   47.3 for echo,    2.0 for decoding of    57378 pixels,       60 bytes sent over the network in   44.9 ms,   44.8 ms for echo
2018-07-07 22:05:38,555 may_recalculate(10, 57378) total 57378 pixels, scheduling recalculate work item
2018-07-07 22:05:38,558 packet decoding sequence 2 for window 10: 438x131 took 5.6ms
2018-07-07 22:05:38,558 record_latency: took   38.7 ms round trip,   38.5 for echo,    5.0 for decoding of    57378 pixels,       60 bytes sent over the network in   33.1 ms,   33.0 ms for echo
2018-07-07 22:05:38,637 packet decoding sequence 13 for window 9: 734x459 took 5.9ms
2018-07-07 22:05:38,638 record_latency: took    9.1 ms round trip,    9.1 for echo,    5.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.2 ms,    3.1 ms for echo
2018-07-07 22:05:38,638 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:05:39,317 calculate_batch_delay for wid=9 current batch delay=39, last update 3 seconds ago
2018-07-07 22:05:39,318 calculate_batch_delay for wid=9, 1349544 pixels updated since the last update
2018-07-07 22:05:39,318 calculate_batch_delay for wid=9, skipping - only 12 bytes sent since the last update
2018-07-07 22:05:39,319 calculate_batch_delay for wid=10 current batch delay=16, last update 417004 seconds ago
2018-07-07 22:05:39,319 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:05:39,320 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 64, 'recent': 64}
2018-07-07 22:05:39,320 update_batch_delay: damage-rate                  : 0.38,0.79  {'max_latency': 100, 'elapsed': 818}
2018-07-07 22:05:39,321 update_batch_delay: client-latency               : 1.07,0.13  {'target': 7, 'weight_multiplier': 470, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1152, 'div': 1000, 'avg': 25, 'recent': 27}
2018-07-07 22:05:39,321 update_batch_delay: client-ping-latency          : 0.76,0.10  {'target': 7, 'weight_multiplier': 198, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 576, 'div': 1000, 'avg': 3, 'recent': 5}
2018-07-07 22:05:39,321 update_batch_delay: server-ping-latency          : 0.50,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 249, 'div': 1000, 'avg': 3, 'recent': 2}
2018-07-07 22:05:39,321 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:39,322 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:39,322 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:39,322 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:39,322 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:05:39,324 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:05:39,324 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:05:39,325 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:05:39,326 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:05:39,326 update_batch_delay: delay=11
2018-07-07 22:05:39,704 packet decoding sequence 14 for window 9: 734x459 took 3.8ms
2018-07-07 22:05:39,705 record_latency: took    8.5 ms round trip,    8.4 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    4.7 ms,    4.6 ms for echo
2018-07-07 22:05:39,705 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:05:39,750 packet decoding sequence 15 for window 9: 12x20 took 1.7ms
2018-07-07 22:05:39,750 record_latency: took    4.2 ms round trip,    4.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.6 ms,    2.5 ms for echo
2018-07-07 22:05:39,805 packet decoding sequence 16 for window 9: 12x20 took 1.8ms
2018-07-07 22:05:39,806 record_latency: took    8.1 ms round trip,    7.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    6.2 ms,    6.0 ms for echo
2018-07-07 22:05:39,853 packet decoding sequence 17 for window 9: 12x20 took 1.7ms
2018-07-07 22:05:39,854 record_latency: took    5.2 ms round trip,    5.1 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.5 ms,    3.4 ms for echo
2018-07-07 22:05:40,317 calculate_batch_delay for wid=9 current batch delay=39, last update 4 seconds ago
2018-07-07 22:05:40,318 calculate_batch_delay for wid=9, 1687170 pixels updated since the last update
2018-07-07 22:05:40,318 calculate_batch_delay for wid=9, skipping - only 16 bytes sent since the last update
2018-07-07 22:05:41,179 packet decoding sequence 18 for window 9: 734x459 took 3.0ms
2018-07-07 22:05:41,180 record_latency: took    5.8 ms round trip,    5.7 for echo,    3.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2018-07-07 22:05:41,180 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:05:41,318 calculate_batch_delay for wid=9 current batch delay=39, last update 5 seconds ago
2018-07-07 22:05:41,319 calculate_batch_delay for wid=9, 2024076 pixels updated since the last update
2018-07-07 22:05:41,319 calculate_batch_delay for wid=9, skipping - only 17 bytes sent since the last update
2018-07-07 22:05:44,134 total_time=65834, total_pixels=2475738
2018-07-07 22:05:44,431 packet decoding sequence 19 for window 9: 263x17 took 4.2ms
2018-07-07 22:05:44,432 record_latency: took    8.0 ms round trip,    7.9 for echo,    4.0 for decoding of     4471 pixels,       57 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2018-07-07 22:05:44,432 may_recalculate(9, 4471) total 4471 pixels, scheduling recalculate work item
2018-07-07 22:05:44,433 calculate_batch_delay for wid=9 current batch delay=39, last update 8 seconds ago
2018-07-07 22:05:44,433 calculate_batch_delay for wid=9, 2028547 pixels updated since the last update
2018-07-07 22:05:44,433 calculate_batch_delay for wid=9, skipping - only 18 bytes sent since the last update
2018-07-07 22:05:44,459 packet decoding sequence 20 for window 9: 12x20 took 1.5ms
2018-07-07 22:05:44,460 record_latency: took    5.3 ms round trip,    5.2 for echo,    1.0 for decoding of      240 pixels,       56 bytes sent over the network in    3.7 ms,    3.7 ms for echo
2018-07-07 22:05:44,916 packet decoding sequence 21 for window 9: 12x20 took 1.1ms
2018-07-07 22:05:44,917 record_latency: took    3.1 ms round trip,    2.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    2.0 ms,    1.8 ms for echo
2018-07-07 22:05:44,952 packet decoding sequence 22 for window 9: 12x20 took 1.3ms
2018-07-07 22:05:44,953 record_latency: took    4.3 ms round trip,    4.1 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.0 ms,    2.7 ms for echo
2018-07-07 22:05:45,006 packet decoding sequence 23 for window 9: 12x20 took 1.8ms
2018-07-07 22:05:45,007 record_latency: took    7.3 ms round trip,    7.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    5.5 ms,    5.4 ms for echo
2018-07-07 22:05:45,973 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,075 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,176 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,281 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,376 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,482 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,578 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,683 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,782 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,884 total_time=75812, total_pixels=2481169
2018-07-07 22:05:46,983 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,078 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,186 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,285 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,385 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,489 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,589 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,688 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,787 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,891 total_time=75812, total_pixels=2481169
2018-07-07 22:05:47,993 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,091 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,192 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,293 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,392 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,494 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,595 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,695 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,797 total_time=75812, total_pixels=2481169
2018-07-07 22:05:48,896 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,000 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,095 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,200 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,302 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,400 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,498 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,602 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,705 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,804 total_time=75812, total_pixels=2481169
2018-07-07 22:05:49,904 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,005 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,105 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,209 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,304 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,405 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,512 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,608 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,712 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,809 total_time=75812, total_pixels=2481169
2018-07-07 22:05:50,909 total_time=75812, total_pixels=2481169
2018-07-07 22:05:51,013 total_time=75812, total_pixels=2481169
2018-07-07 22:05:51,070 packet decoding sequence 24 for window 9: 336x20 took 3.5ms
2018-07-07 22:05:51,070 record_latency: took    6.2 ms round trip,    6.2 for echo,    3.0 for decoding of     6720 pixels,       57 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2018-07-07 22:05:51,071 may_recalculate(9, 6720) total 7680 pixels, scheduling recalculate work item
2018-07-07 22:05:51,071 calculate_batch_delay for wid=9 current batch delay=39, last update 14 seconds ago
2018-07-07 22:05:51,072 calculate_batch_delay for wid=9, 2036227 pixels updated since the last update
2018-07-07 22:05:51,074 calculate_batch_delay for wid=9, skipping - only 23 bytes sent since the last update
2018-07-07 22:05:51,112 total_time=79289, total_pixels=2487889
2018-07-07 22:05:51,165 packet decoding sequence 25 for window 9: 12x20 took 2.1ms
2018-07-07 22:05:51,166 record_latency: took    5.3 ms round trip,    5.2 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.2 ms,    3.1 ms for echo
2018-07-07 22:05:51,211 total_time=81345, total_pixels=2488129
2018-07-07 22:05:51,246 packet decoding sequence 26 for window 9: 12x20 took 1.2ms
2018-07-07 22:05:51,247 record_latency: took   34.3 ms round trip,   34.2 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   33.0 ms,   33.0 ms for echo
2018-07-07 22:05:51,265 packet decoding sequence 27 for window 9: 12x20 took 2.8ms
2018-07-07 22:05:51,265 record_latency: took    6.5 ms round trip,    5.2 for echo,    2.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.6 ms,    2.4 ms for echo
2018-07-07 22:05:51,316 total_time=85432, total_pixels=2488609
2018-07-07 22:05:51,346 packet decoding sequence 28 for window 9: 12x20 took 1.7ms
2018-07-07 22:05:51,347 record_latency: took   28.8 ms round trip,   28.5 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   27.1 ms,   26.8 ms for echo
2018-07-07 22:05:51,373 packet decoding sequence 29 for window 9: 12x20 took 1.1ms
2018-07-07 22:05:51,373 record_latency: took    4.2 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in    3.0 ms,    2.9 ms for echo
2018-07-07 22:05:51,414 total_time=88238, total_pixels=2489089
2018-07-07 22:05:51,517 total_time=88238, total_pixels=2489089
2018-07-07 22:05:51,636 total_time=88238, total_pixels=2489089
2018-07-07 22:05:51,647 packet decoding sequence 1 for window 11: 438x131 took 3.5ms
2018-07-07 22:05:51,648 record_latency: took   27.5 ms round trip,   27.4 for echo,    3.0 for decoding of    57378 pixels,       60 bytes sent over the network in   24.0 ms,   23.9 ms for echo
2018-07-07 22:05:51,649 may_recalculate(11, 57378) total 57378 pixels, scheduling recalculate work item
2018-07-07 22:05:51,705 packet decoding sequence 30 for window 9: 734x459 took 6.4ms
2018-07-07 22:05:51,706 record_latency: took    9.4 ms round trip,    9.3 for echo,    6.0 for decoding of   336906 pixels,       61 bytes sent over the network in    3.0 ms,    2.9 ms for echo
2018-07-07 22:05:51,706 may_recalculate(9, 336906) total 338106 pixels, scheduling recalculate work item
2018-07-07 22:05:51,738 total_time=98120, total_pixels=2883373
2018-07-07 22:05:51,840 total_time=98120, total_pixels=2883373
2018-07-07 22:05:51,940 total_time=98120, total_pixels=2883373
2018-07-07 22:05:52,039 total_time=98120, total_pixels=2883373
2018-07-07 22:05:52,072 calculate_batch_delay for wid=9 current batch delay=39, last update 15 seconds ago
2018-07-07 22:05:52,072 calculate_batch_delay for wid=9, 2374333 pixels updated since the last update
2018-07-07 22:05:52,072 calculate_batch_delay for wid=9, skipping - only 29 bytes sent since the last update
2018-07-07 22:05:52,073 calculate_batch_delay for wid=11 current batch delay=45, last update 417016 seconds ago
2018-07-07 22:05:52,073 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:05:52,073 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 63, 'recent': 63}
2018-07-07 22:05:52,073 update_batch_delay: damage-rate                  : 0.70,0.55  {'max_latency': 100, 'elapsed': 501}
2018-07-07 22:05:52,074 update_batch_delay: client-latency               : 0.88,0.15  {'target': 6, 'weight_multiplier': 431, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 780, 'div': 1000, 'avg': 12, 'recent': 12}
2018-07-07 22:05:52,074 update_batch_delay: client-ping-latency          : 0.74,0.10  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 543, 'div': 1000, 'avg': 3, 'recent': 4}
2018-07-07 22:05:52,074 update_batch_delay: server-ping-latency          : 0.59,0.12  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 346, 'div': 1000, 'avg': 3, 'recent': 2}
2018-07-07 22:05:52,074 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:52,074 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:52,074 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:52,074 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:52,075 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:05:52,075 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:05:52,075 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:05:52,075 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:05:52,075 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:05:52,075 update_batch_delay: delay=33
2018-07-07 22:05:52,155 total_time=98120, total_pixels=2883373
2018-07-07 22:05:52,256 total_time=98120, total_pixels=2883373
2018-07-07 22:05:52,289 packet decoding sequence 31 for window 9: 234x25 took 1.4ms
2018-07-07 22:05:52,289 record_latency: took    3.2 ms round trip,    3.2 for echo,    1.0 for decoding of     5850 pixels,       57 bytes sent over the network in    1.9 ms,    1.8 ms for echo
2018-07-07 22:05:52,289 may_recalculate(9, 5850) total 5850 pixels, scheduling recalculate work item
2018-07-07 22:05:52,382 total_time=99482, total_pixels=2889223
2018-07-07 22:05:52,458 total_time=99482, total_pixels=2889223
2018-07-07 22:05:52,516 packet decoding sequence 32 for window 9: 190x25 took 1.9ms
2018-07-07 22:05:52,517 record_latency: took    5.4 ms round trip,    5.4 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    3.5 ms,    3.4 ms for echo
2018-07-07 22:05:52,520 packet decoding sequence 1 for window 13: 244x180 took 2.0ms
2018-07-07 22:05:52,521 record_latency: took    8.1 ms round trip,    8.0 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    6.1 ms,    6.1 ms for echo
2018-07-07 22:05:52,521 may_recalculate(13, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-07 22:05:52,563 total_time=103391, total_pixels=2937893
2018-07-07 22:05:52,665 total_time=103391, total_pixels=2937893
2018-07-07 22:05:52,764 total_time=103391, total_pixels=2937893
2018-07-07 22:05:52,866 total_time=103391, total_pixels=2937893
2018-07-07 22:05:52,965 total_time=103391, total_pixels=2937893
2018-07-07 22:05:53,095 calculate_batch_delay for wid=9 current batch delay=39, last update 16 seconds ago
2018-07-07 22:05:53,096 calculate_batch_delay for wid=9, 2384933 pixels updated since the last update
2018-07-07 22:05:53,099 calculate_batch_delay for wid=9, skipping - only 31 bytes sent since the last update
2018-07-07 22:05:53,107 total_time=103391, total_pixels=2937893
2018-07-07 22:05:53,194 total_time=103391, total_pixels=2937893
2018-07-07 22:05:53,327 total_time=103391, total_pixels=2937893
2018-07-07 22:05:53,397 total_time=103391, total_pixels=2937893
2018-07-07 22:05:53,406 packet decoding sequence 33 for window 9: 121x25 took 97.6ms
2018-07-07 22:05:53,406 record_latency: took  176.8 ms round trip,  176.7 for echo,   97.0 for decoding of     3025 pixels,       56 bytes sent over the network in   79.2 ms,   79.1 ms for echo
2018-07-07 22:05:53,406 may_recalculate(9, 3025) total 3025 pixels, scheduling recalculate work item
2018-07-07 22:05:53,477 packet decoding sequence 34 for window 9: 190x25 took 1.4ms
2018-07-07 22:05:53,478 record_latency: took    4.1 ms round trip,    4.1 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2018-07-07 22:05:53,498 total_time=202382, total_pixels=2945668
2018-07-07 22:05:53,511 packet decoding sequence 1 for window 16: 312x259 took 2.0ms
2018-07-07 22:05:53,511 record_latency: took   35.4 ms round trip,   35.3 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in   33.4 ms,   33.3 ms for echo
2018-07-07 22:05:53,512 may_recalculate(16, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:05:53,599 total_time=204419, total_pixels=3026476
2018-07-07 22:05:53,719 total_time=204419, total_pixels=3026476
2018-07-07 22:05:53,815 total_time=204419, total_pixels=3026476
2018-07-07 22:05:53,863 packet decoding sequence 1 for window 17: 244x180 took 1.8ms
2018-07-07 22:05:53,864 record_latency: took    5.6 ms round trip,    5.5 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2018-07-07 22:05:53,864 may_recalculate(17, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-07 22:05:53,868 packet decoding sequence 35 for window 9: 190x25 took 1.3ms
2018-07-07 22:05:53,868 record_latency: took    3.8 ms round trip,    3.7 for echo,    1.0 for decoding of     4750 pixels,       57 bytes sent over the network in    2.5 ms,    2.4 ms for echo
2018-07-07 22:05:53,916 total_time=207544, total_pixels=3075146
2018-07-07 22:05:54,018 total_time=207544, total_pixels=3075146
2018-07-07 22:05:54,094 calculate_batch_delay for wid=17 current batch delay=120, last update 417018 seconds ago
2018-07-07 22:05:54,095 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:05:54,095 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 44, 'recent': 44}
2018-07-07 22:05:54,095 update_batch_delay: damage-rate                  : 0.85,0.39  {'max_latency': 100, 'elapsed': 353}
2018-07-07 22:05:54,095 update_batch_delay: client-latency               : 0.86,0.16  {'target': 6, 'weight_multiplier': 426, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 731, 'div': 1000, 'avg': 15, 'recent': 13}
2018-07-07 22:05:54,096 update_batch_delay: client-ping-latency          : 0.60,0.12  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 354, 'div': 1000, 'avg': 3, 'recent': 2}
2018-07-07 22:05:54,096 update_batch_delay: server-ping-latency          : 0.91,0.06  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 827, 'div': 1000, 'avg': 5, 'recent': 8}
2018-07-07 22:05:54,096 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:54,096 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:54,097 update_batch_delay: compression-work-queue       : 0.05,0.24  {'target': 1000, 'weight_multiplier': 250, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 38, 'div': 1000, 'avg': 59, 'recent': 62}
2018-07-07 22:05:54,097 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 43920000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:54,097 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:05:54,097 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:05:54,098 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:05:54,098 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:05:54,098 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:05:54,098 update_batch_delay: delay=86
2018-07-07 22:05:54,101 calculate_batch_delay for wid=9 current batch delay=39, last update 17 seconds ago
2018-07-07 22:05:54,109 calculate_batch_delay for wid=9, 2397458 pixels updated since the last update
2018-07-07 22:05:54,109 calculate_batch_delay for wid=9, skipping - only 34 bytes sent since the last update
2018-07-07 22:05:54,119 total_time=207544, total_pixels=3075146
2018-07-07 22:05:54,220 total_time=207544, total_pixels=3075146
2018-07-07 22:05:54,319 total_time=207544, total_pixels=3075146
2018-07-07 22:05:54,423 total_time=207544, total_pixels=3075146
2018-07-07 22:05:54,463 packet decoding sequence 2 for window 17: 244x180 took 2.3ms
2018-07-07 22:05:54,463 record_latency: took    4.5 ms round trip,    4.1 for echo,    2.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.2 ms,    1.8 ms for echo
2018-07-07 22:05:54,464 may_recalculate(17, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-07 22:05:54,518 total_time=209831, total_pixels=3119066
2018-07-07 22:05:54,574 packet decoding sequence 3 for window 17: 244x180 took 1.4ms
2018-07-07 22:05:54,574 record_latency: took    3.6 ms round trip,    3.1 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.1 ms,    1.7 ms for echo
2018-07-07 22:05:54,619 total_time=211270, total_pixels=3162986
2018-07-07 22:05:54,685 packet decoding sequence 4 for window 17: 244x180 took 3.0ms
2018-07-07 22:05:54,685 record_latency: took    5.9 ms round trip,    5.4 for echo,    3.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.8 ms,    2.3 ms for echo
2018-07-07 22:05:54,719 total_time=214309, total_pixels=3206906
2018-07-07 22:05:54,790 packet decoding sequence 5 for window 17: 244x180 took 1.7ms
2018-07-07 22:05:54,791 record_latency: took    4.1 ms round trip,    4.1 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    2.5 ms,    2.4 ms for echo
2018-07-07 22:05:54,821 total_time=215983, total_pixels=3250826
2018-07-07 22:05:54,921 total_time=215983, total_pixels=3250826
2018-07-07 22:05:54,962 packet decoding sequence 6 for window 17: 244x180 took 1.4ms
2018-07-07 22:05:54,962 record_latency: took    5.5 ms round trip,    5.4 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    4.1 ms,    4.0 ms for echo
2018-07-07 22:05:55,023 total_time=217377, total_pixels=3294746
2018-07-07 22:05:55,095 calculate_batch_delay for wid=17 current batch delay=86, last update 1 seconds ago
2018-07-07 22:05:55,095 calculate_batch_delay for wid=17, skipping - only 5 events since the last update
2018-07-07 22:05:55,120 total_time=217377, total_pixels=3294746
2018-07-07 22:05:55,203 packet decoding sequence 7 for window 17: 244x180 took 1.8ms
2018-07-07 22:05:55,203 record_latency: took    6.1 ms round trip,    6.1 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    4.3 ms,    4.2 ms for echo
2018-07-07 22:05:55,203 may_recalculate(17, 43920) total 43920 pixels, scheduling recalculate work item
2018-07-07 22:05:55,221 total_time=219222, total_pixels=3338666
2018-07-07 22:05:55,293 packet decoding sequence 8 for window 17: 244x180 took 1.5ms
2018-07-07 22:05:55,293 record_latency: took    5.1 ms round trip,    4.6 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    3.6 ms,    3.1 ms for echo
2018-07-07 22:05:55,331 total_time=220724, total_pixels=3382586
2018-07-07 22:05:55,390 packet decoding sequence 9 for window 17: 244x180 took 1.6ms
2018-07-07 22:05:55,391 record_latency: took    5.6 ms round trip,    5.5 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    4.0 ms,    3.9 ms for echo
2018-07-07 22:05:55,427 total_time=222336, total_pixels=3426506
2018-07-07 22:05:55,499 packet decoding sequence 10 for window 17: 244x180 took 1.5ms
2018-07-07 22:05:55,499 record_latency: took   10.9 ms round trip,   10.8 for echo,    1.0 for decoding of    43920 pixels,       60 bytes sent over the network in    9.3 ms,    9.3 ms for echo
2018-07-07 22:05:55,529 total_time=223859, total_pixels=3470426
2018-07-07 22:05:55,656 total_time=223859, total_pixels=3470426
2018-07-07 22:05:55,736 packet decoding sequence 36 for window 9: 121x25 took 1.2ms
2018-07-07 22:05:55,736 record_latency: took   24.0 ms round trip,   23.9 for echo,    1.0 for decoding of     3025 pixels,       56 bytes sent over the network in   22.7 ms,   22.7 ms for echo
2018-07-07 22:05:55,737 may_recalculate(9, 3025) total 3025 pixels, scheduling recalculate work item
2018-07-07 22:05:55,754 total_time=225096, total_pixels=3473451
2018-07-07 22:05:55,856 total_time=225096, total_pixels=3473451
2018-07-07 22:05:55,871 packet decoding sequence 37 for window 9: 76x25 took 1.5ms
2018-07-07 22:05:55,872 record_latency: took   19.7 ms round trip,   19.6 for echo,    1.0 for decoding of     1900 pixels,       56 bytes sent over the network in   18.2 ms,   18.1 ms for echo
2018-07-07 22:05:55,956 total_time=226588, total_pixels=3475351
2018-07-07 22:05:56,059 total_time=226588, total_pixels=3475351
2018-07-07 22:05:56,095 calculate_batch_delay for wid=9 current batch delay=39, last update 19 seconds ago
2018-07-07 22:05:56,096 calculate_batch_delay for wid=9, 2402383 pixels updated since the last update
2018-07-07 22:05:56,096 calculate_batch_delay for wid=9, skipping - only 36 bytes sent since the last update
2018-07-07 22:05:56,165 total_time=226588, total_pixels=3475351
2018-07-07 22:05:56,210 packet decoding sequence 1 for window 19: 248x181 took 1.7ms
2018-07-07 22:05:56,210 record_latency: took   52.4 ms round trip,   52.2 for echo,    1.0 for decoding of    44888 pixels,       60 bytes sent over the network in   50.6 ms,   50.5 ms for echo
2018-07-07 22:05:56,211 may_recalculate(19, 44888) total 44888 pixels, scheduling recalculate work item
2018-07-07 22:05:56,259 total_time=228292, total_pixels=3520239
2018-07-07 22:05:56,364 total_time=228292, total_pixels=3520239
2018-07-07 22:05:56,505 total_time=228292, total_pixels=3520239
2018-07-07 22:05:56,595 total_time=228292, total_pixels=3520239
2018-07-07 22:05:56,691 total_time=228292, total_pixels=3520239
2018-07-07 22:05:56,736 packet decoding sequence 38 for window 9: 121x25 took 194.5ms
2018-07-07 22:05:56,736 record_latency: took  210.9 ms round trip,  210.9 for echo,  194.0 for decoding of     3025 pixels,       56 bytes sent over the network in   16.4 ms,   16.3 ms for echo
2018-07-07 22:05:56,736 may_recalculate(9, 3025) total 3025 pixels, scheduling recalculate work item
2018-07-07 22:05:56,815 total_time=422833, total_pixels=3523264
2018-07-07 22:05:56,856 packet decoding sequence 39 for window 9: 234x25 took 1.7ms
2018-07-07 22:05:56,857 record_latency: took   24.3 ms round trip,   24.3 for echo,    1.0 for decoding of     5850 pixels,       57 bytes sent over the network in   22.6 ms,   22.6 ms for echo
2018-07-07 22:05:56,917 total_time=424510, total_pixels=3529114
2018-07-07 22:05:57,014 total_time=424510, total_pixels=3529114
2018-07-07 22:05:57,096 calculate_batch_delay for wid=9 current batch delay=39, last update 20 seconds ago
2018-07-07 22:05:57,097 calculate_batch_delay for wid=9, 2411258 pixels updated since the last update
2018-07-07 22:05:57,097 calculate_batch_delay for wid=9, skipping - only 38 bytes sent since the last update
2018-07-07 22:05:57,117 total_time=424510, total_pixels=3529114
2018-07-07 22:05:57,224 total_time=424510, total_pixels=3529114
2018-07-07 22:05:57,342 total_time=424510, total_pixels=3529114
2018-07-07 22:05:57,449 total_time=424510, total_pixels=3529114
2018-07-07 22:05:57,501 packet decoding sequence 40 for window 9: 279x25 took 1.7ms
2018-07-07 22:05:57,501 record_latency: took   38.2 ms round trip,   38.1 for echo,    1.0 for decoding of     6975 pixels,       57 bytes sent over the network in   36.6 ms,   36.4 ms for echo
2018-07-07 22:05:57,501 may_recalculate(9, 6975) total 6975 pixels, scheduling recalculate work item
2018-07-07 22:05:57,517 packet decoding sequence 1 for window 23: 130x54 took 1.4ms
2018-07-07 22:05:57,517 record_latency: took    3.9 ms round trip,    3.8 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    2.4 ms,    2.4 ms for echo
2018-07-07 22:05:57,517 may_recalculate(23, 7020) total 7020 pixels, scheduling recalculate work item
2018-07-07 22:05:57,548 total_time=427607, total_pixels=3543109
2018-07-07 22:05:57,648 total_time=427607, total_pixels=3543109
2018-07-07 22:05:57,709 packet decoding sequence 2 for window 23: 130x54 took 1.1ms
2018-07-07 22:05:57,709 record_latency: took    4.0 ms round trip,    4.0 for echo,    1.0 for decoding of     7020 pixels,       57 bytes sent over the network in    2.9 ms,    2.8 ms for echo
2018-07-07 22:05:57,749 total_time=428711, total_pixels=3550129
2018-07-07 22:05:57,854 total_time=428711, total_pixels=3550129
2018-07-07 22:05:57,951 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,052 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,097 calculate_batch_delay for wid=23 current batch delay=164, last update 417022 seconds ago
2018-07-07 22:05:58,097 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:05:58,099 update_batch_delay: client-decode-speed          : 0.98,0.02  {'avg': 184, 'recent': 180}
2018-07-07 22:05:58,100 update_batch_delay: damage-rate                  : 0.59,0.64  {'max_latency': 142, 'elapsed': 699}
2018-07-07 22:05:58,100 update_batch_delay: client-latency               : 0.88,0.15  {'target': 6, 'weight_multiplier': 426, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 767, 'div': 1000, 'avg': 14, 'recent': 13}
2018-07-07 22:05:58,100 update_batch_delay: client-ping-latency          : 1.04,0.04  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1082, 'div': 1000, 'avg': 12, 'recent': 18}
2018-07-07 22:05:58,100 update_batch_delay: server-ping-latency          : 0.51,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 262, 'div': 1000, 'avg': 3, 'recent': 2}
2018-07-07 22:05:58,101 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:58,101 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 7020000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:58,101 update_batch_delay: compression-work-queue       : 0.05,0.23  {'target': 1000, 'weight_multiplier': 238, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 34, 'div': 1000, 'avg': 38, 'recent': 56}
2018-07-07 22:05:58,102 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 7020000, 'avg': 0, 'recent': 0}
2018-07-07 22:05:58,102 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:05:58,102 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:05:58,103 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:05:58,103 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:05:58,103 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:05:58,103 update_batch_delay: delay=116
2018-07-07 22:05:58,104 calculate_batch_delay for wid=9 current batch delay=39, last update 21 seconds ago
2018-07-07 22:05:58,105 calculate_batch_delay for wid=9, 2418233 pixels updated since the last update
2018-07-07 22:05:58,105 calculate_batch_delay for wid=9, skipping - only 39 bytes sent since the last update
2018-07-07 22:05:58,150 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,255 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,354 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,454 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,556 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,655 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,753 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,857 total_time=428711, total_pixels=3550129
2018-07-07 22:05:58,927 packet decoding sequence 41 for window 9: 734x459 took 6.3ms
2018-07-07 22:05:58,927 record_latency: took    8.5 ms round trip,    8.4 for echo,    6.0 for decoding of   336906 pixels,       61 bytes sent over the network in    2.2 ms,    2.1 ms for echo
2018-07-07 22:05:58,927 may_recalculate(9, 336906) total 336906 pixels, scheduling recalculate work item
2018-07-07 22:05:58,958 total_time=435021, total_pixels=3887035
2018-07-07 22:05:59,014 packet decoding sequence 42 for window 9: 12x20 took 1.1ms
2018-07-07 22:05:59,015 record_latency: took   27.9 ms round trip,   27.8 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   26.8 ms,   26.8 ms for echo
2018-07-07 22:05:59,060 total_time=436084, total_pixels=3887275
2018-07-07 22:05:59,097 calculate_batch_delay for wid=9 current batch delay=39, last update 22 seconds ago
2018-07-07 22:05:59,097 calculate_batch_delay for wid=9, 2755379 pixels updated since the last update
2018-07-07 22:05:59,097 calculate_batch_delay for wid=9, skipping - only 41 bytes sent since the last update
2018-07-07 22:05:59,123 packet decoding sequence 43 for window 9: 12x20 took 1.0ms
2018-07-07 22:05:59,124 record_latency: took   25.0 ms round trip,   24.9 for echo,    1.0 for decoding of      240 pixels,       54 bytes sent over the network in   23.9 ms,   23.9 ms for echo
2018-07-07 22:05:59,131 packet decoding sequence 44 for window 9: 12x20 took 1.1ms
2018-07-07 22:05:59,131 record_latency: took    3.1 ms round trip,    2.9 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.0 ms,    1.8 ms for echo
2018-07-07 22:05:59,157 total_time=438187, total_pixels=3887755
2018-07-07 22:05:59,263 total_time=438187, total_pixels=3887755
2018-07-07 22:05:59,291 packet decoding sequence 45 for window 9: 12x20 took 1.1ms
2018-07-07 22:05:59,292 record_latency: took   52.8 ms round trip,   52.7 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   51.7 ms,   51.6 ms for echo
2018-07-07 22:05:59,362 total_time=439291, total_pixels=3887995
2018-07-07 22:05:59,481 total_time=439291, total_pixels=3887995
2018-07-07 22:05:59,512 packet decoding sequence 46 for window 9: 734x459 took 2.8ms
2018-07-07 22:05:59,513 record_latency: took   49.1 ms round trip,   43.0 for echo,    2.0 for decoding of   336906 pixels,       62 bytes sent over the network in   46.3 ms,   40.2 ms for echo
2018-07-07 22:05:59,513 may_recalculate(9, 336906) total 337626 pixels, scheduling recalculate work item
2018-07-07 22:05:59,577 total_time=442041, total_pixels=4224901
2018-07-07 22:05:59,679 total_time=442041, total_pixels=4224901
2018-07-07 22:05:59,779 total_time=442041, total_pixels=4224901
2018-07-07 22:05:59,849 packet decoding sequence 1 for window 24: 312x259 took 3.2ms
2018-07-07 22:05:59,849 record_latency: took    5.5 ms round trip,    5.5 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.4 ms,    2.3 ms for echo
2018-07-07 22:05:59,849 may_recalculate(24, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:05:59,880 total_time=445201, total_pixels=4305709
2018-07-07 22:05:59,981 total_time=445201, total_pixels=4305709
2018-07-07 22:06:00,081 total_time=445201, total_pixels=4305709
2018-07-07 22:06:00,097 calculate_batch_delay for wid=24 current batch delay=4697, last update 417024 seconds ago
2018-07-07 22:06:00,098 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:06:00,098 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 41, 'recent': 41}
2018-07-07 22:06:00,098 update_batch_delay: damage-rate                  : 0.96,0.20  {'max_latency': 100, 'elapsed': 240}
2018-07-07 22:06:00,099 update_batch_delay: client-latency               : 0.97,0.08  {'target': 6, 'weight_multiplier': 426, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 932, 'div': 1000, 'avg': 17, 'recent': 17}
2018-07-07 22:06:00,099 update_batch_delay: client-ping-latency          : 1.06,0.04  {'target': 6, 'weight_multiplier': 187, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1116, 'div': 1000, 'avg': 11, 'recent': 17}
2018-07-07 22:06:00,099 update_batch_delay: server-ping-latency          : 0.72,0.10  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 515, 'div': 1000, 'avg': 4, 'recent': 4}
2018-07-07 22:06:00,099 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:00,099 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:00,100 update_batch_delay: compression-work-queue       : 0.01,0.16  {'target': 1000, 'weight_multiplier': 158, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 5, 'div': 1000, 'avg': 24, 'recent': 8}
2018-07-07 22:06:00,100 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:00,101 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:06:00,101 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:06:00,102 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:06:00,102 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:06:00,102 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:06:00,103 update_batch_delay: delay=362
2018-07-07 22:06:00,104 calculate_batch_delay for wid=9 current batch delay=39, last update 23 seconds ago
2018-07-07 22:06:00,104 calculate_batch_delay for wid=9, 3093005 pixels updated since the last update
2018-07-07 22:06:00,105 calculate_batch_delay for wid=9, skipping - only 45 bytes sent since the last update
2018-07-07 22:06:00,178 total_time=445201, total_pixels=4305709
2018-07-07 22:06:00,285 total_time=445201, total_pixels=4305709
2018-07-07 22:06:00,367 packet decoding sequence 2 for window 24: 312x259 took 2.8ms
2018-07-07 22:06:00,367 record_latency: took    5.7 ms round trip,    5.6 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.8 ms for echo
2018-07-07 22:06:00,369 may_recalculate(24, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:06:00,389 total_time=448040, total_pixels=4386517
2018-07-07 22:06:00,488 total_time=448040, total_pixels=4386517
2018-07-07 22:06:00,589 total_time=448040, total_pixels=4386517
2018-07-07 22:06:00,690 total_time=448040, total_pixels=4386517
2018-07-07 22:06:00,792 total_time=448040, total_pixels=4386517
2018-07-07 22:06:00,856 packet decoding sequence 3 for window 24: 312x259 took 2.1ms
2018-07-07 22:06:00,856 record_latency: took   10.3 ms round trip,   10.2 for echo,    2.0 for decoding of    80808 pixels,       60 bytes sent over the network in    8.2 ms,    8.2 ms for echo
2018-07-07 22:06:00,889 total_time=450094, total_pixels=4467325
2018-07-07 22:06:00,988 total_time=450094, total_pixels=4467325
2018-07-07 22:06:01,093 total_time=450094, total_pixels=4467325
2018-07-07 22:06:01,144 packet decoding sequence 47 for window 9: 190x45 took 2.4ms
2018-07-07 22:06:01,144 record_latency: took    4.5 ms round trip,    4.4 for echo,    2.0 for decoding of     8550 pixels,       61 bytes sent over the network in    2.1 ms,    2.0 ms for echo
2018-07-07 22:06:01,144 may_recalculate(9, 8550) total 8550 pixels, scheduling recalculate work item
2018-07-07 22:06:01,190 total_time=452497, total_pixels=4475875
2018-07-07 22:06:01,291 total_time=452497, total_pixels=4475875
2018-07-07 22:06:01,393 total_time=452497, total_pixels=4475875
2018-07-07 22:06:01,493 total_time=452497, total_pixels=4475875
2018-07-07 22:06:01,594 total_time=452497, total_pixels=4475875
2018-07-07 22:06:01,699 total_time=452497, total_pixels=4475875
2018-07-07 22:06:01,777 packet decoding sequence 48 for window 9: 12x20 took 1.3ms
2018-07-07 22:06:01,778 record_latency: took    4.1 ms round trip,    4.0 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in    2.8 ms,    2.7 ms for echo
2018-07-07 22:06:01,798 total_time=453840, total_pixels=4476115
2018-07-07 22:06:01,897 total_time=453840, total_pixels=4476115
2018-07-07 22:06:01,998 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,100 calculate_batch_delay for wid=9 current batch delay=39, last update 25 seconds ago
2018-07-07 22:06:02,100 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,103 calculate_batch_delay for wid=9, 3101795 pixels updated since the last update
2018-07-07 22:06:02,103 calculate_batch_delay for wid=9, skipping - only 47 bytes sent since the last update
2018-07-07 22:06:02,200 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,298 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,400 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,501 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,605 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,706 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,804 total_time=453840, total_pixels=4476115
2018-07-07 22:06:02,904 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,007 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,107 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,205 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,311 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,410 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,510 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,612 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,712 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,813 total_time=453840, total_pixels=4476115
2018-07-07 22:06:03,915 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,017 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,115 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,215 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,324 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,421 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,517 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,617 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,719 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,822 total_time=453840, total_pixels=4476115
2018-07-07 22:06:04,918 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,022 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,121 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,220 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,324 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,425 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,527 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,627 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,727 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,828 total_time=453840, total_pixels=4476115
2018-07-07 22:06:05,927 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,030 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,131 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,231 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,331 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,431 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,533 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,633 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,737 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,835 total_time=453840, total_pixels=4476115
2018-07-07 22:06:06,936 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,041 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,138 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,236 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,338 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,437 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,540 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,648 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,745 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,843 total_time=453840, total_pixels=4476115
2018-07-07 22:06:07,944 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,044 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,145 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,245 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,348 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,447 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,547 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,647 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,750 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,852 total_time=453840, total_pixels=4476115
2018-07-07 22:06:08,951 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,052 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,153 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,252 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,355 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,455 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,560 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,663 total_time=453840, total_pixels=4476115
2018-07-07 22:06:09,732 packet decoding sequence 49 for window 9: 12x20 took 1.7ms
2018-07-07 22:06:09,732 record_latency: took  165.1 ms round trip,  165.0 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in  163.4 ms,  163.3 ms for echo
2018-07-07 22:06:09,734 packet decoding sequence 50 for window 9: 12x20 took 3.9ms
2018-07-07 22:06:09,734 record_latency: took   78.6 ms round trip,   78.4 for echo,    3.0 for decoding of      240 pixels,       55 bytes sent over the network in   74.7 ms,   74.5 ms for echo
2018-07-07 22:06:09,761 total_time=459470, total_pixels=4476595
2018-07-07 22:06:09,863 total_time=459470, total_pixels=4476595
2018-07-07 22:06:09,945 packet decoding sequence 51 for window 9: 12x20 took 2.3ms
2018-07-07 22:06:09,946 record_latency: took   91.7 ms round trip,   91.6 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in   89.4 ms,   89.4 ms for echo
2018-07-07 22:06:09,962 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,066 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,167 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,266 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,369 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,468 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,569 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,671 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,772 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,872 total_time=461726, total_pixels=4476835
2018-07-07 22:06:10,972 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,072 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,174 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,274 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,376 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,477 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,577 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,677 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,776 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,877 total_time=461726, total_pixels=4476835
2018-07-07 22:06:11,976 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,080 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,178 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,282 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,380 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,490 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,594 total_time=461726, total_pixels=4476835
2018-07-07 22:06:12,663 packet decoding sequence 52 for window 9: 12x20 took 1.1ms
2018-07-07 22:06:12,664 record_latency: took   70.1 ms round trip,   70.0 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   69.0 ms,   68.9 ms for echo
2018-07-07 22:06:12,691 total_time=462857, total_pixels=4477075
2018-07-07 22:06:12,735 packet decoding sequence 53 for window 9: 12x20 took 1.0ms
2018-07-07 22:06:12,736 record_latency: took   48.4 ms round trip,   48.2 for echo,    1.0 for decoding of      240 pixels,       62 bytes sent over the network in   47.4 ms,   47.2 ms for echo
2018-07-07 22:06:12,737 packet decoding sequence 54 for window 9: 12x20 took 2.5ms
2018-07-07 22:06:12,737 record_latency: took   48.4 ms round trip,   47.6 for echo,    2.0 for decoding of      240 pixels,       57 bytes sent over the network in   45.8 ms,   45.1 ms for echo
2018-07-07 22:06:12,790 total_time=466446, total_pixels=4477555
2018-07-07 22:06:12,865 packet decoding sequence 55 for window 9: 12x20 took 3.0ms
2018-07-07 22:06:12,865 record_latency: took   71.2 ms round trip,   70.7 for echo,    3.0 for decoding of      240 pixels,       55 bytes sent over the network in   68.2 ms,   67.6 ms for echo
2018-07-07 22:06:12,891 total_time=469463, total_pixels=4477795
2018-07-07 22:06:12,993 total_time=469463, total_pixels=4477795
2018-07-07 22:06:13,064 packet decoding sequence 56 for window 9: 12x20 took 1.6ms
2018-07-07 22:06:13,064 record_latency: took   77.5 ms round trip,   77.1 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   75.9 ms,   75.5 ms for echo
2018-07-07 22:06:13,092 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,192 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,296 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,393 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,497 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,612 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,694 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,814 total_time=471098, total_pixels=4478035
2018-07-07 22:06:13,863 packet decoding sequence 57 for window 9: 734x459 took 5.1ms
2018-07-07 22:06:13,863 record_latency: took    7.0 ms round trip,    6.9 for echo,    5.0 for decoding of   336906 pixels,       62 bytes sent over the network in    1.8 ms,    1.8 ms for echo
2018-07-07 22:06:13,863 may_recalculate(9, 336906) total 338826 pixels, scheduling recalculate work item
2018-07-07 22:06:13,864 calculate_batch_delay for wid=9 current batch delay=39, last update 37 seconds ago
2018-07-07 22:06:13,864 calculate_batch_delay for wid=9, 3440621 pixels updated since the last update
2018-07-07 22:06:13,864 calculate_batch_delay for wid=9, skipping - only 56 bytes sent since the last update
2018-07-07 22:06:13,916 total_time=476229, total_pixels=4814941
2018-07-07 22:06:14,016 total_time=476229, total_pixels=4814941
2018-07-07 22:06:14,119 total_time=476229, total_pixels=4814941
2018-07-07 22:06:14,176 packet decoding sequence 1 for window 25: 312x259 took 3.8ms
2018-07-07 22:06:14,176 record_latency: took    6.7 ms round trip,    6.6 for echo,    3.0 for decoding of    80808 pixels,       60 bytes sent over the network in    2.8 ms,    2.8 ms for echo
2018-07-07 22:06:14,177 may_recalculate(25, 80808) total 80808 pixels, scheduling recalculate work item
2018-07-07 22:06:14,219 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,318 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,422 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,522 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,622 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,725 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,823 total_time=480052, total_pixels=4895749
2018-07-07 22:06:14,864 calculate_batch_delay for wid=25 current batch delay=1273, last update 417039 seconds ago
2018-07-07 22:06:14,865 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-07 22:06:14,865 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 49, 'recent': 49}
2018-07-07 22:06:14,866 update_batch_delay: damage-rate                  : 0.00,1.03  {'max_latency': 100, 'elapsed': 1270}
2018-07-07 22:06:14,866 update_batch_delay: client-latency               : 0.94,0.10  {'target': 6, 'weight_multiplier': 423, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 886, 'div': 1000, 'avg': 32, 'recent': 23}
2018-07-07 22:06:14,866 update_batch_delay: client-ping-latency          : 0.91,0.05  {'target': 6, 'weight_multiplier': 179, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 823, 'div': 1000, 'avg': 9, 'recent': 11}
2018-07-07 22:06:14,866 update_batch_delay: server-ping-latency          : 0.51,0.13  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 259, 'div': 1000, 'avg': 2, 'recent': 2}
2018-07-07 22:06:14,866 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:14,867 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:14,867 update_batch_delay: compression-work-queue       : 0.00,0.10  {'target': 1000, 'weight_multiplier': 97, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 9, 'recent': 0}
2018-07-07 22:06:14,867 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 80808000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:14,867 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:06:14,867 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:06:14,868 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:06:14,868 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:06:14,868 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:06:14,868 update_batch_delay: delay=281
2018-07-07 22:06:14,922 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,024 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,124 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,226 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,326 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,427 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,526 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,627 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,728 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,829 total_time=480052, total_pixels=4895749
2018-07-07 22:06:15,929 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,035 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,131 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,233 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,330 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,434 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,537 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,635 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,737 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,835 total_time=480052, total_pixels=4895749
2018-07-07 22:06:16,934 total_time=480052, total_pixels=4895749
2018-07-07 22:06:17,054 total_time=480052, total_pixels=4895749
2018-07-07 22:06:17,181 total_time=480052, total_pixels=4895749
2018-07-07 22:06:17,280 total_time=480052, total_pixels=4895749
2018-07-07 22:06:17,411 total_time=480052, total_pixels=4895749
2018-07-07 22:06:17,459 packet decoding sequence 58 for window 9: 234x25 took 1.3ms
2018-07-07 22:06:17,459 record_latency: took  300.2 ms round trip,  300.1 for echo,    1.0 for decoding of     5850 pixels,       58 bytes sent over the network in  298.9 ms,  298.8 ms for echo
2018-07-07 22:06:17,460 may_recalculate(9, 5850) total 5850 pixels, scheduling recalculate work item
2018-07-07 22:06:17,460 calculate_batch_delay for wid=9 current batch delay=39, last update 41 seconds ago
2018-07-07 22:06:17,461 calculate_batch_delay for wid=9, 3446471 pixels updated since the last update
2018-07-07 22:06:17,462 calculate_batch_delay for wid=9, skipping - only 57 bytes sent since the last update
2018-07-07 22:06:17,517 total_time=481330, total_pixels=4901599
2018-07-07 22:06:17,591 packet decoding sequence 1 for window 26: 438x131 took 2.6ms
2018-07-07 22:06:17,591 record_latency: took   48.5 ms round trip,   48.4 for echo,    2.0 for decoding of    57378 pixels,       60 bytes sent over the network in   45.8 ms,   45.8 ms for echo
2018-07-07 22:06:17,591 may_recalculate(26, 57378) total 57378 pixels, scheduling recalculate work item
2018-07-07 22:06:17,614 total_time=483970, total_pixels=4958977
2018-07-07 22:06:17,719 total_time=483970, total_pixels=4958977
2018-07-07 22:06:17,818 total_time=483970, total_pixels=4958977
2018-07-07 22:06:17,913 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,019 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,120 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,217 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,321 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,417 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,460 calculate_batch_delay for wid=26 current batch delay=832, last update 417043 seconds ago
2018-07-07 22:06:18,461 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 22:06:18,462 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 48, 'recent': 48}
2018-07-07 22:06:18,462 update_batch_delay: damage-rate                  : 0.00,1.11  {'max_latency': 100, 'elapsed': 1423}
2018-07-07 22:06:18,462 update_batch_delay: client-latency               : 2.01,0.43  {'target': 6, 'weight_multiplier': 423, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4053, 'div': 1000, 'avg': 58, 'recent': 137}
2018-07-07 22:06:18,463 update_batch_delay: client-ping-latency          : 0.47,0.13  {'target': 6, 'weight_multiplier': 176, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 222, 'div': 1000, 'avg': 5, 'recent': 2}
2018-07-07 22:06:18,463 update_batch_delay: server-ping-latency          : 0.73,0.10  {'target': 7, 'weight_multiplier': 189, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 526, 'div': 1000, 'avg': 3, 'recent': 4}
2018-07-07 22:06:18,463 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:18,464 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:18,464 update_batch_delay: compression-work-queue       : 0.00,0.11  {'target': 1000, 'weight_multiplier': 106, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 11, 'recent': 1}
2018-07-07 22:06:18,464 update_batch_delay: congestion                   : 1.08,0.79  {}
2018-07-07 22:06:18,464 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 57378000, 'avg': 0, 'recent': 0}
2018-07-07 22:06:18,465 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 22:06:18,465 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 22:06:18,465 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 22:06:18,465 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 22:06:18,465 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 22:06:18,465 update_batch_delay: delay=326
2018-07-07 22:06:18,517 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,625 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,720 total_time=483970, total_pixels=4958977
2018-07-07 22:06:18,801 packet decoding sequence 59 for window 9: 234x45 took 2.4ms
2018-07-07 22:06:18,801 record_latency: took   55.1 ms round trip,   55.0 for echo,    2.0 for decoding of    10530 pixels,       61 bytes sent over the network in   52.7 ms,   52.6 ms for echo
2018-07-07 22:06:18,801 may_recalculate(9, 10530) total 10530 pixels, scheduling recalculate work item
2018-07-07 22:06:18,819 total_time=486374, total_pixels=4969507
2018-07-07 22:06:18,899 packet decoding sequence 60 for window 9: 12x20 took 1.0ms
2018-07-07 22:06:18,899 record_latency: took   84.5 ms round trip,   84.5 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   83.5 ms,   83.4 ms for echo
2018-07-07 22:06:18,920 total_time=487399, total_pixels=4969747
2018-07-07 22:06:19,000 packet decoding sequence 61 for window 9: 12x20 took 1.3ms
2018-07-07 22:06:19,001 record_latency: took   35.4 ms round trip,   35.3 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   34.1 ms,   34.0 ms for echo
2018-07-07 22:06:19,027 total_time=488678, total_pixels=4969987
2018-07-07 22:06:19,098 packet decoding sequence 62 for window 9: 12x20 took 1.2ms
2018-07-07 22:06:19,099 record_latency: took   80.0 ms round trip,   79.8 for echo,    1.0 for decoding of      240 pixels,       55 bytes sent over the network in   78.8 ms,   78.6 ms for echo
2018-07-07 22:06:19,126 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,226 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,325 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,423 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,461 calculate_batch_delay for wid=9 current batch delay=39, last update 43 seconds ago
2018-07-07 22:06:19,462 calculate_batch_delay for wid=9, 3457721 pixels updated since the last update
2018-07-07 22:06:19,462 calculate_batch_delay for wid=9, skipping - only 61 bytes sent since the last update
2018-07-07 22:06:19,527 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,627 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,727 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,829 total_time=489905, total_pixels=4970227
2018-07-07 22:06:19,930 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,039 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,153 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,255 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,356 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,457 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,558 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,657 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,761 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,860 total_time=489905, total_pixels=4970227
2018-07-07 22:06:20,958 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,065 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,180 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,276 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,375 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,476 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,579 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,677 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,781 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,877 total_time=489905, total_pixels=4970227
2018-07-07 22:06:21,977 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,077 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,181 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,282 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,382 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,485 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,586 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,686 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,787 total_time=489905, total_pixels=4970227
2018-07-07 22:06:22,841 client @13.644 Warning: the opus sound sink has stopped
2018-07-07 22:06:22,847 sound source stopping
2018-07-07 22:06:22,847 xpra client 2 disconnected.
2018-07-07 22:13:00,364 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:13:00,365 New unix-domain connection received on /home/steved/.xpra/beelink-1
2018-07-07 22:14:17,588 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:14:17,596 Handshake complete; enabling connection
2018-07-07 22:14:17,604  automatic picture encoding enabled, also available:
2018-07-07 22:14:17,605   h264, vp9, vp8, png, png/P, png/L, webp, rgb24, rgb32, jpeg
2018-07-07 22:14:17,606  mmap is enabled using 256MB area in /run/user/1000/xpra/xpra.EKy4y4.mmap
2018-07-07 22:14:17,607 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
2018-07-07 22:14:17,607  connected from 'beelink' as 'steved' - 'Steve Dee'
2018-07-07 22:14:17,612  client root window size is 2880x900 with 1 display:
2018-07-07 22:14:17,613   :0.0 (761x237 mm - DPI: 96x96)
2018-07-07 22:14:17,613     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
2018-07-07 22:14:17,613     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
2018-07-07 22:14:17,623 best resolution matching 2880x900 is unchanged: 2880x960
2018-07-07 22:14:17,631 setting key repeat rate from client: 500ms delay / 50ms interval
2018-07-07 22:14:17,634 keyboard mapping already configured (skipped)
2018-07-07 22:14:17,674 client @04.479 Xpra X11 server version 2.3.2-r19729 64-bit
2018-07-07 22:14:17,679 client @04.480  running on Linux Ubuntu 18.04 bionic
2018-07-07 22:14:17,686 client @04.488 Attached to :1
2018-07-07 22:14:17,687 client @04.490  (press Control-C to detach)
2018-07-07 22:14:17,719 total_time=0, total_pixels=0
2018-07-07 22:14:17,796 client @04.600 server does not support xi input devices
2018-07-07 22:14:17,797 client @04.601  server uses: xtest
2018-07-07 22:14:17,980 New unix-domain connection received on /run/user/1000/xpra/beelink-1
2018-07-07 22:14:17,982 New unix-domain connection received on /home/steved/.xpra/beelink-1
2018-07-07 22:14:19,612 using pulseaudio device:
2018-07-07 22:14:19,612  'Monitor of Xpra Speaker'
2018-07-07 22:14:21,967 sound source using 'opus' audio codec
2018-07-07 22:14:28,085 total_time=0, total_pixels=0