xpra icon
Bug tracker and wiki

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

File xpra-ether-1804-1404.txt, 137.7 KB (added by tc424, 19 months ago)

stats log over ethernet to 14.04 server

Line 
12018-07-07 21:36:10,698 cannot access python uinput module:
22018-07-07 21:36:10,698  No module named uinput
3_XSERVTransSocketUNIXCreateListener: ...SocketCreateListener() failed
4_XSERVTransMakeAllCOTSServerListeners: server already running
52018-07-07 21:36:10,790 created unix domain socket: /run/user/1000/xpra/xubuntu-1
62018-07-07 21:36:10,790 created unix domain socket: /home/steved/.xpra/xubuntu-1
72018-07-07 21:36:10,790 cannot create group socket '/run/xpra/xubuntu-1'
82018-07-07 21:36:10,790  [Errno 13] Permission denied
92018-07-07 21:36:10,824 pointer device emulation using XTest
102018-07-07 21:36:11,598 serving html content from: /usr/share/xpra/www
112018-07-07 21:36:11,674 D-Bus notification forwarding is available
122018-07-07 21:36:11,755 Warning: webcam forwarding is disabled
132018-07-07 21:36:11,756  the virtual video directory '/sys/devices/virtual/video4linux' was not found
142018-07-07 21:36:11,756  make sure that the 'v4l2loopback' kernel module is installed and loaded
152018-07-07 21:36:11,756 found 0 virtual video devices for webcam forwarding
162018-07-07 21:36:11,763 pulseaudio server started with pid 12696
172018-07-07 21:36:11,763  private server socket path:
182018-07-07 21:36:11,763  '/run/user/1000/xpra/pulse-:1/pulse/native'
192018-07-07 21:36:12,472 GStreamer version 1.8.3 for Python 2.7.12 64-bit
202018-07-07 21:36:12,538 xpra X11 version 2.3.2-r19729 64-bit
212018-07-07 21:36:12,538  uid=1000 (steved), gid=1000 (steved)
222018-07-07 21:36:12,538  running with pid 12644 on Linux Ubuntu 16.04 xenial
232018-07-07 21:36:12,538  connected to X11 display :1 with 24 bit colors
242018-07-07 21:36:12,594 xpra is ready.
252018-07-07 21:36:12,643 printer forwarding enabled using postscript and pdf
262018-07-07 21:36:12,651 7.7GB of system memory
27Failed to connect to session manager: Failed to connect to the session manager: SESSION_MANAGER environment variable not defined
282018-07-07 21:37:39,469 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
292018-07-07 21:37:39,476 Handshake complete; enabling connection
302018-07-07 21:37:39,485  automatic picture encoding enabled, also available:
312018-07-07 21:37:39,485   h264, vp9, vp8, png, png/P, png/L, rgb24, rgb32, jpeg
322018-07-07 21:37:39,485 Python/Gtk2 Linux Ubuntu 18.04 bionic client version 2.3.2-r19729 64-bit
332018-07-07 21:37:39,485  connected from 'beelink' as 'steved' - 'Steve Dee'
342018-07-07 21:37:39,486  client root window size is 2880x900 with 1 display:
352018-07-07 21:37:39,486   :0.0 (761x237 mm - DPI: 96x96)
362018-07-07 21:37:39,486     monitor 1 1440x900 (408x255 mm - DPI: 89x89)
372018-07-07 21:37:39,486     monitor 2 1440x900 at 1440x0 (408x255 mm - DPI: 89x89)
382018-07-07 21:37:40,041 server virtual display now set to 2880x900
392018-07-07 21:37:40,045 setting key repeat rate from client: 500ms delay / 50ms interval
402018-07-07 21:37:40,048 setting keymap: rules=evdev, model=pc105, layout=gb
412018-07-07 21:37:40,063 setting keyboard layout to 'gb'
422018-07-07 21:37:40,096 DPI set to 96 x 96
432018-07-07 21:37:40,122 client @45.924 Xpra X11 server version 2.3.2-r19729 64-bit
442018-07-07 21:37:40,128 client @45.926  running on Linux Ubuntu 16.04 xenial
452018-07-07 21:37:40,174 client @45.934 Attached to xubuntu via ssh
462018-07-07 21:37:40,175 client @45.934  (press Control-C to detach)
472018-07-07 21:37:40,217 New unix-domain connection received on /home/steved/.xpra/xubuntu-1
482018-07-07 21:37:40,217 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
492018-07-07 21:37:40,247 client @46.051 server does not support xi input devices
502018-07-07 21:37:40,250 client @46.052  server uses: xtest
512018-07-07 21:37:40,346 total_time=0, total_pixels=0
522018-07-07 21:37:40,405 packet decoding sequence 1 for window 1: 735x457 took 21.0ms
532018-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
542018-07-07 21:37:40,406 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
552018-07-07 21:37:40,407 calculate_batch_delay for wid=1 current batch delay=28, last update 80136 seconds ago
562018-07-07 21:37:40,407 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
572018-07-07 21:37:40,408 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 65, 'recent': 65}
582018-07-07 21:37:40,408 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 85}
592018-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}
602018-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}
612018-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}
622018-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}
632018-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}
642018-07-07 21:37:40,409 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
652018-07-07 21:37:40,409 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
662018-07-07 21:37:40,409 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
672018-07-07 21:37:40,409 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
682018-07-07 21:37:40,409 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
692018-07-07 21:37:40,409 update_batch_delay: delay=13
702018-07-07 21:37:40,413 packet decoding sequence 2 for window 1: 735x457 took 14.8ms
712018-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
722018-07-07 21:37:40,413 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
732018-07-07 21:37:40,616 packet decoding sequence 3 for window 1: 735x457 took 13.6ms
742018-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
752018-07-07 21:37:41,144 the remote printer 'PDF' has been configured
762018-07-07 21:37:41,176 the remote printer 'MX470-series' has been configured
772018-07-07 21:37:41,408 calculate_batch_delay for wid=1 current batch delay=13, last update 1 seconds ago
782018-07-07 21:37:41,409 calculate_batch_delay for wid=1, skipping - only 1 events since the last update
792018-07-07 21:37:41,730 packet decoding sequence 4 for window 1: 13x434 took 3.4ms
802018-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
812018-07-07 21:37:41,730 may_recalculate(1, 5642) total 5642 pixels, scheduling recalculate work item
822018-07-07 21:37:42,050 using pulseaudio device:
832018-07-07 21:37:42,050  'Monitor of Xpra Speaker'
842018-07-07 21:37:42,407 calculate_batch_delay for wid=1 current batch delay=13, last update 2 seconds ago
852018-07-07 21:37:42,408 calculate_batch_delay for wid=1, skipping - only 2 events since the last update
862018-07-07 21:37:43,197 sound source using 'opus' audio codec
872018-07-07 21:37:46,152 packet decoding sequence 5 for window 1: 13x21 took 15.8ms
882018-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
892018-07-07 21:37:46,156 packet decoding sequence 6 for window 1: 243x16 took 19.2ms
902018-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
912018-07-07 21:37:46,156 may_recalculate(1, 3888) total 4161 pixels, scheduling recalculate work item
922018-07-07 21:37:46,157 calculate_batch_delay for wid=1 current batch delay=13, last update 5 seconds ago
932018-07-07 21:37:46,157 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
942018-07-07 21:37:46,157 update_batch_delay: client-decode-speed          : 1.02,0.00  {'avg': 49, 'recent': 50}
952018-07-07 21:37:46,158 update_batch_delay: damage-rate                  : 0.91,0.30  {'max_latency': 111, 'elapsed': 312}
962018-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}
972018-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}
982018-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}
992018-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}
1002018-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}
1012018-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}
1022018-07-07 21:37:46,159 update_batch_delay: congestion                   : 2.75,17.48  {}
1032018-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}
1042018-07-07 21:37:46,160 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
1052018-07-07 21:37:46,161 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
1062018-07-07 21:37:46,162 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
1072018-07-07 21:37:46,163 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
1082018-07-07 21:37:46,164 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
1092018-07-07 21:37:46,164 update_batch_delay: delay=36
1102018-07-07 21:37:46,165 get_client_backlog missing acks: []
1112018-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}
1122018-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
1132018-07-07 21:37:46,166 update_speed() wid=1, info={}, speed=100
1142018-07-07 21:37:47,088 packet decoding sequence 7 for window 1: 735x457 took 950.9ms
1152018-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
1162018-07-07 21:37:47,089 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
1172018-07-07 21:37:47,104 packet decoding sequence 8 for window 1: 735x457 took 949.1ms
1182018-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
1192018-07-07 21:37:47,121 packet decoding sequence 9 for window 1: 735x457 took 953.6ms
1202018-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
1212018-07-07 21:37:47,137 packet decoding sequence 10 for window 1: 735x457 took 957.5ms
1222018-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
1232018-07-07 21:37:47,154 packet decoding sequence 11 for window 1: 735x457 took 15.1ms
1242018-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
1252018-07-07 21:37:47,157 calculate_batch_delay for wid=1 current batch delay=36, last update 1 seconds ago
1262018-07-07 21:37:47,158 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
1272018-07-07 21:37:47,158 update_batch_delay: client-decode-speed          : 1.18,0.18  {'avg': 171, 'recent': 216}
1282018-07-07 21:37:47,158 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 230, 'elapsed': 63}
1292018-07-07 21:37:47,158 update_batch_delay: bandwidth-limit              : 0.06,0.00  {'used': 298672, 'budget': 5242880}
1302018-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}
1312018-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}
1322018-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}
1332018-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}
1342018-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}
1352018-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}
1362018-07-07 21:37:47,159 update_batch_delay: congestion                   : 5.53,45.29  {}
1372018-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}
1382018-07-07 21:37:47,160 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
1392018-07-07 21:37:47,160 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
1402018-07-07 21:37:47,160 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
1412018-07-07 21:37:47,160 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
1422018-07-07 21:37:47,160 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
1432018-07-07 21:37:47,160 update_batch_delay: delay=148
1442018-07-07 21:37:47,161 get_client_backlog missing acks: []
1452018-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}
1462018-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
1472018-07-07 21:37:47,161 update_speed() wid=1, info={}, speed=100
1482018-07-07 21:37:47,170 packet decoding sequence 12 for window 1: 735x457 took 19.3ms
1492018-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
1502018-07-07 21:37:47,171 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
1512018-07-07 21:37:47,383 packet decoding sequence 13 for window 1: 735x457 took 17.6ms
1522018-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
1532018-07-07 21:37:47,433 packet decoding sequence 14 for window 1: 735x457 took 18.4ms
1542018-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
1552018-07-07 21:37:47,796 packet decoding sequence 15 for window 1: 13x21 took 2.1ms
1562018-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
1572018-07-07 21:37:48,158 calculate_batch_delay for wid=1 current batch delay=148, last update 1 seconds ago
1582018-07-07 21:37:48,159 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
1592018-07-07 21:37:48,159 update_batch_delay: client-decode-speed          : 0.97,0.01  {'avg': 94, 'recent': 91}
1602018-07-07 21:37:48,159 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 230, 'elapsed': 138}
1612018-07-07 21:37:48,159 update_batch_delay: bandwidth-limit              : 0.09,0.00  {'used': 307408, 'budget': 3589377}
1622018-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}
1632018-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}
1642018-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}
1652018-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}
1662018-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}
1672018-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}
1682018-07-07 21:37:48,160 update_batch_delay: congestion                   : 1.44,4.40  {}
1692018-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}
1702018-07-07 21:37:48,161 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
1712018-07-07 21:37:48,161 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
1722018-07-07 21:37:48,161 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
1732018-07-07 21:37:48,161 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
1742018-07-07 21:37:48,161 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
1752018-07-07 21:37:48,161 update_batch_delay: delay=160
1762018-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}
1772018-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
1782018-07-07 21:37:48,162 update_speed() wid=1, info={}, speed=100
1792018-07-07 21:37:48,298 packet decoding sequence 1 for window 2: 314x165 took 14.1ms
1802018-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
1812018-07-07 21:37:48,298 may_recalculate(2, 51810) total 51810 pixels, scheduling recalculate work item
1822018-07-07 21:37:48,472 packet decoding sequence 16 for window 1: 65x19 took 53.9ms
1832018-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
1842018-07-07 21:37:48,641 packet decoding sequence 1 for window 3: 221x82 took 12.1ms
1852018-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
1862018-07-07 21:37:48,642 may_recalculate(3, 18122) total 18122 pixels, scheduling recalculate work item
1872018-07-07 21:37:48,820 packet decoding sequence 17 for window 1: 106x19 took 1.1ms
1882018-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
1892018-07-07 21:37:48,820 may_recalculate(1, 2014) total 3249 pixels, scheduling recalculate work item
1902018-07-07 21:37:48,892 packet decoding sequence 1 for window 4: 314x165 took 12.9ms
1912018-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
1922018-07-07 21:37:48,893 may_recalculate(4, 51810) total 51810 pixels, scheduling recalculate work item
1932018-07-07 21:37:49,073 packet decoding sequence 18 for window 1: 106x19 took 2.6ms
1942018-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
1952018-07-07 21:37:49,158 calculate_batch_delay for wid=1 current batch delay=160, last update 0 seconds ago
1962018-07-07 21:37:49,159 update_batch_delay: damage-network-delay         : 1.01,0.51  {'avg': 40, 'recent': 41}
1972018-07-07 21:37:49,159 update_batch_delay: client-decode-speed          : 1.03,0.01  {'avg': 93, 'recent': 97}
1982018-07-07 21:37:49,159 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 174, 'elapsed': 15}
1992018-07-07 21:37:49,159 update_batch_delay: bandwidth-limit              : 0.05,0.00  {'used': 168416, 'budget': 3992478}
2002018-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}
2012018-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}
2022018-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}
2032018-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}
2042018-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}
2052018-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}
2062018-07-07 21:37:49,160 update_batch_delay: congestion                   : 1.13,1.26  {}
2072018-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}
2082018-07-07 21:37:49,160 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2092018-07-07 21:37:49,160 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2102018-07-07 21:37:49,161 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2112018-07-07 21:37:49,161 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2122018-07-07 21:37:49,161 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2132018-07-07 21:37:49,161 update_batch_delay: delay=131
2142018-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}
2152018-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
2162018-07-07 21:37:49,162 update_speed() wid=1, info={}, speed=100
2172018-07-07 21:37:49,284 packet decoding sequence 1 for window 6: 166x48 took 6.8ms
2182018-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
2192018-07-07 21:37:49,285 may_recalculate(6, 7968) total 7968 pixels, scheduling recalculate work item
2202018-07-07 21:37:49,391 packet decoding sequence 19 for window 1: 146x19 took 2.9ms
2212018-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
2222018-07-07 21:37:49,392 may_recalculate(1, 2774) total 2774 pixels, scheduling recalculate work item
2232018-07-07 21:37:49,437 packet decoding sequence 2 for window 6: 166x48 took 2.8ms
2242018-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
2252018-07-07 21:37:49,858 packet decoding sequence 1 for window 8: 314x165 took 12.0ms
2262018-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
2272018-07-07 21:37:49,858 may_recalculate(8, 51810) total 51810 pixels, scheduling recalculate work item
2282018-07-07 21:37:49,891 packet decoding sequence 20 for window 1: 146x19 took 1.3ms
2292018-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
2302018-07-07 21:37:50,158 calculate_batch_delay for wid=1 current batch delay=131, last update 1 seconds ago
2312018-07-07 21:37:50,159 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2322018-07-07 21:37:50,159 update_batch_delay: client-decode-speed          : 1.04,0.02  {'avg': 92, 'recent': 98}
2332018-07-07 21:37:50,159 update_batch_delay: damage-rate                  : 0.88,0.34  {'max_latency': 146, 'elapsed': 407}
2342018-07-07 21:37:50,159 update_batch_delay: bandwidth-limit              : 0.05,0.00  {'used': 177536, 'budget': 3596209}
2352018-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}
2362018-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}
2372018-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}
2382018-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}
2392018-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}
2402018-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}
2412018-07-07 21:37:50,160 update_batch_delay: congestion                   : 1.06,0.59  {}
2422018-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}
2432018-07-07 21:37:50,161 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
2442018-07-07 21:37:50,161 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
2452018-07-07 21:37:50,161 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2462018-07-07 21:37:50,161 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2472018-07-07 21:37:50,161 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2482018-07-07 21:37:50,161 update_batch_delay: delay=111
2492018-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}
2502018-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
2512018-07-07 21:37:50,162 update_speed() wid=1, info={}, speed=100
2522018-07-07 21:37:50,163 calculate_batch_delay for wid=8 current batch delay=88, last update 80145 seconds ago
2532018-07-07 21:37:50,163 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
2542018-07-07 21:37:50,163 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 243, 'recent': 243}
2552018-07-07 21:37:50,163 update_batch_delay: damage-rate                  : 0.79,0.46  {'max_latency': 100, 'elapsed': 411}
2562018-07-07 21:37:50,163 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 35904, 'budget': 1646670}
2572018-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}
2582018-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}
2592018-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}
2602018-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}
2612018-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}
2622018-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}
2632018-07-07 21:37:50,164 update_batch_delay: congestion                   : 1.06,0.59  {}
2642018-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}
2652018-07-07 21:37:50,164 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
2662018-07-07 21:37:50,164 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
2672018-07-07 21:37:50,164 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
2682018-07-07 21:37:50,164 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
2692018-07-07 21:37:50,164 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
2702018-07-07 21:37:50,164 update_batch_delay: delay=70
2712018-07-07 21:37:50,235 packet decoding sequence 2 for window 8: 314x165 took 4.6ms
2722018-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
2732018-07-07 21:37:50,235 may_recalculate(8, 51810) total 51810 pixels, scheduling recalculate work item
2742018-07-07 21:37:51,159 calculate_batch_delay for wid=8 current batch delay=70, last update 0 seconds ago
2752018-07-07 21:37:51,159 calculate_batch_delay for wid=8, skipping - only 1 events since the last update
2762018-07-07 21:37:53,466 packet decoding sequence 3 for window 8: 312x19 took 3.8ms
2772018-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
2782018-07-07 21:37:53,466 may_recalculate(8, 5928) total 5928 pixels, scheduling recalculate work item
2792018-07-07 21:37:53,467 calculate_batch_delay for wid=8 current batch delay=70, last update 3 seconds ago
2802018-07-07 21:37:53,467 calculate_batch_delay for wid=8, skipping - only 2 events since the last update
2812018-07-07 21:37:53,506 packet decoding sequence 21 for window 1: 65x19 took 1.2ms
2822018-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
2832018-07-07 21:37:53,577 packet decoding sequence 4 for window 8: 312x21 took 5.7ms
2842018-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
2852018-07-07 21:37:53,577 may_recalculate(8, 6552) total 6552 pixels, scheduling recalculate work item
2862018-07-07 21:37:53,672 packet decoding sequence 5 for window 8: 312x21 took 5.3ms
2872018-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
2882018-07-07 21:37:53,710 packet decoding sequence 22 for window 1: 65x19 took 2.8ms
2892018-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
2902018-07-07 21:37:53,711 may_recalculate(1, 1235) total 2470 pixels, scheduling recalculate work item
2912018-07-07 21:37:53,834 packet decoding sequence 6 for window 8: 312x19 took 2.8ms
2922018-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
2932018-07-07 21:37:53,871 packet decoding sequence 23 for window 1: 65x19 took 1.2ms
2942018-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
2952018-07-07 21:37:53,931 packet decoding sequence 7 for window 8: 312x19 took 5.5ms
2962018-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
2972018-07-07 21:37:53,933 packet decoding sequence 8 for window 8: 312x19 took 9.1ms
2982018-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
2992018-07-07 21:37:53,936 packet decoding sequence 9 for window 8: 312x19 took 2.0ms
3002018-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
3012018-07-07 21:37:54,015 packet decoding sequence 10 for window 8: 312x19 took 2.5ms
3022018-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
3032018-07-07 21:37:54,040 packet decoding sequence 24 for window 1: 65x19 took 1.2ms
3042018-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
3052018-07-07 21:37:54,468 calculate_batch_delay for wid=1 current batch delay=111, last update 4 seconds ago
3062018-07-07 21:37:54,468 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
3072018-07-07 21:37:54,469 update_batch_delay: client-decode-speed          : 1.05,0.03  {'avg': 88, 'recent': 95}
3082018-07-07 21:37:54,469 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 110, 'elapsed': 98}
3092018-07-07 21:37:54,469 update_batch_delay: bandwidth-limit              : 0.03,0.00  {'used': 158080, 'budget': 5242880}
3102018-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}
3112018-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}
3122018-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}
3132018-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}
3142018-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}
3152018-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}
3162018-07-07 21:37:54,470 update_batch_delay: congestion                   : 1.01,0.12  {}
3172018-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}
3182018-07-07 21:37:54,470 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
3192018-07-07 21:37:54,470 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
3202018-07-07 21:37:54,470 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
3212018-07-07 21:37:54,471 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
3222018-07-07 21:37:54,471 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
3232018-07-07 21:37:54,471 update_batch_delay: delay=81
3242018-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}
3252018-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
3262018-07-07 21:37:54,472 update_speed() wid=1, info={}, speed=100
3272018-07-07 21:37:54,566 packet decoding sequence 25 for window 1: 65x19 took 1.7ms
3282018-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
3292018-07-07 21:37:59,856 packet decoding sequence 26 for window 1: 13x21 took 2.6ms
3302018-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
3312018-07-07 21:38:05,148 total_time=4146070, total_pixels=4012150
3322018-07-07 21:38:05,400 packet decoding sequence 27 for window 1: 13x21 took 3.3ms
3332018-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
3342018-07-07 21:38:06,471 total_time=4149369, total_pixels=4012423
3352018-07-07 21:38:06,573 total_time=4149369, total_pixels=4012423
3362018-07-07 21:38:06,673 total_time=4149369, total_pixels=4012423
3372018-07-07 21:38:06,774 total_time=4149369, total_pixels=4012423
3382018-07-07 21:38:06,874 total_time=4149369, total_pixels=4012423
3392018-07-07 21:38:06,975 total_time=4149369, total_pixels=4012423
3402018-07-07 21:38:07,077 total_time=4149369, total_pixels=4012423
3412018-07-07 21:38:07,175 total_time=4149369, total_pixels=4012423
3422018-07-07 21:38:07,278 total_time=4149369, total_pixels=4012423
3432018-07-07 21:38:07,379 total_time=4149369, total_pixels=4012423
3442018-07-07 21:38:07,480 total_time=4149369, total_pixels=4012423
3452018-07-07 21:38:07,585 total_time=4149369, total_pixels=4012423
3462018-07-07 21:38:07,606 packet decoding sequence 28 for window 1: 13x21 took 1.3ms
3472018-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
3482018-07-07 21:38:07,606 may_recalculate(1, 273) total 2054 pixels, scheduling recalculate work item
3492018-07-07 21:38:07,607 calculate_batch_delay for wid=1 current batch delay=81, last update 13 seconds ago
3502018-07-07 21:38:07,607 calculate_batch_delay for wid=1, skipping - only 4 events since the last update
3512018-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}
3522018-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
3532018-07-07 21:38:07,608 update_speed() wid=1, info={}, speed=100
3542018-07-07 21:38:07,684 total_time=4150623, total_pixels=4012696
3552018-07-07 21:38:07,786 total_time=4150623, total_pixels=4012696
3562018-07-07 21:38:07,887 total_time=4150623, total_pixels=4012696
3572018-07-07 21:38:07,992 total_time=4150623, total_pixels=4012696
3582018-07-07 21:38:08,107 total_time=4150623, total_pixels=4012696
3592018-07-07 21:38:08,207 total_time=4150623, total_pixels=4012696
3602018-07-07 21:38:08,235 packet decoding sequence 29 for window 1: 13x21 took 1.0ms
3612018-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
3622018-07-07 21:38:08,293 total_time=4151626, total_pixels=4012969
3632018-07-07 21:38:08,422 total_time=4151626, total_pixels=4012969
3642018-07-07 21:38:08,527 total_time=4151626, total_pixels=4012969
3652018-07-07 21:38:08,622 total_time=4151626, total_pixels=4012969
3662018-07-07 21:38:08,739 total_time=4151626, total_pixels=4012969
3672018-07-07 21:38:08,843 total_time=4151626, total_pixels=4012969
3682018-07-07 21:38:08,941 total_time=4151626, total_pixels=4012969
3692018-07-07 21:38:09,040 total_time=4151626, total_pixels=4012969
3702018-07-07 21:38:09,142 total_time=4151626, total_pixels=4012969
3712018-07-07 21:38:09,206 packet decoding sequence 30 for window 1: 735x457 took 8.3ms
3722018-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
3732018-07-07 21:38:09,207 may_recalculate(1, 335895) total 336168 pixels, scheduling recalculate work item
3742018-07-07 21:38:09,207 calculate_batch_delay for wid=1 current batch delay=81, last update 14 seconds ago
3752018-07-07 21:38:09,208 update_batch_delay: damage-network-delay         : 1.12,0.62  {'avg': 50, 'recent': 58}
3762018-07-07 21:38:09,208 update_batch_delay: client-decode-speed          : 0.84,0.02  {'avg': 32, 'recent': 25}
3772018-07-07 21:38:09,208 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 22}
3782018-07-07 21:38:09,208 update_batch_delay: bandwidth-limit              : 6.77,45.88  {'used': 32245920, 'budget': 5242880}
3792018-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}
3802018-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}
3812018-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}
3822018-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}
3832018-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}
3842018-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}
3852018-07-07 21:38:09,209 update_batch_delay: congestion                   : 1.87,8.74  {}
3862018-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}
3872018-07-07 21:38:09,210 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
3882018-07-07 21:38:09,210 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
3892018-07-07 21:38:09,210 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
3902018-07-07 21:38:09,210 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
3912018-07-07 21:38:09,210 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
3922018-07-07 21:38:09,210 update_batch_delay: delay=277
3932018-07-07 21:38:09,210 get_client_backlog missing acks: []
3942018-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}
3952018-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
3962018-07-07 21:38:09,211 update_speed() wid=1, info={}, speed=100
3972018-07-07 21:38:09,212 packet decoding sequence 31 for window 1: 735x457 took 13.2ms
3982018-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
3992018-07-07 21:38:09,213 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
4002018-07-07 21:38:09,225 total_time=4173071, total_pixels=4684759
4012018-07-07 21:38:09,232 packet decoding sequence 32 for window 1: 735x457 took 17.0ms
4022018-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
4032018-07-07 21:38:09,328 total_time=4190073, total_pixels=5020654
4042018-07-07 21:38:09,427 total_time=4190073, total_pixels=5020654
4052018-07-07 21:38:09,528 total_time=4190073, total_pixels=5020654
4062018-07-07 21:38:09,592 packet decoding sequence 33 for window 1: 13x21 took 1.3ms
4072018-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
4082018-07-07 21:38:09,630 total_time=4191347, total_pixels=5020927
4092018-07-07 21:38:09,729 total_time=4191347, total_pixels=5020927
4102018-07-07 21:38:09,830 total_time=4191347, total_pixels=5020927
4112018-07-07 21:38:09,932 total_time=4191347, total_pixels=5020927
4122018-07-07 21:38:10,033 total_time=4191347, total_pixels=5020927
4132018-07-07 21:38:10,133 total_time=4191347, total_pixels=5020927
4142018-07-07 21:38:10,208 calculate_batch_delay for wid=1 current batch delay=277, last update 1 seconds ago
4152018-07-07 21:38:10,208 update_batch_delay: damage-network-delay         : 1.02,0.52  {'avg': 47, 'recent': 48}
4162018-07-07 21:38:10,209 update_batch_delay: client-decode-speed          : 0.91,0.01  {'avg': 41, 'recent': 36}
4172018-07-07 21:38:10,209 update_batch_delay: damage-rate                  : 0.18,0.91  {'max_latency': 100, 'elapsed': 1022}
4182018-07-07 21:38:10,209 update_batch_delay: bandwidth-limit              : 0.00,0.00  {'used': 8736, 'budget': 5242880}
4192018-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}
4202018-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}
4212018-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}
4222018-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}
4232018-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}
4242018-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}
4252018-07-07 21:38:10,212 update_batch_delay: congestion                   : 1.08,0.79  {}
4262018-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}
4272018-07-07 21:38:10,212 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
4282018-07-07 21:38:10,212 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
4292018-07-07 21:38:10,212 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
4302018-07-07 21:38:10,212 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
4312018-07-07 21:38:10,212 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
4322018-07-07 21:38:10,213 update_batch_delay: delay=182
4332018-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}
4342018-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
4352018-07-07 21:38:10,214 update_speed() wid=1, info={}, speed=100
4362018-07-07 21:38:10,254 total_time=4191347, total_pixels=5020927
4372018-07-07 21:38:10,345 total_time=4191347, total_pixels=5020927
4382018-07-07 21:38:10,357 packet decoding sequence 1 for window 9: 221x82 took 7.0ms
4392018-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
4402018-07-07 21:38:10,357 may_recalculate(9, 18122) total 18122 pixels, scheduling recalculate work item
4412018-07-07 21:38:10,447 total_time=4198387, total_pixels=5039049
4422018-07-07 21:38:10,507 packet decoding sequence 34 for window 1: 41x19 took 1.6ms
4432018-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
4442018-07-07 21:38:10,546 total_time=4199970, total_pixels=5039828
4452018-07-07 21:38:10,631 packet decoding sequence 2 for window 9: 221x82 took 3.8ms
4462018-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
4472018-07-07 21:38:10,647 total_time=4203794, total_pixels=5057950
4482018-07-07 21:38:10,748 total_time=4203794, total_pixels=5057950
4492018-07-07 21:38:10,849 total_time=4203794, total_pixels=5057950
4502018-07-07 21:38:10,952 total_time=4203794, total_pixels=5057950
4512018-07-07 21:38:10,987 packet decoding sequence 3 for window 9: 219x19 took 5.3ms
4522018-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
4532018-07-07 21:38:11,051 total_time=4209076, total_pixels=5062111
4542018-07-07 21:38:11,153 total_time=4209076, total_pixels=5062111
4552018-07-07 21:38:11,191 packet decoding sequence 35 for window 1: 41x19 took 1.1ms
4562018-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
4572018-07-07 21:38:11,208 calculate_batch_delay for wid=9 current batch delay=72, last update 80166 seconds ago
4582018-07-07 21:38:11,208 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
4592018-07-07 21:38:11,208 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 291, 'recent': 291}
4602018-07-07 21:38:11,208 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 6}
4612018-07-07 21:38:11,209 update_batch_delay: bandwidth-limit              : 0.20,0.00  {'used': 156152, 'budget': 865212}
4622018-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}
4632018-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}
4642018-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}
4652018-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}
4662018-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}
4672018-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}
4682018-07-07 21:38:11,210 update_batch_delay: congestion                   : 1.02,0.21  {}
4692018-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}
4702018-07-07 21:38:11,210 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
4712018-07-07 21:38:11,210 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
4722018-07-07 21:38:11,210 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
4732018-07-07 21:38:11,210 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
4742018-07-07 21:38:11,210 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
4752018-07-07 21:38:11,211 update_batch_delay: delay=53
4762018-07-07 21:38:11,254 total_time=4210162, total_pixels=5062890
4772018-07-07 21:38:11,299 packet decoding sequence 4 for window 9: 219x38 took 2.3ms
4782018-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
4792018-07-07 21:38:11,299 may_recalculate(9, 8322) total 8322 pixels, scheduling recalculate work item
4802018-07-07 21:38:11,354 total_time=4212462, total_pixels=5071212
4812018-07-07 21:38:11,455 total_time=4212462, total_pixels=5071212
4822018-07-07 21:38:11,491 packet decoding sequence 36 for window 1: 41x19 took 1.8ms
4832018-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
4842018-07-07 21:38:11,492 may_recalculate(1, 779) total 2337 pixels, scheduling recalculate work item
4852018-07-07 21:38:11,494 packet decoding sequence 5 for window 9: 219x38 took 4.6ms
4862018-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
4872018-07-07 21:38:11,557 total_time=4218892, total_pixels=5080313
4882018-07-07 21:38:11,598 packet decoding sequence 6 for window 9: 219x38 took 1.5ms
4892018-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
4902018-07-07 21:38:11,657 total_time=4220347, total_pixels=5088635
4912018-07-07 21:38:11,697 packet decoding sequence 37 for window 1: 41x19 took 1.4ms
4922018-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
4932018-07-07 21:38:11,700 packet decoding sequence 7 for window 9: 219x19 took 4.0ms
4942018-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
4952018-07-07 21:38:11,758 total_time=4225696, total_pixels=5093575
4962018-07-07 21:38:11,858 total_time=4225696, total_pixels=5093575
4972018-07-07 21:38:11,899 packet decoding sequence 38 for window 1: 41x19 took 1.0ms
4982018-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
4992018-07-07 21:38:11,959 total_time=4226650, total_pixels=5094354
5002018-07-07 21:38:12,059 total_time=4226650, total_pixels=5094354
5012018-07-07 21:38:12,161 total_time=4226650, total_pixels=5094354
5022018-07-07 21:38:12,208 calculate_batch_delay for wid=1 current batch delay=182, last update 2 seconds ago
5032018-07-07 21:38:12,209 update_batch_delay: damage-network-delay         : 0.97,0.53  {'avg': 43, 'recent': 41}
5042018-07-07 21:38:12,209 update_batch_delay: client-decode-speed          : 0.87,0.03  {'avg': 45, 'recent': 37}
5052018-07-07 21:38:12,209 update_batch_delay: damage-rate                  : 0.60,0.63  {'max_latency': 100, 'elapsed': 597}
5062018-07-07 21:38:12,210 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 74784, 'budget': 4700636}
5072018-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}
5082018-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}
5092018-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}
5102018-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}
5112018-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}
5122018-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}
5132018-07-07 21:38:12,211 update_batch_delay: congestion                   : 1.01,0.10  {}
5142018-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}
5152018-07-07 21:38:12,211 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
5162018-07-07 21:38:12,211 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
5172018-07-07 21:38:12,211 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
5182018-07-07 21:38:12,211 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
5192018-07-07 21:38:12,211 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
5202018-07-07 21:38:12,212 update_batch_delay: delay=130
5212018-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}
5222018-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
5232018-07-07 21:38:12,214 update_speed() wid=1, info={}, speed=100
5242018-07-07 21:38:12,215 calculate_batch_delay for wid=9 current batch delay=53, last update 1 seconds ago
5252018-07-07 21:38:12,215 calculate_batch_delay for wid=9, skipping - only 4 events since the last update
5262018-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}
5272018-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
5282018-07-07 21:38:12,216 update_speed() wid=9, info={}, speed=100
5292018-07-07 21:38:12,259 total_time=4226650, total_pixels=5094354
5302018-07-07 21:38:12,362 total_time=4226650, total_pixels=5094354
5312018-07-07 21:38:12,463 total_time=4226650, total_pixels=5094354
5322018-07-07 21:38:12,564 total_time=4226650, total_pixels=5094354
5332018-07-07 21:38:12,664 total_time=4226650, total_pixels=5094354
5342018-07-07 21:38:12,764 total_time=4226650, total_pixels=5094354
5352018-07-07 21:38:12,866 total_time=4226650, total_pixels=5094354
5362018-07-07 21:38:12,967 total_time=4226650, total_pixels=5094354
5372018-07-07 21:38:13,069 total_time=4226650, total_pixels=5094354
5382018-07-07 21:38:13,095 packet decoding sequence 39 for window 1: 41x19 took 1.1ms
5392018-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
5402018-07-07 21:38:13,167 total_time=4227788, total_pixels=5095133
5412018-07-07 21:38:13,269 total_time=4227788, total_pixels=5095133
5422018-07-07 21:38:13,370 total_time=4227788, total_pixels=5095133
5432018-07-07 21:38:13,470 total_time=4227788, total_pixels=5095133
5442018-07-07 21:38:13,571 total_time=4227788, total_pixels=5095133
5452018-07-07 21:38:13,672 total_time=4227788, total_pixels=5095133
5462018-07-07 21:38:13,772 total_time=4227788, total_pixels=5095133
5472018-07-07 21:38:13,872 total_time=4227788, total_pixels=5095133
5482018-07-07 21:38:13,973 total_time=4227788, total_pixels=5095133
5492018-07-07 21:38:14,073 total_time=4227788, total_pixels=5095133
5502018-07-07 21:38:14,174 total_time=4227788, total_pixels=5095133
5512018-07-07 21:38:14,274 total_time=4227788, total_pixels=5095133
5522018-07-07 21:38:14,375 total_time=4227788, total_pixels=5095133
5532018-07-07 21:38:14,477 total_time=4227788, total_pixels=5095133
5542018-07-07 21:38:14,578 total_time=4227788, total_pixels=5095133
5552018-07-07 21:38:14,679 total_time=4227788, total_pixels=5095133
5562018-07-07 21:38:14,779 total_time=4227788, total_pixels=5095133
5572018-07-07 21:38:14,879 total_time=4227788, total_pixels=5095133
5582018-07-07 21:38:14,980 total_time=4227788, total_pixels=5095133
5592018-07-07 21:38:15,081 total_time=4227788, total_pixels=5095133
5602018-07-07 21:38:15,181 total_time=4227788, total_pixels=5095133
5612018-07-07 21:38:15,282 total_time=4227788, total_pixels=5095133
5622018-07-07 21:38:15,382 total_time=4227788, total_pixels=5095133
5632018-07-07 21:38:15,483 total_time=4227788, total_pixels=5095133
5642018-07-07 21:38:15,584 total_time=4227788, total_pixels=5095133
5652018-07-07 21:38:15,687 total_time=4227788, total_pixels=5095133
5662018-07-07 21:38:15,788 total_time=4227788, total_pixels=5095133
5672018-07-07 21:38:15,888 total_time=4227788, total_pixels=5095133
5682018-07-07 21:38:15,989 total_time=4227788, total_pixels=5095133
5692018-07-07 21:38:16,089 total_time=4227788, total_pixels=5095133
5702018-07-07 21:38:16,189 total_time=4227788, total_pixels=5095133
5712018-07-07 21:38:16,291 total_time=4227788, total_pixels=5095133
5722018-07-07 21:38:16,391 total_time=4227788, total_pixels=5095133
5732018-07-07 21:38:16,492 total_time=4227788, total_pixels=5095133
5742018-07-07 21:38:16,593 total_time=4227788, total_pixels=5095133
5752018-07-07 21:38:16,693 total_time=4227788, total_pixels=5095133
5762018-07-07 21:38:16,793 total_time=4227788, total_pixels=5095133
5772018-07-07 21:38:16,894 total_time=4227788, total_pixels=5095133
5782018-07-07 21:38:16,996 total_time=4227788, total_pixels=5095133
5792018-07-07 21:38:17,097 total_time=4227788, total_pixels=5095133
5802018-07-07 21:38:17,199 total_time=4227788, total_pixels=5095133
5812018-07-07 21:38:17,299 total_time=4227788, total_pixels=5095133
5822018-07-07 21:38:17,399 total_time=4227788, total_pixels=5095133
5832018-07-07 21:38:17,500 total_time=4227788, total_pixels=5095133
5842018-07-07 21:38:17,601 total_time=4227788, total_pixels=5095133
5852018-07-07 21:38:17,702 total_time=4227788, total_pixels=5095133
5862018-07-07 21:38:17,802 total_time=4227788, total_pixels=5095133
5872018-07-07 21:38:17,903 total_time=4227788, total_pixels=5095133
5882018-07-07 21:38:18,003 total_time=4227788, total_pixels=5095133
5892018-07-07 21:38:18,104 total_time=4227788, total_pixels=5095133
5902018-07-07 21:38:18,208 total_time=4227788, total_pixels=5095133
5912018-07-07 21:38:18,308 total_time=4227788, total_pixels=5095133
5922018-07-07 21:38:18,408 total_time=4227788, total_pixels=5095133
5932018-07-07 21:38:18,509 total_time=4227788, total_pixels=5095133
5942018-07-07 21:38:18,610 total_time=4227788, total_pixels=5095133
5952018-07-07 21:38:18,711 total_time=4227788, total_pixels=5095133
5962018-07-07 21:38:18,812 total_time=4227788, total_pixels=5095133
5972018-07-07 21:38:18,913 total_time=4227788, total_pixels=5095133
5982018-07-07 21:38:19,012 total_time=4227788, total_pixels=5095133
5992018-07-07 21:38:19,114 total_time=4227788, total_pixels=5095133
6002018-07-07 21:38:19,214 total_time=4227788, total_pixels=5095133
6012018-07-07 21:38:19,315 total_time=4227788, total_pixels=5095133
6022018-07-07 21:38:19,415 total_time=4227788, total_pixels=5095133
6032018-07-07 21:38:19,518 total_time=4227788, total_pixels=5095133
6042018-07-07 21:38:19,618 total_time=4227788, total_pixels=5095133
6052018-07-07 21:38:19,718 total_time=4227788, total_pixels=5095133
6062018-07-07 21:38:19,819 total_time=4227788, total_pixels=5095133
6072018-07-07 21:38:19,920 total_time=4227788, total_pixels=5095133
6082018-07-07 21:38:20,022 total_time=4227788, total_pixels=5095133
6092018-07-07 21:38:20,122 total_time=4227788, total_pixels=5095133
6102018-07-07 21:38:20,222 total_time=4227788, total_pixels=5095133
6112018-07-07 21:38:20,322 total_time=4227788, total_pixels=5095133
6122018-07-07 21:38:20,423 total_time=4227788, total_pixels=5095133
6132018-07-07 21:38:20,526 total_time=4227788, total_pixels=5095133
6142018-07-07 21:38:20,627 total_time=4227788, total_pixels=5095133
6152018-07-07 21:38:20,728 total_time=4227788, total_pixels=5095133
6162018-07-07 21:38:20,828 total_time=4227788, total_pixels=5095133
6172018-07-07 21:38:20,928 total_time=4227788, total_pixels=5095133
6182018-07-07 21:38:21,029 total_time=4227788, total_pixels=5095133
6192018-07-07 21:38:21,129 total_time=4227788, total_pixels=5095133
6202018-07-07 21:38:21,231 total_time=4227788, total_pixels=5095133
6212018-07-07 21:38:21,330 total_time=4227788, total_pixels=5095133
6222018-07-07 21:38:21,431 total_time=4227788, total_pixels=5095133
6232018-07-07 21:38:21,533 total_time=4227788, total_pixels=5095133
6242018-07-07 21:38:21,635 total_time=4227788, total_pixels=5095133
6252018-07-07 21:38:21,734 total_time=4227788, total_pixels=5095133
6262018-07-07 21:38:21,834 total_time=4227788, total_pixels=5095133
6272018-07-07 21:38:21,934 total_time=4227788, total_pixels=5095133
6282018-07-07 21:38:21,979 packet decoding sequence 40 for window 1: 13x21 took 1.5ms
6292018-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
6302018-07-07 21:38:22,034 total_time=4229244, total_pixels=5095406
6312018-07-07 21:38:22,135 total_time=4229244, total_pixels=5095406
6322018-07-07 21:38:22,245 total_time=4229244, total_pixels=5095406
6332018-07-07 21:38:22,375 total_time=4229244, total_pixels=5095406
6342018-07-07 21:38:22,465 total_time=4229244, total_pixels=5095406
6352018-07-07 21:38:22,566 total_time=4229244, total_pixels=5095406
6362018-07-07 21:38:22,665 total_time=4229244, total_pixels=5095406
6372018-07-07 21:38:22,786 total_time=4229244, total_pixels=5095406
6382018-07-07 21:38:22,900 total_time=4229244, total_pixels=5095406
6392018-07-07 21:38:22,990 packet decoding sequence 41 for window 1: 13x21 took 3.2ms
6402018-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
6412018-07-07 21:38:23,001 total_time=4232398, total_pixels=5095679
6422018-07-07 21:38:23,076 packet decoding sequence 42 for window 1: 735x457 took 89.5ms
6432018-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
6442018-07-07 21:38:23,076 may_recalculate(1, 335895) total 337220 pixels, scheduling recalculate work item
6452018-07-07 21:38:23,079 calculate_batch_delay for wid=1 current batch delay=130, last update 10 seconds ago
6462018-07-07 21:38:23,079 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
6472018-07-07 21:38:23,080 update_batch_delay: client-decode-speed          : 1.73,2.93  {'avg': 118, 'recent': 276}
6482018-07-07 21:38:23,080 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 229, 'elapsed': 100}
6492018-07-07 21:38:23,080 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 158032, 'budget': 7445198}
6502018-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}
6512018-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}
6522018-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}
6532018-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}
6542018-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}
6552018-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}
6562018-07-07 21:38:23,081 update_batch_delay: congestion                   : 2.75,17.48  {}
6572018-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}
6582018-07-07 21:38:23,081 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
6592018-07-07 21:38:23,081 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
6602018-07-07 21:38:23,082 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
6612018-07-07 21:38:23,082 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
6622018-07-07 21:38:23,082 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
6632018-07-07 21:38:23,082 update_batch_delay: delay=265
6642018-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}
6652018-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
6662018-07-07 21:38:23,084 update_speed() wid=1, info={}, speed=100
6672018-07-07 21:38:23,099 total_time=4321928, total_pixels=5431574
6682018-07-07 21:38:23,200 total_time=4321928, total_pixels=5431574
6692018-07-07 21:38:23,295 total_time=4321928, total_pixels=5431574
6702018-07-07 21:38:23,369 packet decoding sequence 43 for window 1: 735x457 took 14.1ms
6712018-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
6722018-07-07 21:38:23,369 may_recalculate(1, 335895) total 335895 pixels, scheduling recalculate work item
6732018-07-07 21:38:23,402 total_time=4336063, total_pixels=5767469
6742018-07-07 21:38:23,504 total_time=4336063, total_pixels=5767469
6752018-07-07 21:38:23,613 total_time=4336063, total_pixels=5767469
6762018-07-07 21:38:23,706 total_time=4336063, total_pixels=5767469
6772018-07-07 21:38:23,810 total_time=4336063, total_pixels=5767469
6782018-07-07 21:38:23,910 total_time=4336063, total_pixels=5767469
6792018-07-07 21:38:24,013 total_time=4336063, total_pixels=5767469
6802018-07-07 21:38:24,080 calculate_batch_delay for wid=1 current batch delay=265, last update 1 seconds ago
6812018-07-07 21:38:24,081 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
6822018-07-07 21:38:24,081 update_batch_delay: client-decode-speed          : 0.96,0.01  {'avg': 65, 'recent': 62}
6832018-07-07 21:38:24,081 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 207, 'elapsed': 387}
6842018-07-07 21:38:24,081 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 149296, 'budget': 14847651}
6852018-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}
6862018-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}
6872018-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}
6882018-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}
6892018-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}
6902018-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}
6912018-07-07 21:38:24,086 update_batch_delay: congestion                   : 1.16,1.59  {}
6922018-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}
6932018-07-07 21:38:24,086 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
6942018-07-07 21:38:24,086 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
6952018-07-07 21:38:24,087 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
6962018-07-07 21:38:24,088 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
6972018-07-07 21:38:24,088 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
6982018-07-07 21:38:24,089 update_batch_delay: delay=255
6992018-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}
7002018-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
7012018-07-07 21:38:24,090 update_speed() wid=1, info={}, speed=100
7022018-07-07 21:38:24,110 total_time=4336063, total_pixels=5767469
7032018-07-07 21:38:24,159 packet decoding sequence 44 for window 1: 13x21 took 1.0ms
7042018-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
7052018-07-07 21:38:24,211 total_time=4337063, total_pixels=5767742
7062018-07-07 21:38:24,315 total_time=4337063, total_pixels=5767742
7072018-07-07 21:38:24,414 total_time=4337063, total_pixels=5767742
7082018-07-07 21:38:24,470 packet decoding sequence 45 for window 1: 13x21 took 1.0ms
7092018-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
7102018-07-07 21:38:24,514 total_time=4338099, total_pixels=5768015
7112018-07-07 21:38:24,618 total_time=4338099, total_pixels=5768015
7122018-07-07 21:38:24,717 total_time=4338099, total_pixels=5768015
7132018-07-07 21:38:24,822 total_time=4338099, total_pixels=5768015
7142018-07-07 21:38:24,931 total_time=4338099, total_pixels=5768015
7152018-07-07 21:38:25,029 total_time=4338099, total_pixels=5768015
7162018-07-07 21:38:25,136 total_time=4338099, total_pixels=5768015
7172018-07-07 21:38:25,239 total_time=4338099, total_pixels=5768015
7182018-07-07 21:38:25,352 total_time=4338099, total_pixels=5768015
7192018-07-07 21:38:25,456 total_time=4338099, total_pixels=5768015
7202018-07-07 21:38:25,566 total_time=4338099, total_pixels=5768015
7212018-07-07 21:38:25,675 total_time=4338099, total_pixels=5768015
7222018-07-07 21:38:25,781 total_time=4338099, total_pixels=5768015
7232018-07-07 21:38:25,886 total_time=4338099, total_pixels=5768015
7242018-07-07 21:38:25,984 total_time=4338099, total_pixels=5768015
7252018-07-07 21:38:26,095 total_time=4338099, total_pixels=5768015
7262018-07-07 21:38:26,185 total_time=4338099, total_pixels=5768015
7272018-07-07 21:38:26,280 packet decoding sequence 46 for window 1: 690x457 took 317.0ms
7282018-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
7292018-07-07 21:38:26,280 may_recalculate(1, 315330) total 315876 pixels, scheduling recalculate work item
7302018-07-07 21:38:26,281 calculate_batch_delay for wid=1 current batch delay=255, last update 2 seconds ago
7312018-07-07 21:38:26,283 update_batch_delay: damage-network-delay         : 0.98,0.52  {'avg': 41, 'recent': 40}
7322018-07-07 21:38:26,283 update_batch_delay: client-decode-speed          : 2.90,7.60  {'avg': 120, 'recent': 778}
7332018-07-07 21:38:26,283 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 119, 'elapsed': 166}
7342018-07-07 21:38:26,283 update_batch_delay: bandwidth-limit              : 0.06,0.00  {'used': 274680, 'budget': 5242880}
7352018-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}
7362018-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}
7372018-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}
7382018-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}
7392018-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}
7402018-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}
7412018-07-07 21:38:26,286 packet decoding sequence 47 for window 1: 519x457 took 312.4ms
7422018-07-07 21:38:26,286 update_batch_delay: congestion                   : 1.89,8.93  {}
7432018-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
7442018-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}
7452018-07-07 21:38:26,287 may_recalculate(1, 237183) total 237183 pixels, scheduling recalculate work item
7462018-07-07 21:38:26,287 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
7472018-07-07 21:38:26,291 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
7482018-07-07 21:38:26,293 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
7492018-07-07 21:38:26,295 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
7502018-07-07 21:38:26,295 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
7512018-07-07 21:38:26,295 update_batch_delay: delay=405
7522018-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}
7532018-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
7542018-07-07 21:38:26,297 update_speed() wid=1, info={}, speed=100
7552018-07-07 21:38:26,307 total_time=4967498, total_pixels=6320528
7562018-07-07 21:38:26,426 total_time=4967498, total_pixels=6320528
7572018-07-07 21:38:26,520 total_time=4967498, total_pixels=6320528
7582018-07-07 21:38:26,590 packet decoding sequence 48 for window 1: 465x457 took 84.9ms
7592018-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
7602018-07-07 21:38:26,628 total_time=5052401, total_pixels=6533033
7612018-07-07 21:38:26,729 total_time=5052401, total_pixels=6533033
7622018-07-07 21:38:26,829 total_time=5052401, total_pixels=6533033
7632018-07-07 21:38:26,932 total_time=5052401, total_pixels=6533033
7642018-07-07 21:38:27,039 total_time=5052401, total_pixels=6533033
7652018-07-07 21:38:27,171 total_time=5052401, total_pixels=6533033
7662018-07-07 21:38:27,285 calculate_batch_delay for wid=1 current batch delay=405, last update 1 seconds ago
7672018-07-07 21:38:27,286 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
7682018-07-07 21:38:27,286 update_batch_delay: client-decode-speed          : 2.15,4.59  {'avg': 131, 'recent': 449}
7692018-07-07 21:38:27,287 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 110, 'elapsed': 6}
7702018-07-07 21:38:27,287 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 133520, 'budget': 10898184}
7712018-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}
7722018-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}
7732018-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}
7742018-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}
7752018-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}
7762018-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}
7772018-07-07 21:38:27,288 update_batch_delay: congestion                   : 1.96,9.64  {}
7782018-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}
7792018-07-07 21:38:27,289 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
7802018-07-07 21:38:27,289 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
7812018-07-07 21:38:27,289 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
7822018-07-07 21:38:27,289 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
7832018-07-07 21:38:27,289 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
7842018-07-07 21:38:27,290 update_batch_delay: delay=449
7852018-07-07 21:38:27,290 get_client_backlog missing acks: []
7862018-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}
7872018-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
7882018-07-07 21:38:27,292 update_speed() wid=1, info={}, speed=100
7892018-07-07 21:38:27,298 total_time=5052401, total_pixels=6533033
7902018-07-07 21:38:27,393 total_time=5052401, total_pixels=6533033
7912018-07-07 21:38:27,494 total_time=5052401, total_pixels=6533033
7922018-07-07 21:38:27,610 total_time=5052401, total_pixels=6533033
7932018-07-07 21:38:27,707 total_time=5052401, total_pixels=6533033
7942018-07-07 21:38:27,809 total_time=5052401, total_pixels=6533033
7952018-07-07 21:38:27,911 total_time=5052401, total_pixels=6533033
7962018-07-07 21:38:28,011 total_time=5052401, total_pixels=6533033
7972018-07-07 21:38:28,112 total_time=5052401, total_pixels=6533033
7982018-07-07 21:38:28,207 total_time=5052401, total_pixels=6533033
7992018-07-07 21:38:28,315 total_time=5052401, total_pixels=6533033
8002018-07-07 21:38:28,417 total_time=5052401, total_pixels=6533033
8012018-07-07 21:38:28,518 total_time=5052401, total_pixels=6533033
8022018-07-07 21:38:28,654 total_time=5052401, total_pixels=6533033
8032018-07-07 21:38:28,732 total_time=5052401, total_pixels=6533033
8042018-07-07 21:38:28,834 total_time=5052401, total_pixels=6533033
8052018-07-07 21:38:28,933 total_time=5052401, total_pixels=6533033
8062018-07-07 21:38:29,053 total_time=5052401, total_pixels=6533033
8072018-07-07 21:38:29,099 packet decoding sequence 49 for window 1: 13x21 took 285.1ms
8082018-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
8092018-07-07 21:38:29,137 packet decoding sequence 50 for window 1: 465x457 took 323.1ms
8102018-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
8112018-07-07 21:38:29,138 may_recalculate(1, 212505) total 212778 pixels, scheduling recalculate work item
8122018-07-07 21:38:29,139 calculate_batch_delay for wid=1 current batch delay=449, last update 1 seconds ago
8132018-07-07 21:38:29,140 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
8142018-07-07 21:38:29,142 update_batch_delay: client-decode-speed          : 3.19,8.76  {'avg': 143, 'recent': 1167}
8152018-07-07 21:38:29,142 update_batch_delay: damage-rate                  : 0.72,0.52  {'max_latency': 111, 'elapsed': 498}
8162018-07-07 21:38:29,142 update_batch_delay: bandwidth-limit              : 0.05,0.00  {'used': 124784, 'budget': 3037124}
8172018-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}
8182018-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}
8192018-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}
8202018-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}
8212018-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}
8222018-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}
8232018-07-07 21:38:29,145 update_batch_delay: congestion                   : 2.80,17.96  {}
8242018-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}
8252018-07-07 21:38:29,145 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
8262018-07-07 21:38:29,145 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
8272018-07-07 21:38:29,145 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
8282018-07-07 21:38:29,145 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
8292018-07-07 21:38:29,146 update_batch_delay: soft-expired                 : 2.00,1.00  {'count': 2}
8302018-07-07 21:38:29,146 update_batch_delay: delay=447
8312018-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}
8322018-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
8332018-07-07 21:38:29,147 update_speed() wid=1, info={}, speed=100
8342018-07-07 21:38:29,148 packet decoding sequence 1 for window 10: 314x165 took 8.4ms
8352018-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
8362018-07-07 21:38:29,148 may_recalculate(10, 51810) total 51810 pixels, scheduling recalculate work item
8372018-07-07 21:38:29,164 total_time=5668990, total_pixels=6797621
8382018-07-07 21:38:29,223 packet decoding sequence 51 for window 1: 65x19 took 1.6ms
8392018-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
8402018-07-07 21:38:29,225 packet decoding sequence 52 for window 1: 13x21 took 1.0ms
8412018-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
8422018-07-07 21:38:29,265 total_time=5671590, total_pixels=6799129
8432018-07-07 21:38:29,367 total_time=5671590, total_pixels=6799129
8442018-07-07 21:38:29,466 total_time=5671590, total_pixels=6799129
8452018-07-07 21:38:29,566 total_time=5671590, total_pixels=6799129
8462018-07-07 21:38:29,667 total_time=5671590, total_pixels=6799129
8472018-07-07 21:38:29,768 total_time=5671590, total_pixels=6799129
8482018-07-07 21:38:29,869 total_time=5671590, total_pixels=6799129
8492018-07-07 21:38:29,968 total_time=5671590, total_pixels=6799129
8502018-07-07 21:38:30,073 total_time=5671590, total_pixels=6799129
8512018-07-07 21:38:30,139 calculate_batch_delay for wid=10 current batch delay=480, last update 80185 seconds ago
8522018-07-07 21:38:30,139 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
8532018-07-07 21:38:30,139 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 169, 'recent': 169}
8542018-07-07 21:38:30,140 update_batch_delay: damage-rate                  : 1.00,0.00  {'max_latency': 100, 'elapsed': 187}
8552018-07-07 21:38:30,140 update_batch_delay: bandwidth-limit              : 0.24,0.00  {'used': 294424, 'budget': 1322321}
8562018-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}
8572018-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}
8582018-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}
8592018-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}
8602018-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}
8612018-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}
8622018-07-07 21:38:30,141 update_batch_delay: congestion                   : 1.18,1.82  {}
8632018-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}
8642018-07-07 21:38:30,141 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
8652018-07-07 21:38:30,141 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
8662018-07-07 21:38:30,141 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
8672018-07-07 21:38:30,141 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
8682018-07-07 21:38:30,142 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
8692018-07-07 21:38:30,142 update_batch_delay: delay=429
8702018-07-07 21:38:30,176 total_time=5671590, total_pixels=6799129
8712018-07-07 21:38:30,277 total_time=5671590, total_pixels=6799129
8722018-07-07 21:38:30,365 packet decoding sequence 2 for window 10: 312x27 took 3.1ms
8732018-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
8742018-07-07 21:38:30,366 may_recalculate(10, 8424) total 8424 pixels, scheduling recalculate work item
8752018-07-07 21:38:30,378 total_time=5674685, total_pixels=6807553
8762018-07-07 21:38:30,463 packet decoding sequence 3 for window 10: 312x19 took 100.9ms
8772018-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
8782018-07-07 21:38:30,465 packet decoding sequence 4 for window 10: 312x19 took 102.5ms
8792018-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
8802018-07-07 21:38:30,469 packet decoding sequence 53 for window 1: 65x19 took 105.6ms
8812018-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
8822018-07-07 21:38:30,470 may_recalculate(1, 1235) total 2743 pixels, scheduling recalculate work item
8832018-07-07 21:38:30,481 total_time=5983643, total_pixels=6820644
8842018-07-07 21:38:30,581 total_time=5983643, total_pixels=6820644
8852018-07-07 21:38:30,682 total_time=5983643, total_pixels=6820644
8862018-07-07 21:38:30,783 total_time=5983643, total_pixels=6820644
8872018-07-07 21:38:30,842 packet decoding sequence 54 for window 1: 65x19 took 1.1ms
8882018-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
8892018-07-07 21:38:30,884 total_time=5984759, total_pixels=6821879
8902018-07-07 21:38:30,988 total_time=5984759, total_pixels=6821879
8912018-07-07 21:38:31,042 packet decoding sequence 5 for window 10: 312x38 took 2.2ms
8922018-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
8932018-07-07 21:38:31,087 total_time=5986928, total_pixels=6833735
8942018-07-07 21:38:31,139 calculate_batch_delay for wid=1 current batch delay=447, last update 2 seconds ago
8952018-07-07 21:38:31,140 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
8962018-07-07 21:38:31,140 update_batch_delay: client-decode-speed          : 1.87,3.47  {'avg': 110, 'recent': 292}
8972018-07-07 21:38:31,140 update_batch_delay: damage-rate                  : 0.33,0.82  {'max_latency': 100, 'elapsed': 868}
8982018-07-07 21:38:31,140 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 79040, 'budget': 4039614}
8992018-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}
9002018-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}
9012018-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}
9022018-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}
9032018-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}
9042018-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}
9052018-07-07 21:38:31,141 update_batch_delay: congestion                   : 3.68,26.78  {}
9062018-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}
9072018-07-07 21:38:31,142 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
9082018-07-07 21:38:31,142 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
9092018-07-07 21:38:31,142 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
9102018-07-07 21:38:31,142 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
9112018-07-07 21:38:31,142 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
9122018-07-07 21:38:31,142 update_batch_delay: delay=460
9132018-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}
9142018-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
9152018-07-07 21:38:31,146 update_speed() wid=1, info={}, speed=100
9162018-07-07 21:38:31,147 calculate_batch_delay for wid=10 current batch delay=429, last update 1 seconds ago
9172018-07-07 21:38:31,147 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
9182018-07-07 21:38:31,147 update_batch_delay: client-decode-speed          : 1.06,0.06  {'avg': 190, 'recent': 207}
9192018-07-07 21:38:31,147 update_batch_delay: damage-rate                  : 0.21,0.89  {'max_latency': 100, 'elapsed': 993}
9202018-07-07 21:38:31,148 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 13504, 'budget': 1203265}
9212018-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}
9222018-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}
9232018-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}
9242018-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}
9252018-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}
9262018-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}
9272018-07-07 21:38:31,149 update_batch_delay: congestion                   : 3.68,26.78  {}
9282018-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}
9292018-07-07 21:38:31,149 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
9302018-07-07 21:38:31,149 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
9312018-07-07 21:38:31,149 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
9322018-07-07 21:38:31,149 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
9332018-07-07 21:38:31,149 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
9342018-07-07 21:38:31,150 update_batch_delay: delay=479
9352018-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}
9362018-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
9372018-07-07 21:38:31,150 update_speed() wid=10, info={}, speed=100
9382018-07-07 21:38:31,188 total_time=5986928, total_pixels=6833735
9392018-07-07 21:38:31,289 total_time=5986928, total_pixels=6833735
9402018-07-07 21:38:31,389 total_time=5986928, total_pixels=6833735
9412018-07-07 21:38:31,490 total_time=5986928, total_pixels=6833735
9422018-07-07 21:38:31,592 total_time=5986928, total_pixels=6833735
9432018-07-07 21:38:31,693 total_time=5986928, total_pixels=6833735
9442018-07-07 21:38:31,794 total_time=5986928, total_pixels=6833735
9452018-07-07 21:38:31,889 total_time=5986928, total_pixels=6833735
9462018-07-07 21:38:31,959 packet decoding sequence 55 for window 1: 65x19 took 1.2ms
9472018-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
9482018-07-07 21:38:31,994 total_time=5988133, total_pixels=6834970
9492018-07-07 21:38:32,096 total_time=5988133, total_pixels=6834970
9502018-07-07 21:38:32,195 total_time=5988133, total_pixels=6834970
9512018-07-07 21:38:32,298 total_time=5988133, total_pixels=6834970
9522018-07-07 21:38:32,397 total_time=5988133, total_pixels=6834970
9532018-07-07 21:38:32,498 total_time=5988133, total_pixels=6834970
9542018-07-07 21:38:32,599 total_time=5988133, total_pixels=6834970
9552018-07-07 21:38:32,702 total_time=5988133, total_pixels=6834970
9562018-07-07 21:38:32,802 total_time=5988133, total_pixels=6834970
9572018-07-07 21:38:32,904 total_time=5988133, total_pixels=6834970
9582018-07-07 21:38:33,005 total_time=5988133, total_pixels=6834970
9592018-07-07 21:38:33,107 total_time=5988133, total_pixels=6834970
9602018-07-07 21:38:33,206 total_time=5988133, total_pixels=6834970
9612018-07-07 21:38:33,307 total_time=5988133, total_pixels=6834970
9622018-07-07 21:38:33,407 total_time=5988133, total_pixels=6834970
9632018-07-07 21:38:33,509 total_time=5988133, total_pixels=6834970
9642018-07-07 21:38:33,609 total_time=5988133, total_pixels=6834970
9652018-07-07 21:38:33,711 total_time=5988133, total_pixels=6834970
9662018-07-07 21:38:33,812 total_time=5988133, total_pixels=6834970
9672018-07-07 21:38:33,912 total_time=5988133, total_pixels=6834970
9682018-07-07 21:38:34,012 total_time=5988133, total_pixels=6834970
9692018-07-07 21:38:34,113 total_time=5988133, total_pixels=6834970
9702018-07-07 21:38:34,213 total_time=5988133, total_pixels=6834970
9712018-07-07 21:38:34,314 total_time=5988133, total_pixels=6834970
9722018-07-07 21:38:34,414 total_time=5988133, total_pixels=6834970
9732018-07-07 21:38:34,515 total_time=5988133, total_pixels=6834970
9742018-07-07 21:38:34,617 total_time=5988133, total_pixels=6834970
9752018-07-07 21:38:34,717 total_time=5988133, total_pixels=6834970
9762018-07-07 21:38:34,835 total_time=5988133, total_pixels=6834970
9772018-07-07 21:38:34,922 total_time=5988133, total_pixels=6834970
9782018-07-07 21:38:35,021 total_time=5988133, total_pixels=6834970
9792018-07-07 21:38:35,145 total_time=5988133, total_pixels=6834970
9802018-07-07 21:38:35,246 total_time=5988133, total_pixels=6834970
9812018-07-07 21:38:35,339 packet decoding sequence 1 for window 11: 314x165 took 7.1ms
9822018-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
9832018-07-07 21:38:35,339 may_recalculate(11, 51810) total 51810 pixels, scheduling recalculate work item
9842018-07-07 21:38:35,340 calculate_batch_delay for wid=11 current batch delay=798, last update 80191 seconds ago
9852018-07-07 21:38:35,341 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 41, 'recent': 41}
9862018-07-07 21:38:35,341 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 143, 'recent': 143}
9872018-07-07 21:38:35,341 update_batch_delay: damage-rate                  : 0.68,0.56  {'max_latency': 100, 'elapsed': 516}
9882018-07-07 21:38:35,341 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 35904, 'budget': 2938306}
9892018-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}
9902018-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}
9912018-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}
9922018-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}
9932018-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}
9942018-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}
9952018-07-07 21:38:35,342 update_batch_delay: congestion                   : 1.02,0.23  {}
9962018-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}
9972018-07-07 21:38:35,346 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
9982018-07-07 21:38:35,347 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
9992018-07-07 21:38:35,348 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
10002018-07-07 21:38:35,348 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
10012018-07-07 21:38:35,349 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
10022018-07-07 21:38:35,349 update_batch_delay: delay=402
10032018-07-07 21:38:35,357 total_time=5995213, total_pixels=6886780
10042018-07-07 21:38:35,403 packet decoding sequence 56 for window 1: 65x19 took 1.0ms
10052018-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
10062018-07-07 21:38:35,404 may_recalculate(1, 1235) total 2470 pixels, scheduling recalculate work item
10072018-07-07 21:38:35,454 total_time=5996215, total_pixels=6888015
10082018-07-07 21:38:35,555 total_time=5996215, total_pixels=6888015
10092018-07-07 21:38:35,678 total_time=5996215, total_pixels=6888015
10102018-07-07 21:38:35,759 total_time=5996215, total_pixels=6888015
10112018-07-07 21:38:35,870 total_time=5996215, total_pixels=6888015
10122018-07-07 21:38:35,971 total_time=5996215, total_pixels=6888015
10132018-07-07 21:38:36,072 total_time=5996215, total_pixels=6888015
10142018-07-07 21:38:36,180 total_time=5996215, total_pixels=6888015
10152018-07-07 21:38:36,301 total_time=5996215, total_pixels=6888015
10162018-07-07 21:38:36,341 calculate_batch_delay for wid=1 current batch delay=460, last update 5 seconds ago
10172018-07-07 21:38:36,341 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
10182018-07-07 21:38:36,342 update_batch_delay: client-decode-speed          : 1.34,0.34  {'avg': 92, 'recent': 141}
10192018-07-07 21:38:36,342 update_batch_delay: damage-rate                  : 0.52,0.69  {'max_latency': 100, 'elapsed': 675}
10202018-07-07 21:38:36,342 update_batch_delay: bandwidth-limit              : 0.02,0.00  {'used': 103360, 'budget': 5611640}
10212018-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}
10222018-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}
10232018-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}
10242018-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}
10252018-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}
10262018-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}
10272018-07-07 21:38:36,344 update_batch_delay: congestion                   : 1.01,0.14  {}
10282018-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}
10292018-07-07 21:38:36,344 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
10302018-07-07 21:38:36,344 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
10312018-07-07 21:38:36,344 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
10322018-07-07 21:38:36,345 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
10332018-07-07 21:38:36,345 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
10342018-07-07 21:38:36,345 update_batch_delay: delay=349
10352018-07-07 21:38:36,345 get_client_backlog missing acks: []
10362018-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}
10372018-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
10382018-07-07 21:38:36,346 update_speed() wid=1, info={}, speed=100
10392018-07-07 21:38:36,402 total_time=5996215, total_pixels=6888015
10402018-07-07 21:38:36,489 packet decoding sequence 1 for window 12: 295x121 took 7.0ms
10412018-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
10422018-07-07 21:38:36,490 may_recalculate(12, 35695) total 35695 pixels, scheduling recalculate work item
10432018-07-07 21:38:36,506 total_time=6003173, total_pixels=6923710
10442018-07-07 21:38:36,552 packet decoding sequence 57 for window 1: 105x19 took 68.7ms
10452018-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
10462018-07-07 21:38:36,603 total_time=6071899, total_pixels=6925705
10472018-07-07 21:38:36,705 total_time=6071899, total_pixels=6925705
10482018-07-07 21:38:36,805 total_time=6071899, total_pixels=6925705
10492018-07-07 21:38:36,909 total_time=6071899, total_pixels=6925705
10502018-07-07 21:38:37,008 total_time=6071899, total_pixels=6925705
10512018-07-07 21:38:37,109 total_time=6071899, total_pixels=6925705
10522018-07-07 21:38:37,210 total_time=6071899, total_pixels=6925705
10532018-07-07 21:38:37,316 total_time=6071899, total_pixels=6925705
10542018-07-07 21:38:37,341 calculate_batch_delay for wid=12 current batch delay=651, last update 80193 seconds ago
10552018-07-07 21:38:37,341 update_batch_delay: damage-network-delay         : 1.00,0.50  {'avg': 40, 'recent': 40}
10562018-07-07 21:38:37,341 update_batch_delay: client-decode-speed          : 1.00,0.00  {'avg': 204, 'recent': 204}
10572018-07-07 21:38:37,342 update_batch_delay: damage-rate                  : 0.00,1.21  {'max_latency': 100, 'elapsed': 1674}
10582018-07-07 21:38:37,342 update_batch_delay: bandwidth-limit              : 0.00,0.00  {'used': 0, 'budget': 754007}
10592018-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}
10602018-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}
10612018-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}
10622018-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}
10632018-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}
10642018-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}
10652018-07-07 21:38:37,344 update_batch_delay: congestion                   : 1.88,8.84  {}
10662018-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}
10672018-07-07 21:38:37,345 update_batch_delay: focus                        : 1.00,0.00  {'has_focus': False}
10682018-07-07 21:38:37,345 update_batch_delay: override-redirect            : 0.00,1.00  {'is_OR': True}
10692018-07-07 21:38:37,345 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
10702018-07-07 21:38:37,345 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
10712018-07-07 21:38:37,345 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
10722018-07-07 21:38:37,345 update_batch_delay: delay=434
10732018-07-07 21:38:37,410 total_time=6071899, total_pixels=6925705
10742018-07-07 21:38:37,512 total_time=6071899, total_pixels=6925705
10752018-07-07 21:38:37,612 total_time=6071899, total_pixels=6925705
10762018-07-07 21:38:37,713 total_time=6071899, total_pixels=6925705
10772018-07-07 21:38:37,813 total_time=6071899, total_pixels=6925705
10782018-07-07 21:38:37,913 total_time=6071899, total_pixels=6925705
10792018-07-07 21:38:38,014 total_time=6071899, total_pixels=6925705
10802018-07-07 21:38:38,116 total_time=6071899, total_pixels=6925705
10812018-07-07 21:38:38,215 total_time=6071899, total_pixels=6925705
10822018-07-07 21:38:38,316 total_time=6071899, total_pixels=6925705
10832018-07-07 21:38:38,368 packet decoding sequence 58 for window 1: 40x19 took 1.3ms
10842018-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
10852018-07-07 21:38:38,369 may_recalculate(1, 760) total 2755 pixels, scheduling recalculate work item
10862018-07-07 21:38:38,370 calculate_batch_delay for wid=1 current batch delay=349, last update 2 seconds ago
10872018-07-07 21:38:38,370 update_batch_delay: damage-network-delay         : 0.99,0.51  {'avg': 41, 'recent': 40}
10882018-07-07 21:38:38,370 update_batch_delay: client-decode-speed          : 1.28,0.14  {'avg': 89, 'recent': 127}
10892018-07-07 21:38:38,371 update_batch_delay: damage-rate                  : 0.78,0.47  {'max_latency': 191, 'elapsed': 606}
10902018-07-07 21:38:38,371 update_batch_delay: bandwidth-limit              : 0.01,0.00  {'used': 24320, 'budget': 5242880}
10912018-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}
10922018-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}
10932018-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}
10942018-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}
10952018-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}
10962018-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}
10972018-07-07 21:38:38,372 update_batch_delay: congestion                   : 1.09,0.87  {}
10982018-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}
10992018-07-07 21:38:38,372 update_batch_delay: focus                        : 0.00,1.00  {'has_focus': True}
11002018-07-07 21:38:38,372 update_batch_delay: override-redirect            : 1.00,0.00  {'is_OR': False}
11012018-07-07 21:38:38,372 update_batch_delay: fullscreen                   : 0.00,0.00  {'other_is_fullscreen': False}
11022018-07-07 21:38:38,372 update_batch_delay: maximized                    : 0.00,0.00  {'other_is_maximized': False}
11032018-07-07 21:38:38,373 update_batch_delay: soft-expired                 : 0.00,0.00  {'count': 0}
11042018-07-07 21:38:38,373 update_batch_delay: delay=306
11052018-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}
11062018-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
11072018-07-07 21:38:38,374 update_speed() wid=1, info={}, speed=100
11082018-07-07 21:38:38,416 total_time=6073202, total_pixels=6926465
11092018-07-07 21:38:38,516 total_time=6073202, total_pixels=6926465
11102018-07-07 21:38:38,618 total_time=6073202, total_pixels=6926465
11112018-07-07 21:38:38,718 total_time=6073202, total_pixels=6926465
11122018-07-07 21:38:38,819 total_time=6073202, total_pixels=6926465
11132018-07-07 21:38:38,920 total_time=6073202, total_pixels=6926465
11142018-07-07 21:38:39,021 total_time=6073202, total_pixels=6926465
11152018-07-07 21:38:39,121 total_time=6073202, total_pixels=6926465
11162018-07-07 21:38:39,222 total_time=6073202, total_pixels=6926465
11172018-07-07 21:38:39,322 total_time=6073202, total_pixels=6926465
11182018-07-07 21:38:39,423 total_time=6073202, total_pixels=6926465
11192018-07-07 21:38:39,525 total_time=6073202, total_pixels=6926465
11202018-07-07 21:38:39,626 total_time=6073202, total_pixels=6926465
11212018-07-07 21:38:39,727 total_time=6073202, total_pixels=6926465
11222018-07-07 21:38:39,828 total_time=6073202, total_pixels=6926465
11232018-07-07 21:38:39,942 total_time=6073202, total_pixels=6926465
11242018-07-07 21:38:40,038 total_time=6073202, total_pixels=6926465
11252018-07-07 21:38:40,156 total_time=6073202, total_pixels=6926465
11262018-07-07 21:38:40,253 total_time=6073202, total_pixels=6926465
11272018-07-07 21:38:40,353 total_time=6073202, total_pixels=6926465
11282018-07-07 21:38:40,456 total_time=6037390, total_pixels=6254675
11292018-07-07 21:38:40,537 packet decoding sequence 59 for window 1: 13x21 took 1.2ms
11302018-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
11312018-07-07 21:38:40,558 total_time=6038617, total_pixels=6254948
11322018-07-07 21:38:40,657 total_time=6025004, total_pixels=5919053
11332018-07-07 21:38:40,757 total_time=6025004, total_pixels=5919053
11342018-07-07 21:38:40,858 total_time=6025004, total_pixels=5919053
11352018-07-07 21:38:40,958 total_time=6025004, total_pixels=5919053
11362018-07-07 21:38:41,059 total_time=6025004, total_pixels=5919053
11372018-07-07 21:38:41,160 total_time=6025004, total_pixels=5919053
11382018-07-07 21:38:41,261 total_time=6025004, total_pixels=5919053
11392018-07-07 21:38:41,361 total_time=6025004, total_pixels=5919053
11402018-07-07 21:38:41,462 total_time=6025004, total_pixels=5919053
11412018-07-07 21:38:41,563 total_time=6025004, total_pixels=5919053
11422018-07-07 21:38:41,666 total_time=6025004, total_pixels=5919053
11432018-07-07 21:38:41,767 total_time=6021565, total_pixels=5913411
11442018-07-07 21:38:41,868 total_time=6021565, total_pixels=5913411
11452018-07-07 21:38:41,969 total_time=6021565, total_pixels=5913411
11462018-07-07 21:38:42,070 total_time=6021565, total_pixels=5913411
11472018-07-07 21:38:42,171 total_time=6021565, total_pixels=5913411
11482018-07-07 21:38:42,270 total_time=6021565, total_pixels=5913411
11492018-07-07 21:38:42,371 total_time=6021565, total_pixels=5913411
11502018-07-07 21:38:42,472 total_time=6021565, total_pixels=5913411
11512018-07-07 21:38:42,572 total_time=6021565, total_pixels=5913411
11522018-07-07 21:38:42,673 total_time=6021565, total_pixels=5913411
11532018-07-07 21:38:42,773 total_time=6021565, total_pixels=5913411
11542018-07-07 21:38:42,874 total_time=6021565, total_pixels=5913411
11552018-07-07 21:38:42,974 total_time=6021565, total_pixels=5913411
11562018-07-07 21:38:43,074 total_time=6021565, total_pixels=5913411
11572018-07-07 21:38:43,175 total_time=6021565, total_pixels=5913411
11582018-07-07 21:38:43,277 total_time=6021565, total_pixels=5913411
11592018-07-07 21:38:43,378 total_time=6021565, total_pixels=5913411
11602018-07-07 21:38:43,477 total_time=6021565, total_pixels=5913411
11612018-07-07 21:38:43,578 total_time=6021565, total_pixels=5913411
11622018-07-07 21:38:43,679 total_time=6021565, total_pixels=5913411
11632018-07-07 21:38:43,779 total_time=6021565, total_pixels=5913411
11642018-07-07 21:38:43,880 total_time=6021565, total_pixels=5913411
11652018-07-07 21:38:43,981 total_time=6021565, total_pixels=5913411
11662018-07-07 21:38:44,081 total_time=6021565, total_pixels=5913411
11672018-07-07 21:38:44,184 total_time=6021565, total_pixels=5913411
11682018-07-07 21:38:44,284 total_time=6021565, total_pixels=5913411
11692018-07-07 21:38:44,385 total_time=6021565, total_pixels=5913411
11702018-07-07 21:38:44,487 total_time=6021565, total_pixels=5913411
11712018-07-07 21:38:44,588 total_time=6021565, total_pixels=5913411
11722018-07-07 21:38:44,689 total_time=6021565, total_pixels=5913411
11732018-07-07 21:38:44,788 total_time=6021565, total_pixels=5913411
11742018-07-07 21:38:44,890 total_time=6021565, total_pixels=5913411
11752018-07-07 21:38:44,991 total_time=6021565, total_pixels=5913411
11762018-07-07 21:38:45,091 total_time=6021565, total_pixels=5913411
11772018-07-07 21:38:45,192 total_time=6021565, total_pixels=5913411
11782018-07-07 21:38:45,293 total_time=6021565, total_pixels=5913411
11792018-07-07 21:38:45,393 total_time=6021565, total_pixels=5913411
11802018-07-07 21:38:45,493 total_time=6021565, total_pixels=5913411
11812018-07-07 21:38:45,594 total_time=6021565, total_pixels=5913411
11822018-07-07 21:38:45,694 total_time=6021565, total_pixels=5913411
11832018-07-07 21:38:45,795 total_time=6021565, total_pixels=5913411
11842018-07-07 21:38:45,898 total_time=6021565, total_pixels=5913411
11852018-07-07 21:38:45,998 total_time=6021565, total_pixels=5913411
11862018-07-07 21:38:46,098 total_time=6021565, total_pixels=5913411
11872018-07-07 21:38:46,198 total_time=5986554, total_pixels=5909250
11882018-07-07 21:38:46,299 total_time=5986554, total_pixels=5909250
11892018-07-07 21:38:46,400 total_time=5986554, total_pixels=5909250
11902018-07-07 21:38:46,501 total_time=5986554, total_pixels=5909250
11912018-07-07 21:38:46,602 total_time=5986554, total_pixels=5909250
11922018-07-07 21:38:46,701 total_time=5986554, total_pixels=5909250
11932018-07-07 21:38:46,801 total_time=5986554, total_pixels=5909250
11942018-07-07 21:38:46,902 total_time=5986554, total_pixels=5909250
11952018-07-07 21:38:47,004 total_time=5986554, total_pixels=5909250
11962018-07-07 21:38:47,105 total_time=4086536, total_pixels=5237460
11972018-07-07 21:38:47,207 total_time=2141036, total_pixels=3893880
11982018-07-07 21:38:47,309 total_time=2141036, total_pixels=3893880
11992018-07-07 21:38:47,408 total_time=2123402, total_pixels=3557985
12002018-07-07 21:38:47,509 total_time=2105012, total_pixels=3222090
12012018-07-07 21:38:47,609 total_time=2105012, total_pixels=3222090
12022018-07-07 21:38:47,711 total_time=2105012, total_pixels=3222090
12032018-07-07 21:38:47,811 total_time=2102911, total_pixels=3221817
12042018-07-07 21:38:47,912 total_time=2102911, total_pixels=3221817
12052018-07-07 21:38:48,012 total_time=2102911, total_pixels=3221817
12062018-07-07 21:38:48,106 total_time=2102911, total_pixels=3221817
12072018-07-07 21:38:48,214 total_time=2102911, total_pixels=3221817
12082018-07-07 21:38:48,314 total_time=2088832, total_pixels=3170007
12092018-07-07 21:38:48,416 total_time=2088832, total_pixels=3170007
12102018-07-07 21:38:48,516 total_time=2034937, total_pixels=3168772
12112018-07-07 21:38:48,616 total_time=2034937, total_pixels=3168772
12122018-07-07 21:38:48,719 total_time=2022816, total_pixels=3150650
12132018-07-07 21:38:48,819 total_time=2022816, total_pixels=3150650
12142018-07-07 21:38:48,920 total_time=2008820, total_pixels=3096826
12152018-07-07 21:38:49,020 total_time=2008820, total_pixels=3096826
12162018-07-07 21:38:49,122 total_time=2006189, total_pixels=3094812
12172018-07-07 21:38:49,222 total_time=2006189, total_pixels=3094812
12182018-07-07 21:38:49,325 total_time=1999343, total_pixels=3086844
12192018-07-07 21:38:49,424 total_time=1996451, total_pixels=3084070
12202018-07-07 21:38:49,526 total_time=1993687, total_pixels=3076102
12212018-07-07 21:38:49,628 total_time=1993687, total_pixels=3076102
12222018-07-07 21:38:49,728 total_time=1993687, total_pixels=3076102
12232018-07-07 21:38:49,829 total_time=1993687, total_pixels=3076102
12242018-07-07 21:38:49,929 total_time=1980344, total_pixels=3021518
12252018-07-07 21:38:50,025 total_time=1980344, total_pixels=3021518
12262018-07-07 21:38:50,130 total_time=1980344, total_pixels=3021518
12272018-07-07 21:38:50,232 total_time=1980344, total_pixels=3021518
12282018-07-07 21:38:50,333 total_time=1975713, total_pixels=2969708
12292018-07-07 21:38:50,433 total_time=1975713, total_pixels=2969708
12302018-07-07 21:38:50,534 total_time=1975713, total_pixels=2969708
12312018-07-07 21:38:50,635 total_time=1975713, total_pixels=2969708
12322018-07-07 21:38:50,731 total_time=1975713, total_pixels=2969708
12332018-07-07 21:38:50,831 total_time=1975713, total_pixels=2969708
12342018-07-07 21:38:50,931 total_time=1975713, total_pixels=2969708
12352018-07-07 21:38:51,033 total_time=1975713, total_pixels=2969708
12362018-07-07 21:38:51,133 total_time=1975713, total_pixels=2969708
12372018-07-07 21:38:51,234 total_time=1975713, total_pixels=2969708
12382018-07-07 21:38:51,334 total_time=1975713, total_pixels=2969708
12392018-07-07 21:38:51,444 total_time=1975713, total_pixels=2969708
12402018-07-07 21:38:51,545 total_time=1975713, total_pixels=2969708
12412018-07-07 21:38:51,625 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
12422018-07-07 21:38:51,628 Handshake complete; enabling connection
12432018-07-07 21:38:51,633 Python2/GObject client version 2.3.2-r19729 64-bit
12442018-07-07 21:38:51,633  connected from 'xubuntu' as 'steved' - 'Steve Dee'
12452018-07-07 21:38:51,648 Shutting down in response to client request
12462018-07-07 21:38:51,649 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/xubuntu-1):
12472018-07-07 21:38:51,649  server shutdown
12482018-07-07 21:38:51,652 xpra client 2 disconnected.
12492018-07-07 21:38:51,653 Disconnecting client Protocol(unix-domain socket:/run/user/1000/xpra/xubuntu-1):
12502018-07-07 21:38:51,653  server shutdown
12512018-07-07 21:38:51,669 sound source stopping
12522018-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)>>
1253Traceback (most recent call last):
1254  File "/usr/lib/python2.7/dist-packages/xpra/server/server_core.py", line 1703, in _get_info_in_thread
1255    info = self.get_info(proto, *args)
1256  File "/usr/lib/python2.7/dist-packages/xpra/server/server_base.py", line 546, in get_info
1257    dgi = self.do_get_info(proto, sources, wids)
1258  File "/usr/lib/python2.7/dist-packages/xpra/x11/server.py", line 325, in do_get_info
1259    info = X11ServerBase.do_get_info(self, proto, server_sources, window_ids)
1260  File "/usr/lib/python2.7/dist-packages/xpra/x11/x11_server_core.py", line 325, in do_get_info
1261    info = GTKServerBase.do_get_info(self, proto, server_sources, window_ids)
1262  File "/usr/lib/python2.7/dist-packages/xpra/server/gtk_server_base.py", line 120, in do_get_info
1263    info = ServerBase.do_get_info(self, proto, *args)
1264  File "/usr/lib/python2.7/dist-packages/xpra/server/server_base.py", line 613, in do_get_info
1265    sinfo = ss.get_info()
1266  File "/usr/lib/python2.7/dist-packages/xpra/server/source/client_connection.py", line 347, in get_info
1267    "connection"       : self.protocol.get_info(),
1268AttributeError: 'NoneType' object has no attribute 'get_info'
12692018-07-07 21:38:51,684 xpra client 1 disconnected.
12702018-07-07 21:38:52,187 stopping pulseaudio with pid 12696
12712018-07-07 21:38:52,292 New unix-domain connection received on /run/user/1000/xpra/xubuntu-1
12722018-07-07 21:38:52,293 New unix-domain connection received on /home/steved/.xpra/xubuntu-1
12732018-07-07 21:38:52,704 killing xvfb with pid 12646
12742018-07-07 21:38:52,704 removing socket /run/user/1000/xpra/xubuntu-1
12752018-07-07 21:38:52,705 removing socket /home/steved/.xpra/xubuntu-1
1276xfce4-terminal: Fatal IO error 11 (Resource temporarily unavailable) on X server :1.