Ticket #1911: xpra-ether-1804-1404.txt

2018-07-07 21:36:10,698 cannot access python uinput module:
2018-07-07 21:36:10,698  No module named uinput
_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
_XSERVTransMakeAllCOTSServerListeners: server already running
2018-07-07 21:36:10,790 created unix domain socket: /run/user/1000/xpra/xubuntu-1
2018-07-07 21:36:10,790 created unix domain socket: /home/steved/.xpra/xubuntu-1
2018-07-07 21:36:10,790 cannot create group socket '/run/xpra/xubuntu-1'
2018-07-07 21:36:10,790  [Errno 13] Permission denied
2018-07-07 21:36:10,824 pointer device emulation using XTest
2018-07-07 21:36:11,598 serving html content from: /usr/share/xpra/www
2018-07-07 21:36:11,674 D-Bus notification forwarding is available
2018-07-07 21:36:11,755 Warning: webcam forwarding is disabled
2018-07-07 21:36:11,756  the virtual video directory '/sys/devices/virtual/video4linux' was not found
2018-07-07 21:36:11,756  make sure that the 'v4l2loopback' kernel module is installed and loaded
2018-07-07 21:36:11,756 found 0 virtual video devices for webcam forwarding
2018-07-07 21:36:11,763 pulseaudio server started with pid 12696
2018-07-07 21:36:11,763  private server socket path:
2018-07-07 21:36:11,763  '/run/user/1000/xpra/pulse-:1/pulse/native'
2018-07-07 21:36:12,472 GStreamer version 1.8.3 for Python 2.7.12 64-bit
2018-07-07 21:36:12,538 xpra X11 version 2.3.2-r19729 64-bit
2018-07-07 21:36:12,538  uid=1000 (steved), gid=1000 (steved)
2018-07-07 21:36:12,538  running with pid 12644 on Linux Ubuntu 16.04 xenial
2018-07-07 21:36:12,538  connected to X11 display :1 with 24 bit colors
2018-07-07 21:36:12,594 xpra is ready.
2018-07-07 21:36:12,643 printer forwarding enabled using postscript and pdf
2018-07-07 21:36:12,651 7.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 21:37:39,469 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
2018-07-07 21:37:39,476 Handshake complete; enabling connection
2018-07-07 21:37:39,485  automatic picture encoding enabled, also available:
2018-07-07 21:37:39,485   h264, vp9, vp8, png, png/P, png/L, rgb24, rgb32, jpeg
2018-07-07 21:37:39,485 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
2018-07-07 21:37:39,485  connected from 'beelink' as 'steved' - 'Steve Dee'
2018-07-07 21:37:39,486  client root window size is 2880x900 with 1 display:
2018-07-07 21:37:39,486   :0.0 (761x237 mm - DPI: 96x96)
2018-07-07 21:37:39,486     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
2018-07-07 21:37:39,486     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
2018-07-07 21:37:40,041 server virtual display now set to 2880x900
2018-07-07 21:37:40,045 setting key repeat rate from client: 500ms delay / 50ms interval
2018-07-07 21:37:40,048 setting keymap: rules=evdev, model=pc105, layout=gb
2018-07-07 21:37:40,063 setting keyboard layout to 'gb'
2018-07-07 21:37:40,096 DPI set to 96 x 96
2018-07-07 21:37:40,122 client @45.924 Xpra X11 server version 2.3.2-r19729 64-bit
2018-07-07 21:37:40,128 client @45.926  running on Linux Ubuntu 16.04 xenial
2018-07-07 21:37:40,174 client @45.934 Attached to xubuntu via ssh
2018-07-07 21:37:40,175 client @45.934  (press Control-C to detach)
2018-07-07 21:37:40,217 New unix-domain connection received on /home/steved/.xpra/xubuntu-1
2018-07-07 21:37:40,217 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
2018-07-07 21:37:40,247 client @46.051 server does not support xi input devices
2018-07-07 21:37:40,250 client @46.052  server uses: xtest
2018-07-07 21:37:40,346 total_time=0, total_pixels=0
2018-07-07 21:37:40,405 packet decoding sequence 1 for window 1: 735x457 took 21.0ms
2018-07-07 21:37:40,406 record_latency: took   55.2 ms round trip,   55.2 for echo,   20.0 for decoding of   335895 pixels,    15758 bytes sent over the network in   34.2 ms,   34.2 ms for echo
2018-07-07 21:37:40,406 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
2018-07-07 21:37:40,407 calculate_batch_delay for wid=1 current batch delay=28, last update 80136 seconds ago
2018-07-07 21:37:40,407 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:40,408 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 65, 'recent': 65}
2018-07-07 21:37:40,408 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 85}
2018-07-07 21:37:40,408 update_batch_delay: client-latency               : 0.92,0.43  {'target': 39, 'weight_multiplier': 1566, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 852, 'div': 1000, 'avg': 34, 'recent': 34}
2018-07-07 21:37:40,408 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:40,408 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:40,408 update_batch_delay: compression-work-queue       : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:40,408 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:40,409 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:37:40,409 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:40,409 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:40,409 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:40,409 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:40,409 update_batch_delay: delay=13
2018-07-07 21:37:40,413 packet decoding sequence 2 for window 1: 735x457 took 14.8ms
2018-07-07 21:37:40,413 record_latency: took   35.7 ms round trip,   35.7 for echo,   14.0 for decoding of   335895 pixels,    18555 bytes sent over the network in   20.9 ms,   20.9 ms for echo
2018-07-07 21:37:40,413 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
2018-07-07 21:37:40,616 packet decoding sequence 3 for window 1: 735x457 took 13.6ms
2018-07-07 21:37:40,616 record_latency: took   18.3 ms round trip,   18.2 for echo,   13.0 for decoding of   335895 pixels,    18555 bytes sent over the network in    4.7 ms,    4.6 ms for echo
2018-07-07 21:37:41,144 the remote printer 'PDF' has been configured
2018-07-07 21:37:41,176 the remote printer 'MX470-series' has been configured
2018-07-07 21:37:41,408 calculate_batch_delay for wid=1 current batch delay=13, last update 1 seconds ago
2018-07-07 21:37:41,409 calculate_batch_delay for wid=1, skipping - only 1 events since the last update
2018-07-07 21:37:41,730 packet decoding sequence 4 for window 1: 13x434 took 3.4ms
2018-07-07 21:37:41,730 record_latency: took    8.6 ms round trip,    8.5 for echo,    3.0 for decoding of     5642 pixels,    22638 bytes sent over the network in    5.2 ms,    5.1 ms for echo
2018-07-07 21:37:41,730 may_recalculate(1, 5642) total 5642 pixels, scheduling recalculate work item
2018-07-07 21:37:42,050 using pulseaudio device:
2018-07-07 21:37:42,050  'Monitor of Xpra Speaker'
2018-07-07 21:37:42,407 calculate_batch_delay for wid=1 current batch delay=13, last update 2 seconds ago
2018-07-07 21:37:42,408 calculate_batch_delay for wid=1, skipping - only 2 events since the last update
2018-07-07 21:37:43,197 sound source using 'opus' audio codec
2018-07-07 21:37:46,152 packet decoding sequence 5 for window 1: 13x21 took 15.8ms
2018-07-07 21:37:46,152 record_latency: took 1362.0 ms round trip, 1361.9 for echo,   15.0 for decoding of      273 pixels,     1151 bytes sent over the network in 1346.1 ms, 1346.1 ms for echo
2018-07-07 21:37:46,156 packet decoding sequence 6 for window 1: 243x16 took 19.2ms
2018-07-07 21:37:46,156 record_latency: took 1364.8 ms round trip, 1364.8 for echo,   19.0 for decoding of     3888 pixels,    15621 bytes sent over the network in 1345.6 ms, 1345.6 ms for echo
2018-07-07 21:37:46,156 may_recalculate(1, 3888) total 4161 pixels, scheduling recalculate work item
2018-07-07 21:37:46,157 calculate_batch_delay for wid=1 current batch delay=13, last update 5 seconds ago
2018-07-07 21:37:46,157 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:46,157 update_batch_delay: client-decode-speed          : 1.02,0.00  {'avg': 49, 'recent': 50}
2018-07-07 21:37:46,158 update_batch_delay: damage-rate                  : 0.91,0.30  {'max_latency': 111, 'elapsed': 312}
2018-07-07 21:37:46,158 update_batch_delay: client-latency               : 4.39,1.05  {'target': 9, 'weight_multiplier': 568, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 19260, 'div': 1000, 'avg': 1025, 'recent': 1336}
2018-07-07 21:37:46,158 update_batch_delay: client-ping-latency          : 0.74,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 544, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:37:46,158 update_batch_delay: server-ping-latency          : 0.55,0.21  {'target': 12, 'weight_multiplier': 310, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 306, 'div': 1000, 'avg': 24, 'recent': 8}
2018-07-07 21:37:46,158 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 21:37:46,159 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:46,159 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 21:37:46,159 update_batch_delay: congestion                   : 2.75,17.48  {}
2018-07-07 21:37:46,159 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:46,160 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:37:46,161 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:46,162 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:46,163 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:46,164 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:46,164 update_batch_delay: delay=36
2018-07-07 21:37:46,165 get_client_backlog missing acks: []
2018-07-07 21:37:46,165 get_target_quality: target=0% (window 735x457) pctpixdamaged=402%, dpc={1: 1007685, 2: 340056, 3: 0, 4: 0, 5: 5642, 6: 1659775, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:37:46,166 update_quality() wid=1, info={'batch-delay-ratio': 241, 'backlog_factor': (4, 1343580, 335895, 4, 0), 'congestion-value': 1748, 'latency': 5, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'min_speed': 30}, quality=30
2018-07-07 21:37:46,166 update_speed() wid=1, info={}, speed=100
2018-07-07 21:37:47,088 packet decoding sequence 7 for window 1: 735x457 took 950.9ms
2018-07-07 21:37:47,089 record_latency: took 2014.9 ms round trip, 2014.8 for echo,  950.0 for decoding of   335895 pixels,    18706 bytes sent over the network in 1064.0 ms, 1063.9 ms for echo
2018-07-07 21:37:47,089 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
2018-07-07 21:37:47,104 packet decoding sequence 8 for window 1: 735x457 took 949.1ms
2018-07-07 21:37:47,105 record_latency: took 1760.2 ms round trip, 1760.1 for echo,  949.0 for decoding of   335895 pixels,    18706 bytes sent over the network in  811.0 ms,  811.0 ms for echo
2018-07-07 21:37:47,121 packet decoding sequence 9 for window 1: 735x457 took 953.6ms
2018-07-07 21:37:47,121 record_latency: took 1366.1 ms round trip, 1366.0 for echo,  953.0 for decoding of   335895 pixels,    18706 bytes sent over the network in  412.5 ms,  412.4 ms for echo
2018-07-07 21:37:47,137 packet decoding sequence 10 for window 1: 735x457 took 957.5ms
2018-07-07 21:37:47,137 record_latency: took 1111.1 ms round trip, 1111.0 for echo,  957.0 for decoding of   335895 pixels,    18706 bytes sent over the network in  153.6 ms,  153.6 ms for echo
2018-07-07 21:37:47,154 packet decoding sequence 11 for window 1: 735x457 took 15.1ms
2018-07-07 21:37:47,154 record_latency: took  453.7 ms round trip,  453.6 for echo,   15.0 for decoding of   335895 pixels,    18706 bytes sent over the network in  438.6 ms,  438.6 ms for echo
2018-07-07 21:37:47,157 calculate_batch_delay for wid=1 current batch delay=36, last update 1 seconds ago
2018-07-07 21:37:47,158 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:47,158 update_batch_delay: client-decode-speed          : 1.18,0.18  {'avg': 171, 'recent': 216}
2018-07-07 21:37:47,158 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 230, 'elapsed': 63}
2018-07-07 21:37:47,158 update_batch_delay: bandwidth-limit              : 0.06,0.00  {'used': 298672, 'budget': 5242880}
2018-07-07 21:37:47,159 update_batch_delay: client-latency               : 2.98,0.80  {'target': 9, 'weight_multiplier': 568, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 8881, 'div': 1000, 'avg': 643, 'recent': 597}
2018-07-07 21:37:47,159 update_batch_delay: client-ping-latency          : 0.73,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 532, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:37:47,159 update_batch_delay: server-ping-latency          : 0.66,0.18  {'target': 12, 'weight_multiplier': 310, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 437, 'div': 1000, 'avg': 28, 'recent': 14}
2018-07-07 21:37:47,159 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 21:37:47,159 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:47,159 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 21:37:47,159 update_batch_delay: congestion                   : 5.53,45.29  {}
2018-07-07 21:37:47,159 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:47,160 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:37:47,160 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:47,160 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:47,160 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:47,160 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:47,160 update_batch_delay: delay=148
2018-07-07 21:37:47,161 get_client_backlog missing acks: []
2018-07-07 21:37:47,161 get_target_quality: target=0% (window 735x457) pctpixdamaged=801%, dpc={1: 1343580, 2: 1007685, 3: 340056, 4: 0, 5: 0, 6: 5642, 7: 1659775, 8: 0, 9: 0, 10: 0}
2018-07-07 21:37:47,161 update_quality() wid=1, info={'batch-delay-ratio': 51, 'backlog_factor': (1, 335895, 335895, 1, 100), 'congestion-value': 4529, 'latency': 12, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=30
2018-07-07 21:37:47,161 update_speed() wid=1, info={}, speed=100
2018-07-07 21:37:47,170 packet decoding sequence 12 for window 1: 735x457 took 19.3ms
2018-07-07 21:37:47,171 record_latency: took  168.8 ms round trip,  168.7 for echo,   19.0 for decoding of   335895 pixels,    18706 bytes sent over the network in  149.5 ms,  149.3 ms for echo
2018-07-07 21:37:47,171 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
2018-07-07 21:37:47,383 packet decoding sequence 13 for window 1: 735x457 took 17.6ms
2018-07-07 21:37:47,383 record_latency: took   23.2 ms round trip,   23.1 for echo,   17.0 for decoding of   335895 pixels,    18706 bytes sent over the network in    5.5 ms,    5.4 ms for echo
2018-07-07 21:37:47,433 packet decoding sequence 14 for window 1: 735x457 took 18.4ms
2018-07-07 21:37:47,433 record_latency: took   23.1 ms round trip,   23.1 for echo,   18.0 for decoding of   335895 pixels,    18706 bytes sent over the network in    4.7 ms,    4.7 ms for echo
2018-07-07 21:37:47,796 packet decoding sequence 15 for window 1: 13x21 took 2.1ms
2018-07-07 21:37:47,797 record_latency: took    6.8 ms round trip,    6.7 for echo,    2.0 for decoding of      273 pixels,     1144 bytes sent over the network in    4.7 ms,    4.6 ms for echo
2018-07-07 21:37:48,158 calculate_batch_delay for wid=1 current batch delay=148, last update 1 seconds ago
2018-07-07 21:37:48,159 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:48,159 update_batch_delay: client-decode-speed          : 0.97,0.01  {'avg': 94, 'recent': 91}
2018-07-07 21:37:48,159 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 230, 'elapsed': 138}
2018-07-07 21:37:48,159 update_batch_delay: bandwidth-limit              : 0.09,0.00  {'used': 307408, 'budget': 3589377}
2018-07-07 21:37:48,159 update_batch_delay: client-latency               : 1.96,0.56  {'target': 9, 'weight_multiplier': 568, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 3853, 'div': 1000, 'avg': 400, 'recent': 246}
2018-07-07 21:37:48,160 update_batch_delay: client-ping-latency          : 0.73,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 538, 'div': 1000, 'avg': 5, 'recent': 5}
2018-07-07 21:37:48,160 update_batch_delay: server-ping-latency          : 0.59,0.20  {'target': 12, 'weight_multiplier': 310, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 346, 'div': 1000, 'avg': 18, 'recent': 7}
2018-07-07 21:37:48,160 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 21:37:48,160 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:48,160 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 21:37:48,160 update_batch_delay: congestion                   : 1.44,4.40  {}
2018-07-07 21:37:48,160 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:48,161 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:37:48,161 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:48,161 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:48,161 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:48,161 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:48,161 update_batch_delay: delay=160
2018-07-07 21:37:48,162 get_target_quality: target=6% (window 735x457) pctpixdamaged=801%, dpc={1: 1508, 2: 1343580, 3: 1007685, 4: 340056, 5: 0, 6: 0, 7: 5642, 8: 1659775, 9: 0, 10: 0}
2018-07-07 21:37:48,162 update_quality() wid=1, info={'batch-delay-ratio': 45, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 439, 'latency': 29, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'max-quality-range': 59, 'min_speed': 30}, quality=30
2018-07-07 21:37:48,162 update_speed() wid=1, info={}, speed=100
2018-07-07 21:37:48,298 packet decoding sequence 1 for window 2: 314x165 took 14.1ms
2018-07-07 21:37:48,298 record_latency: took   20.0 ms round trip,   19.9 for echo,   14.0 for decoding of    51810 pixels,     4537 bytes sent over the network in    5.9 ms,    5.8 ms for echo
2018-07-07 21:37:48,298 may_recalculate(2, 51810) total 51810 pixels, scheduling recalculate work item
2018-07-07 21:37:48,472 packet decoding sequence 16 for window 1: 65x19 took 53.9ms
2018-07-07 21:37:48,472 record_latency: took   62.1 ms round trip,   61.3 for echo,   53.0 for decoding of     1235 pixels,     5008 bytes sent over the network in    8.2 ms,    7.4 ms for echo
2018-07-07 21:37:48,641 packet decoding sequence 1 for window 3: 221x82 took 12.1ms
2018-07-07 21:37:48,641 record_latency: took   15.8 ms round trip,   15.7 for echo,   12.0 for decoding of    18122 pixels,     2928 bytes sent over the network in    3.7 ms,    3.6 ms for echo
2018-07-07 21:37:48,642 may_recalculate(3, 18122) total 18122 pixels, scheduling recalculate work item
2018-07-07 21:37:48,820 packet decoding sequence 17 for window 1: 106x19 took 1.1ms
2018-07-07 21:37:48,820 record_latency: took   44.6 ms round trip,   44.5 for echo,    1.0 for decoding of     2014 pixels,     8124 bytes sent over the network in   43.5 ms,   43.4 ms for echo
2018-07-07 21:37:48,820 may_recalculate(1, 2014) total 3249 pixels, scheduling recalculate work item
2018-07-07 21:37:48,892 packet decoding sequence 1 for window 4: 314x165 took 12.9ms
2018-07-07 21:37:48,892 record_latency: took   16.8 ms round trip,   16.7 for echo,   12.0 for decoding of    51810 pixels,     4537 bytes sent over the network in    3.9 ms,    3.8 ms for echo
2018-07-07 21:37:48,893 may_recalculate(4, 51810) total 51810 pixels, scheduling recalculate work item
2018-07-07 21:37:49,073 packet decoding sequence 18 for window 1: 106x19 took 2.6ms
2018-07-07 21:37:49,073 record_latency: took   10.3 ms round trip,    9.4 for echo,    2.0 for decoding of     2014 pixels,     8124 bytes sent over the network in    7.7 ms,    6.8 ms for echo
2018-07-07 21:37:49,158 calculate_batch_delay for wid=1 current batch delay=160, last update 0 seconds ago
2018-07-07 21:37:49,159 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 40, 'recent': 41}
2018-07-07 21:37:49,159 update_batch_delay: client-decode-speed          : 1.03,0.01  {'avg': 93, 'recent': 97}
2018-07-07 21:37:49,159 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 174, 'elapsed': 15}
2018-07-07 21:37:49,159 update_batch_delay: bandwidth-limit              : 0.05,0.00  {'used': 168416, 'budget': 3992478}
2018-07-07 21:37:49,159 update_batch_delay: client-latency               : 0.93,0.14  {'target': 8, 'weight_multiplier': 520, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 865, 'div': 1000, 'avg': 208, 'recent': 47}
2018-07-07 21:37:49,159 update_batch_delay: client-ping-latency          : 0.73,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 533, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:37:49,160 update_batch_delay: server-ping-latency          : 0.65,0.18  {'target': 12, 'weight_multiplier': 310, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 416, 'div': 1000, 'avg': 20, 'recent': 10}
2018-07-07 21:37:49,160 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 21:37:49,160 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:49,160 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 21:37:49,160 update_batch_delay: congestion                   : 1.13,1.26  {}
2018-07-07 21:37:49,160 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:49,160 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:37:49,160 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:49,161 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:49,161 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:49,161 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:49,161 update_batch_delay: delay=131
2018-07-07 21:37:49,161 get_target_quality: target=25% (window 735x457) pctpixdamaged=803%, dpc={1: 6023, 2: 1508, 3: 1343580, 4: 1007685, 5: 340056, 6: 0, 7: 0, 8: 5642, 9: 1659775, 10: 0}
2018-07-07 21:37:49,162 update_quality() wid=1, info={'batch-delay-ratio': 53, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 125, 'latency': 152, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'max-quality-range': 63, 'min_speed': 30}, quality=36
2018-07-07 21:37:49,162 update_speed() wid=1, info={}, speed=100
2018-07-07 21:37:49,284 packet decoding sequence 1 for window 6: 166x48 took 6.8ms
2018-07-07 21:37:49,284 record_latency: took   29.4 ms round trip,   29.4 for echo,    6.0 for decoding of     7968 pixels,     1728 bytes sent over the network in   22.6 ms,   22.5 ms for echo
2018-07-07 21:37:49,285 may_recalculate(6, 7968) total 7968 pixels, scheduling recalculate work item
2018-07-07 21:37:49,391 packet decoding sequence 19 for window 1: 146x19 took 2.9ms
2018-07-07 21:37:49,392 record_latency: took    9.4 ms round trip,    9.3 for echo,    2.0 for decoding of     2774 pixels,    11166 bytes sent over the network in    6.5 ms,    6.5 ms for echo
2018-07-07 21:37:49,392 may_recalculate(1, 2774) total 2774 pixels, scheduling recalculate work item
2018-07-07 21:37:49,437 packet decoding sequence 2 for window 6: 166x48 took 2.8ms
2018-07-07 21:37:49,437 record_latency: took    7.0 ms round trip,    6.9 for echo,    2.0 for decoding of     7968 pixels,     3337 bytes sent over the network in    4.2 ms,    4.1 ms for echo
2018-07-07 21:37:49,858 packet decoding sequence 1 for window 8: 314x165 took 12.0ms
2018-07-07 21:37:49,858 record_latency: took   15.8 ms round trip,   15.7 for echo,   12.0 for decoding of    51810 pixels,     4537 bytes sent over the network in    3.8 ms,    3.7 ms for echo
2018-07-07 21:37:49,858 may_recalculate(8, 51810) total 51810 pixels, scheduling recalculate work item
2018-07-07 21:37:49,891 packet decoding sequence 20 for window 1: 146x19 took 1.3ms
2018-07-07 21:37:49,891 record_latency: took    6.2 ms round trip,    6.1 for echo,    1.0 for decoding of     2774 pixels,    11166 bytes sent over the network in    4.9 ms,    4.8 ms for echo
2018-07-07 21:37:50,158 calculate_batch_delay for wid=1 current batch delay=131, last update 1 seconds ago
2018-07-07 21:37:50,159 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:50,159 update_batch_delay: client-decode-speed          : 1.04,0.02  {'avg': 92, 'recent': 98}
2018-07-07 21:37:50,159 update_batch_delay: damage-rate                  : 0.88,0.34  {'max_latency': 146, 'elapsed': 407}
2018-07-07 21:37:50,159 update_batch_delay: bandwidth-limit              : 0.05,0.00  {'used': 177536, 'budget': 3596209}
2018-07-07 21:37:50,160 update_batch_delay: client-latency               : 0.79,0.24  {'target': 8, 'weight_multiplier': 520, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 625, 'div': 1000, 'avg': 153, 'recent': 31}
2018-07-07 21:37:50,160 update_batch_delay: client-ping-latency          : 0.73,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 526, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:37:50,160 update_batch_delay: server-ping-latency          : 0.70,0.17  {'target': 12, 'weight_multiplier': 310, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 491, 'div': 1000, 'avg': 22, 'recent': 13}
2018-07-07 21:37:50,160 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 21:37:50,160 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:50,160 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 21:37:50,160 update_batch_delay: congestion                   : 1.06,0.59  {}
2018-07-07 21:37:50,161 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:50,161 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:37:50,161 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:50,161 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:50,161 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:50,161 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:50,161 update_batch_delay: delay=111
2018-07-07 21:37:50,162 get_target_quality: target=32% (window 735x457) pctpixdamaged=703%, dpc={1: 3553, 2: 6023, 3: 1508, 4: 1343580, 5: 1007685, 6: 340056, 7: 0, 8: 0, 9: 5642, 10: 1659775}
2018-07-07 21:37:50,162 update_quality() wid=1, info={'batch-delay-ratio': 61, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 58, 'latency': 226, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'max-quality-range': 59, 'min_speed': 30}, quality=42
2018-07-07 21:37:50,162 update_speed() wid=1, info={}, speed=100
2018-07-07 21:37:50,163 calculate_batch_delay for wid=8 current batch delay=88, last update 80145 seconds ago
2018-07-07 21:37:50,163 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:50,163 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 243, 'recent': 243}
2018-07-07 21:37:50,163 update_batch_delay: damage-rate                  : 0.79,0.46  {'max_latency': 100, 'elapsed': 411}
2018-07-07 21:37:50,163 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 35904, 'budget': 1646670}
2018-07-07 21:37:50,163 update_batch_delay: client-latency               : 0.79,0.24  {'target': 8, 'weight_multiplier': 520, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 625, 'div': 1000, 'avg': 153, 'recent': 31}
2018-07-07 21:37:50,164 update_batch_delay: client-ping-latency          : 0.73,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 526, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:37:50,164 update_batch_delay: server-ping-latency          : 0.70,0.17  {'target': 12, 'weight_multiplier': 310, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 491, 'div': 1000, 'avg': 22, 'recent': 13}
2018-07-07 21:37:50,164 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 21:37:50,164 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:50,164 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 21:37:50,164 update_batch_delay: congestion                   : 1.06,0.59  {}
2018-07-07 21:37:50,164 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:50,164 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:37:50,164 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 21:37:50,164 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:50,164 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:50,164 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:50,164 update_batch_delay: delay=70
2018-07-07 21:37:50,235 packet decoding sequence 2 for window 8: 314x165 took 4.6ms
2018-07-07 21:37:50,235 record_latency: took    8.9 ms round trip,    8.9 for echo,    4.0 for decoding of    51810 pixels,     9327 bytes sent over the network in    4.3 ms,    4.2 ms for echo
2018-07-07 21:37:50,235 may_recalculate(8, 51810) total 51810 pixels, scheduling recalculate work item
2018-07-07 21:37:51,159 calculate_batch_delay for wid=8 current batch delay=70, last update 0 seconds ago
2018-07-07 21:37:51,159 calculate_batch_delay for wid=8, skipping - only 1 events since the last update
2018-07-07 21:37:53,466 packet decoding sequence 3 for window 8: 312x19 took 3.8ms
2018-07-07 21:37:53,466 record_latency: took    9.7 ms round trip,    9.7 for echo,    3.0 for decoding of     5928 pixels,     1078 bytes sent over the network in    5.9 ms,    5.9 ms for echo
2018-07-07 21:37:53,466 may_recalculate(8, 5928) total 5928 pixels, scheduling recalculate work item
2018-07-07 21:37:53,467 calculate_batch_delay for wid=8 current batch delay=70, last update 3 seconds ago
2018-07-07 21:37:53,467 calculate_batch_delay for wid=8, skipping - only 2 events since the last update
2018-07-07 21:37:53,506 packet decoding sequence 21 for window 1: 65x19 took 1.2ms
2018-07-07 21:37:53,507 record_latency: took    9.8 ms round trip,    9.8 for echo,    1.0 for decoding of     1235 pixels,     5008 bytes sent over the network in    8.6 ms,    8.6 ms for echo
2018-07-07 21:37:53,577 packet decoding sequence 4 for window 8: 312x21 took 5.7ms
2018-07-07 21:37:53,577 record_latency: took   11.5 ms round trip,   11.4 for echo,    5.0 for decoding of     6552 pixels,     1348 bytes sent over the network in    5.8 ms,    5.6 ms for echo
2018-07-07 21:37:53,577 may_recalculate(8, 6552) total 6552 pixels, scheduling recalculate work item
2018-07-07 21:37:53,672 packet decoding sequence 5 for window 8: 312x21 took 5.3ms
2018-07-07 21:37:53,672 record_latency: took    9.6 ms round trip,    9.5 for echo,    5.0 for decoding of     6552 pixels,     1263 bytes sent over the network in    4.3 ms,    4.2 ms for echo
2018-07-07 21:37:53,710 packet decoding sequence 22 for window 1: 65x19 took 2.8ms
2018-07-07 21:37:53,711 record_latency: took    8.5 ms round trip,    8.4 for echo,    2.0 for decoding of     1235 pixels,     5009 bytes sent over the network in    5.7 ms,    5.6 ms for echo
2018-07-07 21:37:53,711 may_recalculate(1, 1235) total 2470 pixels, scheduling recalculate work item
2018-07-07 21:37:53,834 packet decoding sequence 6 for window 8: 312x19 took 2.8ms
2018-07-07 21:37:53,834 record_latency: took    7.9 ms round trip,    7.8 for echo,    2.0 for decoding of     5928 pixels,     1298 bytes sent over the network in    5.1 ms,    5.1 ms for echo
2018-07-07 21:37:53,871 packet decoding sequence 23 for window 1: 65x19 took 1.2ms
2018-07-07 21:37:53,871 record_latency: took    5.0 ms round trip,    4.9 for echo,    1.0 for decoding of     1235 pixels,     5009 bytes sent over the network in    3.7 ms,    3.7 ms for echo
2018-07-07 21:37:53,931 packet decoding sequence 7 for window 8: 312x19 took 5.5ms
2018-07-07 21:37:53,931 record_latency: took   12.4 ms round trip,   12.3 for echo,    5.0 for decoding of     5928 pixels,     1487 bytes sent over the network in    6.9 ms,    6.8 ms for echo
2018-07-07 21:37:53,933 packet decoding sequence 8 for window 8: 312x19 took 9.1ms
2018-07-07 21:37:53,934 record_latency: took   13.1 ms round trip,   13.0 for echo,    9.0 for decoding of     5928 pixels,      998 bytes sent over the network in    4.0 ms,    3.9 ms for echo
2018-07-07 21:37:53,936 packet decoding sequence 9 for window 8: 312x19 took 2.0ms
2018-07-07 21:37:53,937 record_latency: took   14.9 ms round trip,   14.8 for echo,    2.0 for decoding of     5928 pixels,     1226 bytes sent over the network in   12.9 ms,   12.8 ms for echo
2018-07-07 21:37:54,015 packet decoding sequence 10 for window 8: 312x19 took 2.5ms
2018-07-07 21:37:54,015 record_latency: took    7.4 ms round trip,    7.3 for echo,    2.0 for decoding of     5928 pixels,     1479 bytes sent over the network in    4.9 ms,    4.8 ms for echo
2018-07-07 21:37:54,040 packet decoding sequence 24 for window 1: 65x19 took 1.2ms
2018-07-07 21:37:54,040 record_latency: took    9.8 ms round trip,    9.7 for echo,    1.0 for decoding of     1235 pixels,     5009 bytes sent over the network in    8.6 ms,    8.5 ms for echo
2018-07-07 21:37:54,468 calculate_batch_delay for wid=1 current batch delay=111, last update 4 seconds ago
2018-07-07 21:37:54,468 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:37:54,469 update_batch_delay: client-decode-speed          : 1.05,0.03  {'avg': 88, 'recent': 95}
2018-07-07 21:37:54,469 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 110, 'elapsed': 98}
2018-07-07 21:37:54,469 update_batch_delay: bandwidth-limit              : 0.03,0.00  {'used': 158080, 'budget': 5242880}
2018-07-07 21:37:54,469 update_batch_delay: client-latency               : 0.51,0.36  {'target': 8, 'weight_multiplier': 520, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 264, 'div': 1000, 'avg': 67, 'recent': 10}
2018-07-07 21:37:54,469 update_batch_delay: client-ping-latency          : 0.72,0.12  {'target': 8, 'weight_multiplier': 226, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 519, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:37:54,469 update_batch_delay: server-ping-latency          : 0.61,0.16  {'target': 9, 'weight_multiplier': 263, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 368, 'div': 1000, 'avg': 13, 'recent': 6}
2018-07-07 21:37:54,469 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 21:37:54,470 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:54,470 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 21:37:54,470 update_batch_delay: congestion                   : 1.01,0.12  {}
2018-07-07 21:37:54,470 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:37:54,470 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:37:54,470 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:37:54,470 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:37:54,471 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:37:54,471 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:37:54,471 update_batch_delay: delay=81
2018-07-07 21:37:54,471 get_target_quality: target=49% (window 735x457) pctpixdamaged=2%, dpc={1: 4940, 2: 1235, 3: 0, 4: 0, 5: 2774, 6: 4788, 7: 3249, 8: 1007958, 9: 1007685, 10: 675951}
2018-07-07 21:37:54,472 update_quality() wid=1, info={'batch-delay-ratio': 82, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 11, 'latency': 698, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=89
2018-07-07 21:37:54,472 update_speed() wid=1, info={}, speed=100
2018-07-07 21:37:54,566 packet decoding sequence 25 for window 1: 65x19 took 1.7ms
2018-07-07 21:37:54,567 record_latency: took    7.2 ms round trip,    7.1 for echo,    1.0 for decoding of     1235 pixels,     5009 bytes sent over the network in    5.5 ms,    5.5 ms for echo
2018-07-07 21:37:59,856 packet decoding sequence 26 for window 1: 13x21 took 2.6ms
2018-07-07 21:37:59,856 record_latency: took    7.8 ms round trip,    7.7 for echo,    2.0 for decoding of      273 pixels,     1144 bytes sent over the network in    5.2 ms,    5.1 ms for echo
2018-07-07 21:38:05,148 total_time=4146070, total_pixels=4012150
2018-07-07 21:38:05,400 packet decoding sequence 27 for window 1: 13x21 took 3.3ms
2018-07-07 21:38:05,400 record_latency: took    8.3 ms round trip,    8.3 for echo,    3.0 for decoding of      273 pixels,     1144 bytes sent over the network in    5.0 ms,    5.0 ms for echo
2018-07-07 21:38:06,471 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:06,573 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:06,673 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:06,774 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:06,874 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:06,975 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,077 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,175 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,278 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,379 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,480 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,585 total_time=4149369, total_pixels=4012423
2018-07-07 21:38:07,606 packet decoding sequence 28 for window 1: 13x21 took 1.3ms
2018-07-07 21:38:07,606 record_latency: took   27.8 ms round trip,   27.7 for echo,    1.0 for decoding of      273 pixels,     1144 bytes sent over the network in   26.5 ms,   26.5 ms for echo
2018-07-07 21:38:07,606 may_recalculate(1, 273) total 2054 pixels, scheduling recalculate work item
2018-07-07 21:38:07,607 calculate_batch_delay for wid=1 current batch delay=81, last update 13 seconds ago
2018-07-07 21:38:07,607 calculate_batch_delay for wid=1, skipping - only 4 events since the last update
2018-07-07 21:38:07,608 get_target_quality: target=49% (window 735x457) pctpixdamaged=0%, dpc={1: 273, 2: 0, 3: 273, 4: 0, 5: 0, 6: 0, 7: 0, 8: 273, 9: 0, 10: 0}
2018-07-07 21:38:07,608 update_quality() wid=1, info={'batch-delay-ratio': 81, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 0, 'latency': 265, 'compression-ratio': (54, 54, 0), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=99
2018-07-07 21:38:07,608 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:07,684 total_time=4150623, total_pixels=4012696
2018-07-07 21:38:07,786 total_time=4150623, total_pixels=4012696
2018-07-07 21:38:07,887 total_time=4150623, total_pixels=4012696
2018-07-07 21:38:07,992 total_time=4150623, total_pixels=4012696
2018-07-07 21:38:08,107 total_time=4150623, total_pixels=4012696
2018-07-07 21:38:08,207 total_time=4150623, total_pixels=4012696
2018-07-07 21:38:08,235 packet decoding sequence 29 for window 1: 13x21 took 1.0ms
2018-07-07 21:38:08,235 record_latency: took   45.8 ms round trip,   45.7 for echo,    1.0 for decoding of      273 pixels,     1144 bytes sent over the network in   44.8 ms,   44.7 ms for echo
2018-07-07 21:38:08,293 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:08,422 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:08,527 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:08,622 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:08,739 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:08,843 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:08,941 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:09,040 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:09,142 total_time=4151626, total_pixels=4012969
2018-07-07 21:38:09,206 packet decoding sequence 30 for window 1: 735x457 took 8.3ms
2018-07-07 21:38:09,206 record_latency: took  776.1 ms round trip,  755.4 for echo,    8.0 for decoding of   335895 pixels,  1343639 bytes sent over the network in  767.9 ms,  747.1 ms for echo
2018-07-07 21:38:09,207 may_recalculate(1, 335895) total 336168 pixels, scheduling recalculate work item
2018-07-07 21:38:09,207 calculate_batch_delay for wid=1 current batch delay=81, last update 14 seconds ago
2018-07-07 21:38:09,208 update_batch_delay: damage-network-delay         : 1.12,0.62  {'avg': 50, 'recent': 58}
2018-07-07 21:38:09,208 update_batch_delay: client-decode-speed          : 0.84,0.02  {'avg': 32, 'recent': 25}
2018-07-07 21:38:09,208 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 22}
2018-07-07 21:38:09,208 update_batch_delay: bandwidth-limit              : 6.77,45.88  {'used': 32245920, 'budget': 5242880}
2018-07-07 21:38:09,208 update_batch_delay: client-latency               : 3.45,0.81  {'target': 8, 'weight_multiplier': 520, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 11895, 'div': 1000, 'avg': 253, 'recent': 671}
2018-07-07 21:38:09,209 update_batch_delay: client-ping-latency          : 0.66,0.13  {'target': 8, 'weight_multiplier': 225, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 438, 'div': 1000, 'avg': 4, 'recent': 4}
2018-07-07 21:38:09,209 update_batch_delay: server-ping-latency          : 0.62,0.13  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 381, 'div': 1000, 'avg': 6, 'recent': 4}
2018-07-07 21:38:09,209 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 21:38:09,209 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:09,209 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 21:38:09,209 update_batch_delay: congestion                   : 1.87,8.74  {}
2018-07-07 21:38:09,209 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:09,210 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:09,210 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:09,210 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:09,210 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:09,210 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
2018-07-07 21:38:09,210 update_batch_delay: delay=277
2018-07-07 21:38:09,210 get_client_backlog missing acks: []
2018-07-07 21:38:09,211 get_target_quality: target=0% (window 735x457) pctpixdamaged=300%, dpc={1: 1007958, 2: 546, 3: 0, 4: 273, 5: 0, 6: 0, 7: 0, 8: 0, 9: 0, 10: 273}
2018-07-07 21:38:09,211 update_quality() wid=1, info={'batch-delay-ratio': 23, 'backlog_factor': (1, 335895, 335895, 1, 100), 'congestion-value': 874, 'latency': 10, 'compression-ratio': (3359, 3995, -61), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=33
2018-07-07 21:38:09,211 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:09,212 packet decoding sequence 31 for window 1: 735x457 took 13.2ms
2018-07-07 21:38:09,212 record_latency: took  376.0 ms round trip,  356.9 for echo,   13.0 for decoding of   335895 pixels,  1343639 bytes sent over the network in  362.8 ms,  343.7 ms for echo
2018-07-07 21:38:09,213 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
2018-07-07 21:38:09,225 total_time=4173071, total_pixels=4684759
2018-07-07 21:38:09,232 packet decoding sequence 32 for window 1: 735x457 took 17.0ms
2018-07-07 21:38:09,232 record_latency: took   58.9 ms round trip,   39.1 for echo,   17.0 for decoding of   335895 pixels,  1343639 bytes sent over the network in   41.9 ms,   22.1 ms for echo
2018-07-07 21:38:09,328 total_time=4190073, total_pixels=5020654
2018-07-07 21:38:09,427 total_time=4190073, total_pixels=5020654
2018-07-07 21:38:09,528 total_time=4190073, total_pixels=5020654
2018-07-07 21:38:09,592 packet decoding sequence 33 for window 1: 13x21 took 1.3ms
2018-07-07 21:38:09,592 record_latency: took    4.8 ms round trip,    4.7 for echo,    1.0 for decoding of      273 pixels,     1144 bytes sent over the network in    3.5 ms,    3.5 ms for echo
2018-07-07 21:38:09,630 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:09,729 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:09,830 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:09,932 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:10,033 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:10,133 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:10,208 calculate_batch_delay for wid=1 current batch delay=277, last update 1 seconds ago
2018-07-07 21:38:10,208 update_batch_delay: damage-network-delay         : 1.02,0.52  {'avg': 47, 'recent': 48}
2018-07-07 21:38:10,209 update_batch_delay: client-decode-speed          : 0.91,0.01  {'avg': 41, 'recent': 36}
2018-07-07 21:38:10,209 update_batch_delay: damage-rate                  : 0.18,0.91  {'max_latency': 100, 'elapsed': 1022}
2018-07-07 21:38:10,209 update_batch_delay: bandwidth-limit              : 0.00,0.00  {'used': 8736, 'budget': 5242880}
2018-07-07 21:38:10,209 update_batch_delay: client-latency               : 1.98,0.51  {'target': 8, 'weight_multiplier': 510, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 3918, 'div': 1000, 'avg': 175, 'recent': 203}
2018-07-07 21:38:10,209 update_batch_delay: client-ping-latency          : 0.65,0.13  {'target': 8, 'weight_multiplier': 225, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 424, 'div': 1000, 'avg': 4, 'recent': 3}
2018-07-07 21:38:10,209 update_batch_delay: server-ping-latency          : 0.66,0.13  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 430, 'div': 1000, 'avg': 6, 'recent': 4}
2018-07-07 21:38:10,209 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 21:38:10,211 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:10,211 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 21:38:10,212 update_batch_delay: congestion                   : 1.08,0.79  {}
2018-07-07 21:38:10,212 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:10,212 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:10,212 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:10,212 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:10,212 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:10,212 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:10,213 update_batch_delay: delay=182
2018-07-07 21:38:10,213 get_target_quality: target=0% (window 735x457) pctpixdamaged=300%, dpc={1: 0, 2: 1007958, 3: 546, 4: 0, 5: 273, 6: 0, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:10,213 update_quality() wid=1, info={'batch-delay-ratio': 35, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 79, 'latency': 35, 'compression-ratio': (3020, 3958, -95), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=32
2018-07-07 21:38:10,214 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:10,254 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:10,345 total_time=4191347, total_pixels=5020927
2018-07-07 21:38:10,357 packet decoding sequence 1 for window 9: 221x82 took 7.0ms
2018-07-07 21:38:10,357 record_latency: took   41.0 ms round trip,   40.9 for echo,    7.0 for decoding of    18122 pixels,     2928 bytes sent over the network in   34.0 ms,   33.9 ms for echo
2018-07-07 21:38:10,357 may_recalculate(9, 18122) total 18122 pixels, scheduling recalculate work item
2018-07-07 21:38:10,447 total_time=4198387, total_pixels=5039049
2018-07-07 21:38:10,507 packet decoding sequence 34 for window 1: 41x19 took 1.6ms
2018-07-07 21:38:10,508 record_latency: took    5.7 ms round trip,    5.6 for echo,    1.0 for decoding of      779 pixels,     3168 bytes sent over the network in    4.1 ms,    4.0 ms for echo
2018-07-07 21:38:10,546 total_time=4199970, total_pixels=5039828
2018-07-07 21:38:10,631 packet decoding sequence 2 for window 9: 221x82 took 3.8ms
2018-07-07 21:38:10,631 record_latency: took    8.8 ms round trip,    8.8 for echo,    3.0 for decoding of    18122 pixels,     4194 bytes sent over the network in    5.0 ms,    4.9 ms for echo
2018-07-07 21:38:10,647 total_time=4203794, total_pixels=5057950
2018-07-07 21:38:10,748 total_time=4203794, total_pixels=5057950
2018-07-07 21:38:10,849 total_time=4203794, total_pixels=5057950
2018-07-07 21:38:10,952 total_time=4203794, total_pixels=5057950
2018-07-07 21:38:10,987 packet decoding sequence 3 for window 9: 219x19 took 5.3ms
2018-07-07 21:38:10,988 record_latency: took   15.4 ms round trip,   15.3 for echo,    5.0 for decoding of     4161 pixels,    12536 bytes sent over the network in   10.1 ms,   10.1 ms for echo
2018-07-07 21:38:11,051 total_time=4209076, total_pixels=5062111
2018-07-07 21:38:11,153 total_time=4209076, total_pixels=5062111
2018-07-07 21:38:11,191 packet decoding sequence 35 for window 1: 41x19 took 1.1ms
2018-07-07 21:38:11,191 record_latency: took   32.9 ms round trip,   32.8 for echo,    1.0 for decoding of      779 pixels,     3168 bytes sent over the network in   31.8 ms,   31.8 ms for echo
2018-07-07 21:38:11,208 calculate_batch_delay for wid=9 current batch delay=72, last update 80166 seconds ago
2018-07-07 21:38:11,208 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:38:11,208 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 291, 'recent': 291}
2018-07-07 21:38:11,208 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 6}
2018-07-07 21:38:11,209 update_batch_delay: bandwidth-limit              : 0.20,0.00  {'used': 156152, 'budget': 865212}
2018-07-07 21:38:11,209 update_batch_delay: client-latency               : 0.86,0.19  {'target': 8, 'weight_multiplier': 510, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 731, 'div': 1000, 'avg': 95, 'recent': 32}
2018-07-07 21:38:11,209 update_batch_delay: client-ping-latency          : 0.60,0.14  {'target': 7, 'weight_multiplier': 213, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 357, 'div': 1000, 'avg': 3, 'recent': 3}
2018-07-07 21:38:11,209 update_batch_delay: server-ping-latency          : 0.57,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 319, 'div': 1000, 'avg': 5, 'recent': 3}
2018-07-07 21:38:11,209 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 21:38:11,209 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 18122000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:11,209 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 21:38:11,210 update_batch_delay: congestion                   : 1.02,0.21  {}
2018-07-07 21:38:11,210 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 18122000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:11,210 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:11,210 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 21:38:11,210 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:11,210 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:11,210 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:11,211 update_batch_delay: delay=53
2018-07-07 21:38:11,254 total_time=4210162, total_pixels=5062890
2018-07-07 21:38:11,299 packet decoding sequence 4 for window 9: 219x38 took 2.3ms
2018-07-07 21:38:11,299 record_latency: took   32.7 ms round trip,   32.6 for echo,    2.0 for decoding of     8322 pixels,     1838 bytes sent over the network in   30.4 ms,   30.3 ms for echo
2018-07-07 21:38:11,299 may_recalculate(9, 8322) total 8322 pixels, scheduling recalculate work item
2018-07-07 21:38:11,354 total_time=4212462, total_pixels=5071212
2018-07-07 21:38:11,455 total_time=4212462, total_pixels=5071212
2018-07-07 21:38:11,491 packet decoding sequence 36 for window 1: 41x19 took 1.8ms
2018-07-07 21:38:11,491 record_latency: took   41.2 ms round trip,   41.1 for echo,    1.0 for decoding of      779 pixels,     3168 bytes sent over the network in   39.3 ms,   39.3 ms for echo
2018-07-07 21:38:11,492 may_recalculate(1, 779) total 2337 pixels, scheduling recalculate work item
2018-07-07 21:38:11,494 packet decoding sequence 5 for window 9: 219x38 took 4.6ms
2018-07-07 21:38:11,494 record_latency: took   29.7 ms round trip,   29.6 for echo,    4.0 for decoding of     8322 pixels,     1930 bytes sent over the network in   25.1 ms,   25.0 ms for echo
2018-07-07 21:38:11,557 total_time=4218892, total_pixels=5080313
2018-07-07 21:38:11,598 packet decoding sequence 6 for window 9: 219x38 took 1.5ms
2018-07-07 21:38:11,598 record_latency: took   34.8 ms round trip,   34.7 for echo,    1.0 for decoding of     8322 pixels,     2035 bytes sent over the network in   33.4 ms,   33.3 ms for echo
2018-07-07 21:38:11,657 total_time=4220347, total_pixels=5088635
2018-07-07 21:38:11,697 packet decoding sequence 37 for window 1: 41x19 took 1.4ms
2018-07-07 21:38:11,697 record_latency: took   29.5 ms round trip,   29.3 for echo,    1.0 for decoding of      779 pixels,     3168 bytes sent over the network in   28.1 ms,   27.9 ms for echo
2018-07-07 21:38:11,700 packet decoding sequence 7 for window 9: 219x19 took 4.0ms
2018-07-07 21:38:11,701 record_latency: took   32.3 ms round trip,   32.2 for echo,    3.0 for decoding of     4161 pixels,     1136 bytes sent over the network in   28.3 ms,   28.2 ms for echo
2018-07-07 21:38:11,758 total_time=4225696, total_pixels=5093575
2018-07-07 21:38:11,858 total_time=4225696, total_pixels=5093575
2018-07-07 21:38:11,899 packet decoding sequence 38 for window 1: 41x19 took 1.0ms
2018-07-07 21:38:11,899 record_latency: took   28.1 ms round trip,   28.0 for echo,    0.0 for decoding of      779 pixels,     3168 bytes sent over the network in   27.1 ms,   27.1 ms for echo
2018-07-07 21:38:11,959 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,059 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,161 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,208 calculate_batch_delay for wid=1 current batch delay=182, last update 2 seconds ago
2018-07-07 21:38:12,209 update_batch_delay: damage-network-delay         : 0.97,0.53  {'avg': 43, 'recent': 41}
2018-07-07 21:38:12,209 update_batch_delay: client-decode-speed          : 0.87,0.03  {'avg': 45, 'recent': 37}
2018-07-07 21:38:12,209 update_batch_delay: damage-rate                  : 0.60,0.63  {'max_latency': 100, 'elapsed': 597}
2018-07-07 21:38:12,210 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 74784, 'budget': 4700636}
2018-07-07 21:38:12,210 update_batch_delay: client-latency               : 0.93,0.14  {'target': 8, 'weight_multiplier': 510, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 860, 'div': 1000, 'avg': 71, 'recent': 34}
2018-07-07 21:38:12,210 update_batch_delay: client-ping-latency          : 0.78,0.10  {'target': 7, 'weight_multiplier': 213, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 608, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:38:12,210 update_batch_delay: server-ping-latency          : 0.55,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 306, 'div': 1000, 'avg': 5, 'recent': 3}
2018-07-07 21:38:12,210 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 21:38:12,210 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:12,210 update_batch_delay: compression-work-queue       : 0.08,0.28  {'target': 1000, 'weight_multiplier': 301, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 55, 'div': 1000, 'avg': 40, 'recent': 90}
2018-07-07 21:38:12,211 update_batch_delay: congestion                   : 1.01,0.10  {}
2018-07-07 21:38:12,211 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:12,211 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:12,211 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:12,211 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:12,211 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:12,211 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:12,212 update_batch_delay: delay=130
2018-07-07 21:38:12,213 get_target_quality: target=0% (window 735x457) pctpixdamaged=301%, dpc={1: 1558, 2: 2337, 3: 0, 4: 1007958, 5: 546, 6: 0, 7: 273, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:12,213 update_quality() wid=1, info={'batch-delay-ratio': 50, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 9, 'latency': 211, 'compression-ratio': (2569, 3788, -131), 'min_quality': 30, 'max-quality-range': 68, 'min_speed': 30}, quality=31
2018-07-07 21:38:12,214 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:12,215 calculate_batch_delay for wid=9 current batch delay=53, last update 1 seconds ago
2018-07-07 21:38:12,215 calculate_batch_delay for wid=9, skipping - only 4 events since the last update
2018-07-07 21:38:12,215 get_target_quality: target=4% (window 221x82) pctpixdamaged=383%, dpc={1: 20805, 2: 48727, 3: 0, 4: 0, 5: 0, 6: 0, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:12,216 update_quality() wid=9, info={'batch-delay-ratio': 176, 'backlog_factor': (0, 0, 18122, 0, 100), 'congestion-value': 9, 'latency': 211, 'compression-ratio': (265, 268, 0), 'min_quality': 30, 'max-quality-range': 23, 'min_speed': 30}, quality=32
2018-07-07 21:38:12,216 update_speed() wid=9, info={}, speed=100
2018-07-07 21:38:12,259 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,362 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,463 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,564 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,664 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,764 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,866 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:12,967 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:13,069 total_time=4226650, total_pixels=5094354
2018-07-07 21:38:13,095 packet decoding sequence 39 for window 1: 41x19 took 1.1ms
2018-07-07 21:38:13,096 record_latency: took    4.5 ms round trip,    4.4 for echo,    1.0 for decoding of      779 pixels,     3168 bytes sent over the network in    3.4 ms,    3.3 ms for echo
2018-07-07 21:38:13,167 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,269 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,370 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,470 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,571 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,672 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,772 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,872 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:13,973 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,073 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,174 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,274 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,375 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,477 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,578 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,679 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,779 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,879 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:14,980 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,081 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,181 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,282 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,382 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,483 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,584 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,687 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,788 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,888 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:15,989 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,089 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,189 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,291 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,391 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,492 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,593 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,693 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,793 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,894 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:16,996 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,097 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,199 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,299 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,399 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,500 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,601 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,702 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,802 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:17,903 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,003 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,104 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,208 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,308 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,408 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,509 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,610 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,711 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,812 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:18,913 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,012 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,114 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,214 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,315 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,415 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,518 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,618 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,718 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,819 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:19,920 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,022 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,122 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,222 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,322 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,423 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,526 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,627 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,728 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,828 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:20,928 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,029 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,129 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,231 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,330 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,431 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,533 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,635 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,734 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,834 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,934 total_time=4227788, total_pixels=5095133
2018-07-07 21:38:21,979 packet decoding sequence 40 for window 1: 13x21 took 1.5ms
2018-07-07 21:38:21,980 record_latency: took   56.5 ms round trip,   54.7 for echo,    1.0 for decoding of      273 pixels,     1144 bytes sent over the network in   55.1 ms,   53.2 ms for echo
2018-07-07 21:38:22,034 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,135 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,245 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,375 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,465 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,566 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,665 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,786 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,900 total_time=4229244, total_pixels=5095406
2018-07-07 21:38:22,990 packet decoding sequence 41 for window 1: 13x21 took 3.2ms
2018-07-07 21:38:22,990 record_latency: took  725.4 ms round trip,  725.4 for echo,    3.0 for decoding of      273 pixels,     1144 bytes sent over the network in  722.3 ms,  722.2 ms for echo
2018-07-07 21:38:23,001 total_time=4232398, total_pixels=5095679
2018-07-07 21:38:23,076 packet decoding sequence 42 for window 1: 735x457 took 89.5ms
2018-07-07 21:38:23,076 record_latency: took  315.7 ms round trip,  315.7 for echo,   89.0 for decoding of   335895 pixels,    18701 bytes sent over the network in  226.2 ms,  226.1 ms for echo
2018-07-07 21:38:23,076 may_recalculate(1, 335895) total 337220 pixels, scheduling recalculate work item
2018-07-07 21:38:23,079 calculate_batch_delay for wid=1 current batch delay=130, last update 10 seconds ago
2018-07-07 21:38:23,079 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
2018-07-07 21:38:23,080 update_batch_delay: client-decode-speed          : 1.73,2.93  {'avg': 118, 'recent': 276}
2018-07-07 21:38:23,080 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 229, 'elapsed': 100}
2018-07-07 21:38:23,080 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 158032, 'budget': 7445198}
2018-07-07 21:38:23,080 update_batch_delay: client-latency               : 2.86,0.69  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 8178, 'div': 1000, 'avg': 233, 'recent': 446}
2018-07-07 21:38:23,080 update_batch_delay: client-ping-latency          : 0.79,0.10  {'target': 7, 'weight_multiplier': 209, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 616, 'div': 1000, 'avg': 5, 'recent': 6}
2018-07-07 21:38:23,080 update_batch_delay: server-ping-latency          : 0.57,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 329, 'div': 1000, 'avg': 4, 'recent': 3}
2018-07-07 21:38:23,081 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 21:38:23,081 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:23,081 update_batch_delay: compression-work-queue       : 0.00,0.08  {'target': 1000, 'weight_multiplier': 80, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 6, 'recent': 0}
2018-07-07 21:38:23,081 update_batch_delay: congestion                   : 2.75,17.48  {}
2018-07-07 21:38:23,081 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:23,081 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:23,081 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:23,082 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:23,082 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:23,082 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:23,082 update_batch_delay: delay=265
2018-07-07 21:38:23,083 get_target_quality: target=1% (window 735x457) pctpixdamaged=200%, dpc={1: 672336, 2: 546, 3: 0, 4: 0, 5: 0, 6: 0, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:23,083 update_quality() wid=1, info={'batch-delay-ratio': 24, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 1748, 'latency': 16, 'compression-ratio': (683, 66, 127), 'min_quality': 30, 'max-quality-range': 86, 'min_speed': 30}, quality=30
2018-07-07 21:38:23,084 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:23,099 total_time=4321928, total_pixels=5431574
2018-07-07 21:38:23,200 total_time=4321928, total_pixels=5431574
2018-07-07 21:38:23,295 total_time=4321928, total_pixels=5431574
2018-07-07 21:38:23,369 packet decoding sequence 43 for window 1: 735x457 took 14.1ms
2018-07-07 21:38:23,369 record_latency: took   76.9 ms round trip,   76.8 for echo,   14.0 for decoding of   335895 pixels,    18701 bytes sent over the network in   62.7 ms,   62.7 ms for echo
2018-07-07 21:38:23,369 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
2018-07-07 21:38:23,402 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:23,504 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:23,613 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:23,706 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:23,810 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:23,910 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:24,013 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:24,080 calculate_batch_delay for wid=1 current batch delay=265, last update 1 seconds ago
2018-07-07 21:38:24,081 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
2018-07-07 21:38:24,081 update_batch_delay: client-decode-speed          : 0.96,0.01  {'avg': 65, 'recent': 62}
2018-07-07 21:38:24,081 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 207, 'elapsed': 387}
2018-07-07 21:38:24,081 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 149296, 'budget': 14847651}
2018-07-07 21:38:24,081 update_batch_delay: client-latency               : 2.17,0.54  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4692, 'div': 1000, 'avg': 174, 'recent': 241}
2018-07-07 21:38:24,081 update_batch_delay: client-ping-latency          : 0.73,0.11  {'target': 7, 'weight_multiplier': 209, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 530, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:38:24,082 update_batch_delay: server-ping-latency          : 0.57,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 328, 'div': 1000, 'avg': 4, 'recent': 3}
2018-07-07 21:38:24,083 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 21:38:24,085 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:24,085 update_batch_delay: compression-work-queue       : 0.00,0.08  {'target': 1000, 'weight_multiplier': 83, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 7, 'recent': 0}
2018-07-07 21:38:24,086 update_batch_delay: congestion                   : 1.16,1.59  {}
2018-07-07 21:38:24,086 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 335895000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:24,086 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:24,086 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:24,087 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:24,088 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:24,088 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:24,089 update_batch_delay: delay=255
2018-07-07 21:38:24,090 get_target_quality: target=6% (window 735x457) pctpixdamaged=200%, dpc={1: 546, 2: 672336, 3: 546, 4: 0, 5: 0, 6: 0, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:24,090 update_quality() wid=1, info={'batch-delay-ratio': 25, 'backlog_factor': (0, 0, 335895, 0, 100), 'congestion-value': 158, 'latency': 29, 'compression-ratio': (558, 81, 102), 'min_quality': 30, 'max-quality-range': 121, 'min_speed': 30}, quality=32
2018-07-07 21:38:24,090 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:24,110 total_time=4336063, total_pixels=5767469
2018-07-07 21:38:24,159 packet decoding sequence 44 for window 1: 13x21 took 1.0ms
2018-07-07 21:38:24,159 record_latency: took   22.2 ms round trip,   22.1 for echo,    1.0 for decoding of      273 pixels,     1145 bytes sent over the network in   21.2 ms,   21.1 ms for echo
2018-07-07 21:38:24,211 total_time=4337063, total_pixels=5767742
2018-07-07 21:38:24,315 total_time=4337063, total_pixels=5767742
2018-07-07 21:38:24,414 total_time=4337063, total_pixels=5767742
2018-07-07 21:38:24,470 packet decoding sequence 45 for window 1: 13x21 took 1.0ms
2018-07-07 21:38:24,470 record_latency: took   40.2 ms round trip,   40.1 for echo,    1.0 for decoding of      273 pixels,     1145 bytes sent over the network in   39.2 ms,   39.1 ms for echo
2018-07-07 21:38:24,514 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:24,618 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:24,717 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:24,822 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:24,931 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,029 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,136 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,239 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,352 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,456 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,566 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,675 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,781 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,886 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:25,984 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:26,095 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:26,185 total_time=4338099, total_pixels=5768015
2018-07-07 21:38:26,280 packet decoding sequence 46 for window 1: 690x457 took 317.0ms
2018-07-07 21:38:26,280 record_latency: took  991.4 ms round trip,  991.4 for echo,  317.0 for decoding of   315330 pixels,    18153 bytes sent over the network in  674.4 ms,  674.4 ms for echo
2018-07-07 21:38:26,280 may_recalculate(1, 315330) total 315876 pixels, scheduling recalculate work item
2018-07-07 21:38:26,281 calculate_batch_delay for wid=1 current batch delay=255, last update 2 seconds ago
2018-07-07 21:38:26,283 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
2018-07-07 21:38:26,283 update_batch_delay: client-decode-speed          : 2.90,7.60  {'avg': 120, 'recent': 778}
2018-07-07 21:38:26,283 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 119, 'elapsed': 166}
2018-07-07 21:38:26,283 update_batch_delay: bandwidth-limit              : 0.06,0.00  {'used': 274680, 'budget': 5242880}
2018-07-07 21:38:26,283 update_batch_delay: client-latency               : 3.37,0.78  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 11387, 'div': 1000, 'avg': 241, 'recent': 625}
2018-07-07 21:38:26,284 update_batch_delay: client-ping-latency          : 1.33,0.12  {'target': 7, 'weight_multiplier': 209, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 1772, 'div': 1000, 'avg': 10, 'recent': 27}
2018-07-07 21:38:26,284 update_batch_delay: server-ping-latency          : 0.67,0.12  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 449, 'div': 1000, 'avg': 4, 'recent': 4}
2018-07-07 21:38:26,284 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 21:38:26,285 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:26,285 update_batch_delay: compression-work-queue       : 0.00,0.07  {'target': 1000, 'weight_multiplier': 72, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 5, 'recent': 0}
2018-07-07 21:38:26,286 packet decoding sequence 47 for window 1: 519x457 took 312.4ms
2018-07-07 21:38:26,286 update_batch_delay: congestion                   : 1.89,8.93  {}
2018-07-07 21:38:26,286 record_latency: took  381.6 ms round trip,  381.6 for echo,  312.0 for decoding of   237183 pixels,    16262 bytes sent over the network in   69.2 ms,   69.2 ms for echo
2018-07-07 21:38:26,286 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:26,287 may_recalculate(1, 237183) total 237183 pixels, scheduling recalculate work item
2018-07-07 21:38:26,287 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:26,291 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:26,293 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:26,295 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:26,295 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
2018-07-07 21:38:26,295 update_batch_delay: delay=405
2018-07-07 21:38:26,296 get_target_quality: target=0% (window 465x457) pctpixdamaged=1019%, dpc={1: 919941, 2: 573078, 3: 546, 4: 672063, 5: 819, 6: 0, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:26,297 update_quality() wid=1, info={'batch-delay-ratio': 15, 'backlog_factor': (0, 0, 212505, 0, 100), 'congestion-value': 893, 'latency': 11, 'compression-ratio': (428, 76, 83), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=30
2018-07-07 21:38:26,297 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:26,307 total_time=4967498, total_pixels=6320528
2018-07-07 21:38:26,426 total_time=4967498, total_pixels=6320528
2018-07-07 21:38:26,520 total_time=4967498, total_pixels=6320528
2018-07-07 21:38:26,590 packet decoding sequence 48 for window 1: 465x457 took 84.9ms
2018-07-07 21:38:26,590 record_latency: took  195.8 ms round trip,  195.8 for echo,   84.0 for decoding of   212505 pixels,    15638 bytes sent over the network in  110.9 ms,  110.9 ms for echo
2018-07-07 21:38:26,628 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:26,729 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:26,829 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:26,932 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,039 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,171 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,285 calculate_batch_delay for wid=1 current batch delay=405, last update 1 seconds ago
2018-07-07 21:38:27,286 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
2018-07-07 21:38:27,286 update_batch_delay: client-decode-speed          : 2.15,4.59  {'avg': 131, 'recent': 449}
2018-07-07 21:38:27,287 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 110, 'elapsed': 6}
2018-07-07 21:38:27,287 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 133520, 'budget': 10898184}
2018-07-07 21:38:27,287 update_batch_delay: client-latency               : 2.11,0.53  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4459, 'div': 1000, 'avg': 175, 'recent': 229}
2018-07-07 21:38:27,287 update_batch_delay: client-ping-latency          : 0.68,0.12  {'target': 7, 'weight_multiplier': 209, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 457, 'div': 1000, 'avg': 8, 'recent': 5}
2018-07-07 21:38:27,287 update_batch_delay: server-ping-latency          : 0.75,0.11  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 559, 'div': 1000, 'avg': 5, 'recent': 5}
2018-07-07 21:38:27,288 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 21:38:27,288 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:27,288 update_batch_delay: compression-work-queue       : 0.00,0.07  {'target': 1000, 'weight_multiplier': 67, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 4, 'recent': 0}
2018-07-07 21:38:27,288 update_batch_delay: congestion                   : 1.96,9.64  {}
2018-07-07 21:38:27,289 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:27,289 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:27,289 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:27,289 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:27,289 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:27,289 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:27,290 update_batch_delay: delay=449
2018-07-07 21:38:27,290 get_client_backlog missing acks: []
2018-07-07 21:38:27,291 get_target_quality: target=1% (window 465x457) pctpixdamaged=1119%, dpc={1: 213051, 2: 919941, 3: 573078, 4: 546, 5: 672063, 6: 819, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:27,291 update_quality() wid=1, info={'batch-delay-ratio': 14, 'backlog_factor': (1, 273, 212505, 0, 100), 'congestion-value': 964, 'latency': 31, 'compression-ratio': (451, 97, 0), 'min_quality': 30, 'max-quality-range': 104, 'min_speed': 30}, quality=30
2018-07-07 21:38:27,292 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:27,298 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,393 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,494 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,610 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,707 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,809 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:27,911 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,011 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,112 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,207 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,315 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,417 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,518 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,654 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,732 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,834 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:28,933 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:29,053 total_time=5052401, total_pixels=6533033
2018-07-07 21:38:29,099 packet decoding sequence 49 for window 1: 13x21 took 285.1ms
2018-07-07 21:38:29,099 record_latency: took 2098.1 ms round trip, 2098.1 for echo,  285.0 for decoding of      273 pixels,     1145 bytes sent over the network in 1813.0 ms, 1813.0 ms for echo
2018-07-07 21:38:29,137 packet decoding sequence 50 for window 1: 465x457 took 323.1ms
2018-07-07 21:38:29,138 record_latency: took  957.6 ms round trip,  957.6 for echo,  323.0 for decoding of   212505 pixels,    15638 bytes sent over the network in  634.5 ms,  634.5 ms for echo
2018-07-07 21:38:29,138 may_recalculate(1, 212505) total 212778 pixels, scheduling recalculate work item
2018-07-07 21:38:29,139 calculate_batch_delay for wid=1 current batch delay=449, last update 1 seconds ago
2018-07-07 21:38:29,140 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
2018-07-07 21:38:29,142 update_batch_delay: client-decode-speed          : 3.19,8.76  {'avg': 143, 'recent': 1167}
2018-07-07 21:38:29,142 update_batch_delay: damage-rate                  : 0.72,0.52  {'max_latency': 111, 'elapsed': 498}
2018-07-07 21:38:29,142 update_batch_delay: bandwidth-limit              : 0.05,0.00  {'used': 124784, 'budget': 3037124}
2018-07-07 21:38:29,142 update_batch_delay: client-latency               : 4.42,0.93  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 19562, 'div': 1000, 'avg': 522, 'recent': 1186}
2018-07-07 21:38:29,143 update_batch_delay: client-ping-latency          : 0.93,0.05  {'target': 7, 'weight_multiplier': 209, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 868, 'div': 1000, 'avg': 7, 'recent': 11}
2018-07-07 21:38:29,143 update_batch_delay: server-ping-latency          : 0.57,0.14  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 329, 'div': 1000, 'avg': 4, 'recent': 3}
2018-07-07 21:38:29,144 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 21:38:29,144 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:29,144 update_batch_delay: compression-work-queue       : 0.00,0.07  {'target': 1000, 'weight_multiplier': 69, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 4, 'recent': 0}
2018-07-07 21:38:29,145 update_batch_delay: congestion                   : 2.80,17.96  {}
2018-07-07 21:38:29,145 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:29,145 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:29,145 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:29,145 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:29,145 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:29,146 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
2018-07-07 21:38:29,146 update_batch_delay: delay=447
2018-07-07 21:38:29,146 get_target_quality: target=0% (window 465x457) pctpixdamaged=903%, dpc={1: 1508, 2: 425283, 3: 273, 4: 1177689, 5: 315330, 6: 546, 7: 672336, 8: 546, 9: 0, 10: 0}
2018-07-07 21:38:29,147 update_quality() wid=1, info={'batch-delay-ratio': 14, 'backlog_factor': (0, 0, 212505, 0, 100), 'congestion-value': 1795, 'latency': 6, 'compression-ratio': (478, 111, 76), 'min_quality': 30, 'max-quality-range': 55, 'min_speed': 30}, quality=30
2018-07-07 21:38:29,147 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:29,148 packet decoding sequence 1 for window 10: 314x165 took 8.4ms
2018-07-07 21:38:29,148 record_latency: took   23.3 ms round trip,   23.2 for echo,    8.0 for decoding of    51810 pixels,     4537 bytes sent over the network in   14.9 ms,   14.8 ms for echo
2018-07-07 21:38:29,148 may_recalculate(10, 51810) total 51810 pixels, scheduling recalculate work item
2018-07-07 21:38:29,164 total_time=5668990, total_pixels=6797621
2018-07-07 21:38:29,223 packet decoding sequence 51 for window 1: 65x19 took 1.6ms
2018-07-07 21:38:29,224 record_latency: took    5.8 ms round trip,    5.6 for echo,    1.0 for decoding of     1235 pixels,     5017 bytes sent over the network in    4.2 ms,    4.0 ms for echo
2018-07-07 21:38:29,225 packet decoding sequence 52 for window 1: 13x21 took 1.0ms
2018-07-07 21:38:29,225 record_latency: took    5.8 ms round trip,    5.8 for echo,    0.0 for decoding of      273 pixels,     1145 bytes sent over the network in    4.9 ms,    4.8 ms for echo
2018-07-07 21:38:29,265 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,367 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,466 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,566 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,667 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,768 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,869 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:29,968 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:30,073 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:30,139 calculate_batch_delay for wid=10 current batch delay=480, last update 80185 seconds ago
2018-07-07 21:38:30,139 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-07 21:38:30,139 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 169, 'recent': 169}
2018-07-07 21:38:30,140 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 187}
2018-07-07 21:38:30,140 update_batch_delay: bandwidth-limit              : 0.24,0.00  {'used': 294424, 'budget': 1322321}
2018-07-07 21:38:30,140 update_batch_delay: client-latency               : 2.73,0.66  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 7478, 'div': 1000, 'avg': 294, 'recent': 424}
2018-07-07 21:38:30,140 update_batch_delay: client-ping-latency          : 0.80,0.09  {'target': 7, 'weight_multiplier': 209, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 646, 'div': 1000, 'avg': 7, 'recent': 8}
2018-07-07 21:38:30,140 update_batch_delay: server-ping-latency          : 0.64,0.13  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 409, 'div': 1000, 'avg': 4, 'recent': 3}
2018-07-07 21:38:30,140 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 21:38:30,140 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:30,141 update_batch_delay: compression-work-queue       : 0.00,0.06  {'target': 1000, 'weight_multiplier': 61, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 3, 'recent': 0}
2018-07-07 21:38:30,141 update_batch_delay: congestion                   : 1.18,1.82  {}
2018-07-07 21:38:30,141 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:30,141 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:30,141 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 21:38:30,141 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:30,141 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:30,142 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:30,142 update_batch_delay: delay=429
2018-07-07 21:38:30,176 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:30,277 total_time=5671590, total_pixels=6799129
2018-07-07 21:38:30,365 packet decoding sequence 2 for window 10: 312x27 took 3.1ms
2018-07-07 21:38:30,366 record_latency: took  303.5 ms round trip,  303.5 for echo,    3.0 for decoding of     8424 pixels,     1290 bytes sent over the network in  300.4 ms,  300.4 ms for echo
2018-07-07 21:38:30,366 may_recalculate(10, 8424) total 8424 pixels, scheduling recalculate work item
2018-07-07 21:38:30,378 total_time=5674685, total_pixels=6807553
2018-07-07 21:38:30,463 packet decoding sequence 3 for window 10: 312x19 took 100.9ms
2018-07-07 21:38:30,464 record_latency: took  397.3 ms round trip,  397.3 for echo,  100.0 for decoding of     5928 pixels,    17845 bytes sent over the network in  296.4 ms,  296.4 ms for echo
2018-07-07 21:38:30,465 packet decoding sequence 4 for window 10: 312x19 took 102.5ms
2018-07-07 21:38:30,466 record_latency: took  398.1 ms round trip,  398.0 for echo,  102.0 for decoding of     5928 pixels,    17837 bytes sent over the network in  295.6 ms,  295.6 ms for echo
2018-07-07 21:38:30,469 packet decoding sequence 53 for window 1: 65x19 took 105.6ms
2018-07-07 21:38:30,469 record_latency: took  135.0 ms round trip,  134.9 for echo,  105.0 for decoding of     1235 pixels,     5010 bytes sent over the network in   29.4 ms,   29.3 ms for echo
2018-07-07 21:38:30,470 may_recalculate(1, 1235) total 2743 pixels, scheduling recalculate work item
2018-07-07 21:38:30,481 total_time=5983643, total_pixels=6820644
2018-07-07 21:38:30,581 total_time=5983643, total_pixels=6820644
2018-07-07 21:38:30,682 total_time=5983643, total_pixels=6820644
2018-07-07 21:38:30,783 total_time=5983643, total_pixels=6820644
2018-07-07 21:38:30,842 packet decoding sequence 54 for window 1: 65x19 took 1.1ms
2018-07-07 21:38:30,842 record_latency: took   46.4 ms round trip,   46.3 for echo,    1.0 for decoding of     1235 pixels,     5010 bytes sent over the network in   45.3 ms,   45.2 ms for echo
2018-07-07 21:38:30,884 total_time=5984759, total_pixels=6821879
2018-07-07 21:38:30,988 total_time=5984759, total_pixels=6821879
2018-07-07 21:38:31,042 packet decoding sequence 5 for window 10: 312x38 took 2.2ms
2018-07-07 21:38:31,043 record_latency: took   27.4 ms round trip,   27.3 for echo,    2.0 for decoding of    11856 pixels,     1736 bytes sent over the network in   25.2 ms,   25.1 ms for echo
2018-07-07 21:38:31,087 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,139 calculate_batch_delay for wid=1 current batch delay=447, last update 2 seconds ago
2018-07-07 21:38:31,140 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
2018-07-07 21:38:31,140 update_batch_delay: client-decode-speed          : 1.87,3.47  {'avg': 110, 'recent': 292}
2018-07-07 21:38:31,140 update_batch_delay: damage-rate                  : 0.33,0.82  {'max_latency': 100, 'elapsed': 868}
2018-07-07 21:38:31,140 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 79040, 'budget': 4039614}
2018-07-07 21:38:31,140 update_batch_delay: client-latency               : 1.47,0.35  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2171, 'div': 1000, 'avg': 213, 'recent': 116}
2018-07-07 21:38:31,141 update_batch_delay: client-ping-latency          : 0.59,0.13  {'target': 7, 'weight_multiplier': 206, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 349, 'div': 1000, 'avg': 6, 'recent': 4}
2018-07-07 21:38:31,141 update_batch_delay: server-ping-latency          : 0.74,0.11  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 551, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:38:31,141 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 21:38:31,141 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:31,141 update_batch_delay: compression-work-queue       : 0.00,0.06  {'target': 1000, 'weight_multiplier': 57, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 3, 'recent': 0}
2018-07-07 21:38:31,141 update_batch_delay: congestion                   : 3.68,26.78  {}
2018-07-07 21:38:31,141 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:31,142 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:31,142 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:31,142 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:31,142 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:31,142 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:31,142 update_batch_delay: delay=460
2018-07-07 21:38:31,145 get_target_quality: target=34% (window 465x457) pctpixdamaged=202%, dpc={1: 1235, 2: 1235, 3: 1508, 4: 425283, 5: 273, 6: 1177689, 7: 315330, 8: 546, 9: 672336, 10: 546}
2018-07-07 21:38:31,146 update_quality() wid=1, info={'batch-delay-ratio': 14, 'backlog_factor': (0, 0, 212505, 0, 100), 'congestion-value': 2678, 'latency': 61, 'compression-ratio': (571, 215, 57), 'min_quality': 30, 'max-quality-range': 63, 'min_speed': 30}, quality=31
2018-07-07 21:38:31,146 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:31,147 calculate_batch_delay for wid=10 current batch delay=429, last update 1 seconds ago
2018-07-07 21:38:31,147 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:38:31,147 update_batch_delay: client-decode-speed          : 1.06,0.06  {'avg': 190, 'recent': 207}
2018-07-07 21:38:31,147 update_batch_delay: damage-rate                  : 0.21,0.89  {'max_latency': 100, 'elapsed': 993}
2018-07-07 21:38:31,148 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 13504, 'budget': 1203265}
2018-07-07 21:38:31,148 update_batch_delay: client-latency               : 1.47,0.35  {'target': 8, 'weight_multiplier': 503, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 2171, 'div': 1000, 'avg': 213, 'recent': 116}
2018-07-07 21:38:31,148 update_batch_delay: client-ping-latency          : 0.59,0.13  {'target': 7, 'weight_multiplier': 206, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 349, 'div': 1000, 'avg': 6, 'recent': 4}
2018-07-07 21:38:31,148 update_batch_delay: server-ping-latency          : 0.74,0.11  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 551, 'div': 1000, 'avg': 4, 'recent': 5}
2018-07-07 21:38:31,148 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:31,148 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:31,148 update_batch_delay: compression-work-queue       : 0.00,0.06  {'target': 1000, 'weight_multiplier': 57, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 3, 'recent': 0}
2018-07-07 21:38:31,149 update_batch_delay: congestion                   : 3.68,26.78  {}
2018-07-07 21:38:31,149 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:31,149 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:31,149 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 21:38:31,149 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:31,149 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:31,149 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:31,150 update_batch_delay: delay=479
2018-07-07 21:38:31,150 get_target_quality: target=6% (window 314x165) pctpixdamaged=262%, dpc={1: 11856, 2: 20280, 3: 103620, 4: 0, 5: 0, 6: 0, 7: 0, 8: 0, 9: 0, 10: 0}
2018-07-07 21:38:31,150 update_quality() wid=10, info={'batch-delay-ratio': 28, 'backlog_factor': (0, 0, 51810, 0, 100), 'congestion-value': 2678, 'latency': 61, 'compression-ratio': (211, 206, 1), 'min_quality': 30, 'max-quality-range': 34, 'min_speed': 30}, quality=30
2018-07-07 21:38:31,150 update_speed() wid=10, info={}, speed=100
2018-07-07 21:38:31,188 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,289 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,389 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,490 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,592 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,693 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,794 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,889 total_time=5986928, total_pixels=6833735
2018-07-07 21:38:31,959 packet decoding sequence 55 for window 1: 65x19 took 1.2ms
2018-07-07 21:38:31,960 record_latency: took    4.7 ms round trip,    4.6 for echo,    1.0 for decoding of     1235 pixels,     5010 bytes sent over the network in    3.5 ms,    3.4 ms for echo
2018-07-07 21:38:31,994 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,096 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,195 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,298 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,397 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,498 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,599 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,702 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,802 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:32,904 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,005 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,107 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,206 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,307 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,407 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,509 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,609 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,711 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,812 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:33,912 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,012 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,113 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,213 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,314 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,414 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,515 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,617 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,717 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,835 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:34,922 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:35,021 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:35,145 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:35,246 total_time=5988133, total_pixels=6834970
2018-07-07 21:38:35,339 packet decoding sequence 1 for window 11: 314x165 took 7.1ms
2018-07-07 21:38:35,339 record_latency: took   10.2 ms round trip,   10.1 for echo,    7.0 for decoding of    51810 pixels,     4537 bytes sent over the network in    3.1 ms,    3.1 ms for echo
2018-07-07 21:38:35,339 may_recalculate(11, 51810) total 51810 pixels, scheduling recalculate work item
2018-07-07 21:38:35,340 calculate_batch_delay for wid=11 current batch delay=798, last update 80191 seconds ago
2018-07-07 21:38:35,341 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
2018-07-07 21:38:35,341 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 143, 'recent': 143}
2018-07-07 21:38:35,341 update_batch_delay: damage-rate                  : 0.68,0.56  {'max_latency': 100, 'elapsed': 516}
2018-07-07 21:38:35,341 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 35904, 'budget': 2938306}
2018-07-07 21:38:35,341 update_batch_delay: client-latency               : 0.55,0.33  {'target': 8, 'weight_multiplier': 491, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 301, 'div': 1000, 'avg': 160, 'recent': 15}
2018-07-07 21:38:35,342 update_batch_delay: client-ping-latency          : 0.88,0.07  {'target': 7, 'weight_multiplier': 206, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 781, 'div': 1000, 'avg': 6, 'recent': 8}
2018-07-07 21:38:35,342 update_batch_delay: server-ping-latency          : 0.70,0.12  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 490, 'div': 1000, 'avg': 4, 'recent': 4}
2018-07-07 21:38:35,342 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 21:38:35,342 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:35,342 update_batch_delay: compression-work-queue       : 0.00,0.06  {'target': 1000, 'weight_multiplier': 61, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 3, 'recent': 0}
2018-07-07 21:38:35,342 update_batch_delay: congestion                   : 1.02,0.23  {}
2018-07-07 21:38:35,343 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 51810000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:35,346 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:35,347 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 21:38:35,348 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:35,348 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:35,349 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:35,349 update_batch_delay: delay=402
2018-07-07 21:38:35,357 total_time=5995213, total_pixels=6886780
2018-07-07 21:38:35,403 packet decoding sequence 56 for window 1: 65x19 took 1.0ms
2018-07-07 21:38:35,403 record_latency: took   39.7 ms round trip,   39.5 for echo,    1.0 for decoding of     1235 pixels,     5010 bytes sent over the network in   38.7 ms,   38.5 ms for echo
2018-07-07 21:38:35,404 may_recalculate(1, 1235) total 2470 pixels, scheduling recalculate work item
2018-07-07 21:38:35,454 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:35,555 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:35,678 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:35,759 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:35,870 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:35,971 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:36,072 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:36,180 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:36,301 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:36,341 calculate_batch_delay for wid=1 current batch delay=460, last update 5 seconds ago
2018-07-07 21:38:36,341 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
2018-07-07 21:38:36,342 update_batch_delay: client-decode-speed          : 1.34,0.34  {'avg': 92, 'recent': 141}
2018-07-07 21:38:36,342 update_batch_delay: damage-rate                  : 0.52,0.69  {'max_latency': 100, 'elapsed': 675}
2018-07-07 21:38:36,342 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 103360, 'budget': 5611640}
2018-07-07 21:38:36,342 update_batch_delay: client-latency               : 1.08,0.14  {'target': 8, 'weight_multiplier': 491, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1167, 'div': 1000, 'avg': 159, 'recent': 58}
2018-07-07 21:38:36,342 update_batch_delay: client-ping-latency          : 0.96,0.04  {'target': 7, 'weight_multiplier': 206, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 915, 'div': 1000, 'avg': 7, 'recent': 11}
2018-07-07 21:38:36,343 update_batch_delay: server-ping-latency          : 0.60,0.13  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 365, 'div': 1000, 'avg': 4, 'recent': 3}
2018-07-07 21:38:36,343 update_batch_delay: packet-queue-size            : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:36,343 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:36,344 update_batch_delay: compression-work-queue       : 0.00,0.06  {'target': 1000, 'weight_multiplier': 55, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 3, 'recent': 0}
2018-07-07 21:38:36,344 update_batch_delay: congestion                   : 1.01,0.14  {}
2018-07-07 21:38:36,344 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:36,344 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:36,344 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:36,344 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:36,345 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:36,345 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:36,345 update_batch_delay: delay=349
2018-07-07 21:38:36,345 get_client_backlog missing acks: []
2018-07-07 21:38:36,346 get_target_quality: target=2% (window 465x457) pctpixdamaged=2%, dpc={1: 1995, 2: 1235, 3: 0, 4: 0, 5: 1235, 6: 0, 7: 2470, 8: 1508, 9: 212505, 10: 213051}
2018-07-07 21:38:36,346 update_quality() wid=1, info={'batch-delay-ratio': 18, 'backlog_factor': (1, 1995, 212505, 0, 100), 'congestion-value': 14, 'latency': 124, 'compression-ratio': (680, 424, 0), 'min_quality': 30, 'max-quality-range': 74, 'min_speed': 30}, quality=87
2018-07-07 21:38:36,346 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:36,402 total_time=5996215, total_pixels=6888015
2018-07-07 21:38:36,489 packet decoding sequence 1 for window 12: 295x121 took 7.0ms
2018-07-07 21:38:36,489 record_latency: took  316.6 ms round trip,  316.4 for echo,    6.0 for decoding of    35695 pixels,     2953 bytes sent over the network in  309.6 ms,  309.4 ms for echo
2018-07-07 21:38:36,490 may_recalculate(12, 35695) total 35695 pixels, scheduling recalculate work item
2018-07-07 21:38:36,506 total_time=6003173, total_pixels=6923710
2018-07-07 21:38:36,552 packet decoding sequence 57 for window 1: 105x19 took 68.7ms
2018-07-07 21:38:36,552 record_latency: took  348.2 ms round trip,  348.0 for echo,   68.0 for decoding of     1995 pixels,     8050 bytes sent over the network in  279.4 ms,  279.3 ms for echo
2018-07-07 21:38:36,603 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:36,705 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:36,805 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:36,909 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,008 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,109 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,210 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,316 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,341 calculate_batch_delay for wid=12 current batch delay=651, last update 80193 seconds ago
2018-07-07 21:38:37,341 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2018-07-07 21:38:37,341 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 204, 'recent': 204}
2018-07-07 21:38:37,342 update_batch_delay: damage-rate                  : 0.00,1.21  {'max_latency': 100, 'elapsed': 1674}
2018-07-07 21:38:37,342 update_batch_delay: bandwidth-limit              : 0.00,0.00  {'used': 0, 'budget': 754007}
2018-07-07 21:38:37,342 update_batch_delay: client-latency               : 2.18,0.53  {'target': 8, 'weight_multiplier': 491, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 4752, 'div': 1000, 'avg': 191, 'recent': 246}
2018-07-07 21:38:37,342 update_batch_delay: client-ping-latency          : 0.76,0.10  {'target': 7, 'weight_multiplier': 206, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 571, 'div': 1000, 'avg': 6, 'recent': 6}
2018-07-07 21:38:37,342 update_batch_delay: server-ping-latency          : 0.84,0.09  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 708, 'div': 1000, 'avg': 5, 'recent': 7}
2018-07-07 21:38:37,342 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 21:38:37,343 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 35695000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:37,344 update_batch_delay: compression-work-queue       : 0.00,0.06  {'target': 1000, 'weight_multiplier': 60, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 3, 'recent': 0}
2018-07-07 21:38:37,344 update_batch_delay: congestion                   : 1.88,8.84  {}
2018-07-07 21:38:37,345 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 35695000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:37,345 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2018-07-07 21:38:37,345 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2018-07-07 21:38:37,345 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:37,345 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:37,345 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:37,345 update_batch_delay: delay=434
2018-07-07 21:38:37,410 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,512 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,612 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,713 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,813 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:37,913 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:38,014 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:38,116 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:38,215 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:38,316 total_time=6071899, total_pixels=6925705
2018-07-07 21:38:38,368 packet decoding sequence 58 for window 1: 40x19 took 1.3ms
2018-07-07 21:38:38,368 record_latency: took   56.0 ms round trip,   55.9 for echo,    1.0 for decoding of      760 pixels,     3094 bytes sent over the network in   54.7 ms,   54.6 ms for echo
2018-07-07 21:38:38,369 may_recalculate(1, 760) total 2755 pixels, scheduling recalculate work item
2018-07-07 21:38:38,370 calculate_batch_delay for wid=1 current batch delay=349, last update 2 seconds ago
2018-07-07 21:38:38,370 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
2018-07-07 21:38:38,370 update_batch_delay: client-decode-speed          : 1.28,0.14  {'avg': 89, 'recent': 127}
2018-07-07 21:38:38,371 update_batch_delay: damage-rate                  : 0.78,0.47  {'max_latency': 191, 'elapsed': 606}
2018-07-07 21:38:38,371 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 24320, 'budget': 5242880}
2018-07-07 21:38:38,371 update_batch_delay: client-latency               : 1.19,0.21  {'target': 8, 'weight_multiplier': 491, 'smoothing': 'sqrt', 'aim': 800, 'aimed_avg': 1404, 'div': 1000, 'avg': 164, 'recent': 70}
2018-07-07 21:38:38,371 update_batch_delay: client-ping-latency          : 0.62,0.13  {'target': 7, 'weight_multiplier': 206, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 378, 'div': 1000, 'avg': 5, 'recent': 4}
2018-07-07 21:38:38,371 update_batch_delay: server-ping-latency          : 0.66,0.13  {'target': 8, 'weight_multiplier': 214, 'smoothing': 'sqrt', 'aim': 949, 'aimed_avg': 433, 'div': 1000, 'avg': 4, 'recent': 4}
2018-07-07 21:38:38,371 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 21:38:38,372 update_batch_delay: packet-queue-pixels          : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:38,372 update_batch_delay: compression-work-queue       : 0.00,0.05  {'target': 1000, 'weight_multiplier': 50, 'smoothing': 'logp', 'aim': 250, 'aimed_avg': 0, 'div': 1000, 'avg': 2, 'recent': 0}
2018-07-07 21:38:38,372 update_batch_delay: congestion                   : 1.09,0.87  {}
2018-07-07 21:38:38,372 update_batch_delay: damage-packet-queue-pixels   : 0.00,0.00  {'target': 1000, 'weight_multiplier': 0, 'smoothing': 'sqrt', 'aim': 250, 'aimed_avg': 0, 'div': 212505000, 'avg': 0, 'recent': 0}
2018-07-07 21:38:38,372 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2018-07-07 21:38:38,372 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2018-07-07 21:38:38,372 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2018-07-07 21:38:38,372 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2018-07-07 21:38:38,373 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2018-07-07 21:38:38,373 update_batch_delay: delay=306
2018-07-07 21:38:38,373 get_target_quality: target=16% (window 465x457) pctpixdamaged=1%, dpc={1: 760, 2: 0, 3: 1995, 4: 1235, 5: 0, 6: 0, 7: 1235, 8: 0, 9: 2470, 10: 1508}
2018-07-07 21:38:38,374 update_quality() wid=1, info={'batch-delay-ratio': 21, 'backlog_factor': (0, 0, 212505, 0, 100), 'congestion-value': 86, 'latency': 102, 'compression-ratio': (701, 484, 22), 'min_quality': 30, 'max-quality-range': 72, 'min_speed': 30}, quality=66
2018-07-07 21:38:38,374 update_speed() wid=1, info={}, speed=100
2018-07-07 21:38:38,416 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:38,516 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:38,618 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:38,718 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:38,819 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:38,920 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,021 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,121 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,222 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,322 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,423 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,525 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,626 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,727 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,828 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:39,942 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:40,038 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:40,156 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:40,253 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:40,353 total_time=6073202, total_pixels=6926465
2018-07-07 21:38:40,456 total_time=6037390, total_pixels=6254675
2018-07-07 21:38:40,537 packet decoding sequence 59 for window 1: 13x21 took 1.2ms
2018-07-07 21:38:40,537 record_latency: took    5.0 ms round trip,    5.0 for echo,    1.0 for decoding of      273 pixels,     1145 bytes sent over the network in    3.8 ms,    3.8 ms for echo
2018-07-07 21:38:40,558 total_time=6038617, total_pixels=6254948
2018-07-07 21:38:40,657 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:40,757 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:40,858 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:40,958 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,059 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,160 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,261 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,361 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,462 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,563 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,666 total_time=6025004, total_pixels=5919053
2018-07-07 21:38:41,767 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:41,868 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:41,969 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,070 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,171 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,270 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,371 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,472 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,572 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,673 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,773 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,874 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:42,974 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,074 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,175 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,277 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,378 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,477 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,578 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,679 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,779 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,880 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:43,981 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,081 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,184 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,284 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,385 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,487 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,588 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,689 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,788 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,890 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:44,991 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,091 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,192 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,293 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,393 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,493 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,594 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,694 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,795 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,898 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:45,998 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:46,098 total_time=6021565, total_pixels=5913411
2018-07-07 21:38:46,198 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,299 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,400 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,501 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,602 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,701 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,801 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:46,902 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:47,004 total_time=5986554, total_pixels=5909250
2018-07-07 21:38:47,105 total_time=4086536, total_pixels=5237460
2018-07-07 21:38:47,207 total_time=2141036, total_pixels=3893880
2018-07-07 21:38:47,309 total_time=2141036, total_pixels=3893880
2018-07-07 21:38:47,408 total_time=2123402, total_pixels=3557985
2018-07-07 21:38:47,509 total_time=2105012, total_pixels=3222090
2018-07-07 21:38:47,609 total_time=2105012, total_pixels=3222090
2018-07-07 21:38:47,711 total_time=2105012, total_pixels=3222090
2018-07-07 21:38:47,811 total_time=2102911, total_pixels=3221817
2018-07-07 21:38:47,912 total_time=2102911, total_pixels=3221817
2018-07-07 21:38:48,012 total_time=2102911, total_pixels=3221817
2018-07-07 21:38:48,106 total_time=2102911, total_pixels=3221817
2018-07-07 21:38:48,214 total_time=2102911, total_pixels=3221817
2018-07-07 21:38:48,314 total_time=2088832, total_pixels=3170007
2018-07-07 21:38:48,416 total_time=2088832, total_pixels=3170007
2018-07-07 21:38:48,516 total_time=2034937, total_pixels=3168772
2018-07-07 21:38:48,616 total_time=2034937, total_pixels=3168772
2018-07-07 21:38:48,719 total_time=2022816, total_pixels=3150650
2018-07-07 21:38:48,819 total_time=2022816, total_pixels=3150650
2018-07-07 21:38:48,920 total_time=2008820, total_pixels=3096826
2018-07-07 21:38:49,020 total_time=2008820, total_pixels=3096826
2018-07-07 21:38:49,122 total_time=2006189, total_pixels=3094812
2018-07-07 21:38:49,222 total_time=2006189, total_pixels=3094812
2018-07-07 21:38:49,325 total_time=1999343, total_pixels=3086844
2018-07-07 21:38:49,424 total_time=1996451, total_pixels=3084070
2018-07-07 21:38:49,526 total_time=1993687, total_pixels=3076102
2018-07-07 21:38:49,628 total_time=1993687, total_pixels=3076102
2018-07-07 21:38:49,728 total_time=1993687, total_pixels=3076102
2018-07-07 21:38:49,829 total_time=1993687, total_pixels=3076102
2018-07-07 21:38:49,929 total_time=1980344, total_pixels=3021518
2018-07-07 21:38:50,025 total_time=1980344, total_pixels=3021518
2018-07-07 21:38:50,130 total_time=1980344, total_pixels=3021518
2018-07-07 21:38:50,232 total_time=1980344, total_pixels=3021518
2018-07-07 21:38:50,333 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:50,433 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:50,534 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:50,635 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:50,731 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:50,831 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:50,931 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,033 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,133 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,234 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,334 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,444 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,545 total_time=1975713, total_pixels=2969708
2018-07-07 21:38:51,625 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
2018-07-07 21:38:51,628 Handshake complete; enabling connection
2018-07-07 21:38:51,633 Python2/GObject client version 2.3.2-r19729 64-bit
2018-07-07 21:38:51,633  connected from 'xubuntu' as 'steved' - 'Steve Dee'
2018-07-07 21:38:51,648 Shutting down in response to client request
2018-07-07 21:38:51,649 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/xubuntu-1):
2018-07-07 21:38:51,649  server shutdown
2018-07-07 21:38:51,652 xpra client 2 disconnected.
2018-07-07 21:38:51,653 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/xubuntu-1):
2018-07-07 21:38:51,653  server shutdown
2018-07-07 21:38:51,669 sound source stopping
2018-07-07 21:38:51,671 Error during info collection using <bound method XpraServer.get_info of <XpraServer object at 0x7fc0a8abdcd0 (xpra+x11+server+XpraServer at 0x318c0a0)>>
Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/xpra/server/server_core.py", line 1703, in _get_info_in_thread
    info = self.get_info(proto, *args)
  File "/usr/lib/python2.7/dist-packages/xpra/server/server_base.py", line 546, in get_info
    dgi = self.do_get_info(proto, sources, wids)
  File "/usr/lib/python2.7/dist-packages/xpra/x11/server.py", line 325, in do_get_info
    info = X11ServerBase.do_get_info(self, proto, server_sources, window_ids)
  File "/usr/lib/python2.7/dist-packages/xpra/x11/x11_server_core.py", line 325, in do_get_info
    info = GTKServerBase.do_get_info(self, proto, server_sources, window_ids)
  File "/usr/lib/python2.7/dist-packages/xpra/server/gtk_server_base.py", line 120, in do_get_info
    info = ServerBase.do_get_info(self, proto, *args)
  File "/usr/lib/python2.7/dist-packages/xpra/server/server_base.py", line 613, in do_get_info
    sinfo = ss.get_info()
  File "/usr/lib/python2.7/dist-packages/xpra/server/source/client_connection.py", line 347, in get_info
    "connection"       : self.protocol.get_info(),
AttributeError: 'NoneType' object has no attribute 'get_info'
2018-07-07 21:38:51,684 xpra client 1 disconnected.
2018-07-07 21:38:52,187 stopping pulseaudio with pid 12696
2018-07-07 21:38:52,292 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
2018-07-07 21:38:52,293 New unix-domain connection received on /home/steved/.xpra/xubuntu-1
2018-07-07 21:38:52,704 killing xvfb with pid 12646
2018-07-07 21:38:52,704 removing socket /run/user/1000/xpra/xubuntu-1
2018-07-07 21:38:52,705 removing socket /home/steved/.xpra/xubuntu-1
xfce4-terminal: Fatal IO error 11 (Resource temporarily unavailable) on X server :1.