[   14.777581] iwlwifi 0000:01:00.0: L1 Enabled; Disabling L0S
[   14.784207] iwlwifi 0000:01:00.0: Radio type=0x2-0x1-0x0
[   14.885999] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   14.886221] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   14.915041] ip_tables: (C) 2000-2006 Netfilter Core Team
[   14.936978] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[   14.948621] IPv6: ADDRCONF(NETDEV_UP): virbr0: link is not ready
[   15.157046] Ebtables v2.0 registered
[   15.171810] ip6_tables: (C) 2000-2006 Netfilter Core Team
[   15.190040] init: plymouth-stop pre-start process (1858) terminated with status 1
[   16.541955] cfg80211: Found new beacon on frequency: 5180 MHz (Ch 36) on phy0
[   22.443778] wlan0: authenticate with 28:be:9b:d2:09:41
[   22.453763] wlan0: send auth to 28:be:9b:d2:09:41 (try 1/3)
[   22.456040] wlan0: authenticated
[   22.460005] wlan0: associate with 28:be:9b:d2:09:41 (try 1/3)
[   22.463577] wlan0: RX AssocResp from 28:be:9b:d2:09:41 (capab=0x411 status=0 aid=1)
[   22.483856] wlan0: associated
[   22.484511] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   36.529870] warning: `VirtualBox' uses 32-bit capabilities (legacy support in use)
[   37.707804] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[   39.218381] audit_printk_skb: 39 callbacks suppressed
[   39.218385] type=1400 audit(1373910221.056:29): apparmor="DENIED" operation="capable" parent=1 profile="/usr/sbin/cupsd" pid=1263 comm="cupsd" pid=1263 comm="cupsd" capability=36  capname="block_suspend"
[  327.259267] FS-Cache: Netfs 'cifs' registered for caching
[  327.259528] Key type cifs.spnego registered
[  327.259546] Key type cifs.idmap registered
[  327.260059] CIFS: no cache= option specified, using "cache=loose". This default will change to "cache=strict" in 3.7.
[  327.284949] CIFS VFS: default security mechanism requested.  The default security mechanism will be upgraded from ntlm to ntlmv2 in kernel release 3.3
[  793.628257] type=1400 audit(1373910976.651:30): apparmor="DENIED" operation="capable" parent=1 profile="/usr/sbin/cupsd" pid=1263 comm="cupsd" pid=1263 comm="cupsd" capability=36  capname="block_suspend"
[ 2352.650524] type=1400 audit(1373912537.571:31): apparmor="DENIED" operation="capable" parent=1 profile="/usr/sbin/cupsd" pid=1263 comm="cupsd" pid=1263 comm="cupsd" capability=36  capname="block_suspend"
[ 3080.484422] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3082.489695] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3084.494968] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3086.500244] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3088.513509] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3090.518793] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3092.528064] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3094.537345] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3096.542616] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3098.547899] iwlwifi 0000:01:00.0: fail to flush all tx fifo queues
[ 3132.172458] type=1400 audit(1373913318.095:32): apparmor="DENIED" operation="capable" parent=1 profile="/usr/sbin/cupsd" pid=1263 comm="cupsd" pid=1263 comm="cupsd" capability=36  capname="block_suspend"
[ 3318.351501] cfg80211: All devices are disconnected, going to restore regulatory settings
[ 3318.351508] cfg80211: Restoring regulatory settings
[ 3318.351512] cfg80211: Calling CRDA to update world regulatory domain
[ 3318.353999] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
[ 3318.354002] cfg80211: World regulatory domain updated:
[ 3318.354003] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 3318.354004] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3318.354005] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 3318.354006] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 3318.354007] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3318.354008] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 3321.711414] wlan0: authenticate with 28:be:9b:d2:09:41
[ 3321.714965] wlan0: send auth to 28:be:9b:d2:09:41 (try 1/3)
[ 3321.717350] wlan0: authenticated
[ 3321.718696] wlan0: associate with 28:be:9b:d2:09:41 (try 1/3)
[ 3321.722197] wlan0: RX AssocResp from 28:be:9b:d2:09:41 (capab=0x411 status=0 aid=1)
[ 3321.724997] wlan0: associated
[16811.354181] wlan0: deauthenticating from 28:be:9b:d2:09:41 by local choice (reason=3)
[16811.367622] cfg80211: All devices are disconnected, going to restore regulatory settings
[16811.367626] cfg80211: Restoring regulatory settings
[16811.367648] cfg80211: Calling CRDA to update world regulatory domain
[16811.376972] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
[16811.376976] cfg80211: World regulatory domain updated:
[16811.376977] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[16811.376979] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16811.376981] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[16811.376983] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[16811.376985] cfg80211:   (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16811.376986] cfg80211:   (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[16813.331363] init: anacron main process (10457) killed by TERM signal
[16813.557890] PM: Syncing filesystems ... done.
[16813.560453] PM: Preparing system for mem sleep
[16813.693311] Freezing user space processes ... (elapsed 0.01 seconds) done.
[16813.709417] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
[16813.725393] PM: Entering mem sleep
[16813.725435] Suspending console(s) (use no_console_suspend to debug)
[16813.725615] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[16813.729903] sd 0:0:0:0: [sda] Stopping disk
[16813.935985] PM: suspend of drv:psmouse dev:serio1 complete after 210.523 msecs
[16814.056831] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.618 msecs
[16814.357663] PM: suspend of drv:sd dev:0:0:0:0 complete after 632.865 msecs
[16814.357704] PM: suspend of drv:scsi dev:target0:0:0 complete after 632.916 msecs
[16814.357744] PM: suspend of drv:scsi dev:host0 complete after 632.944 msecs
[16814.364493] PM: suspend of drv: dev:ata1 complete after 639.587 msecs
[16814.380407] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 443.629 msecs
[16814.380461] PM: suspend of drv: dev:pci0000:00 complete after 443.473 msecs
[16814.380500] PM: suspend of devices complete after 655.836 msecs
[16814.380501] PM: suspend devices took 0.656 seconds
[16814.380633] PM: late suspend of devices complete after 0.128 msecs
[16814.396522] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
[16814.412451] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
[16814.444378] xhci_hcd 0000:00:14.0: wake-up capability enabled by ACPI
[16814.460368] PM: noirq suspend of devices complete after 79.835 msecs
[16814.460597] ACPI: Preparing to enter system sleep state S3
[16814.472665] PM: Saving platform NVS memory
[16814.474024] Disabling non-boot CPUs ...
[16814.576131] CPU 1 is now offline
[16814.679998] CPU 2 is now offline
[16814.680609] Broke affinity for irq 1
[16814.680633] Broke affinity for irq 12
[16814.783851] CPU 3 is now offline
[16814.784241] Extended CMOS year: 2000
[16814.785648] ACPI: Low-level resume complete
[16814.785686] PM: Restoring platform NVS memory
[16814.786002] CPU0: Thermal monitoring handled by SMI
[16814.786058] Extended CMOS year: 2000
[16814.786103] Enabling non-boot CPUs ...
[16814.786192] Booting Node 0 Processor 1 APIC 0x1
[16814.797803] CPU1: Thermal monitoring handled by SMI
[16814.800517] CPU1 is up
[16814.800596] Booting Node 0 Processor 2 APIC 0x2
[16814.812082] CPU2: Thermal monitoring handled by SMI
[16814.814666] CPU2 is up
[16814.814732] Booting Node 0 Processor 3 APIC 0x3
[16814.826223] CPU3: Thermal monitoring handled by SMI
[16814.828809] CPU3 is up
[16814.833742] ACPI: Waking up from system sleep state S3
[16814.873769] xhci_hcd 0000:00:14.0: wake-up capability disabled by ACPI
[16814.873878] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
[16814.874068] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
[16814.874837] PM: noirq resume of devices complete after 1.198 msecs
[16814.874977] PM: early resume of devices complete after 0.110 msecs
[16814.875004] i915 0000:00:02.0: setting latency timer to 64
[16814.875045] xhci_hcd 0000:00:14.0: setting latency timer to 64
[16814.875099] usb usb3: root hub lost power or was reset
[16814.875100] usb usb4: root hub lost power or was reset
[16814.875110] mei 0000:00:16.0: irq 43 for MSI/MSI-X
[16814.875267] xhci_hcd 0000:00:14.0: irq 40 for MSI/MSI-X
[16814.875304] ehci_hcd 0000:00:1a.0: setting latency timer to 64
[16814.875320] usb usb1: root hub lost power or was reset
[16814.875376] ehci_hcd 0000:00:1d.0: setting latency timer to 64
[16814.875379] snd_hda_intel 0000:00:1b.0: irq 45 for MSI/MSI-X
[16814.875404] usb usb2: root hub lost power or was reset
[16814.875476] ahci 0000:00:1f.2: setting latency timer to 64
[16814.879207] ehci_hcd 0000:00:1a.0: cache line size of 64 is not supported
[16814.879299] ehci_hcd 0000:00:1d.0: cache line size of 64 is not supported
[16814.917781] [drm] Enabling RC6 states: RC6 on, RC6p on, RC6pp off
[16814.997444] PM: resume of drv: dev:ep_00 complete after 119.953 msecs
[16814.997460] PM: resume of drv:hub dev:4-0:1.0 complete after 119.997 msecs
[16814.997471] PM: resume of drv: dev:ep_00 complete after 120.046 msecs
[16814.997474] PM: resume of drv:hub dev:3-0:1.0 complete after 120.077 msecs
[16814.997476] PM: resume of drv: dev:ep_81 complete after 120.003 msecs
[16814.997482] PM: resume of drv: dev:ep_81 complete after 120.071 msecs
[16815.084809] dpm_run_callback(): pnp_bus_resume+0x0/0x70 returns -19
[16815.084811] PM: Device 00:07 failed to resume: error -19
[16815.113305] PM: resume of drv:hub dev:2-0:1.0 complete after 236.108 msecs
[16815.113309] PM: resume of drv:hub dev:1-0:1.0 complete after 236.118 msecs
[16815.113315] PM: resume of drv: dev:ep_00 complete after 236.116 msecs
[16815.113316] PM: resume of drv: dev:ep_00 complete after 236.127 msecs
[16815.113320] PM: resume of drv:usb dev:2-1 complete after 235.926 msecs
[16815.113321] PM: resume of drv:usb dev:1-1 complete after 235.973 msecs
[16815.113343] PM: resume of drv: dev:ep_81 complete after 236.156 msecs
[16815.113349] PM: resume of drv: dev:ep_81 complete after 236.153 msecs
[16815.225136] usb 1-1: reset high-speed USB device number 2 using ehci_hcd
[16815.253085] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[16815.254075] ata1.00: configured for UDMA/133
[16815.269119] PM: resume of drv:scsi dev:host0 complete after 391.612 msecs
[16815.269137] PM: resume of drv:scsi_host dev:host0 complete after 391.620 msecs
[16815.269169] PM: resume of drv:scsi dev:target0:0:0 complete after 391.604 msecs
[16815.269179] PM: resume of drv:sd dev:0:0:0:0 complete after 391.602 msecs
[16815.269187] sd 0:0:0:0: [sda] Starting disk
[16815.303470] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 425.921 msecs
[16815.389736] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input12
[16815.468811] usb 2-1: reset high-speed USB device number 2 using ehci_hcd
[16815.564719] PM: resume of drv: dev:ep_00 complete after 687.924 msecs
[16815.564722] PM: resume of drv:usb dev:1-1.5 complete after 687.872 msecs
[16815.564725] PM: resume of drv:hub dev:1-1:1.0 complete after 687.955 msecs
[16815.564738] PM: resume of drv: dev:ep_81 complete after 687.956 msecs
[16815.672675] usb 1-1.5: reset high-speed USB device number 3 using ehci_hcd
[16815.808375] PM: resume of drv:hub dev:2-1:1.0 complete after 931.875 msecs
[16815.808380] PM: resume of drv:usb dev:2-1.5 complete after 931.786 msecs
[16815.808385] PM: resume of drv: dev:ep_81 complete after 931.879 msecs
[16815.808443] PM: resume of drv: dev:ep_00 complete after 931.924 msecs
[16815.856911] PM: resume of drv:uvcvideo dev:1-1.5:1.1 complete after 980.403 msecs
[16815.856915] PM: resume of drv: dev:ep_00 complete after 980.396 msecs
[16815.856918] PM: resume of drv:uvcvideo dev:1-1.5:1.0 complete after 980.437 msecs
[16815.856926] PM: resume of drv: dev:ep_83 complete after 980.434 msecs
[16815.880417] usb 2-1.5: reset full-speed USB device number 3 using ehci_hcd
[16815.887384] mei 0000:00:16.0: wd: failed to find the client
[16815.975344] btusb 2-1.5:1.0: no reset_resume for driver btusb?
[16815.975346] btusb 2-1.5:1.1: no reset_resume for driver btusb?
[16815.975517] PM: resume of drv:usb dev:2-1.5:1.1 complete after 1099.083 msecs
[16815.975531] PM: resume of drv: dev:ep_00 complete after 1099.061 msecs
[16815.975540] PM: resume of drv:usb dev:2-1.5:1.0 complete after 1099.155 msecs
[16815.975543] PM: resume of drv: dev:ep_83 complete after 1099.085 msecs
[16815.975550] PM: resume of drv: dev:ep_81 complete after 1099.153 msecs
[16815.975552] PM: resume of drv: dev:ep_02 complete after 1099.143 msecs
[16815.975554] PM: resume of drv: dev:ep_03 complete after 1099.109 msecs
[16815.975556] PM: resume of drv: dev:ep_82 complete after 1099.136 msecs
[16816.779369] PM: resume of devices complete after 1906.883 msecs
[16816.779582] PM: resume devices took 1.908 seconds
[16816.779673] PM: Finishing wakeup.
[16816.779674] Restarting tasks ... done.
[16816.801671] video LNXVIDEO:00: Restoring backlight state
[16817.018692] usb 3-2: new high-speed USB device number 2 using xhci_hcd
[16817.042599] usb 3-2: New USB device found, idVendor=05e3, idProduct=0612
[16817.042602] usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[16817.042605] usb 3-2: Product: USB2.0 Hub
[16817.042607] usb 3-2: Manufacturer: GenesysLogic
[16817.043596] hub 3-2:1.0: USB hub found
[16817.044648] hub 3-2:1.0: 4 ports detected
[16817.290794] usb 4-2: new SuperSpeed USB device number 2 using xhci_hcd
[16817.311480] usb 4-2: New USB device found, idVendor=05e3, idProduct=0612
[16817.311494] usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[16817.311503] usb 4-2: Product: USB3.0 Hub
[16817.311511] usb 4-2: Manufacturer: GenesysLogic
[16817.320032] hub 4-2:1.0: USB hub found
[16817.320518] hub 4-2:1.0: 4 ports detected
[16817.498572] usb 3-2.1: new low-speed USB device number 3 using xhci_hcd
[16817.526615] usb 3-2.1: New USB device found, idVendor=046a, idProduct=0023
[16817.526620] usb 3-2.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[16817.526722] usb 3-2.1: ep 0x81 - rounding interval to 64 microframes, ep desc says 80 microframes
[16817.526726] usb 3-2.1: ep 0x82 - rounding interval to 64 microframes, ep desc says 80 microframes
[16817.602332] usb 3-2.2: new high-speed USB device number 4 using xhci_hcd
[16817.618041] usb 3-2.2: New USB device found, idVendor=0424, idProduct=2514
[16817.618046] usb 3-2.2: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[16817.618288] hub 3-2.2:1.0: USB hub found
[16817.618347] hub 3-2.2:1.0: 4 ports detected
[16817.890059] usb 3-2.2.2: new full-speed USB device number 5 using xhci_hcd
[16817.911788] usb 3-2.2.2: New USB device found, idVendor=046d, idProduct=0a0c
[16817.911792] usb 3-2.2.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[16817.911795] usb 3-2.2.2: Product: Logitech USB Headset
[16817.911797] usb 3-2.2.2: Manufacturer: Logitech
[16817.997433] usb 3-2.2.3: new full-speed USB device number 6 using xhci_hcd
[16818.016353] usb 3-2.2.3: New USB device found, idVendor=046d, idProduct=c52b
[16818.016357] usb 3-2.2.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[16818.016359] usb 3-2.2.3: Product: USB Receiver
[16818.016362] usb 3-2.2.3: Manufacturer: Logitech
[16818.171558] usbcore: registered new interface driver snd-usb-audio
[16818.177422] usbcore: registered new interface driver usbhid
[16818.177426] usbhid: USB HID core driver
[16818.456675] input: HID 046a:0023 as /devices/pci0000:00/0000:00:14.0/usb3/3-2/3-2.1/3-2.1:1.0/input/input13
[16818.456763] cherry 0003:046A:0023.0001: input,hidraw0: USB HID v1.11 Keyboard [HID 046a:0023] on usb-0000:00:14.0-2.1/input0
[16818.460511] input: HID 046a:0023 as /devices/pci0000:00/0000:00:14.0/usb3/3-2/3-2.1/3-2.1:1.1/input/input14
[16818.461760] cherry 0003:046A:0023.0002: input,hidraw1: USB HID v1.11 Device [HID 046a:0023] on usb-0000:00:14.0-2.1/input1
[16818.461865] input: Logitech Logitech USB Headset as /devices/pci0000:00/0000:00:14.0/usb3/3-2/3-2.2/3-2.2.2/3-2.2.2:1.3/input/input15
[16818.462138] logitech-djreceiver 0003:046D:C52B.0006: hiddev0,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:14.0-2.2.3/input2
[16818.463571] hid-generic 0003:046D:0A0C.0003: input,hidraw3: USB HID v1.00 Device [Logitech Logitech USB Headset] on usb-0000:00:14.0-2.2.2/input3
[16818.466288] input: Logitech Unifying Device. Wireless PID:1024 as /devices/pci0000:00/0000:00:14.0/usb3/3-2/3-2.2/3-2.2.3/3-2.2.3:1.2/0003:046D:C52B.0006/input/input16
[16818.466703] logitech-djdevice 0003:046D:C52B.0007: input,hidraw4: USB HID v1.11 Mouse [Logitech Unifying Device. Wireless PID:1024] on usb-0000:00:14.0-2.2.3:1
[16818.466960] input: Logitech Unifying Device. Wireless PID:2011 as /devices/pci0000:00/0000:00:14.0/usb3/3-2/3-2.2/3-2.2.3/3-2.2.3:1.2/0003:046D:C52B.0006/input/input17
[16818.467044] logitech-djdevice 0003:046D:C52B.0008: input,hidraw5: USB HID v1.11 Keyboard [Logitech Unifying Device. Wireless PID:2011] on usb-0000:00:14.0-2.2.3:2
[16818.789851] iwlwifi 0000:01:00.0: L1 Enabled; Disabling L0S
[16818.796563] iwlwifi 0000:01:00.0: Radio type=0x2-0x1-0x0
[16818.898939] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[16826.532304] wlan0: authenticate with c0:3f:0e:e1:a0:08
[16826.538091] wlan0: send auth to c0:3f:0e:e1:a0:08 (try 1/3)
[16826.539937] wlan0: authenticated
[16826.542217] wlan0: associate with c0:3f:0e:e1:a0:08 (try 1/3)
[16826.545936] wlan0: RX AssocResp from c0:3f:0e:e1:a0:08 (capab=0x411 status=0 aid=1)
[16826.549407] wlan0: associated
[16826.550493] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[16830.701939] delay: estimated 89, actual 0
[30246.055119] vboxdrv: Found 4 processor cores.
[30246.055238] vboxdrv: fAsync=0 offMin=0x138 offMax=0xc22
[30246.055290] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'.
[30246.055291] vboxdrv: Successfully loaded version 4.2.16 (interface 0x001a0005).
[30246.273335] vboxpci: IOMMU not found (not registered)
[30260.187129] EXT4-fs (dm-0): Unaligned AIO/DIO on inode 10879783 by AioMgr0-N; performance will be poor.
[30318.268133] delay: estimated 90, actual 1
[33079.664092] delay: estimated 90, actual 1
[33385.290500] delay: estimated 90, actual 1
[33515.113636] delay: estimated 90, actual 1
[33570.026222] delay: estimated 90, actual 1
[33674.410412] delay: estimated 89, actual 0
[33680.043092] delay: estimated 89, actual 0
[33691.859728] delay: estimated 90, actual 1
[33719.815393] delay: estimated 90, actual 1
[33761.792845] delay: estimated 89, actual 0
[34090.788784] delay: estimated 90, actual 1
[34241.832303] delay: estimated 90, actual 1
[34351.673438] delay: estimated 90, actual 1
[34463.847526] delay: estimated 89, actual 0
[34468.841028] delay: estimated 89, actual 0
[34506.304275] delay: estimated 90, actual 1
[34679.351193] delay: estimated 89, actual 0
[34685.567089] delay: estimated 90, actual 1
[34762.483047] delay: estimated 90, actual 1
[34822.381137] delay: estimated 90, actual 1
[34837.745138] delay: estimated 89, actual 0
[34896.293012] delay: estimated 90, actual 1
[34962.391011] delay: estimated 90, actual 1
[35014.227592] delay: estimated 89, actual 0
[35273.786023] delay: estimated 90, actual 1
[35326.820965] delay: estimated 89, actual 0
[35341.801450] delay: estimated 89, actual 0
[35525.282779] delay: estimated 90, actual 1
[35572.557283] delay: estimated 89, actual 0
[35926.968267] delay: estimated 90, actual 1
[36040.812186] delay: estimated 90, actual 1
[36142.655763] delay: estimated 90, actual 1
[36156.629543] delay: estimated 89, actual 0
[36182.603755] delay: estimated 89, actual 0
[36391.356193] delay: estimated 90, actual 1
[36400.544222] delay: estimated 90, actual 1
[36403.540324] delay: estimated 90, actual 1
[37705.662537] delay: estimated 89, actual 0
[37744.851533] delay: estimated 90, actual 1
[37771.401016] delay: estimated 89, actual 0
[37782.458617] delay: estimated 90, actual 1
[37782.530556] delay: estimated 90, actual 1
[37883.367360] delay: estimated 89, actual 0
[37947.300182] delay: estimated 89, actual 0
[38511.598167] delay: estimated 90, actual 1
[38548.821729] delay: estimated 89, actual 0
[38550.283830] delay: estimated 90, actual 1
[38610.429603] delay: estimated 89, actual 0
[38761.009699] delay: estimated 90, actual 1
[38779.218011] delay: estimated 89, actual 0
[38781.199450] delay: estimated 90, actual 1
[38845.212191] delay: estimated 90, actual 1
[38889.051153] delay: estimated 89, actual 0
[38890.073817] delay: estimated 90, actual 1
[38908.034476] delay: estimated 89, actual 0
[38986.332618] delay: estimated 90, actual 1
[39172.386572] delay: estimated 90, actual 1
[39310.311180] delay: estimated 90, actual 1
[39360.437984] delay: estimated 89, actual 0
[39463.312234] delay: estimated 90, actual 1
[39771.902727] delay: estimated 90, actual 1
[39777.903006] delay: estimated 90, actual 1
[39851.646982] delay: estimated 89, actual 0
[39985.616726] delay: estimated 89, actual 0
[39987.638114] delay: estimated 90, actual 1
[40215.317935] delay: estimated 90, actual 1
[40767.630481] delay: estimated 133, actual 44
[41099.159218] delay: estimated 133, actual 44
[41113.987930] delay: estimated 133, actual 44
[41168.069576] delay: estimated 134, actual 45
[41497.664851] delay: estimated 133, actual 44
[41864.156133] delay: estimated 133, actual 44
[42028.622151] delay: estimated 133, actual 44
[42066.900381] delay: estimated 133, actual 44
[42126.814440] delay: estimated 134, actual 45
[42164.773056] delay: estimated 134, actual 45
[42331.140629] delay: estimated 133, actual 44
[42332.554808] delay: estimated 134, actual 45
[42355.540909] delay: estimated 134, actual 45
[42449.394830] delay: estimated 134, actual 45
[42549.264916] delay: estimated 133, actual 44
[42580.256593] delay: estimated 134, actual 45
[42662.533545] delay: estimated 134, actual 45
[42701.099412] delay: estimated 133, actual 44
[42738.003382] delay: estimated 134, actual 45
[42743.028858] delay: estimated 133, actual 44
[42760.749784] delay: estimated 134, actual 45
[42818.570592] delay: estimated 134, actual 45
[42906.799854] delay: estimated 134, actual 45
[42923.769820] delay: estimated 134, actual 45
[42929.809882] delay: estimated 134, actual 45
[42935.770176] delay: estimated 133, actual 44
[42989.843774] delay: estimated 133, actual 44
[42999.974598] delay: estimated 134, actual 45
[43004.672496] delay: estimated 133, actual 44
[43172.446264] delay: estimated 133, actual 44
[43594.920683] delay: estimated 133, actual 44
[43729.721341] delay: estimated 134, actual 45
[43890.272491] delay: estimated 134, actual 45
[44202.554266] delay: estimated 133, actual 44
[44451.782089] delay: estimated 133, actual 44
[44551.412470] delay: estimated 134, actual 45
[44560.656488] delay: estimated 133, actual 44
[44618.836784] delay: estimated 133, actual 44
[44641.535243] delay: estimated 133, actual 44
[44668.540108] delay: estimated 133, actual 44
[44830.297701] delay: estimated 133, actual 44
[45128.893293] delay: estimated 134, actual 45
[45402.537407] delay: estimated 133, actual 44
[45473.453095] delay: estimated 133, actual 44
[45570.319093] delay: estimated 133, actual 44
[45779.518974] delay: estimated 134, actual 45
[46077.675105] delay: estimated 134, actual 45
[46080.024055] delay: estimated 133, actual 44
[46080.679176] delay: estimated 134, actual 45
[46123.591372] delay: estimated 134, actual 45