[ 1.323105] pci 0000:00:1d.0: bridge window [mem 0xcc100000-0xcc1fffff] [ 1.323119] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.323120] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.323120] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.323121] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000c3fff window] [ 1.323122] pci_bus 0000:00: resource 8 [mem 0x000c4000-0x000c7fff window] [ 1.323123] pci_bus 0000:00: resource 9 [mem 0x000c8000-0x000cbfff window] [ 1.323124] pci_bus 0000:00: resource 10 [mem 0x000cc000-0x000cffff window] [ 1.323124] pci_bus 0000:00: resource 11 [mem 0x000d0000-0x000d3fff window] [ 1.323125] pci_bus 0000:00: resource 12 [mem 0x000d4000-0x000d7fff window] [ 1.323126] pci_bus 0000:00: resource 13 [mem 0x000d8000-0x000dbfff window] [ 1.323127] pci_bus 0000:00: resource 14 [mem 0x000dc000-0x000dffff window] [ 1.323127] pci_bus 0000:00: resource 15 [mem 0x7f800000-0xefffffff window] [ 1.323128] pci_bus 0000:00: resource 16 [mem 0xfc800000-0xfe7fffff window] [ 1.323129] pci_bus 0000:01: resource 0 [io 0x4000-0x4fff] [ 1.323130] pci_bus 0000:01: resource 1 [mem 0xb4000000-0xca0fffff] [ 1.323131] pci_bus 0000:01: resource 2 [mem 0x90000000-0xb1ffffff 64bit pref] [ 1.323132] pci_bus 0000:3a: resource 1 [mem 0xcc300000-0xcc3fffff] [ 1.323132] pci_bus 0000:3b: resource 1 [mem 0xcc200000-0xcc2fffff] [ 1.323133] pci_bus 0000:3c: resource 1 [mem 0xcc100000-0xcc1fffff] [ 1.323237] NET: Registered protocol family 2 [ 1.323321] tcp_listen_portaddr_hash hash table entries: 16384 (order: 6, 262144 bytes, linear) [ 1.323349] TCP established hash table entries: 262144 (order: 9, 2097152 bytes, linear) [ 1.323587] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) [ 1.323677] TCP: Hash tables configured (established 262144 bind 65536) [ 1.323692] UDP hash table entries: 16384 (order: 7, 524288 bytes, linear) [ 1.323744] UDP-Lite hash table entries: 16384 (order: 7, 524288 bytes, linear) [ 1.323808] NET: Registered protocol family 1 [ 1.323881] RPC: Registered named UNIX socket transport module. [ 1.323882] RPC: Registered udp transport module. [ 1.323882] RPC: Registered tcp transport module. [ 1.323883] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.323939] NET: Registered protocol family 44 [ 1.323946] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 1.324699] PCI: CLS 64 bytes, default 64 [ 1.324725] DMAR: No ATSR found [ 1.324757] DMAR: dmar0: Using Queued invalidation [ 1.324759] DMAR: dmar1: Using Queued invalidation [ 1.325030] pci 0000:00:00.0: Adding to iommu group 0 [ 1.325038] pci 0000:00:02.0: Adding to iommu group 1 [ 1.325044] pci 0000:00:04.0: Adding to iommu group 2 [ 1.325050] pci 0000:00:08.0: Adding to iommu group 3 [ 1.325058] pci 0000:00:12.0: Adding to iommu group 4 [ 1.325068] pci 0000:00:14.0: Adding to iommu group 5 [ 1.325075] pci 0000:00:14.2: Adding to iommu group 5 [ 1.325087] pci 0000:00:15.0: Adding to iommu group 6 [ 1.325093] pci 0000:00:15.1: Adding to iommu group 6 [ 1.325101] pci 0000:00:16.0: Adding to iommu group 7 [ 1.325106] pci 0000:00:17.0: Adding to iommu group 8 [ 1.325123] pci 0000:00:1b.0: Adding to iommu group 9 [ 1.325135] pci 0000:00:1c.0: Adding to iommu group 10 [ 1.325147] pci 0000:00:1c.4: Adding to iommu group 11 [ 1.325156] pci 0000:00:1d.0: Adding to iommu group 12 [ 1.325171] pci 0000:00:1f.0: Adding to iommu group 13 [ 1.325177] pci 0000:00:1f.3: Adding to iommu group 13 [ 1.325184] pci 0000:00:1f.4: Adding to iommu group 13 [ 1.325190] pci 0000:00:1f.5: Adding to iommu group 13 [ 1.325206] pci 0000:3a:00.0: Adding to iommu group 14 [ 1.325219] pci 0000:3b:00.0: Adding to iommu group 15 [ 1.325229] pci 0000:3c:00.0: Adding to iommu group 16 [ 1.325233] DMAR: Intel(R) Virtualization Technology for Directed I/O [ 1.333169] Initialise system trusted keyrings [ 1.333198] workingset: timestamp_bits=46 max_order=23 bucket_order=0 [ 1.333672] zbud: loaded [ 1.333862] NFS: Registering the id_resolver key type [ 1.333864] Key type id_resolver registered [ 1.333864] Key type id_legacy registered [ 1.333865] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.333921] FS-Cache: Netfs 'cifs' registered for caching [ 1.333944] Key type cifs.spnego registered [ 1.333946] Key type cifs.idmap registered [ 1.336163] NET: Registered protocol family 38 [ 1.336212] Key type asymmetric registered [ 1.336213] Asymmetric key parser 'x509' registered [ 1.336213] Asymmetric key parser 'pkcs8' registered [ 1.336214] Key type pkcs7_test registered [ 1.336217] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 1.336217] io scheduler mq-deadline registered [ 1.336432] crc32: CRC_LE_BITS = 64, CRC_BE BITS = 64 [ 1.336432] crc32: self tests passed, processed 225944 bytes in 105041 nsec [ 1.336540] crc32c: CRC_LE_BITS = 64 [ 1.336540] crc32c: self tests passed, processed 225944 bytes in 53080 nsec [ 1.344337] crc32_combine: 8373 self tests passed [ 1.352118] crc32c_combine: 8373 self tests passed [ 1.352134] xz_dec_test: module loaded [ 1.352135] xz_dec_test: Create a device node with 'mknod xz_dec_test c 246 0' and write .xz files to it. [ 1.352409] pcieport 0000:00:1b.0: PME: Signaling with IRQ 122 [ 1.352439] pcieport 0000:00:1b.0: pciehp: Slot #20 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl+ LLActRep+ [ 1.352700] pcieport 0000:00:1c.0: PME: Signaling with IRQ 123 [ 1.352928] pcieport 0000:00:1c.4: PME: Signaling with IRQ 124 [ 1.353130] pcieport 0000:00:1d.0: PME: Signaling with IRQ 125 [ 1.353234] usbcore: registered new interface driver udlfb [ 1.353235] intel_idle: MWAIT substates: 0x11142120 [ 1.353235] intel_idle: v0.4.1 model 0x9E [ 1.353461] intel_idle: lapic_timer_reliable_states 0xffffffff [ 1.355154] ACPI: AC Adapter [AC] (off-line) [ 1.355190] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0 [ 1.358936] ACPI: Lid Switch [LID0] [ 1.358955] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1 [ 1.368931] ACPI: Power Button [PBTN] [ 1.368951] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input2 [ 1.368955] ACPI: Sleep Button [SBTN] [ 1.369051] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 [ 1.369852] ACPI: Power Button [PWRF] [ 1.371967] thermal LNXTHERM:00: registered as thermal_zone0 [ 1.371967] ACPI: Thermal Zone [THM] (25 C) [ 1.372228] EINJ: EINJ table not found. [ 1.372229] ERST DBG: ERST support is disabled. [ 1.389945] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 1.391639] Non-volatile memory driver v1.3 [ 1.391677] Linux agpgart interface v0.103 [ 1.392197] i915 0000:00:02.0: [drm:i915_driver_probe] WOPCM: 1024K [ 1.392203] i915 0000:00:02.0: [drm:intel_uc_init_early] enable_guc=3 (guc:yes submission:no huc:yes) [ 1.392204] i915 0000:00:02.0: Incompatible option enable_guc=3 - GuC submission is N/A [ 1.392654] [drm:i915_ggtt_probe_hw] GGTT size = 4096M [ 1.392655] [drm:i915_ggtt_probe_hw] GMADR size = 256M [ 1.392656] [drm:i915_ggtt_probe_hw] DSM size = 64M [ 1.392657] i915 0000:00:02.0: VT-d active for gfx access [ 1.392672] [drm:i915_gem_init_stolen] GEN6_STOLEN_RESERVED = 7f700047 [ 1.392673] [drm:i915_gem_init_stolen] Memory reserved for graphics device: 65536K, usable: 64512K [ 1.392677] [drm:intel_gt_init_workarounds] Initialized 4 GT workarounds on global [ 1.392689] [drm:intel_gvt_init] GVT-g is disabled by kernel params [ 1.392691] [drm:intel_opregion_setup] graphic opregion physical addr: 0x76f2a018 [ 1.392693] [drm:intel_opregion_setup] ACPI OpRegion version 2.0.0 [ 1.392693] [drm:intel_opregion_setup] Public ACPI methods supported [ 1.392694] [drm:intel_opregion_setup] SWSCI supported [ 1.396792] [drm:intel_opregion_setup] SWSCI GBDA callbacks 00000cb3, SBCB callbacks 00300583 [ 1.396793] [drm:intel_opregion_setup] ASLE supported [ 1.396793] [drm:intel_opregion_setup] ASLE extension supported [ 1.396795] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 1.396795] [drm] Driver supports precise vblank timestamp query. [ 1.398152] [drm:i915_driver_probe] rawclk rate: 24000 kHz [ 1.398164] [drm:intel_dump_cdclk_state] Current CDCLK 675000 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 3 [ 1.398165] [drm:intel_update_max_cdclk] Max CD clock rate: 675000 kHz [ 1.398166] [drm:intel_cdclk_init] Max dotclock rate: 675000 kHz [ 1.398712] [drm] Finished loading DMC firmware i915/kbl_dmc_ver1_04.bin (v1.4) [ 1.399303] [drm:intel_dump_cdclk_state] Current CDCLK 675000 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 3 [ 1.403957] battery: ACPI: Battery Slot [BAT0] (battery present) [ 1.409963] [drm:drm_crtc_vblank_on] crtc 0, vblank enabled 0, inmodeset 1 [ 1.409968] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1102)@ 1.419313 -> 1.411047 [e 0 us, 0 rep] [ 1.414238] [drm:i915_gem_object_create_stolen_for_preallocated] creating preallocated stolen object: stolen_offset=0x0000000000000000, gtt_offset=0x0000000000000000, size=0x0000000000a8c000 [ 1.414268] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1676)@ 1.423613 -> 1.411041 [e 0 us, 0 rep] [ 1.414269] [drm:drm_vblank_restore] missed 0 vblanks in -5412 ns, frame duration=16667635 ns, hw_diff=0 [ 1.414272] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1676)@ 1.423618 -> 1.411046 [e 0 us, 0 rep] [ 1.414274] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1, diff=0, hw=304 hw_last=304 [ 1.414287] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1678)@ 1.423633 -> 1.411046 [e 0 us, 0 rep] [ 1.414288] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1, diff=0, hw=304 hw_last=304 [ 1.417903] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 1.427248 -> 1.427713 [e 0 us, 0 rep] [ 1.417904] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1, diff=1, hw=305 hw_last=304 [ 1.418381] i915 0000:00:02.0: [drm:intel_wopcm_init] Calculated GuC WOPCM [240K, 784K) [ 1.419323] [drm:intel_engine_init_workarounds] Initialized 5 engine workarounds on rcs'0 [ 1.419324] [drm:intel_engine_init_whitelist] Initialized 4 whitelist workarounds on rcs'0 [ 1.419325] [drm:intel_engine_init_ctx_wa] Initialized 14 context workarounds on rcs'0 [ 1.419337] [drm:i915_gem_contexts_init] logical context support initialized [ 1.419396] [drm:intel_guc_log_create] guc_log_level=1 (enabled, verbose:no, verbosity:0) [ 1.419412] [drm:intel_guc_init] param[ 0] = 0x0 [ 1.419413] [drm:intel_guc_init] param[ 1] = 0xa92fd3 [ 1.419413] [drm:intel_guc_init] param[ 2] = 0x0 [ 1.419414] [drm:intel_guc_init] param[ 3] = 0x4000 [ 1.419415] [drm:intel_guc_init] param[ 4] = 0x40 [ 1.419415] [drm:intel_guc_init] param[ 5] = 0x154a [ 1.419416] [drm:intel_guc_init] param[ 6] = 0x0 [ 1.419417] [drm:intel_guc_init] param[ 7] = 0x0 [ 1.419417] [drm:intel_guc_init] param[ 8] = 0x0 [ 1.419418] [drm:intel_guc_init] param[ 9] = 0x0 [ 1.419419] [drm:intel_guc_init] param[10] = 0x0 [ 1.419420] [drm:intel_guc_init] param[11] = 0x0 [ 1.419420] [drm:intel_guc_init] param[12] = 0x0 [ 1.419421] [drm:intel_guc_init] param[13] = 0x0 [ 1.430962] [drm:intel_guc_fw_upload] GuC status 0x8002f0ec [ 1.431049] [drm] GuC communication enabled [ 1.434710] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-43)@ 1.444055 -> 1.444377 [e 0 us, 0 rep] [ 1.434712] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=2, diff=1, hw=306 hw_last=305 [ 1.434715] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-42)@ 1.444061 -> 1.444376 [e 0 us, 0 rep] [ 1.434717] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=3, diff=0, hw=306 hw_last=306 [ 1.441824] i915 0000:00:02.0: GuC firmware i915/kbl_guc_33.0.0.bin version 33.0 submission:disabled [ 1.441825] i915 0000:00:02.0: HuC firmware i915/kbl_huc_ver02_00_1810.bin version 2.0 authenticated:yes [ 1.442395] [drm:intel_engines_driver_register] renamed rcs'0 to rcs0 [ 1.442396] [drm:intel_engines_driver_register] renamed bcs'0 to bcs0 [ 1.442397] [drm:intel_engines_driver_register] renamed vcs'0 to vcs0 [ 1.442398] [drm:intel_engines_driver_register] renamed vecs'0 to vecs0 [ 1.443118] [drm] Initialized i915 1.6.0 20190822 for 0000:00:02.0 on minor 0 [ 1.445283] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 1.445801] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input4 [ 1.445829] i915 device info: pciid=0x3e9b rev=0x00 platform=COFFEELAKE (subplatform=0x0) gen=9 [ 1.445830] i915 device info: is_mobile: no [ 1.445830] i915 device info: is_lp: no [ 1.445830] i915 device info: require_force_probe: no [ 1.445831] i915 device info: has_64bit_reloc: yes [ 1.445831] i915 device info: gpu_reset_clobbers_display: no [ 1.445831] i915 device info: has_reset_engine: yes [ 1.445832] i915 device info: has_fpga_dbg: yes [ 1.445832] i915 device info: has_global_mocs: no [ 1.445832] i915 device info: has_gt_uc: yes [ 1.445833] i915 device info: has_l3_dpf: no [ 1.445833] i915 device info: has_llc: yes [ 1.445833] i915 device info: has_logical_ring_contexts: yes [ 1.445834] i915 device info: has_logical_ring_elsq: no [ 1.445834] i915 device info: has_logical_ring_preemption: yes [ 1.445834] i915 device info: has_pooled_eu: no [ 1.445835] i915 device info: has_rc6: yes [ 1.445835] i915 device info: has_rc6p: no [ 1.445835] i915 device info: has_rps: yes [ 1.445836] i915 device info: has_runtime_pm: yes [ 1.445836] i915 device info: has_snoop: no [ 1.445836] i915 device info: has_coherent_ggtt: yes [ 1.445837] i915 device info: unfenced_needs_alignment: no [ 1.445837] i915 device info: hws_needs_physical: no [ 1.445837] i915 device info: cursor_needs_physical: no [ 1.445838] i915 device info: has_csr: yes [ 1.445838] i915 device info: has_ddi: yes [ 1.445838] i915 device info: has_dp_mst: yes [ 1.445838] i915 device info: has_fbc: yes [ 1.445839] i915 device info: has_gmch: no [ 1.445839] i915 device info: has_hotplug: yes [ 1.445839] i915 device info: has_ipc: yes [ 1.445840] i915 device info: has_modular_fia: no [ 1.445840] i915 device info: has_overlay: no [ 1.445840] i915 device info: has_psr: yes [ 1.445841] i915 device info: overlay_needs_physical: no [ 1.445841] i915 device info: supports_tv: no [ 1.445841] i915 device info: slice total: 1, mask=0001 [ 1.445842] i915 device info: subslice total: 3 [ 1.445842] i915 device info: slice0: 3 subslices, mask=00000007 [ 1.445843] i915 device info: slice1: 0 subslices, mask=00000000 [ 1.445843] i915 device info: slice2: 0 subslices, mask=00000000 [ 1.445844] i915 device info: EU total: 23 [ 1.445844] i915 device info: EU per subslice: 8 [ 1.445844] i915 device info: has slice power gating: no [ 1.445844] i915 device info: has subslice power gating: no [ 1.445845] i915 device info: has EU power gating: yes [ 1.445845] i915 device info: CS timestamp frequency: 12000 kHz [ 1.445939] [drm] Initialized vgem 1.0.0 20120112 for vgem on minor 1 [ 1.445944] usbcore: registered new interface driver udl [ 1.446657] loop: module loaded [ 1.446698] zram: Added device: zram0 [ 1.446764] mic_init not running on X100 ret -19 [ 1.447015] intel-lpss 0000:00:15.0: enabling device (0000 -> 0002) [ 1.447201] idma64 idma64.0: Found Intel integrated DMA 64-bit [ 1.453801] intel-lpss 0000:00:15.1: enabling device (0000 -> 0002) [ 1.454014] idma64 idma64.1: Found Intel integrated DMA 64-bit [ 1.455375] fbcon: i915drmfb (fb0) is primary device [ 1.455776] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,543)@ 1.465119 -> 1.461046 [e 0 us, 0 rep] [ 1.455777] [drm:drm_vblank_restore] missed 1 vblanks in 16668714 ns, frame duration=16667635 ns, hw_diff=1 [ 1.455781] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,544)@ 1.465127 -> 1.461046 [e 1 us, 0 rep] [ 1.455783] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=4, diff=0, hw=307 hw_last=307 [ 1.455794] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,546)@ 1.465140 -> 1.461044 [e 1 us, 0 rep] [ 1.455795] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=4, diff=0, hw=307 hw_last=307 [ 1.461605] nvme nvme0: pci function 0000:3c:00.0 [ 1.461762] ahci 0000:00:17.0: version 3.0 [ 1.461958] ahci 0000:00:17.0: AHCI 0001.0301 32 slots 1 ports 6 Gbps 0x4 impl SATA mode [ 1.461959] ahci 0000:00:17.0: flags: 64bit ncq sntf pm clo only pio slum part ems deso sadm sds apst [ 1.462272] scsi host0: ahci [ 1.462433] scsi host1: ahci [ 1.462530] scsi host2: ahci [ 1.462555] ata1: DUMMY [ 1.462555] ata2: DUMMY [ 1.462562] ata3: SATA max UDMA/133 abar m2048@0xcc421000 port 0xcc421200 irq 128 [ 1.462726] xhci_hcd 0000:00:14.0: xHCI Host Controller [ 1.462745] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 1 [ 1.463827] xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x110 quirks 0x0000000000009810 [ 1.463834] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported [ 1.463961] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.03 [ 1.463962] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.463962] usb usb1: Product: xHCI Host Controller [ 1.463963] usb usb1: Manufacturer: Linux 5.3.0-rc8+ xhci-hcd [ 1.463963] usb usb1: SerialNumber: 0000:00:14.0 [ 1.464008] hub 1-0:1.0: USB hub found [ 1.464022] hub 1-0:1.0: 16 ports detected [ 1.464707] xhci_hcd 0000:00:14.0: xHCI Host Controller [ 1.464722] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2 [ 1.464723] xhci_hcd 0000:00:14.0: Host supports USB 3.0 SuperSpeed [ 1.464745] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.03 [ 1.464746] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.464746] usb usb2: Product: xHCI Host Controller [ 1.464747] usb usb2: Manufacturer: Linux 5.3.0-rc8+ xhci-hcd [ 1.464747] usb usb2: SerialNumber: 0000:00:14.0 [ 1.464785] hub 2-0:1.0: USB hub found [ 1.464805] hub 2-0:1.0: 10 ports detected [ 1.464939] usb: port power management may be unreliable [ 1.465388] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12 [ 1.465811] i8042: Warning: Keylock active [ 1.467731] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 1.467734] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 1.467818] rtc_cmos 00:01: RTC can wake from S4 [ 1.467975] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 1.477319 -> 1.477717 [e 8 us, 0 rep] [ 1.467978] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=4, diff=1, hw=308 hw_last=307 [ 1.468432] Console: switching to colour frame buffer device 240x67 [ 1.468761] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,52)@ 1.478105 -> 1.477715 [e 4 us, 0 rep] [ 1.468768] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=5, diff=0, hw=308 hw_last=308 [ 1.469077] rtc_cmos 00:01: registered as rtc0 [ 1.469086] rtc_cmos 00:01: alarms up to one month, y3k, 242 bytes nvram [ 1.469099] i2c /dev entries driver [ 1.470915] i801_smbus 0000:00:1f.4: SPD Write Disable is set [ 1.471004] i801_smbus 0000:00:1f.4: SMBus using PCI interrupt [ 1.484612] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 1.493956 -> 1.494384 [e 1 us, 0 rep] [ 1.484615] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=5, diff=1, hw=309 hw_last=308 [ 1.493787] random: fast init done [ 1.495017] i915 0000:00:02.0: fb0: i915drmfb frame buffer device [ 1.499994] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1994)@ 1.509338 -> 1.494381 [e 0 us, 0 rep] [ 1.499996] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=6, diff=0, hw=309 hw_last=309 [ 1.501242] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 1.510587 -> 1.511052 [e 0 us, 0 rep] [ 1.501244] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=6, diff=1, hw=310 hw_last=309 [ 1.506213] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,601)@ 1.515558 -> 1.511050 [e 0 us, 0 rep] [ 1.506215] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=7, diff=0, hw=310 hw_last=310 [ 1.517957] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 1.527302 -> 1.527714 [e 3 us, 0 rep] [ 1.517962] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=7, diff=1, hw=311 hw_last=310 [ 1.534610] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 1.543955 -> 1.544382 [e 0 us, 0 rep] [ 1.534612] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=8, diff=1, hw=312 hw_last=311 [ 1.534615] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 1.543962 -> 1.544389 [e 0 us, 0 rep] [ 1.534617] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=9, diff=0, hw=312 hw_last=312 [ 1.543050] i801_smbus 0000:00:1f.4: Accelerometer lis3lv02d is present on SMBus but its address is unknown, skipping registration [ 1.634027] proc_thermal 0000:00:04.0: enabling device (0000 -> 0002) [ 1.653563] intel_rapl_common: Found RAPL domain package [ 1.653563] intel_rapl_common: Found RAPL domain dram [ 1.654197] proc_thermal 0000:00:04.0: Creating sysfs group for PROC_THERMAL_PCI [ 1.654391] intel_pstate: Intel P-state driver initializing [ 1.654403] intel_pstate: Disabling energy efficiency optimization [ 1.655004] intel_pstate: HWP enabled [ 1.655021] sdhci: Secure Digital Host Controller Interface driver [ 1.655021] sdhci: Copyright(c) Pierre Ossman [ 1.655451] hidraw: raw HID events driver (C) Jiri Kosina [ 1.655459] usbcore: registered new interface driver usbhid [ 1.655459] usbhid: USB HID core driver [ 1.655491] ashmem: initialized [ 1.658734] dell_wmi_aio: No known WMI GUID found [ 1.658879] input: Intel HID events as /devices/platform/INT33D5:00/input/input5 [ 1.659436] intel-hid INT33D5:00: platform supports 5 button array [ 1.659451] input: Intel HID 5 button array as /devices/platform/INT33D5:00/input/input6 [ 1.663325] intel_telemetry_core Init [ 1.663351] intel_pmc_core INT33A1:00: initialized [ 1.663391] intel_rapl_common: Found RAPL domain package [ 1.663391] intel_rapl_common: Found RAPL domain core [ 1.663392] intel_rapl_common: Found RAPL domain uncore [ 1.663392] intel_rapl_common: Found RAPL domain dram [ 1.664438] snd_hda_intel 0000:00:1f.3: enabling device (0000 -> 0002) [ 1.664514] snd_hda_intel 0000:00:1f.3: bound 0000:00:02.0 (ops 0xffffffff822ddbe0) [ 1.664638] NET: Registered protocol family 26 [ 1.664817] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 1.664983] Initializing XFRM netlink socket [ 1.665037] NET: Registered protocol family 10 [ 1.665176] Segment Routing with IPv6 [ 1.666033] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 1.666103] NET: Registered protocol family 17 [ 1.666105] NET: Registered protocol family 15 [ 1.666127] Bluetooth: RFCOMM TTY layer initialized [ 1.666129] Bluetooth: RFCOMM socket layer initialized [ 1.666131] Bluetooth: RFCOMM ver 1.11 [ 1.666131] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 1.666132] Bluetooth: BNEP filters: protocol multicast [ 1.666132] Bluetooth: BNEP socket layer initialized [ 1.666133] Bluetooth: HIDP (Human Interface Emulation) ver 1.2 [ 1.666133] Bluetooth: HIDP socket layer initialized [ 1.666179] NET: Registered protocol family 33 [ 1.666183] Key type rxrpc registered [ 1.666184] Key type rxrpc_s registered [ 1.666188] 8021q: 802.1Q VLAN Support v1.8 [ 1.666201] sctp: Hash tables configured (bind 512/512) [ 1.666474] NET: Registered protocol family 21 [ 1.666500] Registered RDS/tcp transport [ 1.666517] Key type dns_resolver registered [ 1.666648] NET: Registered protocol family 40 [ 1.666828] microcode: sig=0x906ea, pf=0x20, revision=0xb4 [ 1.666978] microcode: Microcode Update Driver: v2.2. [ 1.666981] AVX2 version of gcm_enc/dec engaged. [ 1.666981] AES CTR mode by8 optimization enabled [ 1.670579] sched_clock: Marking stable (1679923731, -9347473)->(1681851575, -11275317) [ 1.671157] registered taskstats version 1 [ 1.671158] Loading compiled-in X.509 certificates [ 1.671177] zswap: loaded using pool lzo/zbud [ 1.686815] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC3266: line_outs=1 (0x17/0x0/0x0/0x0/0x0) type:speaker [ 1.686816] snd_hda_codec_realtek hdaudioC1D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 1.686816] snd_hda_codec_realtek hdaudioC1D0: hp_outs=1 (0x21/0x0/0x0/0x0/0x0) [ 1.686817] snd_hda_codec_realtek hdaudioC1D0: mono: mono_out=0x0 [ 1.686817] snd_hda_codec_realtek hdaudioC1D0: inputs: [ 1.686818] snd_hda_codec_realtek hdaudioC1D0: Headset Mic=0x18 [ 1.686819] snd_hda_codec_realtek hdaudioC1D0: Headphone Mic=0x1a [ 1.686819] snd_hda_codec_realtek hdaudioC1D0: Internal Mic=0x12 [ 1.713931] input: Dell WMI hotkeys as /devices/platform/PNP0C14:03/wmi_bus/wmi_bus-PNP0C14:03/9DBB5994-A997-11DA-B012-B622A1EF5492/input/input7 [ 1.713944] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 1.714899] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 1.714913] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 1.714913] cfg80211: failed to load regulatory.db [ 1.717292] nvme nvme0: 7/0/0 default/read/poll queues [ 1.725772] nvme0n1: p1 p2 p3 [ 1.754771] input: HDA Intel PCH Headphone Mic as /devices/pci0000:00/0000:00:1f.3/sound/card1/input8 [ 1.754783] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1f.3/sound/card1/input9 [ 1.754791] input: HDA Intel PCH HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:1f.3/sound/card1/input10 [ 1.754799] input: HDA Intel PCH HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:1f.3/sound/card1/input11 [ 1.754806] input: HDA Intel PCH HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:1f.3/sound/card1/input12 [ 1.754814] input: HDA Intel PCH HDMI/DP,pcm=10 as /devices/pci0000:00/0000:00:1f.3/sound/card1/input13 [ 1.802126] ata3: SATA link down (SStatus 4 SControl 300) [ 1.805089] ALSA device list: [ 1.805092] #0: Dummy 1 [ 1.805093] #1: HDA Intel PCH at 0xcc418000 irq 130 [ 1.828659] EXT4-fs (nvme0n1p2): mounted filesystem with ordered data mode. Opts: (null) [ 1.828667] VFS: Mounted root (ext4 filesystem) readonly on device 259:2. [ 1.829115] devtmpfs: mounted [ 1.829200] usb 1-4: new full-speed USB device number 2 using xhci_hcd [ 1.829677] Freeing unused kernel image memory: 1060K [ 1.879301] Write protecting the kernel read-only data: 24576k [ 1.880295] Freeing unused kernel image memory: 2000K [ 1.880591] Freeing unused kernel image memory: 1804K [ 1.880592] Run /usr/lib/systemd/systemd as init process [ 1.919986] modprobe (568) used greatest stack depth: 13720 bytes left [ 2.012407] usb 1-4: New USB device found, idVendor=8087, idProduct=0025, bcdDevice= 0.02 [ 2.012408] usb 1-4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 2.077597] systemd-fstab-generator[575]: Ignoring "noauto" for root device [ 2.081610] systemd-fstab-g (575) used greatest stack depth: 13680 bytes left [ 2.087993] gentoo-local-ge (571) used greatest stack depth: 13576 bytes left [ 2.149051] udevadm (592) used greatest stack depth: 13448 bytes left [ 2.159136] EXT4-fs (nvme0n1p2): re-mounted. Opts: (null) [ 2.159771] usb 1-12: new high-speed USB device number 3 using xhci_hcd [ 2.372554] systemd-journald[589]: Received client request to flush runtime journal. [ 2.395559] usb 1-12: New USB device found, idVendor=0c45, idProduct=6713, bcdDevice=56.05 [ 2.395561] usb 1-12: New USB device strings: Mfr=2, Product=1, SerialNumber=0 [ 2.395562] usb 1-12: Product: Integrated_Webcam_HD [ 2.395564] usb 1-12: Manufacturer: CN045G28724875B4B0V2A01 [ 2.410225] tsc: Refined TSC clocksource calibration: 2304.018 MHz [ 2.410230] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x213608c06ae, max_idle_ns: 440795240117 ns [ 2.468964] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input14 [ 2.512372] random: alsactl: uninitialized urandom read (4 bytes read) [ 2.580271] random: alsactl: uninitialized urandom read (4 bytes read) [ 2.587822] input: SYNA2393:00 06CB:7A13 Mouse as /devices/pci0000:00/0000:00:15.1/i2c_designware.1/i2c-9/i2c-SYNA2393:00/0018:06CB:7A13.0001/input/input16 [ 2.587889] input: SYNA2393:00 06CB:7A13 Touchpad as /devices/pci0000:00/0000:00:15.1/i2c_designware.1/i2c-9/i2c-SYNA2393:00/0018:06CB:7A13.0001/input/input17 [ 2.587939] hid-generic 0018:06CB:7A13.0001: input,hidraw0: I2C HID v1.00 Mouse [SYNA2393:00 06CB:7A13] on i2c-SYNA2393:00 [ 2.597113] [drm:intel_panel_actually_set_backlight] set backlight PWM = 48000 [ 2.641570] usbcore: registered new interface driver btusb [ 2.645931] Bluetooth: hci0: Firmware revision 0.1 build 226 week 31 2019 [ 2.667315] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1251)@ 2.675365 -> 2.665981 [e 3 us, 0 rep] [ 2.667320] [drm:drm_vblank_restore] missed 67 vblanks in 1121598168 ns, frame duration=16667635 ns, hw_diff=62 [ 2.667329] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1253)@ 2.675382 -> 2.665983 [e 3 us, 0 rep] [ 2.667334] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=76, diff=0, hw=374 hw_last=374 [ 2.667426] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1266)@ 2.675478 -> 2.665982 [e 1 us, 0 rep] [ 2.667428] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=76, diff=0, hw=374 hw_last=374 [ 2.674150] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 2.682186 -> 2.682651 [e 1 us, 0 rep] [ 2.674153] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=76, diff=1, hw=375 hw_last=374 [ 2.678111] mc: Linux media interface: v0.10 [ 2.679794] videodev: Linux video capture interface: v2.00 [ 2.685735] uvcvideo: Found UVC 1.00 device Integrated_Webcam_HD (0c45:6713) [ 2.685932] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1511)@ 2.693983 -> 2.682649 [e 4 us, 0 rep] [ 2.685934] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=77, diff=0, hw=375 hw_last=375 [ 2.690799] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 2.698851 -> 2.699316 [e 0 us, 0 rep] [ 2.690802] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=77, diff=1, hw=376 hw_last=375 [ 2.696526] Intel(R) Wireless WiFi driver for Linux [ 2.696526] Copyright(c) 2003- 2015 Intel Corporation [ 2.701117] rtsx_pci 0000:3b:00.0: enabling device (0000 -> 0002) [ 2.701314] rtsx_pci 0000:3b:00.0: Using iommu dma mapping [ 2.701315] rtsx_pci 0000:3b:00.0: DMAR: 32bit DMA uses non-identity mapping [ 2.707504] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 2.715555 -> 2.715983 [e 0 us, 0 rep] [ 2.707506] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=78, diff=1, hw=377 hw_last=376 [ 2.707510] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 2.715564 -> 2.715984 [e 0 us, 0 rep] [ 2.707511] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=79, diff=0, hw=377 hw_last=377 [ 2.717283] setfont (732) used greatest stack depth: 13160 bytes left [ 2.719955] uvcvideo 1-12:1.0: Entity type for entity Extension 4 was not initialized! [ 2.719957] uvcvideo 1-12:1.0: Entity type for entity Extension 3 was not initialized! [ 2.719958] uvcvideo 1-12:1.0: Entity type for entity Processing 2 was not initialized! [ 2.719959] uvcvideo 1-12:1.0: Entity type for entity Camera 1 was not initialized! [ 2.719998] input: Integrated_Webcam_HD: Integrate as /devices/pci0000:00/0000:00:14.0/usb1/1-12/1-12:1.0/input/input19 [ 2.724095] iwlwifi 0000:3a:00.0: enabling device (0000 -> 0002) [ 2.725150] usbcore: registered new interface driver uvcvideo [ 2.725151] USB Video Class driver (1.1.1) [ 2.733819] iwlwifi 0000:3a:00.0: Found debug destination: EXTERNAL_DRAM [ 2.733822] iwlwifi 0000:3a:00.0: Found debug configuration: 0 [ 2.734049] iwlwifi 0000:3a:00.0: loaded firmware version 46.6bf1df06.0 op_mode iwlmvm [ 2.736723] input: SYNA2393:00 06CB:7A13 Touchpad as /devices/pci0000:00/0000:00:15.1/i2c_designware.1/i2c-9/i2c-SYNA2393:00/0018:06CB:7A13.0001/input/input21 [ 2.736774] hid-multitouch 0018:06CB:7A13.0001: input,hidraw0: I2C HID v1.00 Mouse [SYNA2393:00 06CB:7A13] on i2c-SYNA2393:00 [ 2.737387] iwlwifi 0000:3a:00.0: Detected Intel(R) Wireless-AC 9260 160MHz, REV=0x324 [ 2.751270] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 2.751443] iwlwifi 0000:3a:00.0: Allocated 0x00400000 bytes for firmware monitor. [ 2.770367] Adding 34186560k swap on /dev/nvme0n1p3. Priority:-2 extents:1 across:34186560k SSFS [ 2.799346] iwlwifi 0000:3a:00.0: base HW address: 18:56:80:17:8c:93 [ 2.840219] random: alsactl: uninitialized urandom read (4 bytes read) [ 2.853730] random: crng init done [ 2.853731] random: 1 urandom warning(s) missed due to ratelimiting [ 2.870754] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs' [ 2.870980] thermal thermal_zone10: failed to read out thermal zone (-61) [ 2.876183] iwlwifi 0000:3a:00.0 wlp58s0: renamed from wlan0 [ 2.892007] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 3.066009] [drm:_drm_lease_revoke] revoke leases for 00000000d314560b 0 [ 3.066011] [drm:drm_lease_destroy] drm_lease_destroy 0 [ 3.066012] [drm:drm_master_destroy] drm_lease_destroy done 0 [ 3.066048] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1080)@ 3.074104 -> 3.066002 [e 0 us, 0 rep] [ 3.066050] [drm:drm_vblank_restore] missed 21 vblanks in 350019515 ns, frame duration=16667635 ns, hw_diff=21 [ 3.066053] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1081)@ 3.074110 -> 3.066001 [e 1 us, 0 rep] [ 3.066055] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=100, diff=0, hw=398 hw_last=398 [ 3.066065] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1082)@ 3.074122 -> 3.066006 [e 0 us, 0 rep] [ 3.066067] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=100, diff=0, hw=398 hw_last=398 [ 3.074153] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 3.082205 -> 3.082670 [e 1 us, 0 rep] [ 3.074158] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=100, diff=1, hw=399 hw_last=398 [ 3.090839] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 3.098885 -> 3.099335 [e 2 us, 0 rep] [ 3.090846] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=101, diff=1, hw=400 hw_last=399 [ 3.090854] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 3.098910 -> 3.099338 [e 1 us, 0 rep] [ 3.090857] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=102, diff=0, hw=400 hw_last=400 [ 3.093526] [drm:_drm_lease_revoke] revoke leases for 00000000b225d840 0 [ 3.093528] [drm:drm_lease_destroy] drm_lease_destroy 0 [ 3.093529] [drm:drm_master_destroy] drm_lease_destroy done 0 [ 3.093569] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,305)@ 3.101624 -> 3.099336 [e 0 us, 0 rep] [ 3.093570] [drm:drm_vblank_restore] missed 0 vblanks in 405 ns, frame duration=16667635 ns, hw_diff=0 [ 3.093573] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,306)@ 3.101630 -> 3.099335 [e 0 us, 0 rep] [ 3.093575] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=102, diff=0, hw=400 hw_last=400 [ 3.093585] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,307)@ 3.101642 -> 3.099340 [e 0 us, 0 rep] [ 3.093587] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=102, diff=0, hw=400 hw_last=400 [ 3.107521] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 3.115575 -> 3.116002 [e 0 us, 0 rep] [ 3.107523] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=102, diff=1, hw=401 hw_last=400 [ 3.113724] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,770)@ 3.121778 -> 3.116002 [e 2 us, 0 rep] [ 3.113727] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=103, diff=0, hw=401 hw_last=401 [ 3.124186] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 3.132240 -> 3.132675 [e 0 us, 0 rep] [ 3.124188] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=103, diff=1, hw=402 hw_last=401 [ 3.133453] [drm:_drm_lease_revoke] revoke leases for 000000001d4a1f9d 0 [ 3.133455] [drm:drm_lease_destroy] drm_lease_destroy 0 [ 3.133457] [drm:drm_master_destroy] drm_lease_destroy done 0 [ 3.133614] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1199)@ 3.141668 -> 3.132674 [e 3 us, 0 rep] [ 3.133618] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=104, diff=0, hw=402 hw_last=402 [ 3.140853] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 3.148907 -> 3.149342 [e 0 us, 0 rep] [ 3.140855] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=104, diff=1, hw=403 hw_last=402 [ 3.157487] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 3.165542 -> 3.166007 [e 0 us, 0 rep] [ 3.157490] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=105, diff=1, hw=404 hw_last=403 [ 3.157496] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 3.165553 -> 3.166011 [e 0 us, 0 rep] [ 3.157497] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=106, diff=0, hw=404 hw_last=404 [ 3.232761] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1085)@ 3.240815 -> 3.232676 [e 2 us, 0 rep] [ 3.232762] [drm:drm_vblank_restore] missed 4 vblanks in 66669674 ns, frame duration=16667635 ns, hw_diff=4 [ 3.232770] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1087)@ 3.240829 -> 3.232675 [e 3 us, 0 rep] [ 3.232773] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=110, diff=0, hw=408 hw_last=408 [ 3.232800] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1090)@ 3.240858 -> 3.232682 [e 1 us, 0 rep] [ 3.232803] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=110, diff=0, hw=408 hw_last=408 [ 3.240882] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 3.248915 -> 3.249343 [e 0 us, 0 rep] [ 3.240884] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=110, diff=1, hw=409 hw_last=408 [ 3.240931] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-48)@ 3.248989 -> 3.249349 [e 0 us, 0 rep] [ 3.240933] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=111, diff=0, hw=409 hw_last=409 [ 3.257523] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 3.265577 -> 3.266012 [e 1 us, 0 rep] [ 3.257525] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=111, diff=1, hw=410 hw_last=409 [ 3.257572] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-51)@ 3.265629 -> 3.266012 [e 0 us, 0 rep] [ 3.257573] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=112, diff=0, hw=410 hw_last=410 [ 3.274181] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 3.282231 -> 3.282681 [e 4 us, 0 rep] [ 3.274186] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=112, diff=1, hw=411 hw_last=410 [ 3.274257] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-49)@ 3.282313 -> 3.282681 [e 1 us, 0 rep] [ 3.274261] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=113, diff=0, hw=411 hw_last=411 [ 3.290865] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 3.298921 -> 3.299348 [e 3 us, 0 rep] [ 3.290869] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=113, diff=1, hw=412 hw_last=411 [ 3.295617] [drm:drm_mode_list_lessees_ioctl] List lessees for 0 [ 3.295618] [drm:drm_mode_list_lessees_ioctl] Lessor leases to 0 [ 3.295619] [drm:drm_mode_list_lessees_ioctl] List lessees for 0 [ 3.295620] [drm:drm_mode_list_lessees_ioctl] Lessor leases to 0 [ 3.307500] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 3.315552 -> 3.316017 [e 1 us, 0 rep] [ 3.307503] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=114, diff=1, hw=413 hw_last=412 [ 3.307510] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 3.315569 -> 3.316019 [e 1 us, 0 rep] [ 3.307514] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=115, diff=0, hw=413 hw_last=413 [ 3.315534] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 3.435712] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 3.512382] iwlwifi 0000:3a:00.0: FW already configured (0) - re-configuring [ 3.566535] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 3.696124] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 3.776420] iwlwifi 0000:3a:00.0: FW already configured (0) - re-configuring [ 4.436424] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1565)@ 4.444483 -> 4.432744 [e 2 us, 0 rep] [ 4.436427] [drm:drm_vblank_restore] missed 67 vblanks in 1116727041 ns, frame duration=16667635 ns, hw_diff=67 [ 4.436436] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1567)@ 4.444501 -> 4.432747 [e 2 us, 0 rep] [ 4.436440] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=182, diff=0, hw=480 hw_last=480 [ 4.436461] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1570)@ 4.444527 -> 4.432750 [e 2 us, 0 rep] [ 4.436465] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=182, diff=0, hw=480 hw_last=480 [ 4.440916] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-59)@ 4.448975 -> 4.449417 [e 2 us, 0 rep] [ 4.440922] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=182, diff=1, hw=481 hw_last=480 [ 4.441230] i915 0000:00:02.0: [drm] plane[31]: plane 1A [ 4.441233] i915 0000:00:02.0: [drm] crtc=pipe A [ 4.441234] i915 0000:00:02.0: [drm] fb=122 [ 4.441237] i915 0000:00:02.0: [drm] allocated by = Xorg [ 4.441238] i915 0000:00:02.0: [drm] refcount=2 [ 4.441242] i915 0000:00:02.0: [drm] format=XR24 little-endian (0x34325258) [ 4.441243] i915 0000:00:02.0: [drm] modifier=0x100000000000002 [ 4.441245] i915 0000:00:02.0: [drm] size=3840x2160 [ 4.441246] i915 0000:00:02.0: [drm] layers: [ 4.441248] i915 0000:00:02.0: [drm] size[0]=3840x2160 [ 4.441250] i915 0000:00:02.0: [drm] pitch[0]=15360 [ 4.441252] i915 0000:00:02.0: [drm] offset[0]=0 [ 4.441254] i915 0000:00:02.0: [drm] obj[0]: [ 4.441256] i915 0000:00:02.0: [drm] name=0 [ 4.441257] i915 0000:00:02.0: [drm] refcount=4 [ 4.441259] i915 0000:00:02.0: [drm] start=00000000 [ 4.441261] i915 0000:00:02.0: [drm] size=33554432 [ 4.441262] i915 0000:00:02.0: [drm] imported=no [ 4.441264] i915 0000:00:02.0: [drm] crtc-pos=3840x2160+0+0 [ 4.441267] i915 0000:00:02.0: [drm] src-pos=3840.000000x2160.000000+0.000000+0.000000 [ 4.441269] i915 0000:00:02.0: [drm] rotation=1 [ 4.441270] i915 0000:00:02.0: [drm] normalized-zpos=0 [ 4.441272] i915 0000:00:02.0: [drm] color-encoding=ITU-R BT.709 YCbCr [ 4.441273] i915 0000:00:02.0: [drm] color-range=YCbCr limited range [ 4.441275] i915 0000:00:02.0: [drm] crtc[51]: pipe A [ 4.441277] i915 0000:00:02.0: [drm] enable=1 [ 4.441278] i915 0000:00:02.0: [drm] active=1 [ 4.441280] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 4.441281] i915 0000:00:02.0: [drm] planes_changed=0 [ 4.441282] i915 0000:00:02.0: [drm] mode_changed=0 [ 4.441284] i915 0000:00:02.0: [drm] active_changed=0 [ 4.441285] i915 0000:00:02.0: [drm] connectors_changed=0 [ 4.441286] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 4.441288] i915 0000:00:02.0: [drm] plane_mask=1 [ 4.441289] i915 0000:00:02.0: [drm] connector_mask=1 [ 4.441290] i915 0000:00:02.0: [drm] encoder_mask=1 [ 4.441294] i915 0000:00:02.0: [drm] mode: "": 0 533250 3840 3888 3920 4000 2160 2163 2168 2222 0x0 0xa [ 4.441296] i915 0000:00:02.0: [drm] connector[95]: eDP-1 [ 4.441297] i915 0000:00:02.0: [drm] crtc=pipe A [ 4.441298] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 4.444558] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,427)@ 4.452618 -> 4.449415 [e 2 us, 0 rep] [ 4.444564] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=183, diff=0, hw=481 hw_last=481 [ 4.457578] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 4.465626 -> 4.466083 [e 3 us, 0 rep] [ 4.457585] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=183, diff=1, hw=482 hw_last=481 [ 4.457776] [drm:drm_mode_list_lessees_ioctl] List lessees for 0 [ 4.457779] [drm:drm_mode_list_lessees_ioctl] Lessor leases to 0 [ 4.457784] [drm:drm_mode_list_lessees_ioctl] List lessees for 0 [ 4.457786] [drm:drm_mode_list_lessees_ioctl] Lessor leases to 0 [ 4.474239] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 4.482297 -> 4.482755 [e 3 us, 0 rep] [ 4.474247] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=184, diff=1, hw=483 hw_last=482 [ 4.474259] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 4.482323 -> 4.482751 [e 3 us, 0 rep] [ 4.474264] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=185, diff=0, hw=483 hw_last=483 [ 4.650963] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 4.659021 -> 4.659426 [e 3 us, 0 rep] [ 4.650968] [drm:drm_vblank_restore] missed 11 vblanks in 176671070 ns, frame duration=16667635 ns, hw_diff=9 [ 4.650980] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 4.659045 -> 4.659450 [e 2 us, 0 rep] [ 4.650986] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=196, diff=0, hw=492 hw_last=492 [ 4.672823] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 4.680875 -> 4.681325 [e 3 us, 0 rep] [ 4.672833] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=196, diff=1, hw=493 hw_last=492 [ 4.672849] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 4.680912 -> 4.681325 [e 3 us, 0 rep] [ 4.672856] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=197, diff=0, hw=493 hw_last=493 [ 9.668470] traps: ck-remove-direc[995] trap int3 ip:7fb57feb4a35 sp:7ffdceff4a10 error:0 in libglib-2.0.so.0.6000.6[7fb57fe7b000+80000] [ 9.805043] fuse: init (API version 7.31) [ 10.494107] wlp58s0: authenticate with dc:ee:06:74:61:d8 [ 10.497958] wlp58s0: send auth to dc:ee:06:74:61:d8 (try 1/3) [ 10.554157] wlp58s0: authenticated [ 10.560169] wlp58s0: associate with dc:ee:06:74:61:d8 (try 1/3) [ 10.580948] wlp58s0: RX AssocResp from dc:ee:06:74:61:d8 (capab=0x411 status=0 aid=1) [ 10.582535] wlp58s0: AP has invalid WMM params (AIFSN=1 for ACI 0), will use 2 [ 10.593041] wlp58s0: associated [ 10.793524] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,233)@ 10.801638 -> 10.799890 [e 0 us, 0 rep] [ 10.793527] [drm:drm_vblank_restore] missed 367 vblanks in 6118564427 ns, frame duration=16667635 ns, hw_diff=307 [ 10.793531] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,234)@ 10.801648 -> 10.799892 [e 1 us, 0 rep] [ 10.793533] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=564, diff=0, hw=800 hw_last=800 [ 10.808004] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 10.816095 -> 10.816560 [e 1 us, 0 rep] [ 10.808009] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=564, diff=1, hw=801 hw_last=800 [ 10.808014] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 10.816131 -> 10.816558 [e 1 us, 0 rep] [ 10.808016] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=565, diff=0, hw=801 hw_last=801 [ 10.816026] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1009)@ 10.824128 -> 10.816559 [e 1 us, 0 rep] [ 10.816031] [drm:drm_vblank_restore] missed 0 vblanks in -905 ns, frame duration=16667635 ns, hw_diff=0 [ 10.816035] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1012)@ 10.824152 -> 10.816561 [e 1 us, 0 rep] [ 10.816037] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=565, diff=0, hw=801 hw_last=801 [ 10.820820] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1650)@ 10.828934 -> 10.816557 [e 0 us, 0 rep] [ 10.820823] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=565, diff=0, hw=801 hw_last=801 [ 10.824655] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 10.832762 -> 10.833227 [e 1 us, 0 rep] [ 10.824660] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=565, diff=1, hw=802 hw_last=801 [ 10.841321] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 10.849431 -> 10.849896 [e 0 us, 0 rep] [ 10.841325] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=566, diff=1, hw=803 hw_last=802 [ 10.841330] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 10.849447 -> 10.849897 [e 0 us, 0 rep] [ 10.841332] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=567, diff=0, hw=803 hw_last=803 [ 11.507607] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 11.515710 -> 11.499515 [e 1 us, 0 rep] [ 11.507609] [drm:drm_vblank_restore] missed 39 vblanks in 649619241 ns, frame duration=16667635 ns, hw_diff=9 [ 11.507613] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 11.515736 -> 11.499541 [e 0 us, 0 rep] [ 11.507615] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=606, diff=0, hw=812 hw_last=812 [ 11.507620] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 11.515741 -> 11.499546 [e 1 us, 0 rep] [ 11.507622] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=606, diff=0, hw=812 hw_last=812 [ 11.507626] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 11.515748 -> 11.499553 [e 0 us, 0 rep] [ 11.507627] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=606, diff=0, hw=812 hw_last=812 [ 11.530552] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,78)@ 11.538663 -> 11.538077 [e 1 us, 0 rep] [ 11.530555] [drm:drm_vblank_restore] missed 2 vblanks in 38562605 ns, frame duration=16667635 ns, hw_diff=1 [ 11.530559] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,80)@ 11.538682 -> 11.538082 [e 1 us, 0 rep] [ 11.530561] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=608, diff=0, hw=813 hw_last=813 [ 11.537095] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,951)@ 11.545212 -> 11.538079 [e 0 us, 0 rep] [ 11.537097] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=608, diff=0, hw=813 hw_last=813 [ 11.546309] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-44)@ 11.554417 -> 11.554747 [e 1 us, 0 rep] [ 11.546314] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=608, diff=1, hw=814 hw_last=813 [ 11.562862] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 11.570962 -> 11.571419 [e 3 us, 0 rep] [ 11.562870] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=609, diff=1, hw=815 hw_last=814 [ 11.562884] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 11.571002 -> 11.571415 [e 3 us, 0 rep] [ 11.562891] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=610, diff=0, hw=815 hw_last=815 [ 11.564207] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,120)@ 11.572317 -> 11.571417 [e 2 us, 0 rep] [ 11.564214] [drm:drm_vblank_restore] missed 0 vblanks in -2235 ns, frame duration=16667635 ns, hw_diff=0 [ 11.564227] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,124)@ 11.572346 -> 11.571415 [e 3 us, 0 rep] [ 11.564234] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=610, diff=0, hw=815 hw_last=815 [ 11.564265] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,129)@ 11.572383 -> 11.571415 [e 2 us, 0 rep] [ 11.564270] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=610, diff=0, hw=815 hw_last=815 [ 11.579571] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.587680 -> 11.588085 [e 3 us, 0 rep] [ 11.579580] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=610, diff=1, hw=816 hw_last=815 [ 11.586306] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,844)@ 11.594415 -> 11.588084 [e 2 us, 0 rep] [ 11.586314] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=611, diff=0, hw=816 hw_last=816 [ 11.596267] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-50)@ 11.604374 -> 11.604749 [e 3 us, 0 rep] [ 11.596276] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=611, diff=1, hw=817 hw_last=816 [ 11.612889] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.620993 -> 11.621420 [e 3 us, 0 rep] [ 11.612899] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=612, diff=1, hw=818 hw_last=817 [ 11.612915] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 11.621032 -> 11.621422 [e 3 us, 0 rep] [ 11.612922] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=613, diff=0, hw=818 hw_last=818 [ 11.614184] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,117)@ 11.622294 -> 11.621416 [e 3 us, 0 rep] [ 11.614192] [drm:drm_vblank_restore] missed 0 vblanks in -3922 ns, frame duration=16667635 ns, hw_diff=0 [ 11.614205] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,120)@ 11.622323 -> 11.621423 [e 3 us, 0 rep] [ 11.614211] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=613, diff=0, hw=818 hw_last=818 [ 11.619247] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,791)@ 11.627357 -> 11.621424 [e 3 us, 0 rep] [ 11.619253] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=613, diff=0, hw=818 hw_last=818 [ 11.629554] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.637659 -> 11.638086 [e 3 us, 0 rep] [ 11.629566] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=613, diff=1, hw=819 hw_last=818 [ 11.635916] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,790)@ 11.644016 -> 11.638090 [e 3 us, 0 rep] [ 11.635925] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=614, diff=0, hw=819 hw_last=819 [ 11.646234] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 11.654333 -> 11.654753 [e 4 us, 0 rep] [ 11.646246] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=614, diff=1, hw=820 hw_last=819 [ 11.662885] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.670991 -> 11.671419 [e 3 us, 0 rep] [ 11.662894] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=615, diff=1, hw=821 hw_last=820 [ 11.662907] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 11.671027 -> 11.671424 [e 2 us, 0 rep] [ 11.662913] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=616, diff=0, hw=821 hw_last=821 [ 11.671422] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1080)@ 11.679525 -> 11.671424 [e 3 us, 0 rep] [ 11.671428] [drm:drm_vblank_restore] missed 0 vblanks in 5197 ns, frame duration=16667635 ns, hw_diff=0 [ 11.671440] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1085)@ 11.679560 -> 11.671421 [e 2 us, 0 rep] [ 11.671446] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=616, diff=0, hw=821 hw_last=821 [ 11.674505] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1492)@ 11.682618 -> 11.671426 [e 3 us, 0 rep] [ 11.674512] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=616, diff=0, hw=821 hw_last=821 [ 11.679612] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-49)@ 11.687720 -> 11.688088 [e 3 us, 0 rep] [ 11.679619] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=616, diff=1, hw=822 hw_last=821 [ 11.691328] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1513)@ 11.699441 -> 11.688092 [e 2 us, 0 rep] [ 11.691335] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=617, diff=0, hw=822 hw_last=822 [ 11.696245] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.704353 -> 11.704758 [e 3 us, 0 rep] [ 11.696253] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=617, diff=1, hw=823 hw_last=822 [ 11.712878] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 11.720990 -> 11.721425 [e 5 us, 0 rep] [ 11.712885] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=618, diff=1, hw=824 hw_last=823 [ 11.712899] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.721018 -> 11.721423 [e 2 us, 0 rep] [ 11.712904] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=619, diff=0, hw=824 hw_last=824 [ 11.714771] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,193)@ 11.722875 -> 11.721428 [e 3 us, 0 rep] [ 11.714776] [drm:drm_vblank_restore] missed 0 vblanks in 2639 ns, frame duration=16667635 ns, hw_diff=0 [ 11.714789] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,198)@ 11.722909 -> 11.721424 [e 2 us, 0 rep] [ 11.714794] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=619, diff=0, hw=824 hw_last=824 [ 11.729542] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-59)@ 11.737651 -> 11.738093 [e 4 us, 0 rep] [ 11.729550] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=619, diff=1, hw=825 hw_last=824 [ 11.729563] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 11.737683 -> 11.738096 [e 3 us, 0 rep] [ 11.729568] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=620, diff=0, hw=825 hw_last=825 [ 11.743536] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1806)@ 11.751640 -> 11.738093 [e 3 us, 0 rep] [ 11.743542] [drm:drm_vblank_restore] missed 0 vblanks in -503 ns, frame duration=16667635 ns, hw_diff=0 [ 11.743553] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1811)@ 11.751674 -> 11.738090 [e 3 us, 0 rep] [ 11.743560] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=620, diff=0, hw=825 hw_last=825 [ 11.746221] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.754330 -> 11.754758 [e 3 us, 0 rep] [ 11.746228] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=620, diff=1, hw=826 hw_last=825 [ 11.746242] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 11.754363 -> 11.754760 [e 3 us, 0 rep] [ 11.746247] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=621, diff=0, hw=826 hw_last=826 [ 11.751981] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,711)@ 11.760091 -> 11.754758 [e 3 us, 0 rep] [ 11.751985] [drm:drm_vblank_restore] missed 0 vblanks in -402 ns, frame duration=16667635 ns, hw_diff=0 [ 11.751998] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,714)@ 11.760118 -> 11.754762 [e 3 us, 0 rep] [ 11.752003] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=621, diff=0, hw=826 hw_last=826 [ 11.752033] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,719)@ 11.760152 -> 11.754759 [e 2 us, 0 rep] [ 11.752037] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=621, diff=0, hw=826 hw_last=826 [ 11.762923] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 11.771030 -> 11.771427 [e 3 us, 0 rep] [ 11.762931] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=621, diff=1, hw=827 hw_last=826 [ 11.769005] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,759)@ 11.777118 -> 11.771425 [e 2 us, 0 rep] [ 11.769012] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=622, diff=0, hw=827 hw_last=827 [ 11.779576] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 11.787686 -> 11.788099 [e 3 us, 0 rep] [ 11.779583] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=622, diff=1, hw=828 hw_last=827 [ 11.796248] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.804358 -> 11.804763 [e 3 us, 0 rep] [ 11.796257] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=623, diff=1, hw=829 hw_last=828 [ 11.796271] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-50)@ 11.804391 -> 11.804766 [e 3 us, 0 rep] [ 11.796276] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=624, diff=0, hw=829 hw_last=829 [ 11.798206] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,207)@ 11.806314 -> 11.804761 [e 3 us, 0 rep] [ 11.798212] [drm:drm_vblank_restore] missed 0 vblanks in -1974 ns, frame duration=16667635 ns, hw_diff=0 [ 11.798224] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,211)@ 11.806345 -> 11.804762 [e 3 us, 0 rep] [ 11.798230] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=624, diff=0, hw=829 hw_last=829 [ 11.801725] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,677)@ 11.809838 -> 11.804760 [e 2 us, 0 rep] [ 11.801733] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=624, diff=0, hw=829 hw_last=829 [ 11.812911] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.821024 -> 11.821429 [e 3 us, 0 rep] [ 11.812919] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=624, diff=1, hw=830 hw_last=829 [ 11.819673] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,847)@ 11.827784 -> 11.821431 [e 3 us, 0 rep] [ 11.819680] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=625, diff=0, hw=830 hw_last=830 [ 11.829585] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.837697 -> 11.838103 [e 3 us, 0 rep] [ 11.829593] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=625, diff=1, hw=831 hw_last=830 [ 11.836252] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,835)@ 11.844363 -> 11.838100 [e 2 us, 0 rep] [ 11.836259] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=626, diff=0, hw=831 hw_last=831 [ 11.846261] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 11.854373 -> 11.854763 [e 3 us, 0 rep] [ 11.846269] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=626, diff=1, hw=832 hw_last=831 [ 11.852864] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,828)@ 11.860975 -> 11.854764 [e 3 us, 0 rep] [ 11.852871] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=627, diff=0, hw=832 hw_last=832 [ 11.862912] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 11.871023 -> 11.871436 [e 3 us, 0 rep] [ 11.862920] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=627, diff=1, hw=833 hw_last=832 [ 11.869299] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,797)@ 11.877411 -> 11.871432 [e 2 us, 0 rep] [ 11.869306] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=628, diff=0, hw=833 hw_last=833 [ 11.879563] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.887674 -> 11.888102 [e 3 us, 0 rep] [ 11.879570] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=628, diff=1, hw=834 hw_last=833 [ 11.886403] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,855)@ 11.894512 -> 11.888098 [e 2 us, 0 rep] [ 11.886413] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=629, diff=0, hw=834 hw_last=834 [ 11.896253] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 11.904363 -> 11.904768 [e 3 us, 0 rep] [ 11.896260] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=629, diff=1, hw=835 hw_last=834 [ 11.908467] IPv6: ADDRCONF(NETDEV_CHANGE): wlp58s0: link becomes ready [ 11.912908] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.921010 -> 11.921438 [e 3 us, 0 rep] [ 11.912919] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=630, diff=1, hw=836 hw_last=835 [ 11.912936] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-51)@ 11.921056 -> 11.921439 [e 3 us, 0 rep] [ 11.912944] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=631, diff=0, hw=836 hw_last=836 [ 11.914421] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,146)@ 11.922530 -> 11.921435 [e 3 us, 0 rep] [ 11.914426] [drm:drm_vblank_restore] missed 0 vblanks in -3273 ns, frame duration=16667635 ns, hw_diff=0 [ 11.914439] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,150)@ 11.922560 -> 11.921435 [e 2 us, 0 rep] [ 11.914445] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=631, diff=0, hw=836 hw_last=836 [ 11.919484] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,820)@ 11.927590 -> 11.921439 [e 3 us, 0 rep] [ 11.919493] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=631, diff=0, hw=836 hw_last=836 [ 11.929561] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-59)@ 11.937665 -> 11.938107 [e 7 us, 0 rep] [ 11.929571] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=631, diff=1, hw=837 hw_last=836 [ 11.941398] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1520)@ 11.949508 -> 11.938107 [e 3 us, 0 rep] [ 11.941407] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=632, diff=0, hw=837 hw_last=837 [ 11.946231] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.954343 -> 11.954770 [e 3 us, 0 rep] [ 11.946238] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=632, diff=1, hw=838 hw_last=837 [ 11.962905] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 11.971012 -> 11.971439 [e 3 us, 0 rep] [ 11.962915] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=633, diff=1, hw=839 hw_last=838 [ 11.962930] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 11.971050 -> 11.971440 [e 3 us, 0 rep] [ 11.962935] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=634, diff=0, hw=839 hw_last=839 [ 11.964858] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,204)@ 11.972967 -> 11.971437 [e 3 us, 0 rep] [ 11.964864] [drm:drm_vblank_restore] missed 0 vblanks in -2811 ns, frame duration=16667635 ns, hw_diff=0 [ 11.964876] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,208)@ 11.972998 -> 11.971438 [e 2 us, 0 rep] [ 11.964882] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=634, diff=0, hw=839 hw_last=839 [ 11.970235] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,920)@ 11.978342 -> 11.971441 [e 3 us, 0 rep] [ 11.970245] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=634, diff=0, hw=839 hw_last=839 [ 11.979597] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 11.987709 -> 11.988107 [e 3 us, 0 rep] [ 11.979606] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=634, diff=1, hw=840 hw_last=839 [ 11.986520] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,869)@ 11.994629 -> 11.988111 [e 3 us, 0 rep] [ 11.986529] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=635, diff=0, hw=840 hw_last=840 [ 11.996257] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 12.004368 -> 12.004773 [e 3 us, 0 rep] [ 11.996264] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=635, diff=1, hw=841 hw_last=840 [ 12.012934] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 12.021046 -> 12.021443 [e 3 us, 0 rep] [ 12.012941] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=636, diff=1, hw=842 hw_last=841 [ 12.012955] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-49)@ 12.021077 -> 12.021444 [e 3 us, 0 rep] [ 12.012960] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=637, diff=0, hw=842 hw_last=842 [ 12.015509] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,290)@ 12.023619 -> 12.021443 [e 3 us, 0 rep] [ 12.015515] [drm:drm_vblank_restore] missed 0 vblanks in 47 ns, frame duration=16667635 ns, hw_diff=0 [ 12.015528] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,294)@ 12.023650 -> 12.021445 [e 2 us, 0 rep] [ 12.015534] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=637, diff=0, hw=842 hw_last=842 [ 12.019267] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,791)@ 12.027375 -> 12.021442 [e 3 us, 0 rep] [ 12.019277] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=637, diff=0, hw=842 hw_last=842 [ 12.029601] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 12.037709 -> 12.038107 [e 3 us, 0 rep] [ 12.029608] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=637, diff=1, hw=843 hw_last=842 [ 12.035905] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,787)@ 12.044014 -> 12.038110 [e 3 us, 0 rep] [ 12.035915] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=638, diff=0, hw=843 hw_last=843 [ 12.046277] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 12.054382 -> 12.054779 [e 3 us, 0 rep] [ 12.046284] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=638, diff=1, hw=844 hw_last=843 [ 12.052477] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,774)@ 12.060586 -> 12.054780 [e 3 us, 0 rep] [ 12.052486] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=639, diff=0, hw=844 hw_last=844 [ 12.062935] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 12.071045 -> 12.071443 [e 3 us, 0 rep] [ 12.062944] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=639, diff=1, hw=845 hw_last=844 [ 12.069301] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,795)@ 12.077411 -> 12.071448 [e 3 us, 0 rep] [ 12.069311] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=640, diff=0, hw=845 hw_last=845 [ 12.079605] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 12.087715 -> 12.088112 [e 3 us, 0 rep] [ 12.079613] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=640, diff=1, hw=846 hw_last=845 [ 12.085984] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,798)@ 12.094095 -> 12.088109 [e 2 us, 0 rep] [ 12.085993] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=641, diff=0, hw=846 hw_last=846 [ 12.096255] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.104367 -> 12.104780 [e 2 us, 0 rep] [ 12.096261] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=641, diff=1, hw=847 hw_last=846 [ 12.112910] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.121030 -> 12.121443 [e 1 us, 0 rep] [ 12.112913] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=642, diff=1, hw=848 hw_last=847 [ 12.112919] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 12.121046 -> 12.121443 [e 1 us, 0 rep] [ 12.112921] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=643, diff=0, hw=848 hw_last=848 [ 12.114198] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,116)@ 12.122315 -> 12.121445 [e 1 us, 0 rep] [ 12.114202] [drm:drm_vblank_restore] missed 0 vblanks in 1540 ns, frame duration=16667635 ns, hw_diff=0 [ 12.114207] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,118)@ 12.122333 -> 12.121448 [e 1 us, 0 rep] [ 12.114210] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=643, diff=0, hw=848 hw_last=848 [ 12.118970] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,752)@ 12.127086 -> 12.121446 [e 1 us, 0 rep] [ 12.118974] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=643, diff=0, hw=848 hw_last=848 [ 12.129585] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 12.137697 -> 12.138117 [e 1 us, 0 rep] [ 12.129589] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=643, diff=1, hw=849 hw_last=848 [ 12.134807] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,642)@ 12.142926 -> 12.138110 [e 0 us, 0 rep] [ 12.134812] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=644, diff=0, hw=849 hw_last=849 [ 12.146249] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 12.154363 -> 12.154783 [e 0 us, 0 rep] [ 12.146253] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=644, diff=1, hw=850 hw_last=849 [ 12.151699] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,671)@ 12.159818 -> 12.154785 [e 0 us, 0 rep] [ 12.151702] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=645, diff=0, hw=850 hw_last=850 [ 12.162998] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-44)@ 12.171118 -> 12.171448 [e 1 us, 0 rep] [ 12.163002] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=645, diff=1, hw=851 hw_last=850 [ 12.168542] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,695)@ 12.176661 -> 12.171448 [e 0 us, 0 rep] [ 12.168546] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=646, diff=0, hw=851 hw_last=851 [ 12.179590] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.187703 -> 12.188115 [e 1 us, 0 rep] [ 12.179593] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=646, diff=1, hw=852 hw_last=851 [ 12.185797] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,773)@ 12.193918 -> 12.188119 [e 0 us, 0 rep] [ 12.185801] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=647, diff=0, hw=852 hw_last=852 [ 12.196256] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.204369 -> 12.204782 [e 1 us, 0 rep] [ 12.196260] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=647, diff=1, hw=853 hw_last=852 [ 12.212994] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-45)@ 12.221114 -> 12.221452 [e 1 us, 0 rep] [ 12.212998] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=648, diff=1, hw=854 hw_last=853 [ 12.213003] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-43)@ 12.221131 -> 12.221453 [e 0 us, 0 rep] [ 12.213004] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=649, diff=0, hw=854 hw_last=854 [ 12.214041] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,94)@ 12.222157 -> 12.221452 [e 0 us, 0 rep] [ 12.214044] [drm:drm_vblank_restore] missed 0 vblanks in -352 ns, frame duration=16667635 ns, hw_diff=0 [ 12.214048] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,97)@ 12.222176 -> 12.221448 [e 0 us, 0 rep] [ 12.214050] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=649, diff=0, hw=854 hw_last=854 [ 12.218317] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,665)@ 12.226439 -> 12.221451 [e 0 us, 0 rep] [ 12.218320] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=649, diff=0, hw=854 hw_last=854 [ 12.229570] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 12.237690 -> 12.238117 [e 1 us, 0 rep] [ 12.229574] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=649, diff=1, hw=855 hw_last=854 [ 12.235075] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,677)@ 12.243197 -> 12.238119 [e 1 us, 0 rep] [ 12.235079] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=650, diff=0, hw=855 hw_last=855 [ 12.246252] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 12.254371 -> 12.254791 [e 1 us, 0 rep] [ 12.246257] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=650, diff=1, hw=856 hw_last=855 [ 12.251083] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,589)@ 12.259205 -> 12.254787 [e 1 us, 0 rep] [ 12.251086] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=651, diff=0, hw=856 hw_last=856 [ 12.262922] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.271041 -> 12.271454 [e 1 us, 0 rep] [ 12.262927] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=651, diff=1, hw=857 hw_last=856 [ 12.268623] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,705)@ 12.276741 -> 12.271453 [e 1 us, 0 rep] [ 12.268628] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=652, diff=0, hw=857 hw_last=857 [ 12.279600] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 12.287717 -> 12.288122 [e 1 us, 0 rep] [ 12.279606] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=652, diff=1, hw=858 hw_last=857 [ 12.296260] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.304379 -> 12.304792 [e 2 us, 0 rep] [ 12.296266] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=653, diff=1, hw=859 hw_last=858 [ 12.296277] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 12.304403 -> 12.304793 [e 2 us, 0 rep] [ 12.296281] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=654, diff=0, hw=859 hw_last=859 [ 12.337140] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,948)@ 12.345242 -> 12.338130 [e 3 us, 0 rep] [ 12.337148] [drm:drm_vblank_restore] missed 2 vblanks in 33338488 ns, frame duration=16667635 ns, hw_diff=2 [ 12.337163] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,954)@ 12.345286 -> 12.338130 [e 3 us, 0 rep] [ 12.337170] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=656, diff=0, hw=861 hw_last=861 [ 12.339754] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1299)@ 12.347870 -> 12.338126 [e 2 us, 0 rep] [ 12.339763] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=656, diff=0, hw=861 hw_last=861 [ 12.346265] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 12.354380 -> 12.354793 [e 3 us, 0 rep] [ 12.346272] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=656, diff=1, hw=862 hw_last=861 [ 12.350539] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,515)@ 12.358658 -> 12.354794 [e 2 us, 0 rep] [ 12.350545] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=657, diff=0, hw=862 hw_last=862 [ 12.362916] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 12.371032 -> 12.371460 [e 3 us, 0 rep] [ 12.362923] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=657, diff=1, hw=863 hw_last=862 [ 12.379674] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-45)@ 12.387791 -> 12.388128 [e 3 us, 0 rep] [ 12.379682] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=658, diff=1, hw=864 hw_last=863 [ 12.379695] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-41)@ 12.387821 -> 12.388128 [e 3 us, 0 rep] [ 12.379700] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=659, diff=0, hw=864 hw_last=864 [ 15.224034] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2058)@ 15.232174 -> 15.216737 [e 3 us, 0 rep] [ 15.224038] [drm:drm_vblank_restore] missed 170 vblanks in 2828608859 ns, frame duration=16667635 ns, hw_diff=167 [ 15.224050] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2061)@ 15.232198 -> 15.216738 [e 2 us, 0 rep] [ 15.224055] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=829, diff=0, hw=1031 hw_last=1031 [ 15.224846] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 15.232989 -> 15.233402 [e 2 us, 0 rep] [ 15.224853] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=829, diff=1, hw=1032 hw_last=1031 [ 15.224864] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 15.233013 -> 15.233403 [e 2 us, 0 rep] [ 15.224869] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=830, diff=0, hw=1032 hw_last=1032 [ 15.225050] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-28)@ 15.233194 -> 15.233404 [e 2 us, 0 rep] [ 15.225054] [drm:drm_vblank_restore] missed 0 vblanks in 1963 ns, frame duration=16667635 ns, hw_diff=0 [ 15.225065] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-25)@ 15.233214 -> 15.233401 [e 2 us, 0 rep] [ 15.225071] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=830, diff=0, hw=1032 hw_last=1032 [ 15.241503] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 15.249647 -> 15.250067 [e 2 us, 0 rep] [ 15.241510] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=830, diff=1, hw=1033 hw_last=1032 [ 15.241521] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 15.249670 -> 15.250067 [e 2 us, 0 rep] [ 15.241526] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=831, diff=0, hw=1033 hw_last=1033 [ 15.436072] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1441)@ 15.444220 -> 15.433411 [e 1 us, 0 rep] [ 15.436075] [drm:drm_vblank_restore] missed 11 vblanks in 183344057 ns, frame duration=16667635 ns, hw_diff=11 [ 15.436080] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1443)@ 15.444233 -> 15.433408 [e 1 us, 0 rep] [ 15.436082] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=842, diff=0, hw=1044 hw_last=1044 [ 15.439344] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1877)@ 15.447491 -> 15.433411 [e 1 us, 0 rep] [ 15.439348] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=842, diff=0, hw=1044 hw_last=1044 [ 15.441522] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 15.449661 -> 15.450081 [e 1 us, 0 rep] [ 15.441526] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=842, diff=1, hw=1045 hw_last=1044 [ 15.458193] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 15.466332 -> 15.466745 [e 1 us, 0 rep] [ 15.458197] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=843, diff=1, hw=1046 hw_last=1045 [ 15.458201] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 15.466355 -> 15.466745 [e 0 us, 0 rep] [ 15.458203] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=844, diff=0, hw=1046 hw_last=1046 [ 15.622580] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 15.630730 -> 15.631135 [e 1 us, 0 rep] [ 15.622582] [drm:drm_vblank_restore] missed 10 vblanks in 164390768 ns, frame duration=16667635 ns, hw_diff=9 [ 15.622587] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 15.630741 -> 15.631146 [e 1 us, 0 rep] [ 15.622590] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=854, diff=0, hw=1055 hw_last=1055 [ 15.622613] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 15.630766 -> 15.631171 [e 1 us, 0 rep] [ 15.622615] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=854, diff=0, hw=1055 hw_last=1055 [ 15.622620] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 15.630774 -> 15.631179 [e 1 us, 0 rep] [ 15.622621] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=854, diff=0, hw=1055 hw_last=1055 [ 15.622697] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 15.630851 -> 15.631256 [e 1 us, 0 rep] [ 15.622699] [drm:drm_vblank_restore] missed 0 vblanks in 120685 ns, frame duration=16667635 ns, hw_diff=0 [ 15.622703] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 15.630857 -> 15.631263 [e 1 us, 0 rep] [ 15.622705] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=854, diff=0, hw=1055 hw_last=1055 [ 15.644503] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 15.652638 -> 15.653096 [e 1 us, 0 rep] [ 15.644508] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=854, diff=1, hw=1056 hw_last=1055 [ 15.644512] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 15.652667 -> 15.653102 [e 1 us, 0 rep] [ 15.644514] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=855, diff=0, hw=1056 hw_last=1056 [ 15.653496] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1138)@ 15.661638 -> 15.653101 [e 1 us, 0 rep] [ 15.653499] [drm:drm_vblank_restore] missed 0 vblanks in 5547 ns, frame duration=16667635 ns, hw_diff=0 [ 15.653504] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1141)@ 15.661658 -> 15.653099 [e 1 us, 0 rep] [ 15.653507] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=855, diff=0, hw=1056 hw_last=1056 [ 15.661167] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 15.669306 -> 15.669763 [e 1 us, 0 rep] [ 15.661173] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=855, diff=1, hw=1057 hw_last=1056 [ 15.661178] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 15.669332 -> 15.669768 [e 1 us, 0 rep] [ 15.661179] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=856, diff=0, hw=1057 hw_last=1057 [ 15.666463] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,645)@ 15.674602 -> 15.669764 [e 1 us, 0 rep] [ 15.666468] [drm:drm_vblank_restore] missed 0 vblanks in 367 ns, frame duration=16667635 ns, hw_diff=0 [ 15.666473] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,648)@ 15.674628 -> 15.669767 [e 1 us, 0 rep] [ 15.666475] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=856, diff=0, hw=1057 hw_last=1057 [ 15.670504] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1185)@ 15.678655 -> 15.669766 [e 0 us, 0 rep] [ 15.670507] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=856, diff=0, hw=1057 hw_last=1057 [ 15.677848] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-58)@ 15.686001 -> 15.686436 [e 0 us, 0 rep] [ 15.677850] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=856, diff=1, hw=1058 hw_last=1057 [ 15.694488] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 15.702637 -> 15.703102 [e 1 us, 0 rep] [ 15.694492] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=857, diff=1, hw=1059 hw_last=1058 [ 15.694496] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 15.702651 -> 15.703101 [e 0 us, 0 rep] [ 15.694497] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=858, diff=0, hw=1059 hw_last=1059 [ 16.227780] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 16.235931 -> 16.219736 [e 1 us, 0 rep] [ 16.227783] [drm:drm_vblank_restore] missed 31 vblanks in 516633344 ns, frame duration=16667635 ns, hw_diff=9 [ 16.227787] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 16.235946 -> 16.219751 [e 1 us, 0 rep] [ 16.227788] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=889, diff=0, hw=1068 hw_last=1068 [ 16.227794] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 16.235952 -> 16.219757 [e 1 us, 0 rep] [ 16.227797] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=889, diff=0, hw=1068 hw_last=1068 [ 16.227802] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 16.235961 -> 16.219766 [e 0 us, 0 rep] [ 16.227803] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=889, diff=0, hw=1068 hw_last=1068 [ 16.230828] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 16.238984 -> 16.222789 [e 0 us, 0 rep] [ 16.230829] [drm:drm_vblank_restore] missed 0 vblanks in 3053182 ns, frame duration=16667635 ns, hw_diff=0 [ 16.230833] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 16.238992 -> 16.222797 [e 0 us, 0 rep] [ 16.230834] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=889, diff=0, hw=1068 hw_last=1068 [ 16.233539] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,7)@ 16.241686 -> 16.241633 [e 3 us, 0 rep] [ 16.233547] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=889, diff=0, hw=1068 hw_last=1068 [ 16.249704] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 16.257852 -> 16.258302 [e 1 us, 0 rep] [ 16.249707] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=889, diff=1, hw=1069 hw_last=1068 [ 16.266358] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 16.274505 -> 16.274970 [e 1 us, 0 rep] [ 16.266363] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=890, diff=1, hw=1070 hw_last=1069 [ 16.266369] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-59)@ 16.274528 -> 16.274970 [e 1 us, 0 rep] [ 16.266371] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=891, diff=0, hw=1070 hw_last=1070 [ 16.267550] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,99)@ 16.275707 -> 16.274964 [e 1 us, 0 rep] [ 16.267552] [drm:drm_vblank_restore] missed 0 vblanks in -5302 ns, frame duration=16667635 ns, hw_diff=0 [ 16.267556] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,100)@ 16.275716 -> 16.274966 [e 1 us, 0 rep] [ 16.267559] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=891, diff=0, hw=1070 hw_last=1070 [ 16.283076] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 16.291220 -> 16.291640 [e 4 us, 0 rep] [ 16.283086] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=891, diff=1, hw=1071 hw_last=1070 [ 16.283115] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-51)@ 16.291258 -> 16.291641 [e 4 us, 0 rep] [ 16.283117] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=892, diff=0, hw=1071 hw_last=1071 [ 22.604802] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1447)@ 22.613000 -> 22.602146 [e 2 us, 0 rep] [ 22.604807] [drm:drm_vblank_restore] missed 379 vblanks in 6310505720 ns, frame duration=16667635 ns, hw_diff=-946 [ 22.604818] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1450)@ 22.613024 -> 22.602147 [e 2 us, 0 rep] [ 22.604823] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1271, diff=0, hw=125 hw_last=125 [ 22.610165] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 22.618352 -> 22.618810 [e 3 us, 0 rep] [ 22.610172] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1271, diff=1, hw=126 hw_last=125 [ 22.610184] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 22.618390 -> 22.618810 [e 2 us, 0 rep] [ 22.610189] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1272, diff=0, hw=126 hw_last=126 [ 23.057767] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1841)@ 23.065976 -> 23.052167 [e 0 us, 0 rep] [ 23.057768] [drm:drm_vblank_restore] missed 26 vblanks in 433357233 ns, frame duration=16667635 ns, hw_diff=26 [ 23.057772] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1842)@ 23.065985 -> 23.052168 [e 0 us, 0 rep] [ 23.057774] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1298, diff=0, hw=152 hw_last=152 [ 23.060160] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 23.068370 -> 23.068835 [e 1 us, 0 rep] [ 23.060162] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1298, diff=1, hw=153 hw_last=152 [ 23.060167] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 23.068380 -> 23.068837 [e 0 us, 0 rep] [ 23.060168] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1299, diff=0, hw=153 hw_last=153 [ 77.592837] [drm:intel_backlight_device_get_brightness] get backlight PWM = 48000 [ 78.619672] [drm:intel_backlight_device_get_brightness] get backlight PWM = 48000 [ 78.694960] [drm:intel_backlight_device_get_brightness] get backlight PWM = 48000 [ 83.921023] PM: suspend entry (deep) [ 83.930087] Filesystems sync: 0.009 seconds [ 83.930919] Freezing user space processes ... (elapsed 0.001 seconds) done. [ 83.932857] OOM killer disabled. [ 83.932858] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done. [ 83.934095] printk: Suspending console(s) (use no_console_suspend to debug) [ 83.934679] [drm] GuC communication disabled [ 83.935022] wlp58s0: deauthenticating from dc:ee:06:74:61:d8 by local choice (Reason: 3=DEAUTH_LEAVING) [ 84.199848] [drm:intel_panel_actually_set_backlight] set backlight PWM = 0 [ 84.199896] [drm:drm_crtc_vblank_off] crtc 0, vblank enabled 0, inmodeset 0 [ 84.218874] [drm:gen8_de_irq_handler] hotplug event received, stat 0x01000000, dig 0x11101010, pins 0x00000010, long 0x00000000 [ 84.218882] [drm:intel_hpd_irq_handler] digital hpd on [ENCODER:94:DDI A] - short [ 84.272444] [drm:intel_dump_cdclk_state] Changing CDCLK to 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0 [ 85.260966] ACPI: EC: interrupt blocked [ 85.320346] ACPI: Preparing to enter system sleep state S3 [ 85.327448] ACPI: EC: event blocked [ 85.327449] ACPI: EC: EC stopped [ 85.327450] PM: Saving platform NVS memory [ 85.327466] Disabling non-boot CPUs ... [ 85.327926] IRQ 141: no longer affine to CPU1 [ 85.329696] smpboot: CPU 1 is now offline [ 85.331525] IRQ 142: no longer affine to CPU2 [ 85.332548] smpboot: CPU 2 is now offline [ 85.334367] IRQ 143: no longer affine to CPU3 [ 85.335398] smpboot: CPU 3 is now offline [ 85.337326] IRQ 144: no longer affine to CPU4 [ 85.338360] smpboot: CPU 4 is now offline [ 85.340354] IRQ 145: no longer affine to CPU5 [ 85.341375] smpboot: CPU 5 is now offline [ 85.343155] IRQ 146: no longer affine to CPU6 [ 85.344179] smpboot: CPU 6 is now offline [ 85.346150] IRQ 147: no longer affine to CPU7 [ 85.347174] smpboot: CPU 7 is now offline [ 85.352949] ACPI: Low-level resume complete [ 85.353037] ACPI: EC: EC started [ 85.353038] PM: Restoring platform NVS memory [ 85.354175] Enabling non-boot CPUs ... [ 85.354210] x86: Booting SMP configuration: [ 85.354211] smpboot: Booting Node 0 Processor 1 APIC 0x2 [ 85.354628] intel_pstate: Disabling energy efficiency optimization [ 85.354663] CPU1 is up [ 85.354688] smpboot: Booting Node 0 Processor 2 APIC 0x4 [ 85.355154] CPU2 is up [ 85.355179] smpboot: Booting Node 0 Processor 3 APIC 0x6 [ 85.355635] CPU3 is up [ 85.355659] smpboot: Booting Node 0 Processor 4 APIC 0x1 [ 85.356224] CPU4 is up [ 85.356249] smpboot: Booting Node 0 Processor 5 APIC 0x3 [ 85.356706] CPU5 is up [ 85.356729] smpboot: Booting Node 0 Processor 6 APIC 0x5 [ 85.357183] CPU6 is up [ 85.357206] smpboot: Booting Node 0 Processor 7 APIC 0x7 [ 85.357666] CPU7 is up [ 85.359020] ACPI: Waking up from system sleep state S3 [ 85.414620] ACPI: EC: interrupt unblocked [ 85.472548] ACPI: EC: event unblocked [ 85.487747] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 85.591421] nvme nvme0: 7/0/0 default/read/poll queues [ 85.616739] iwlwifi 0000:3a:00.0: Applying debug destination EXTERNAL_DRAM [ 85.694216] iwlwifi 0000:3a:00.0: FW already configured (0) - re-configuring [ 85.734937] usb 1-4: reset full-speed USB device number 2 using xhci_hcd [ 85.759639] [drm:intel_guc_fw_upload] GuC status 0x8002f0ec [ 85.759725] [drm] GuC communication enabled [ 85.770338] i915 0000:00:02.0: GuC firmware i915/kbl_guc_33.0.0.bin version 33.0 submission:disabled [ 85.770339] i915 0000:00:02.0: HuC firmware i915/kbl_huc_ver02_00_1810.bin version 2.0 authenticated:yes [ 85.770800] [drm:intel_dump_cdclk_state] Current CDCLK 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0 [ 85.807436] ata3: SATA link down (SStatus 4 SControl 300) [ 85.822785] [drm:swsci] SWSCI request timed out [ 85.822786] [drm:swsci] SWSCI request already in progress [ 85.822787] [drm:swsci] SWSCI request already in progress [ 85.822788] [drm:swsci] SWSCI request already in progress [ 85.822788] [drm:swsci] SWSCI request already in progress [ 85.822789] [drm:swsci] SWSCI request already in progress [ 85.822789] [drm:swsci] SWSCI request already in progress [ 85.822790] [drm:swsci] SWSCI request already in progress [ 85.822790] [drm:swsci] SWSCI request already in progress [ 85.822791] [drm:swsci] SWSCI request already in progress [ 85.822791] [drm:swsci] SWSCI request already in progress [ 85.822791] [drm:swsci] SWSCI request already in progress [ 85.822792] [drm:swsci] SWSCI request already in progress [ 85.822839] [drm:intel_reference_shared_dpll.isra.0] using DPLL 0 for pipe A [ 85.822880] [drm:intel_dump_cdclk_state] Changing CDCLK to 540000 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 2 [ 85.868768] [drm:gen8_de_irq_handler] hotplug event received, stat 0x01000000, dig 0x12101010, pins 0x00000010, long 0x00000010 [ 85.868774] [drm:intel_hpd_irq_handler] digital hpd on [ENCODER:94:DDI A] - long [ 85.989077] [drm:drm_crtc_vblank_on] crtc 0, vblank enabled 0, inmodeset 1 [ 85.989096] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 85.992641 -> 85.993106 [e 7 us, 0 rep] [ 85.989107] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-61)@ 85.992654 -> 85.993111 [e 2 us, 0 rep] [ 85.989139] [drm:intel_panel_actually_set_backlight] set backlight PWM = 48000 [ 85.989662] [drm:gen8_de_irq_handler] hotplug event received, stat 0x01000000, dig 0x11101010, pins 0x00000010, long 0x00000000 [ 85.989668] [drm:intel_hpd_irq_handler] digital hpd on [ENCODER:94:DDI A] - short [ 86.015915] [drm] Reducing the compressed framebuffer size. This may lead to less power savings than a non-reduced-size. Try to increase stolen memory size if available in BIOS. [ 86.015933] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1293)@ 86.019476 -> 86.009777 [e 2 us, 0 rep] [ 86.015936] [drm:drm_vblank_restore] missed 1 vblanks in 16665258 ns, frame duration=16667604 ns, hw_diff=1 [ 86.015947] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1296)@ 86.019494 -> 86.009773 [e 2 us, 0 rep] [ 86.015952] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1301, diff=0, hw=2 hw_last=2 [ 86.015984] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,1301)@ 86.019532 -> 86.009773 [e 2 us, 0 rep] [ 86.015989] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1301, diff=0, hw=2 hw_last=2 [ 86.022513] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 86.026032 -> 86.026445 [e 4 us, 0 rep] [ 86.022520] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1301, diff=1, hw=3 hw_last=2 [ 86.039162] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-54)@ 86.042705 -> 86.043110 [e 3 us, 0 rep] [ 86.039169] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1302, diff=1, hw=4 hw_last=3 [ 86.039191] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-50)@ 86.042737 -> 86.043113 [e 2 us, 0 rep] [ 86.039197] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1303, diff=0, hw=4 hw_last=4 [ 86.065120] usb 1-12: reset high-speed USB device number 3 using xhci_hcd [ 86.286739] acpi LNXPOWER:06: Turning OFF [ 86.288684] acpi LNXPOWER:01: Turning OFF [ 86.289808] acpi LNXPOWER:00: Turning OFF [ 86.290358] OOM killer enabled. [ 86.290359] Restarting tasks ... done. [ 86.434448] PM: suspend exit [ 86.478950] input: SYNA2393:00 06CB:7A13 Touchpad as /devices/pci0000:00/0000:00:15.1/i2c_designware.1/i2c-9/i2c-SYNA2393:00/0018:06CB:7A13.0002/input/input25 [ 86.479153] hid-multitouch 0018:06CB:7A13.0002: input,hidraw0: I2C HID v1.00 Mouse [SYNA2393:00 06CB:7A13] on i2c-SYNA2393:00 [ 86.480801] modprobe (1437) used greatest stack depth: 13128 bytes left [ 86.497128] Bluetooth: hci0: Bootloader revision 0.1 build 42 week 52 2015 [ 86.498314] Bluetooth: hci0: Device revision is 2 [ 86.498315] Bluetooth: hci0: Secure boot is enabled [ 86.498316] Bluetooth: hci0: OTP lock is enabled [ 86.498317] Bluetooth: hci0: API lock is enabled [ 86.498318] Bluetooth: hci0: Debug lock is disabled [ 86.498320] Bluetooth: hci0: Minimum firmware build 1 week 10 2014 [ 86.506367] Bluetooth: hci0: Found device firmware: intel/ibt-18-16-1.sfi [ 88.228168] Bluetooth: hci0: Waiting for firmware download to complete [ 88.229101] Bluetooth: hci0: Firmware loaded in 1692575 usecs [ 88.229199] Bluetooth: hci0: Waiting for device to boot [ 88.242135] Bluetooth: hci0: Device booted in 12687 usecs [ 88.248558] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-18-16-1.ddc [ 88.251223] Bluetooth: hci0: Applying Intel DDC parameters completed [ 92.115409] wlp58s0: authenticate with dc:ee:06:74:61:d8 [ 92.117767] wlp58s0: send auth to dc:ee:06:74:61:d8 (try 1/3) [ 92.248273] wlp58s0: authenticated [ 92.254750] wlp58s0: associate with dc:ee:06:74:61:d8 (try 1/3) [ 92.259323] wlp58s0: RX AssocResp from dc:ee:06:74:61:d8 (capab=0x411 status=0 aid=1) [ 92.262479] wlp58s0: AP has invalid WMM params (AIFSN=1 for ACI 0), will use 2 [ 92.267642] wlp58s0: associated [ 93.562627] IPv6: ADDRCONF(NETDEV_CHANGE): wlp58s0: link becomes ready [ 93.700627] i915 0000:00:02.0: [drm] crtc[51]: pipe A [ 93.700631] i915 0000:00:02.0: [drm] enable=0 [ 93.700633] i915 0000:00:02.0: [drm] active=0 [ 93.700634] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 93.700636] i915 0000:00:02.0: [drm] planes_changed=0 [ 93.700638] i915 0000:00:02.0: [drm] mode_changed=0 [ 93.700640] i915 0000:00:02.0: [drm] active_changed=0 [ 93.700641] i915 0000:00:02.0: [drm] connectors_changed=0 [ 93.700643] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 93.700644] i915 0000:00:02.0: [drm] plane_mask=5 [ 93.700646] i915 0000:00:02.0: [drm] connector_mask=0 [ 93.700648] i915 0000:00:02.0: [drm] encoder_mask=1 [ 93.700651] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 93.700653] i915 0000:00:02.0: [drm] connector[95]: eDP-1 [ 93.700655] i915 0000:00:02.0: [drm] crtc=(null) [ 93.700657] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 93.700723] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,360)@ 93.704324 -> 93.701623 [e 3 us, 0 rep] [ 93.700727] [drm:drm_vblank_restore] missed 459 vblanks in 7658513520 ns, frame duration=16667604 ns, hw_diff=414 [ 93.700739] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,363)@ 93.704346 -> 93.701623 [e 3 us, 0 rep] [ 93.700745] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1762, diff=0, hw=418 hw_last=418 [ 93.714272] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 93.717873 -> 93.718293 [e 3 us, 0 rep] [ 93.714279] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1762, diff=1, hw=419 hw_last=418 [ 93.714294] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-52)@ 93.717900 -> 93.718290 [e 3 us, 0 rep] [ 93.714299] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1763, diff=0, hw=419 hw_last=419 [ 93.914727] [drm:intel_panel_actually_set_backlight] set backlight PWM = 0 [ 93.914779] [drm:drm_crtc_vblank_off] crtc 0, vblank enabled 0, inmodeset 0 [ 93.974717] [drm:gen8_de_irq_handler] hotplug event received, stat 0x01000000, dig 0x12101010, pins 0x00000010, long 0x00000010 [ 93.974725] [drm:intel_hpd_irq_handler] digital hpd on [ENCODER:94:DDI A] - long [ 93.974764] [drm:intel_dump_cdclk_state] Changing CDCLK to 337500 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 0 [ 93.975040] i915 0000:00:02.0: [drm] crtc[51]: pipe A [ 93.975042] i915 0000:00:02.0: [drm] enable=0 [ 93.975044] i915 0000:00:02.0: [drm] active=0 [ 93.975046] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 93.975048] i915 0000:00:02.0: [drm] planes_changed=0 [ 93.975049] i915 0000:00:02.0: [drm] mode_changed=0 [ 93.975051] i915 0000:00:02.0: [drm] active_changed=0 [ 93.975053] i915 0000:00:02.0: [drm] connectors_changed=0 [ 93.975054] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 93.975056] i915 0000:00:02.0: [drm] plane_mask=5 [ 93.975058] i915 0000:00:02.0: [drm] connector_mask=0 [ 93.975059] i915 0000:00:02.0: [drm] encoder_mask=0 [ 93.975063] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 93.975118] i915 0000:00:02.0: [drm] crtc[51]: pipe A [ 93.975120] i915 0000:00:02.0: [drm] enable=0 [ 93.975121] i915 0000:00:02.0: [drm] active=0 [ 93.975123] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 93.975124] i915 0000:00:02.0: [drm] planes_changed=0 [ 93.975126] i915 0000:00:02.0: [drm] mode_changed=0 [ 93.975128] i915 0000:00:02.0: [drm] active_changed=0 [ 93.975129] i915 0000:00:02.0: [drm] connectors_changed=0 [ 93.975131] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 93.975132] i915 0000:00:02.0: [drm] plane_mask=5 [ 93.975134] i915 0000:00:02.0: [drm] connector_mask=0 [ 93.975136] i915 0000:00:02.0: [drm] encoder_mask=0 [ 93.975139] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 93.975141] i915 0000:00:02.0: [drm] crtc[72]: pipe B [ 93.975142] i915 0000:00:02.0: [drm] enable=0 [ 93.975144] i915 0000:00:02.0: [drm] active=0 [ 93.975146] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 93.975147] i915 0000:00:02.0: [drm] planes_changed=0 [ 93.975149] i915 0000:00:02.0: [drm] mode_changed=0 [ 93.975150] i915 0000:00:02.0: [drm] active_changed=0 [ 93.975152] i915 0000:00:02.0: [drm] connectors_changed=0 [ 93.975153] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 93.975155] i915 0000:00:02.0: [drm] plane_mask=0 [ 93.975157] i915 0000:00:02.0: [drm] connector_mask=0 [ 93.975158] i915 0000:00:02.0: [drm] encoder_mask=0 [ 93.975161] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 93.975163] i915 0000:00:02.0: [drm] crtc[93]: pipe C [ 93.975164] i915 0000:00:02.0: [drm] enable=0 [ 93.975166] i915 0000:00:02.0: [drm] active=0 [ 93.975168] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 93.975169] i915 0000:00:02.0: [drm] planes_changed=0 [ 93.975171] i915 0000:00:02.0: [drm] mode_changed=0 [ 93.975172] i915 0000:00:02.0: [drm] active_changed=0 [ 93.975174] i915 0000:00:02.0: [drm] connectors_changed=0 [ 93.975175] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 93.975177] i915 0000:00:02.0: [drm] plane_mask=0 [ 93.975179] i915 0000:00:02.0: [drm] connector_mask=0 [ 93.975180] i915 0000:00:02.0: [drm] encoder_mask=0 [ 93.975183] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 93.975185] i915 0000:00:02.0: [drm] connector[95]: eDP-1 [ 93.975187] i915 0000:00:02.0: [drm] crtc=(null) [ 93.975188] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 93.975190] i915 0000:00:02.0: [drm] connector[101]: DP-1 [ 93.975192] i915 0000:00:02.0: [drm] crtc=(null) [ 93.975193] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 93.975195] i915 0000:00:02.0: [drm] connector[109]: DP-2 [ 93.975197] i915 0000:00:02.0: [drm] crtc=(null) [ 93.975198] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 93.975200] i915 0000:00:02.0: [drm] connector[115]: DP-3 [ 93.975201] i915 0000:00:02.0: [drm] crtc=(null) [ 93.975203] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 94.879075] i915 0000:00:02.0: [drm] crtc[51]: pipe A [ 94.879078] i915 0000:00:02.0: [drm] enable=1 [ 94.879080] i915 0000:00:02.0: [drm] active=1 [ 94.879082] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 94.879084] i915 0000:00:02.0: [drm] planes_changed=0 [ 94.879086] i915 0000:00:02.0: [drm] mode_changed=0 [ 94.879087] i915 0000:00:02.0: [drm] active_changed=0 [ 94.879089] i915 0000:00:02.0: [drm] connectors_changed=0 [ 94.879091] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 94.879093] i915 0000:00:02.0: [drm] plane_mask=5 [ 94.879094] i915 0000:00:02.0: [drm] connector_mask=1 [ 94.879096] i915 0000:00:02.0: [drm] encoder_mask=0 [ 94.879100] i915 0000:00:02.0: [drm] mode: "": 0 533250 3840 3888 3920 4000 2160 2163 2168 2222 0x0 0xa [ 94.879102] i915 0000:00:02.0: [drm] crtc[72]: pipe B [ 94.879104] i915 0000:00:02.0: [drm] enable=0 [ 94.879106] i915 0000:00:02.0: [drm] active=0 [ 94.879107] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 94.879109] i915 0000:00:02.0: [drm] planes_changed=0 [ 94.879111] i915 0000:00:02.0: [drm] mode_changed=0 [ 94.879112] i915 0000:00:02.0: [drm] active_changed=0 [ 94.879114] i915 0000:00:02.0: [drm] connectors_changed=0 [ 94.879115] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 94.879117] i915 0000:00:02.0: [drm] plane_mask=0 [ 94.879119] i915 0000:00:02.0: [drm] connector_mask=0 [ 94.879120] i915 0000:00:02.0: [drm] encoder_mask=0 [ 94.879124] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 94.879125] i915 0000:00:02.0: [drm] crtc[93]: pipe C [ 94.879127] i915 0000:00:02.0: [drm] enable=0 [ 94.879129] i915 0000:00:02.0: [drm] active=0 [ 94.879130] i915 0000:00:02.0: [drm] self_refresh_active=0 [ 94.879132] i915 0000:00:02.0: [drm] planes_changed=0 [ 94.879134] i915 0000:00:02.0: [drm] mode_changed=0 [ 94.879135] i915 0000:00:02.0: [drm] active_changed=0 [ 94.879137] i915 0000:00:02.0: [drm] connectors_changed=0 [ 94.879138] i915 0000:00:02.0: [drm] color_mgmt_changed=0 [ 94.879140] i915 0000:00:02.0: [drm] plane_mask=0 [ 94.879142] i915 0000:00:02.0: [drm] connector_mask=0 [ 94.879143] i915 0000:00:02.0: [drm] encoder_mask=0 [ 94.879146] i915 0000:00:02.0: [drm] mode: "": 0 0 0 0 0 0 0 0 0 0 0x0 0x0 [ 94.879148] i915 0000:00:02.0: [drm] connector[95]: eDP-1 [ 94.879150] i915 0000:00:02.0: [drm] crtc=pipe A [ 94.879151] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 94.879153] i915 0000:00:02.0: [drm] connector[101]: DP-1 [ 94.879155] i915 0000:00:02.0: [drm] crtc=(null) [ 94.879156] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 94.879158] i915 0000:00:02.0: [drm] connector[109]: DP-2 [ 94.879160] i915 0000:00:02.0: [drm] crtc=(null) [ 94.879161] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 94.879163] i915 0000:00:02.0: [drm] connector[115]: DP-3 [ 94.879165] i915 0000:00:02.0: [drm] crtc=(null) [ 94.879166] i915 0000:00:02.0: [drm] self_refresh_aware=0 [ 94.879221] [drm:intel_reference_shared_dpll.isra.0] using DPLL 0 for pipe A [ 94.879285] [drm:intel_dump_cdclk_state] Changing CDCLK to 540000 kHz, VCO 8100000 kHz, ref 24000 kHz, bypass 24000 kHz, voltage level 2 [ 94.925201] [drm:gen8_de_irq_handler] hotplug event received, stat 0x01000000, dig 0x12101010, pins 0x00000010, long 0x00000010 [ 94.925208] [drm:intel_hpd_irq_handler] digital hpd on [ENCODER:94:DDI A] - long [ 95.044367] [drm:drm_crtc_vblank_on] crtc 0, vblank enabled 0, inmodeset 1 [ 95.044383] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-62)@ 95.047997 -> 95.048462 [e 3 us, 0 rep] [ 95.044421] [drm:intel_panel_actually_set_backlight] set backlight PWM = 48000 [ 95.065890] [drm] Reducing the compressed framebuffer size. This may lead to less power savings than a non-reduced-size. Try to increase stolen memory size if available in BIOS. [ 95.065909] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,585)@ 95.069522 -> 95.065134 [e 3 us, 0 rep] [ 95.065913] [drm:drm_vblank_restore] missed 1 vblanks in 16671266 ns, frame duration=16667604 ns, hw_diff=1 [ 95.065925] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,588)@ 95.069542 -> 95.065132 [e 3 us, 0 rep] [ 95.065931] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1765, diff=0, hw=433 hw_last=433 [ 95.065967] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,593)@ 95.069584 -> 95.065136 [e 3 us, 0 rep] [ 95.065972] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1765, diff=0, hw=433 hw_last=433 [ 95.077775] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-55)@ 95.081387 -> 95.081800 [e 3 us, 0 rep] [ 95.077782] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1765, diff=1, hw=434 hw_last=433 [ 95.094437] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-56)@ 95.098050 -> 95.098470 [e 3 us, 0 rep] [ 95.094445] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1766, diff=1, hw=435 hw_last=434 [ 95.094457] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-53)@ 95.098074 -> 95.098472 [e 2 us, 0 rep] [ 95.094463] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=1767, diff=0, hw=435 hw_last=435 [ 100.216208] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 100.219858 -> 100.203663 [e 3 us, 0 rep] [ 100.216213] [drm:drm_vblank_restore] missed 306 vblanks in 5105193402 ns, frame duration=16667604 ns, hw_diff=127 [ 100.216225] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,2159)@ 100.219882 -> 100.203687 [e 2 us, 0 rep] [ 100.216231] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=2073, diff=0, hw=562 hw_last=562 [ 100.238099] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-60)@ 100.241748 -> 100.242198 [e 3 us, 0 rep] [ 100.238106] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=2073, diff=1, hw=563 hw_last=562 [ 100.238119] [drm:drm_calc_vbltimestamp_from_scanoutpos] crtc 0 : v p(0,-57)@ 100.241776 -> 100.242204 [e 2 us, 0 rep] [ 100.238124] [drm:drm_update_vblank_count] updating vblank count on crtc 0: current=2074, diff=0, hw=563 hw_last=563 [ 113.120710] pool-clock-appl (1472) used greatest stack depth: 13096 bytes left