Launched in 2004, dmesgd aims to provide a user-submitted repository of searchable *BSD dmesgs. The dmesg(8) command displays the system message buffer's content, and during boot a copy is saved to /var/run/dmesg.boot. This buffer contains the operating system release, name and version, a list of devices identified, plus a whole host of other useful information. We hope others find this resource useful and further contribute to its growth. Contact us at [ admin at lists dot nycbug dot org ]. Note that this site is not a substitute for sending the dmesg directly to the respective project.


Date: 2023-05-08 17:46:42
From: herauthon
Description: NetBSD 9.3 - HPSys industrial P4 boot -x
Welcome to minicom 2.8

OPTIONS: I18n
Port /dev/ttyS0, 19:12:48

Press CTRL-A Z for help on special keys


>> NetBSD/x86 BIOS Boot, Revision 5.9 (from NetBSD 6.1.2)
>> Memory: 636/3397504 k
>
> help
commands are:
boot [xdNx:][filename] [-12acdqsvxz]
     (ex. "hd0a:netbsd.old -s"
ls [path]
dev xd[N[x]]:
consdev {pc|com[0123]|com[0123]kbd|auto}
vesa {modenum|on|off|enabled|disabled|list}
modules {on|off|enabled|disabled}
load {path_to_module}
multiboot [xdNx:][filename] [<args>]
userconf {command}
rndseed {path_to_rndseed_file}
help|?
quit
> boot -x
18476680+668736+1428416 [869943+1252488+894273]=0x16e11b0
[   1.0000000] Copyright (c) 1996, 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
[   1.0000000]     2006, 2007, 2008, 2009, 2010, 2011, 2012, 2013, 2014, 2015, 2016, 2017,
[   1.0000000]     2018, 2019, 2020, 2021, 2022
[   1.0000000]     The NetBSD Foundation, Inc.  All rights reserved.
[   1.0000000] Copyright (c) 1982, 1986, 1989, 1991, 1993
[   1.0000000]     The Regents of the University of California.  All rights reserved.

[   1.0000000] NetBSD 9.3 (GENERIC) #0: Thu Aug  4 15:30:37 UTC 2022
[   1.0000000]  [_EMAIL_XXXXXXXXXXXXXXXXX]:/usr/src/sys/arch/amd64/compile/GENERIC
[   1.0000000] total memory = 3318 MB
[   1.0000000] avail memory = 3198 MB
[   1.0000000] running cgd selftest aes-xts-256 aes-xts-512 done
[   1.0000000] No bootinfo commands at boot
[   1.0000030] efi: missing or invalid systbl
[   1.0000030] SMBIOS rev. 2.2 @ 0xf0800 (38 entries)
[   1.0000030] mainbus0 (root)
[   1.0000030] ACPI: RSDP 0x00000000000F8650 000014 (v00 IntelR)
[   1.0000030] ACPI: RSDT 0x00000000CF6E3040 000038 (v01 IntelR AWRDACPI 42302E31 AWRD 00000000)
[   1.0000030] ACPI: FACP 0x00000000CF6E30C0 000074 (v01 IntelR AWRDACPI 42302E31 AWRD 00000000)
[   1.0000030] ACPI: DSDT 0x00000000CF6E3180 0045E8 (v01 INTELR AWRDACPI 00001000 MSFT 0100000E)
[   1.0000030] ACPI: FACS 0x00000000CF6E0000 000040
[   1.0000030] ACPI: MCFG 0x00000000CF6E7880 00003C (v01 IntelR AWRDACPI 42302E31 AWRD 00000000)
[   1.0000030] ACPI: APIC 0x00000000CF6E77C0 000068 (v01 IntelR AWRDACPI 42302E31 AWRD 00000000)
[   1.0000030] ACPI: SSDT 0x00000000CF6E7900 00019E (v01 PmRef  Cpu0Ist  00003000 INTL 20051117)
[   1.0000030] ACPI: SSDT 0x00000000CF6E7D90 0001E3 (v01 PmRef  CpuPm    00003000 INTL 20051117)
[   1.0000030] ACPI: 3 ACPI AML tables successfully acquired and loaded
[   1.0000030] ioapic0 at mainbus0 apid 2
[   1.0000030] ioapic0: misconfigured as apic 0
[   1.0000030] ioapic0: remapped to apic 2
[   1.0000030] cpu0 at mainbus0 apid 0
[   1.0000030] cpu0: 64 page colors
[   1.0000030] cpu0: calibrating local timer
[   1.0000030] cpu0: apic clock running at 199 MHz
[   1.0000030] cpu0: Intel(R) Pentium(R) 4 CPU 3.20GHz, id 0xf65
[   1.0000030] cpu0: package 0, core 0, smt 0
[   1.0000030] cpu1 at mainbus0 apid 1
[   1.0000030] cpu1: 2 page colors
[   1.0000030] i82489_ipi_init: ESR 00000040
[   1.0000030] cpu1: Intel(R) Pentium(R) 4 CPU 3.20GHz, id 0xf65
[   1.0000030] cpu1: package 0, core 0, smt 1
[   1.0000030] acpi0 at mainbus0: Intel ACPICA 20190405
[   1.0000030] acpi0: MCFG: segment 0, bus 0-255, address 0x00000000e0000000
[   1.0000030] MCFG: MEMMAP: 0x0000000000000000-0x000000000009f3ff, size=0x000000000009f400, type=1(Memory)
[   1.0000030] MCFG: MEMMAP: 0x000000000009f400-0x000000000009ffff, size=0x0000000000000c00, type=2(Reserved)
[   1.0000030] MCFG: MEMMAP: 0x00000000000f0000-0x00000000000fffff, size=0x0000000000010000, type=2(Reserved)
[   1.0000030] MCFG: MEMMAP: 0x0000000000100000-0x00000000cf6dffff, size=0x00000000cf5e0000, type=1(Memory)
[   1.0000030] MCFG: MEMMAP: 0x00000000cf6e0000-0x00000000cf6e2fff, size=0x0000000000003000, type=4(NVS)
[   1.0000030] MCFG: MEMMAP: 0x00000000cf6e3000-0x00000000cf6effff, size=0x000000000000d000, type=3(ACPI)
[   1.0000030] MCFG: MEMMAP: 0x00000000cf6f0000-0x00000000cf6fffff, size=0x0000000000010000, type=2(Reserved)
[   1.0000030] MCFG: MEMMAP: 0x00000000e0000000-0x00000000efffffff, size=0x0000000010000000, type=2(Reserved)
[   1.0000030] acpi0: fixed power button present
[   1.0299339] acpi0: ACPI-Fast 24-bit timer
[   1.0299339] acpibut0 at acpi0 (PWRB, PNP0C0C): ACPI Power Button
[   1.0299339] SYSR (PNP0C02) at acpi0 not configured
[   1.0299339] attimer1 at acpi0 (TMR, PNP0100): io 0x40-0x43 irq 0
[   1.0299339] pcppi1 at acpi0 (SPKR, PNP0800): io 0x61
[   1.0299339] spkr0 at pcppi1: PC Speaker
[   1.0299339] wsbell at spkr0 not configured
[   1.0299339] midi0 at pcppi1: PC speaker
[   1.0299339] sysbeep0 at pcppi1
[   1.0299339] UAR1 (PNP0501) at acpi0 not configured
[   1.0299339] UAR2 (PNP0501) at acpi0 not configured
[   1.0299339] PMIO (PNP0C02) at acpi0 not configured
[   1.0299339] FWH (INT0800) at acpi0 not configured
[   1.0299339] EXPL (PNP0C02) at acpi0 not configured
[   1.0299339] MEM (PNP0C01) at acpi0 not configured
[   1.0299339] acpifan0 at acpi0 (FAN, PNP0C0B): ACPI Fan
[   1.0299339] acpitz0 at acpi0 (THRM): cpu0
[   1.0299339] acpitz0: polling interval 30.0 seconds
[   1.0299339] acpitz0: active cooling level 0: 50.0C
[   1.0299339] ACPI: Enabled 1 GPEs in block 00 to 1F
[   1.0299339] attimer1: attached to pcppi1
[   1.0299339] pci0 at mainbus0 bus 0: configuration mode 1
[   1.0299339] acpi0: MCFG: 000:00:0: invalid config space (cfg[0x100]=0x00000000, alias=false)
[   1.0299339] acpi0: MCFG: 000:00:0: Ok (cfg[0x100]=0x00000000 extconf=N)
[   1.0299339] acpi0: MCFG: 000:02:0: invalid config space (cfg[0x100]=0x00000000, alias=false)
[   1.0299339] acpi0: MCFG: 000:02:0: Ok (cfg[0x100]=0x00000000 extconf=N)
[   1.0299339] acpi0: MCFG: 000:28:0: Ok (cfg[0x100]=0x18010002 extconf=Y)
[   1.0299339] acpi0: MCFG: 000:28:1: Ok (cfg[0x100]=0x18010002 extconf=Y)
[   1.0299339] acpi0: MCFG: 000:28:2: Ok (cfg[0x100]=0x18010002 extconf=Y)
[   1.0299339] acpi0: MCFG: 000:28:3: Ok (cfg[0x100]=0x18010002 extconf=Y)
[   1.0299339] acpi0: MCFG: 000:28:4: Ok (cfg[0x100]=0x18010002 extconf=Y)
[   1.0299339] acpi0: MCFG: 000:28:5: Ok (cfg[0x100]=0x18010002 extconf=Y)
[   1.0299339] acpi0: MCFG: 000:29:0: invalid config space (cfg[0x100]=0x27c88086, alias=true)
[   1.0299339] acpi0: MCFG: 000:29:0: Ok (cfg[0x100]=0x27c88086 extconf=N)
[   1.0299339] acpi0: MCFG: 000:29:1: invalid config space (cfg[0x100]=0x27c98086, alias=true)
[   1.0299339] acpi0: MCFG: 000:29:1: Ok (cfg[0x100]=0x27c98086 extconf=N)
[   1.0299339] acpi0: MCFG: 000:29:2: invalid config space (cfg[0x100]=0x27ca8086, alias=true)
[   1.0299339] acpi0: MCFG: 000:29:2: Ok (cfg[0x100]=0x27ca8086 extconf=N)
[   1.0299339] acpi0: MCFG: 000:29:7: invalid config space (cfg[0x100]=0xffffffff, alias=false)
[   1.0299339] acpi0: MCFG: 000:29:7: Ok (cfg[0x100]=0xffffffff extconf=N)
[   1.0299339] acpi0: MCFG: 000:30:0: invalid config space (cfg[0x100]=0xffffffff, alias=false)
[   1.0299339] acpi0: MCFG: 000:30:0: Ok (cfg[0x100]=0xffffffff extconf=N)
[   1.0299339] acpi0: MCFG: 000:31:0: invalid config space (cfg[0x100]=0xffffffff, alias=false)
[   1.0299339] acpi0: MCFG: 000:31:0: Ok (cfg[0x100]=0xffffffff extconf=N)
[   1.0299339] acpi0: MCFG: 000:31:1: invalid config space (cfg[0x100]=0xffffffff, alias=false)
[   1.0299339] acpi0: MCFG: 000:31:1: Ok (cfg[0x100]=0xffffffff extconf=N)
[   1.0299339] acpi0: MCFG: 000:31:2: invalid config space (cfg[0x100]=0x27c08086, alias=true)
[   1.0299339] acpi0: MCFG: 000:31:2: Ok (cfg[0x100]=0x27c08086 extconf=N)
[   1.0299339] acpi0: MCFG: 000:31:3: invalid config space (cfg[0x100]=0xffffffff, alias=false)
[   1.0299339] acpi0: MCFG: 000:31:3: Ok (cfg[0x100]=0xffffffff extconf=N)
[   1.0299339] acpi0: MCFG: bus 0: valid devices
[   1.0299339] acpi0: MCFG: 000:00:0
[   1.0299339] acpi0: MCFG: 000:02:0
[   1.0299339] acpi0: MCFG: 000:28:0
[   1.0299339] acpi0: MCFG: 000:28:1
[   1.0299339] acpi0: MCFG: 000:28:2
[   1.0299339] acpi0: MCFG: 000:28:3
[   1.0299339] acpi0: MCFG: 000:28:4
[   1.0299339] acpi0: MCFG: 000:28:5
[   1.0299339] acpi0: MCFG: 000:29:0
[   1.0299339] acpi0: MCFG: 000:29:1
[   1.0299339] acpi0: MCFG: 000:29:2
[   1.0299339] acpi0: MCFG: 000:29:7
[   1.0299339] acpi0: MCFG: 000:30:0
[   1.0299339] acpi0: MCFG: 000:31:0
[   1.0299339] acpi0: MCFG: 000:31:1
[   1.0299339] acpi0: MCFG: 000:31:2
[   1.0299339] acpi0: MCFG: 000:31:3
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] pchb0 at pci0 dev 0 function 0: vendor 8086 product 2770 (rev. 0x02)
[   1.0299339] agp0 at pchb0: i915-family chipset
[   1.0299339] agp0: detected 7932k stolen memory
[   1.0299339] agp0: aperture at 0xd0000000, size 0x10000000
[   1.0299339] i915drmkms0 at pci0 dev 2 function 0: vendor 8086 product 2772 (rev. 0x02)
[   1.0299339] ppb0 at pci0 dev 28 function 0: vendor 8086 product 27d0 (rev. 0x01)
[   1.0299339] ppb0: PCI Express capability version 1 <Root Port of PCI-E Root Complex> x1 @ 2.5GT/s
[   1.0299339] pci1 at ppb0 bus 1
[   1.0299339] acpi0: MCFG: 001:00:0: Ok (cfg[0x100]=0x14010001 extconf=Y)
[   1.0299339] acpi0: MCFG: bus 1: valid devices
[   1.0299339] acpi0: MCFG: 001:00:0
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] wm0 at pci1 dev 0 function 0: Intel i82573E IAMT (rev. 0x03)
[   1.0299339] wm0: interrupting at msi0 vec 0
[   1.0299339] wm0: Ethernet address [_MAC_XXXXXXXXXX]
[   1.0299339] makphy0 at wm0 phy 1: Marvell 88E1111 Gigabit PHY, rev. 2
[   1.0299339] makphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[   1.0299339] ppb1 at pci0 dev 28 function 1: vendor 8086 product 27d2 (rev. 0x01)
[   1.0299339] ppb1: PCI Express capability version 1 <Root Port of PCI-E Root Complex> x1 @ 2.5GT/s
[   1.0299339] pci2 at ppb1 bus 2
[   1.0299339] acpi0: MCFG: 002:00:0: Ok (cfg[0x100]=0x14010001 extconf=Y)
[   1.0299339] acpi0: MCFG: bus 2: valid devices
[   1.0299339] acpi0: MCFG: 002:00:0
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] wm1 at pci2 dev 0 function 0: Intel i82573E IAMT (rev. 0x03)
[   1.0299339] wm1: interrupting at msi1 vec 0
[   1.0299339] wm1: Ethernet address [_MAC_XXXXXXXXXX]
[   1.0299339] makphy1 at wm1 phy 1: Marvell 88E1111 Gigabit PHY, rev. 2
[   1.0299339] makphy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[   1.0299339] ppb2 at pci0 dev 28 function 2: vendor 8086 product 27d4 (rev. 0x01)
[   1.0299339] ppb2: PCI Express capability version 1 <Root Port of PCI-E Root Complex> x1 @ 2.5GT/s
[   1.0299339] pci3 at ppb2 bus 3
[   1.0299339] acpi0: MCFG: 003:00:0: Ok (cfg[0x100]=0x14010001 extconf=Y)
[   1.0299339] acpi0: MCFG: bus 3: valid devices
[   1.0299339] acpi0: MCFG: 003:00:0
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] wm2 at pci3 dev 0 function 0: Intel i82573E IAMT (rev. 0x03)
[   1.0299339] wm2: interrupting at msi2 vec 0
[   1.0299339] wm2: Ethernet address [_MAC_XXXXXXXXXX]
[   1.0299339] makphy2 at wm2 phy 1: Marvell 88E1111 Gigabit PHY, rev. 2
[   1.0299339] makphy2: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[   1.0299339] ppb3 at pci0 dev 28 function 3: vendor 8086 product 27d6 (rev. 0x01)
[   1.0299339] ppb3: PCI Express capability version 1 <Root Port of PCI-E Root Complex> x1 @ 2.5GT/s
[   1.0299339] pci4 at ppb3 bus 4
[   1.0299339] acpi0: MCFG: 004:00:0: Ok (cfg[0x100]=0x14010001 extconf=Y)
[   1.0299339] acpi0: MCFG: bus 4: valid devices
[   1.0299339] acpi0: MCFG: 004:00:0
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] wm3 at pci4 dev 0 function 0: Intel i82573E IAMT (rev. 0x03)
[   1.0299339] wm3: interrupting at msi3 vec 0
[   1.0299339] wm3: Ethernet address [_MAC_XXXXXXXXXX]
[   1.0299339] makphy3 at wm3 phy 1: Marvell 88E1111 Gigabit PHY, rev. 2
[   1.0299339] makphy3: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[   1.0299339] ppb4 at pci0 dev 28 function 4: vendor 8086 product 27e0 (rev. 0x01)
[   1.0299339] ppb4: PCI Express capability version 1 <Root Port of PCI-E Root Complex> x1 @ 2.5GT/s
[   1.0299339] pci5 at ppb4 bus 5
[   1.0299339] acpi0: MCFG: 005:00:0: Ok (cfg[0x100]=0x14010001 extconf=Y)
[   1.0299339] acpi0: MCFG: bus 5: valid devices
[   1.0299339] acpi0: MCFG: 005:00:0
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] wm4 at pci5 dev 0 function 0: Intel i82573E IAMT (rev. 0x03)
[   1.0299339] wm4: interrupting at msi4 vec 0
[   1.0299339] wm4: Ethernet address [_MAC_XXXXXXXXXX]
[   1.0299339] makphy4 at wm4 phy 1: Marvell 88E1111 Gigabit PHY, rev. 2
[   1.0299339] makphy4: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[   1.0299339] ppb5 at pci0 dev 28 function 5: vendor 8086 product 27e2 (rev. 0x01)
[   1.0299339] ppb5: PCI Express capability version 1 <Root Port of PCI-E Root Complex> x1 @ 2.5GT/s
[   1.0299339] pci6 at ppb5 bus 6
[   1.0299339] acpi0: MCFG: 006:00:0: Ok (cfg[0x100]=0x14010001 extconf=Y)
[   1.0299339] acpi0: MCFG: bus 6: valid devices
[   1.0299339] acpi0: MCFG: 006:00:0
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] wm5 at pci6 dev 0 function 0: Intel i82573E IAMT (rev. 0x03)
[   1.0299339] wm5: interrupting at msi5 vec 0
[   1.0299339] wm5: Ethernet address [_MAC_XXXXXXXXXX]
[   1.0299339] makphy5 at wm5 phy 1: Marvell 88E1111 Gigabit PHY, rev. 2
[   1.0299339] makphy5: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-FDX, auto
[   1.0299339] uhci0 at pci0 dev 29 function 0: vendor 8086 product 27c8 (rev. 0x01)
[   1.0299339] uhci0: interrupting at ioapic0 pin 23
[   1.0299339] usb0 at uhci0: USB revision 1.0
[   1.0299339] uhci1 at pci0 dev 29 function 1: vendor 8086 product 27c9 (rev. 0x01)
[   1.0299339] uhci1: interrupting at ioapic0 pin 19
[   1.0299339] usb1 at uhci1: USB revision 1.0
[   1.0299339] uhci2 at pci0 dev 29 function 2: vendor 8086 product 27ca (rev. 0x01)
[   1.0299339] uhci2: interrupting at ioapic0 pin 18
[   1.0299339] usb2 at uhci2: USB revision 1.0
[   1.0299339] ehci0 at pci0 dev 29 function 7: vendor 8086 product 27cc (rev. 0x01)
[   1.0299339] ehci0: interrupting at ioapic0 pin 23
[   1.0299339] ehci0: 3 companion controllers, 2 ports each: uhci0 uhci1 uhci2
[   1.0299339] usb3 at ehci0: USB revision 2.0
[   1.0299339] ppb6 at pci0 dev 30 function 0: vendor 8086 product 244e (rev. 0xe1)
[   1.0299339] pci7 at ppb6 bus 7
[   1.0299339] acpi0: MCFG: bus 7: no valid devices.
[   1.0299339] acpi0: acpimcfg_map_bus done
[   1.0299339] ichlpcib0 at pci0 dev 31 function 0: vendor 8086 product 27b8 (rev. 0x01)
[   1.0299339] ichlpcib0: 24-bit timer
[   1.0299339] tco0 at ichlpcib0: TCO (watchdog) timer configured.
[   1.0299339] piixide0 at pci0 dev 31 function 1: Intel 82801GB/GR IDE Controller (ICH7) (rev. 0x01)
[   1.0299339] piixide0: primary channel interrupting at ioapic0 pin 14
[   1.0299339] atabus0 at piixide0 channel 0
[   1.0299339] piixide0: secondary channel ignored (disabled)
[   1.0299339] piixide1 at pci0 dev 31 function 2: Intel 82801GB/GR Serial ATA/Raid Controller (ICH7) (rev. 0x01)
[   1.0299339] piixide1: using ioapic0 pin 19 for native-PCI interrupt
[   1.0299339] atabus1 at piixide1 channel 0
[   1.0299339] atabus2 at piixide1 channel 1
[   1.0299339] ichsmb0 at pci0 dev 31 function 3: vendor 8086 product 27da (rev. 0x01)
[   1.0299339] ichsmb0: interrupting at ioapic0 pin 19
[   1.0299339] iic0 at ichsmb0: I2C bus
[   1.0299339] isa0 at ichlpcib0
[   1.0299339] com0 at isa0 port 0x3f8-0x3ff irq 4: ns16550a, working fifo
[   1.0299339] com0: console
[   1.0299339] com1 at isa0 port 0x2f8-0x2ff irq 3: ns16550a, working fifo
[   1.0299339] pckbc0 at isa0 port 0x60-0x64
[   1.0299339] pckbdprobe: reset error 5
[   1.0299339] pmsprobe: reset error 5
[   1.0299339] acpicpu0 at cpu0: ACPI CPU
[   1.0299339] acpicpu0: id 0, lapic id 0, cap 0x03fd, flags 0x00082e33
[   1.0299339] acpicpu1 at cpu1: ACPI CPU
[   1.0299339] acpicpu1: id 1, lapic id 1, cap 0x03fd, flags 0x00082e31
[   1.0299339] Initializing SSP: 7c040b181ba66814 83bb96924d16c03e 5020c2736397e9b d565a3e2de724495 86050e023eed13b6 d505ee36a306c9ce 3344150fa3bd85a6 5b25d076c1f7a58
[   1.0299339] cpu1: TSC skew=0 drift=-8
[   1.2373963] cpu1: running
[   1.2674107] TSC not known invariant on this CPU
[   1.3374341] acpicpu1: ACPI CPUs started
[   1.3774515] uhub0 at usb0: NetBSD (0000) UHCI root hub (0000), class 9/0, rev 1.00/1.00, addr 1
[   1.4774911] uhub1 at usb1: NetBSD (0000) UHCI root hub (0000), class 9/0, rev 1.00/1.00, addr 1
[   1.5875316] uhub2 at usb3: NetBSD (0000) EHCI root hub (0000), class 9/0, rev 2.00/1.00, addr 1
[   1.6875722] atapibus0 at atabus0: 2 targets
[   1.7475938] uhub3 at usb2: NetBSD (0000) UHCI root hub (0000), class 9/0, rev 1.00/1.00, addr 1
[   1.8576363] cd0 at atapibus0 drive 0: <TSSTcorpCD/DVDW SH-S182M, , SB03> cdrom removable
[   1.9576753] wd0 at atabus2 drive 1
[   1.9976940] wd0: <Hitachi HDT725032VLA380>
[   2.0377162] wd0: 298 GB, 620181 cyl, 16 head, 63 sec, 512 bytes/sect x 625142448 sectors
[   3.3882917] Searching for RAID components...
[   3.4433049] boot device: wd0
[   3.4776257] root on wd0a dumps on wd0b
[   3.5483939] root file system type: ffs
[   3.5956698] kern.module.path=/stand/amd64/9.3/modules
[   3.6559908] pci_mem_find: void region
[   3.6996732] pci_mem_find: void region
[   3.7433533] pci_mem_find: void region
[   3.7870357] i915drmkms0: map 4 failed
[   3.8307161] pci_mem_find: void region
[   3.8743982] i915drmkms0: map 5 failed
[   3.9180795] DRM debug in drm_minor_register:
[   3.9700813] DRM debug in drm_minor_register: new minor registered 64
[   4.0460030] DRM debug in drm_minor_register:
[   4.0959252] DRM debug in drm_minor_register: new minor registered 128
[   4.1749674] DRM debug in drm_minor_register:
[   4.2248900] DRM debug in drm_minor_register: new minor registered 0
[   4.3018514] DRM debug in i915_dump_device_info: i915 device info: gen=3, pciid=0x2772 rev=0x02 flags=has_hotplug,cursor_needs_physical,has_overlay,overlay_needs_phn
[   4.5098586] pci_mem_find: void region
[   4.5535398] pci_mem_find: void region
[   4.5972214] pci_mem_find: void region
[   4.6409023] pci_mem_find: void region
[   4.6845839] pci_mem_find: void region
[   4.7282662] pci_mem_find: void region
[   4.7719477] pci_mem_find: void region
[   4.8156282] pci_mem_find: void region
[   4.8593102] pci_mem_find: void region
[   4.9029921] pci_mem_find: void region
[   4.9466727] pci_mem_find: void region
[   4.9903548] DRM debug in intel_detect_pch: No PCH found.
[   5.0537970] kern info: [drm] Memory usable by graphics device = 256M
[   5.1297184] DRM debug in i915_gem_gtt_init: GMADR size = 256M
[   5.1983612] DRM debug in i915_gem_gtt_init: GTT stolen size = 7M
[   5.2701231] DRM debug in i915_gem_gtt_init: ppgtt mode: 0
[   5.3346056] DRM debug in intel_opregion_setup: graphic opregion physical addr: 0x0
[   5.4250877] DRM debug in intel_opregion_setup: ACPI OpRegion not supported!
[   5.5082905] DRM debug in intel_device_info_runtime_init: slice total: 0
[   5.5873332] DRM debug in intel_device_info_runtime_init: subslice total: 0
[   5.6694967] DRM debug in intel_device_info_runtime_init: subslice per slice: 0
[   5.7558183] DRM debug in intel_device_info_runtime_init: EU total: 0
[   5.8317408] DRM debug in intel_device_info_runtime_init: EU per subslice: 0
[   5.9149441] DRM debug in intel_device_info_runtime_init: has slice power gating: n
[   6.0054269] DRM debug in intel_device_info_runtime_init: has subslice power gating: n
[   6.0990309] DRM debug in intel_device_info_runtime_init: has EU power gating: n
[   6.1863929] kern info: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   6.2768753] kern info: [drm] Driver supports precise vblank timestamp query.
[   6.3611182] DRM debug in init_vbt_defaults: Set default to SSC at 100000 kHz
[   6.4453610] pci_mem_find: void region
[   6.4890425] DRM debug in validate_vbt: Using VBT from PCI ROM: $VBT LAKEPORT-G     d
[   6.5816057] DRM debug in parse_general_features: BDB_GENERAL_FEATURES int_tv_support 1 int_crt_support 0 lvds_use_ssc 0 lvds_ssc_freq 96000 display_clock_mode 0 fd0
[   6.7812925] DRM debug in parse_general_definitions: crt_ddc_bus_pin: 2
[   6.8592943] DRM debug in parse_sdvo_panel_data: Found SDVO panel mode in BIOS VBT tables:
[   6.9570573] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"1024x768" 0 65000 1024 1048 1184 1344 768 771 777 806 0x8 0xa
[   7.0953824] DRM debug in parse_sdvo_device_mapping: No SDVO device info is found in VBT
[   7.1910664] DRM debug in parse_device_mapping: no child dev is parsed from VBT
[   7.2773877] DRM debug in parse_driver_features: DRRS State Enabled:0
[   7.3533102] DRM debug in parse_psr: No PSR BDB found.
[   7.4136332] DRM debug in i915_gem_init_stolen: Memory reserved for graphics device: 7932K, usable: 7932K
[   7.5269968] DRM debug in intel_power_well_enable: enabling always-on
[   7.6029196] i915drmkms0: interrupting at ioapic0 pin 16 (i915drmkms0)
[   7.6798821] DRM debug in intel_modeset_init: 2 display pipes available.
[   7.7589243] DRM debug in intel_modeset_init: pipe A sprite A init failed: -19
[   7.8442069] DRM debug in intel_modeset_init: pipe B sprite B init failed: -19
[   7.9294890] DRM debug in intel_update_cdclk: Current CD clock rate: 400000 kHz
[   8.0158128] DRM debug in intel_update_max_cdclk: Max CD clock rate: 400000 kHz
[   8.1021347] DRM debug in intel_update_max_cdclk: Max dotclock rate: 720000 kHz
[   8.1884579] DRM debug in intel_modeset_readout_hw_state: [CRTC:20] hw state readout: enabled
[   8.2893406] DRM debug in intel_modeset_readout_hw_state: [CRTC:23] hw state readout: enabled
[   8.3902238] DRM debug in intel_modeset_readout_hw_state: [ENCODER:25:DAC-25] hw state readout: enabled, pipe A
[   8.5098281] DRM debug in intel_modeset_readout_hw_state: [CONNECTOR:24:VGA-1] hw state readout: enabled
[   8.6221519] DRM debug in drm_atomic_set_mode_for_crtc: Set [MODE:640x480] for CRTC state 0xffffc37ab306d808
[   8.7386360] DRM debug in drm_calc_timestamping_constants: crtc 20: hwmode: htotal 800, vtotal 525, vdisplay 480
[   8.8592804] DRM debug in drm_calc_timestamping_constants: crtc 20: clock 25200 kHz framedur 16666666 linedur 31746
[   8.9830436] DRM debug in drm_atomic_set_mode_for_crtc: Set [MODE:640x480] for CRTC state 0xffffc37b3b27c408
[   9.0995268] DRM debug in drm_calc_timestamping_constants: crtc 23: hwmode: htotal 800, vtotal 525, vdisplay 480
[   9.2201720] DRM debug in drm_calc_timestamping_constants: crtc 23: clock 25200 kHz framedur 16666666 linedur 31746
[   9.3439347] DRM debug in drm_vblank_on: crtc 0, vblank enabled 0, inmodeset 1
[   9.3439347] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 0 : v 0x5 p(144,8)@ 9.429221 -> 9.428961 [e 1 us, 0 rep]
[   9.5644242] DRM debug in intel_dump_pipe_config: [CRTC:20][setup_hw_state] config 0xffffc37ab306d808 for pipe A
[   9.6850663] DRM debug in intel_dump_pipe_config: cpu_transcoder: A
[   9.7589092] DRM debug in intel_dump_pipe_config: pipe bpp: 0, dithering: 0
[   9.8410713] DRM debug in intel_dump_pipe_config: fdi/pch: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[   9.9710753] DRM debug in intel_dump_pipe_config: dp: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[  10.0958794] DRM debug in intel_dump_pipe_config: dp: 0, lanes: 0, gmch_m2: 0, gmch_n2: 0, link_m2: 0, link_n2: 0, tu2: 0
[  10.2258834] DRM debug in intel_dump_pipe_config: audio: 0, infoframes: 0
[  10.3059670] DRM debug in intel_dump_pipe_config: requested mode:
[  10.3777295] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"640x480" 60 25200 640 656 752 800 480 490 492 525 0x40 0x5
[  10.5129335] DRM debug in intel_dump_pipe_config: adjusted mode:
[  10.5836559] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"640x480" 60 25200 640 656 752 800 480 490 492 525 0x40 0x5
[  10.7188603] DRM debug in intel_dump_crtc_timings: crtc timings: 25200 640 656 752 800 480 490 492 525, type: 0x40 flags: 0x5
[  10.8530240] DRM debug in intel_dump_pipe_config: port clock: 25200
[  10.9268666] DRM debug in intel_dump_pipe_config: pipe src size: 1x1
[  11.0017485] DRM debug in intel_dump_pipe_config: num_scalers: 0, scaler_users: 0x0, scaler_id: 0
[  11.1067923] DRM debug in intel_dump_pipe_config: gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
[  11.2409562] DRM debug in intel_dump_pipe_config: pch pfit: pos: 0x00000000, size: 0x00000000, disabled
[  11.3522405] DRM debug in intel_dump_pipe_config: ips: 0
[  11.4146428] DRM debug in intel_dump_pipe_config: double wide: 0
[  11.4853650] DRM debug in intel_dump_pipe_config: dpll_hw_state: dpll: 0x84800003, dpll_md: 0x0, fp0: 0x31108, fp1: 0x31108
[  11.6174493] DRM debug in intel_dump_pipe_config: planes on this crtc
[  11.6933718] DRM debug in intel_dump_pipe_config: STANDARD PLANE:18 plane: 0.0 idx: 0 disabled, scaler_id = 0
[  11.8108952] DRM debug in intel_dump_pipe_config: CURSOR PLANE:19 plane: 0.1 idx: 1 disabled, scaler_id = 0
[  11.9263397] DRM debug in drm_vblank_on: crtc 1, vblank enabled 0, inmodeset 1
[  11.9263397] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(117,454)@ 12.11624 -> 11.997206 [e 1 us, 0 rep]
[  11.9263397] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(280,90)@ 12.149949 -> 12.147080 [e 1 us, 0 rep]
[  11.9263397] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(378,251)@ 12.288273 -> 12.280290 [e 1 us, 0 rep]
[  12.4276372] DRM debug in drm_vblank_enable: enabling vblank on crtc 1, ret: 0
[  12.4276372] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(364,35)@ 12.512920 -> 12.511795 [e 1 us, 0 rep]
[  12.4276372] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(523,196)@ 12.651245 -> 12.645002 [e 1 us, 0 rep]
[  12.4276372] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-730,-19)@ 12.790610 -> 12.791242 [e 1 us, 0 rep]
[  12.4276372] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=1, diff=136, hw=22817 hw_last=22681
[  13.0672565] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(17,303)@ 13.67264 -> 13.57644 [e 1 us, 0 rep]
[  13.0672565] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(646,168)@ 13.203503 -> 13.198144 [e 1 us, 0 rep]
[  13.0672565] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(253,477)@ 13.342868 -> 13.327716 [e 1 us, 0 rep]
[  13.0672565] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=148 refcount 1, vblirq 1
[  13.0672565] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=137, diff=1, hw=22965 hw_last=22817
[  13.7453592] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(717,245)@ 13.745379 -> 13.737572 [e 2 us, 0 rep]
[  13.7453592] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(604,26)@ 13.884725 -> 13.883876 [e 1 us, 0 rep]
[  13.7453592] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(771,187)@ 14.23050 -> 14.17083 [e 1 us, 0 rep]
[  13.7453592] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  13.7453592] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=138, diff=1, hw=23149 hw_last=22965
[  14.4234618] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(11,186)@ 14.423473 -> 14.417568 [e 1 us, 0 rep]
[  14.4234618] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(546,345)@ 14.561788 -> 14.550814 [e 1 us, 0 rep]
[  14.4234618] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(85,129)@ 14.701152 -> 14.697054 [e 1 us, 0 rep]
[  14.4234618] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  14.4234618] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=139, diff=1, hw=23332 hw_last=23149
[  15.1026026] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(412,274)@ 15.102616 -> 15.93901 [e 1 us, 0 rep]
[  15.1026026] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(74,434)@ 15.240930 -> 15.227149 [e 1 us, 0 rep]
[  15.1026026] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(109,70)@ 15.379254 -> 15.377027 [e 1 us, 0 rep]
[  15.1026026] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  15.1026026] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=140, diff=1, hw=23515 hw_last=23332
[  15.7807047] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(166,216)@ 15.780722 -> 15.773858 [e 1 us, 0 rep]
[  15.7807047] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-386,-2)@ 15.920072 -> 15.920151 [e 1 us, 0 rep]
[  15.7807047] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(697,305)@ 16.59436 -> 16.49726 [e 1 us, 0 rep]
[  15.7807047] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  15.7807047] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=141, diff=1, hw=23698 hw_last=23515
[  16.4598469] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(751,303)@ 16.459860 -> 16.450211 [e 2 us, 0 rep]
[  16.4598469] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(678,85)@ 16.599214 -> 16.596488 [e 1 us, 0 rep]
[  16.4598469] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(773,246)@ 16.737538 -> 16.729698 [e 1 us, 0 rep]
[  16.4598469] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  16.4598469] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=142, diff=1, hw=23881 hw_last=23698
[  17.1400278] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(413,14)@ 17.140041 -> 17.139580 [e 1 us, 0 rep]
[  17.1400278] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(179,174)@ 17.278355 -> 17.272825 [e 1 us, 0 rep]
[  17.1400278] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(456,482)@ 17.417720 -> 17.402400 [e 1 us, 0 rep]
[  17.1400278] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  17.1400278] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=143, diff=1, hw=24064 hw_last=23881
[  17.8202103] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(657,249)@ 17.820220 -> 17.812290 [e 1 us, 0 rep]
[  17.8202103] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(79,32)@ 17.959577 -> 17.958558 [e 1 us, 0 rep]
[  17.8202103] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(729,45)@ 18.96861 -> 18.95404 [e 1 us, 0 rep]
[  17.8202103] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  17.8202103] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=144, diff=1, hw=24248 hw_last=24064
[  18.4962320] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(228,422)@ 18.496249 -> 18.482843 [e 1 us, 0 rep]
[  18.4962320] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(525,203)@ 18.635599 -> 18.629134 [e 1 us, 0 rep]
[  18.4962320] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-755,-12)@ 18.774964 -> 18.775374 [e 1 us, 0 rep]
[  18.4962320] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  18.4962320] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=145, diff=1, hw=24431 hw_last=24248
[  19.1784947] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(5,427)@ 19.178507 -> 19.164952 [e 1 us, 0 rep]
[  19.1784947] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(408,439)@ 19.315782 -> 19.301830 [e 1 us, 0 rep]
[  19.1784947] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(570,222)@ 19.455146 -> 19.448075 [e 1 us, 0 rep]
[  19.1784947] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  19.1784947] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=146, diff=1, hw=24614 hw_last=24431
[  19.8576369] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-120,-9)@ 19.857653 -> 19.857943 [e 1 us, 0 rep]
[  19.8576369] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(221,297)@ 19.997003 -> 19.987566 [e 1 us, 0 rep]
[  19.8576369] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(504,80)@ 20.136368 -> 20.133808 [e 1 us, 0 rep]
[  19.8576369] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  19.8576369] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=147, diff=1, hw=24798 hw_last=24614
[  20.5378183] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(595,225)@ 20.537829 -> 20.530663 [e 1 us, 0 rep]
[  20.5378183] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(87,8)@ 20.677187 -> 20.676929 [e 1 us, 0 rep]
[  20.5378183] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(426,399)@ 20.813430 -> 20.800746 [e 1 us, 0 rep]
[  20.5378183] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  20.5378183] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=148, diff=1, hw=24980 hw_last=24798
[  21.2159209] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(451,167)@ 21.215936 -> 21.210617 [e 1 us, 0 rep]
[  21.2159209] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(77,474)@ 21.355288 -> 21.340237 [e 1 us, 0 rep]
[  21.2159209] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(193,110)@ 21.493612 -> 21.490112 [e 1 us, 0 rep]
[  21.2159209] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  21.2159209] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=149, diff=1, hw=25164 hw_last=24980
[  21.8961029] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(404,402)@ 21.896113 -> 21.883335 [e 1 us, 0 rep]
[  21.8961029] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(700,184)@ 22.35470 -> 22.29601 [e 1 us, 0 rep]
[  21.8961029] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(420,198)@ 22.172753 -> 22.166451 [e 1 us, 0 rep]
[  21.8961029] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  21.8961029] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=150, diff=1, hw=25347 hw_last=25164
[  22.5752445] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(473,491)@ 22.575260 -> 22.559654 [e 1 us, 0 rep]
[  22.5752445] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(107,273)@ 22.714611 -> 22.705941 [e 1 us, 0 rep]
[  22.5752445] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(385,56)@ 22.853976 -> 22.852183 [e 1 us, 0 rep]
[  22.5752445] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  22.5752445] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=151, diff=1, hw=25531 hw_last=25347
[  23.2554265] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(130,202)@ 23.255441 -> 23.249024 [e 1 us, 0 rep]
[  23.2554265] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-92,-16)@ 23.394794 -> 23.395306 [e 1 us, 0 rep]
[  23.2554265] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(194,292)@ 23.534159 -> 23.524881 [e 1 us, 0 rep]
[  23.2554265] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  23.2554265] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=152, diff=1, hw=25714 hw_last=25531
[  23.9366483] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(119,60)@ 23.936664 -> 23.934755 [e 1 us, 0 rep]
[  23.9366483] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(334,219)@ 24.74975 -> 24.68010 [e 1 us, 0 rep]
[  23.9366483] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(286,233)@ 24.212260 -> 24.204852 [e 1 us, 0 rep]
[  23.9366483] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  23.9366483] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=153, diff=1, hw=25897 hw_last=25714
[  24.6147504] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(444,0)@ 24.614761 -> 24.614743 [e 1 us, 0 rep]
[  24.6147504] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(332,13)@ 24.752038 -> 24.751612 [e 1 us, 0 rep]
[  24.6147504] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(272,174)@ 24.890361 -> 24.884827 [e 1 us, 0 rep]
[  24.6147504] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  24.6147504] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=154, diff=1, hw=26080 hw_last=25897
[  25.2928519] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(318,471)@ 25.292896 -> 25.277931 [e 1 us, 0 rep]
[  25.2928519] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(108,249)@ 25.432221 -> 25.424312 [e 1 us, 0 rep]
[  25.2928519] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(238,32)@ 25.571584 -> 25.570558 [e 1 us, 0 rep]
[  25.2928519] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  25.2928519] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=155, diff=1, hw=26264 hw_last=26080
[  25.9730345] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(237,188)@ 25.973122 -> 25.967145 [e 1 us, 0 rep]
[  25.9730345] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(468,484)@ 26.112401 -> 26.97018 [e 1 us, 0 rep]
[  25.9730345] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(604,120)@ 26.250726 -> 26.246893 [e 1 us, 0 rep]
[  25.9730345] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  25.9730345] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=156, diff=1, hw=26447 hw_last=26264
[  26.6532173] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(721,413)@ 26.653233 -> 26.640094 [e 1 us, 0 rep]
[  26.6532173] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(292,195)@ 26.792584 -> 26.786382 [e 1 us, 0 rep]
[  26.6532173] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-333,-21)@ 26.931948 -> 26.932628 [e 1 us, 0 rep]
[  26.6532173] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  26.6532173] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=157, diff=1, hw=26631 hw_last=26447
[  27.3354794] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(391,418)@ 27.335491 -> 27.322206 [e 1 us, 0 rep]
[  27.3354794] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(440,200)@ 27.474846 -> 27.468480 [e 1 us, 0 rep]
[  27.3354794] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-91,-16)@ 27.614211 -> 27.614722 [e 1 us, 0 rep]
[  27.3354794] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  27.3354794] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=158, diff=1, hw=26815 hw_last=26631
[  28.0167101] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(713,276)@ 28.16717 -> 28.7926 [e 1 us, 0 rep]
[  28.0167101] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(432,141)@ 28.152947 -> 28.148454 [e 1 us, 0 rep]
[  28.0167101] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(742,449)@ 28.292312 -> 28.278029 [e 1 us, 0 rep]
[  28.0167101] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  28.0167101] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=159, diff=1, hw=26997 hw_last=26815
[  28.6948025] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(123,217)@ 28.694813 -> 28.687919 [e 1 us, 0 rep]
[  28.6948025] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-315,0)@ 28.834171 -> 28.834183 [e 1 us, 0 rep]
[  28.6948025] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(551,160)@ 28.972495 -> 28.967394 [e 1 us, 0 rep]
[  28.6948025] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  28.6948025] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=160, diff=1, hw=27181 hw_last=26997
[  29.3749853] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(282,453)@ 29.374998 -> 29.360606 [e 1 us, 0 rep]
[  29.3749853] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(163,235)@ 29.514352 -> 29.506885 [e 1 us, 0 rep]
[  29.3749853] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(384,18)@ 29.653716 -> 29.653129 [e 1 us, 0 rep]
[  29.3749853] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  29.3749853] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=161, diff=1, hw=27365 hw_last=27181
[  30.0551664] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(264,163)@ 30.55176 -> 30.49991 [e 1 us, 0 rep]
[  30.0551664] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(249,176)@ 30.192454 -> 30.186857 [e 1 us, 0 rep]
[  30.0551664] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(626,484)@ 30.331819 -> 30.316429 [e 1 us, 0 rep]
[  30.0551664] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  30.0551664] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=162, diff=1, hw=27547 hw_last=27365
[  30.7343089] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(364,252)@ 30.734323 -> 30.726308 [e 1 us, 0 rep]
[  30.7343089] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(162,34)@ 30.873676 -> 30.872590 [e 1 us, 0 rep]
[  30.7343089] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(252,195)@ 31.12000 -> 31.5800 [e 1 us, 0 rep]
[  30.7343089] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  30.7343089] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=163, diff=1, hw=27731 hw_last=27547
[  31.4113709] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(47,46)@ 31.411383 -> 31.409921 [e 1 us, 0 rep]
[  31.4113709] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(399,58)@ 31.548657 -> 31.546800 [e 1 us, 0 rep]
[  31.4113709] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(515,219)@ 31.686982 -> 31.680009 [e 1 us, 0 rep]
[  31.4113709] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  31.4113709] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=164, diff=1, hw=27913 hw_last=27731
[  32.0894727] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-591,-12)@ 32.89485 -> 32.89890 [e 1 us, 0 rep]
[  32.0894727] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(764,146)@ 32.227800 -> 32.223135 [e 1 us, 0 rep]
[  32.0894727] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(203,455)@ 32.367164 -> 32.352711 [e 1 us, 0 rep]
[  32.0894727] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  32.0894727] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=165, diff=1, hw=28096 hw_last=27913
[  32.7696550] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(373,222)@ 32.769664 -> 32.762602 [e 1 us, 0 rep]
[  32.7696550] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(661,4)@ 32.909022 -> 32.908868 [e 1 us, 0 rep]
[  32.7696550] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(516,18)@ 33.46306 -> 33.45714 [e 1 us, 0 rep]
[  32.7696550] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  32.7696550] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=166, diff=1, hw=28280 hw_last=28096
[  33.4456772] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(462,394)@ 33.445690 -> 33.433164 [e 1 us, 0 rep]
[  33.4456772] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(432,176)@ 33.585044 -> 33.579440 [e 1 us, 0 rep]
[  33.4456772] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(717,484)@ 33.724409 -> 33.709015 [e 1 us, 0 rep]
[  33.4456772] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  33.4456772] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=167, diff=1, hw=28462 hw_last=28280
[  34.1268987] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(783,251)@ 34.126908 -> 34.118909 [e 1 us, 0 rep]
[  34.1268987] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(278,34)@ 34.266266 -> 34.265175 [e 1 us, 0 rep]
[  34.1268987] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(381,195)@ 34.404590 -> 34.398385 [e 1 us, 0 rep]
[  34.1268987] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  34.1268987] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=168, diff=1, hw=28646 hw_last=28462
[  34.8070804] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(686,487)@ 34.807092 -> 34.791604 [e 2 us, 0 rep]
[  34.8070804] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(788,269)@ 34.946448 -> 34.937877 [e 1 us, 0 rep]
[  34.8070804] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(339,53)@ 35.85813 -> 35.84117 [e 1 us, 0 rep]
[  34.8070804] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  34.8070804] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=169, diff=1, hw=28830 hw_last=28646
[  35.4851823] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(487,428)@ 35.485191 -> 35.471585 [e 1 us, 0 rep]
[  35.4851823] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(138,211)@ 35.624550 -> 35.617846 [e 1 us, 0 rep]
[  35.4851823] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-443,-5)@ 35.763914 -> 35.764090 [e 1 us, 0 rep]
[  35.4851823] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  35.4851823] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=170, diff=1, hw=29013 hw_last=28830
[  36.1664054] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(160,287)@ 36.166418 -> 36.157301 [e 1 us, 0 rep]
[  36.1664054] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(102,69)@ 36.305772 -> 36.303578 [e 1 us, 0 rep]
[  36.1664054] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(113,230)@ 36.444096 -> 36.436790 [e 1 us, 0 rep]
[  36.1664054] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  36.1664054] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=171, diff=1, hw=29196 hw_last=29013
[  36.8465877] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-435,-2)@ 36.846597 -> 36.846678 [e 1 us, 0 rep]
[  36.8465877] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(620,304)@ 36.985954 -> 36.976279 [e 1 us, 0 rep]
[  36.8465877] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(143,88)@ 37.125319 -> 37.122520 [e 1 us, 0 rep]
[  36.8465877] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  36.8465877] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=172, diff=1, hw=29380 hw_last=29196
[  37.5267685] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(332,233)@ 37.526781 -> 37.519371 [e 1 us, 0 rep]
[  37.5267685] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(340,15)@ 37.666136 -> 37.665647 [e 1 us, 0 rep]
[  37.5267685] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(369,176)@ 37.804460 -> 37.798858 [e 1 us, 0 rep]
[  37.5267685] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  37.5267685] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=173, diff=1, hw=29563 hw_last=29380
[  38.2069508] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(452,468)@ 38.206960 -> 38.192085 [e 1 us, 0 rep]
[  38.2069508] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(87,251)@ 38.346319 -> 38.338347 [e 1 us, 0 rep]
[  38.2069508] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(87,412)@ 38.484642 -> 38.471559 [e 1 us, 0 rep]
[  38.2069508] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  38.2069508] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=174, diff=1, hw=29746 hw_last=29563
[  38.8860935] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(478,32)@ 38.886106 -> 38.885072 [e 1 us, 0 rep]
[  38.8860935] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(212,192)@ 39.24421 -> 39.18317 [e 1 us, 0 rep]
[  38.8860935] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(24,206)@ 39.161705 -> 39.155164 [e 1 us, 0 rep]
[  38.8860935] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  38.8860935] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=175, diff=1, hw=29929 hw_last=29746
[  39.5631545] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(717,350)@ 39.563164 -> 39.552025 [e 1 us, 0 rep]
[  39.5631545] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(356,133)@ 39.702523 -> 39.698287 [e 1 us, 0 rep]
[  39.5631545] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(616,441)@ 39.841887 -> 39.827863 [e 1 us, 0 rep]
[  39.5631545] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  39.5631545] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=176, diff=1, hw=30112 hw_last=29929
[  40.2443776] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(633,209)@ 40.244393 -> 40.237733 [e 1 us, 0 rep]
[  40.2443776] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-677,-8)@ 40.383744 -> 40.384025 [e 1 us, 0 rep]
[  40.2443776] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(567,299)@ 40.523109 -> 40.513595 [e 1 us, 0 rep]
[  40.2443776] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  40.2443776] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=177, diff=1, hw=30296 hw_last=30112
[  40.9255988] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(638,66)@ 40.925609 -> 40.923488 [e 1 us, 0 rep]
[  40.9255988] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(773,226)@ 41.63927 -> 41.56722 [e 1 us, 0 rep]
[  40.9255988] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(602,240)@ 41.201211 -> 41.193568 [e 1 us, 0 rep]
[  40.9255988] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  40.9255988] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=178, diff=1, hw=30479 hw_last=30296
[  41.6037018] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(678,8)@ 41.603717 -> 41.603437 [e 1 us, 0 rep]
[  41.6037018] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(645,20)@ 41.740989 -> 41.740328 [e 1 us, 0 rep]
[  41.6037018] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(715,181)@ 41.879313 -> 41.873538 [e 1 us, 0 rep]
[  41.6037018] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  41.6037018] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=179, diff=1, hw=30662 hw_last=30479
[  42.2818031] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(258,474)@ 42.281815 -> 42.266757 [e 1 us, 0 rep]
[  42.2818031] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(390,256)@ 42.421171 -> 42.413028 [e 1 us, 0 rep]
[  42.2818031] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(638,39)@ 42.560535 -> 42.559271 [e 1 us, 0 rep]
[  42.2818031] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  42.2818031] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=180, diff=1, hw=30846 hw_last=30662
[  42.9619850] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(85,185)@ 42.961998 -> 42.956121 [e 1 us, 0 rep]
[  42.9619850] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(727,344)@ 43.100313 -> 43.89364 [e 1 us, 0 rep]
[  42.9619850] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-138,-19)@ 43.238636 -> 43.239245 [e 1 us, 0 rep]
[  42.9619850] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  42.9619850] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=181, diff=1, hw=31029 hw_last=30846
[  43.6421673] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(283,420)@ 43.642177 -> 43.628832 [e 1 us, 0 rep]
[  43.6421673] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(561,202)@ 43.781534 -> 43.775099 [e 1 us, 0 rep]
[  43.6421673] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-658,-13)@ 43.920899 -> 43.921338 [e 1 us, 0 rep]
[  43.6421673] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  43.6421673] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=182, diff=1, hw=31213 hw_last=31029
[  44.3244289] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(214,426)@ 44.324444 -> 44.310912 [e 1 us, 0 rep]
[  44.3244289] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(787,207)@ 44.463797 -> 44.457194 [e 1 us, 0 rep]
[  44.3244289] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-576,-8)@ 44.603161 -> 44.603438 [e 1 us, 0 rep]
[  44.3244289] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  44.3244289] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=183, diff=1, hw=31397 hw_last=31213
[  45.0056515] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(448,283)@ 45.5662 -> 44.996660 [e 1 us, 0 rep]
[  45.0056515] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(348,296)@ 45.142939 -> 45.133529 [e 1 us, 0 rep]
[  45.0056515] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(534,79)@ 45.282303 -> 45.279774 [e 1 us, 0 rep]
[  45.0056515] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  45.0056515] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=184, diff=1, hw=31580 hw_last=31397
[  45.6837541] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(111,225)@ 45.683767 -> 45.676620 [e 1 us, 0 rep]
[  45.6837541] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(694,6)@ 45.823120 -> 45.822902 [e 1 us, 0 rep]
[  45.6837541] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(707,20)@ 45.960406 -> 45.959743 [e 1 us, 0 rep]
[  45.6837541] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  45.6837541] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=185, diff=1, hw=31763 hw_last=31580
[  46.3618557] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(584,165)@ 46.361865 -> 46.356604 [e 1 us, 0 rep]
[  46.3618557] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(104,473)@ 46.501223 -> 46.486203 [e 1 us, 0 rep]
[  46.3618557] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(302,256)@ 46.640586 -> 46.632447 [e 1 us, 0 rep]
[  46.3618557] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  46.3618557] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=186, diff=1, hw=31946 hw_last=31763
[  47.0430780] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(76,24)@ 47.43090 -> 47.42325 [e 1 us, 0 rep]
[  47.0430780] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(683,266)@ 47.178284 -> 47.169812 [e 1 us, 0 rep]
[  47.0430780] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(231,50)@ 47.317649 -> 47.316052 [e 1 us, 0 rep]
[  47.0430780] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  47.0430780] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=187, diff=1, hw=32129 hw_last=31946
[  47.7190989] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(110,195)@ 47.719108 -> 47.712914 [e 1 us, 0 rep]
[  47.7190989] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-293,-22)@ 47.858467 -> 47.859177 [e 1 us, 0 rep]
[  47.7190989] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(163,433)@ 47.998871 -> 47.985118 [e 1 us, 0 rep]
[  47.7190989] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  47.7190989] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=188, diff=1, hw=32312 hw_last=32129
[  48.4013618] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(711,200)@ 48.401374 -> 48.394997 [e 1 us, 0 rep]
[  48.4013618] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-157,-17)@ 48.540729 -> 48.541275 [e 1 us, 0 rep]
[  48.4013618] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(310,438)@ 48.681133 -> 48.667216 [e 1 us, 0 rep]
[  48.4013618] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  48.4013618] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=189, diff=1, hw=32496 hw_last=32312
[  49.0836245] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(599,205)@ 49.83634 -> 49.77103 [e 1 us, 0 rep]
[  49.0836245] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(423,218)@ 49.220911 -> 49.213974 [e 1 us, 0 rep]
[  49.0836245] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(646,1)@ 49.360275 -> 49.360218 [e 1 us, 0 rep]
[  49.0836245] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  49.0836245] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=190, diff=1, hw=32680 hw_last=32496
[  49.7606857] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-81,0)@ 49.760698 -> 49.760702 [e 1 us, 0 rep]
[  49.7606857] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(177,12)@ 49.897972 -> 49.897584 [e 1 us, 0 rep]
[  49.7606857] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(332,173)@ 50.36297 -> 50.30792 [e 1 us, 0 rep]
[  49.7606857] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  49.7606857] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=191, diff=1, hw=32862 hw_last=32680
[  50.4367082] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(95,171)@ 50.436717 -> 50.431285 [e 1 us, 0 rep]
[  50.4367082] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(236,331)@ 50.575035 -> 50.564518 [e 1 us, 0 rep]
[  50.4367082] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(429,114)@ 50.714399 -> 50.710763 [e 1 us, 0 rep]
[  50.4367082] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  50.4367082] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=192, diff=1, hw=33045 hw_last=32862
[  51.1168891] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(90,407)@ 51.116902 -> 51.103978 [e 1 us, 0 rep]
[  51.1168891] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(711,41)@ 51.255217 -> 51.253887 [e 1 us, 0 rep]
[  51.1168891] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(749,202)@ 51.393541 -> 51.387099 [e 1 us, 0 rep]
[  51.1168891] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  51.1168891] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=193, diff=1, hw=33228 hw_last=33045
[  51.7960313] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-418,-28)@ 51.796051 -> 51.796956 [e 1 us, 0 rep]
[  51.7960313] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(614,424)@ 51.936439 -> 51.922954 [e 1 us, 0 rep]
[  51.7960313] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(128,208)@ 52.75803 -> 52.69195 [e 1 us, 0 rep]
[  51.7960313] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  51.7960313] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=194, diff=1, hw=33412 hw_last=33228
[  52.4762135] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(176,206)@ 52.476226 -> 52.469680 [e 1 us, 0 rep]
[  52.4762135] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-743,-11)@ 52.615580 -> 52.615959 [e 1 us, 0 rep]
[  52.4762135] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(560,443)@ 52.755985 -> 52.741899 [e 1 us, 0 rep]
[  52.4762135] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  52.4762135] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=195, diff=1, hw=33595 hw_last=33412
[  53.1584756] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(709,210)@ 53.158485 -> 53.151790 [e 1 us, 0 rep]
[  53.1584756] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-568,-6)@ 53.297843 -> 53.298056 [e 1 us, 0 rep]
[  53.1584756] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(610,301)@ 53.437208 -> 53.427628 [e 1 us, 0 rep]
[  53.1584756] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  53.1584756] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=196, diff=1, hw=33779 hw_last=33595
[  53.8396977] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(235,69)@ 53.839710 -> 53.837511 [e 1 us, 0 rep]
[  53.8396977] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(136,229)@ 53.978026 -> 53.970751 [e 1 us, 0 rep]
[  53.8396977] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(274,12)@ 54.117389 -> 54.116997 [e 1 us, 0 rep]
[  53.8396977] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  53.8396977] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=197, diff=1, hw=33963 hw_last=33779
[  54.5188396] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(345,157)@ 54.518851 -> 54.513853 [e 1 us, 0 rep]
[  54.5188396] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(572,464)@ 54.658207 -> 54.643455 [e 1 us, 0 rep]
[  54.5188396] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(25,248)@ 54.797571 -> 54.789697 [e 1 us, 0 rep]
[  54.5188396] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  54.5188396] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=198, diff=1, hw=34146 hw_last=33963
[  55.1990216] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(239,393)@ 55.199034 -> 55.186549 [e 1 us, 0 rep]
[  55.1990216] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(250,175)@ 55.338389 -> 55.332824 [e 1 us, 0 rep]
[  55.1990216] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(530,483)@ 55.477753 -> 55.462399 [e 1 us, 0 rep]
[  55.1990216] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  55.1990216] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=199, diff=1, hw=34329 hw_last=34146
[  55.8802434] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(601,250)@ 55.880253 -> 55.872293 [e 1 us, 0 rep]
[  55.8802434] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(284,33)@ 56.19612 -> 56.18553 [e 1 us, 0 rep]
[  55.8802434] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(565,424)@ 56.155855 -> 56.142372 [e 1 us, 0 rep]
[  55.8802434] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  55.8802434] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=200, diff=1, hw=34512 hw_last=34329
[  56.5583460] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(357,192)@ 56.558359 -> 56.552250 [e 1 us, 0 rep]
[  56.5583460] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-504,-25)@ 56.697713 -> 56.698527 [e 1 us, 0 rep]
[  56.5583460] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(37,430)@ 56.838118 -> 56.824466 [e 1 us, 0 rep]
[  56.5583460] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  56.5583460] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=201, diff=1, hw=34696 hw_last=34512
[  57.2395686] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(714,49)@ 57.239578 -> 57.237994 [e 1 us, 0 rep]
[  57.2395686] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(18,210)@ 57.377895 -> 57.371228 [e 1 us, 0 rep]
[  57.2395686] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(155,371)@ 57.516220 -> 57.504436 [e 1 us, 0 rep]
[  57.2395686] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  57.2395686] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=202, diff=1, hw=34879 hw_last=34696
[  57.9187105] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(596,138)@ 57.918723 -> 57.914318 [e 1 us, 0 rep]
[  57.9187105] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(470,445)@ 58.58077 -> 58.43931 [e 1 us, 0 rep]
[  57.9187105] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(391,459)@ 58.195361 -> 58.180774 [e 1 us, 0 rep]
[  57.9187105] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  57.9187105] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=203, diff=1, hw=35062 hw_last=34879
[  58.5978522] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(528,226)@ 58.597861 -> 58.590666 [e 1 us, 0 rep]
[  58.5978522] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(98,9)@ 58.737220 -> 58.736930 [e 1 us, 0 rep]
[  58.5978522] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(561,400)@ 58.873464 -> 58.860743 [e 1 us, 0 rep]
[  58.5978522] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  58.5978522] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=204, diff=1, hw=35245 hw_last=35062
[  59.2759537] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(176,168)@ 59.275967 -> 59.270626 [e 1 us, 0 rep]
[  59.2759537] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(88,475)@ 59.415321 -> 59.400238 [e 1 us, 0 rep]
[  59.2759537] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(253,111)@ 59.553646 -> 59.550112 [e 1 us, 0 rep]
[  59.2759537] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  59.2759537] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=205, diff=1, hw=35429 hw_last=35245
[  59.9561362] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(53,404)@ 59.956150 -> 59.943322 [e 1 us, 0 rep]
[  59.9561362] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(531,38)@ 60.94464 -> 60.93236 [e 1 us, 0 rep]
[  59.9561362] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(149,430)@ 60.230708 -> 60.217051 [e 1 us, 0 rep]
[  59.9561362] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  59.9561362] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=206, diff=1, hw=35611 hw_last=35429
[  60.6331986] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(729,197)@ 60.633212 -> 60.626929 [e 1 us, 0 rep]
[  60.6331986] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-255,-20)@ 60.772565 -> 60.773210 [e 1 us, 0 rep]
[  60.6331986] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(298,435)@ 60.912970 -> 60.899149 [e 1 us, 0 rep]
[  60.6331986] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  60.6331986] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=207, diff=1, hw=35795 hw_last=35611
[  61.3154607] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(44,203)@ 61.315474 -> 61.309027 [e 1 us, 0 rep]
[  61.3154607] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(446,362)@ 61.453787 -> 61.442277 [e 1 us, 0 rep]
[  61.3154607] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(54,146)@ 61.593152 -> 61.588515 [e 1 us, 0 rep]
[  61.3154607] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  61.3154607] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=208, diff=1, hw=35979 hw_last=35795
[  61.9946020] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(308,291)@ 61.994615 -> 61.985365 [e 1 us, 0 rep]
[  61.9946020] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(225,73)@ 62.133969 -> 62.131643 [e 1 us, 0 rep]
[  61.9946020] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(255,234)@ 62.272293 -> 62.264855 [e 1 us, 0 rep]
[  61.9946020] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  61.9946020] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=209, diff=1, hw=36162 hw_last=35979
[  62.6747840] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(420,1)@ 62.674794 -> 62.674745 [e 1 us, 0 rep]
[  62.6747840] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(276,14)@ 62.812071 -> 62.811615 [e 1 us, 0 rep]
[  62.6747840] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(482,175)@ 62.950396 -> 62.944822 [e 1 us, 0 rep]
[  62.6747840] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  62.6747840] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=210, diff=1, hw=36345 hw_last=36162
[  63.3528856] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(17,468)@ 63.352898 -> 63.338040 [e 1 us, 0 rep]
[  63.3528856] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(604,102)@ 63.491213 -> 63.487951 [e 1 us, 0 rep]
[  63.3528856] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(162,411)@ 63.630578 -> 63.617524 [e 1 us, 0 rep]
[  63.3528856] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  63.3528856] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=211, diff=1, hw=36528 hw_last=36345
[  64.0330675] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(157,178)@ 64.33077 -> 64.27420 [e 1 us, 0 rep]
[  64.0330675] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(139,191)@ 64.170355 -> 64.164286 [e 1 us, 0 rep]
[  64.0330675] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-377,-25)@ 64.309720 -> 64.310528 [e 1 us, 0 rep]
[  64.0330675] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  64.0330675] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=212, diff=1, hw=36712 hw_last=36528
[  64.7132509] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(535,414)@ 64.713265 -> 64.700101 [e 1 us, 0 rep]
[  64.7132509] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(387,196)@ 64.852618 -> 64.846381 [e 1 us, 0 rep]
[  64.7132509] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-254,-20)@ 64.991981 -> 64.992626 [e 1 us, 0 rep]
[  64.7132509] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  64.7132509] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=213, diff=1, hw=36896 hw_last=36712
[  65.3955125] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(152,419)@ 65.395522 -> 65.382214 [e 1 us, 0 rep]
[  65.3955125] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(412,201)@ 65.534879 -> 65.528482 [e 1 us, 0 rep]
[  65.3955125] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-111,-15)@ 65.674243 -> 65.674724 [e 1 us, 0 rep]
[  65.3955125] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  65.3955125] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=214, diff=1, hw=37080 hw_last=36896
[  66.0777740] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(559,424)@ 66.77786 -> 66.64304 [e 1 us, 0 rep]
[  66.0777740] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(123,437)@ 66.215061 -> 66.201183 [e 1 us, 0 rep]
[  66.0777740] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(379,220)@ 66.354425 -> 66.347426 [e 1 us, 0 rep]
[  66.0777740] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  66.0777740] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=215, diff=1, hw=37263 hw_last=37080
[  66.7569162] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-266,-12)@ 66.756926 -> 66.757317 [e 1 us, 0 rep]
[  66.7569162] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(283,442)@ 66.897323 -> 66.883280 [e 1 us, 0 rep]
[  66.7569162] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(621,225)@ 67.36688 -> 67.29521 [e 1 us, 0 rep]
[  66.7569162] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  66.7569162] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=216, diff=1, hw=37447 hw_last=37263
[  67.4370990] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(756,223)@ 67.437112 -> 67.430003 [e 1 us, 0 rep]
[  67.4370990] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(701,5)@ 67.576466 -> 67.576280 [e 1 us, 0 rep]
[  67.4370990] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(585,19)@ 67.713751 -> 67.713124 [e 1 us, 0 rep]
[  67.4370990] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  67.4370990] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=217, diff=1, hw=37630 hw_last=37447
[  68.1152006] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(462,164)@ 68.115210 -> 68.109986 [e 1 us, 0 rep]
[  68.1152006] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(725,471)@ 68.254568 -> 68.239586 [e 1 us, 0 rep]
[  68.1152006] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(234,255)@ 68.393932 -> 68.385828 [e 1 us, 0 rep]
[  68.1152006] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  68.1152006] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=218, diff=1, hw=37813 hw_last=37630
[  68.7964229] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(742,22)@ 68.796436 -> 68.795708 [e 1 us, 0 rep]
[  68.7964229] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(440,182)@ 68.934750 -> 68.928954 [e 1 us, 0 rep]
[  68.7964229] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(756,490)@ 69.74114 -> 69.58529 [e 1 us, 0 rep]
[  68.7964229] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  68.7964229] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=219, diff=1, hw=37996 hw_last=37813
[  69.4745242] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(432,488)@ 69.474534 -> 69.459025 [e 1 us, 0 rep]
[  69.4745242] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(718,270)@ 69.613891 -> 69.605291 [e 1 us, 0 rep]
[  69.4745242] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(268,54)@ 69.753256 -> 69.751531 [e 1 us, 0 rep]
[  69.4745242] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  69.4745242] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=220, diff=1, hw=38180 hw_last=37996
[  70.1547073] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(612,199)@ 70.154720 -> 70.148378 [e 2 us, 0 rep]
[  70.1547073] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-340,-18)@ 70.294073 -> 70.294658 [e 1 us, 0 rep]
[  70.1547073] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(158,437)@ 70.434478 -> 70.420599 [e 1 us, 0 rep]
[  70.1547073] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  70.1547073] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=221, diff=1, hw=38363 hw_last=38180
[  70.8369689] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(444,204)@ 70.836979 -> 70.830485 [e 1 us, 0 rep]
[  70.8369689] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-87,-13)@ 70.976336 -> 70.976753 [e 1 us, 0 rep]
[  70.8369689] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(158,295)@ 71.115700 -> 71.106329 [e 1 us, 0 rep]
[  70.8369689] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  70.8369689] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=222, diff=1, hw=38547 hw_last=38363
[  71.5181904] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(474,62)@ 71.518202 -> 71.516215 [e 2 us, 0 rep]
[  71.5181904] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(324,222)@ 71.656517 -> 71.649457 [e 1 us, 0 rep]
[  71.5181904] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(648,5)@ 71.795882 -> 71.795698 [e 1 us, 0 rep]
[  71.5181904] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  71.5181904] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=223, diff=1, hw=38731 hw_last=38547
[  72.1962930] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(366,3)@ 72.196302 -> 72.196193 [e 1 us, 0 rep]
[  72.1962930] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(255,16)@ 72.333580 -> 72.333062 [e 1 us, 0 rep]
[  72.1962930] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(324,177)@ 72.471904 -> 72.466272 [e 1 us, 0 rep]
[  72.1962930] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  72.1962930] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=224, diff=1, hw=38913 hw_last=38731
[  72.8743948] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(59,470)@ 72.874408 -> 72.859485 [e 1 us, 0 rep]
[  72.8743948] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(533,104)@ 73.12721 -> 73.9399 [e 1 us, 0 rep]
[  72.8743948] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-543,-28)@ 73.148966 -> 73.149877 [e 1 us, 0 rep]
[  72.8743948] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  72.8743948] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=225, diff=1, hw=39096 hw_last=38913
[  73.5524964] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(703,410)@ 73.552507 -> 73.539464 [e 1 us, 0 rep]
[  73.5524964] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(123,193)@ 73.691864 -> 73.685732 [e 1 us, 0 rep]
[  73.5524964] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-449,-23)@ 73.831228 -> 73.831976 [e 1 us, 0 rep]
[  73.5524964] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  73.5524964] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=226, diff=1, hw=39280 hw_last=39096
[  74.2347582] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(357,416)@ 74.234772 -> 74.221552 [e 1 us, 0 rep]
[  74.2347582] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(238,198)@ 74.374126 -> 74.367831 [e 1 us, 0 rep]
[  74.2347582] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-274,-18)@ 74.513490 -> 74.514073 [e 1 us, 0 rep]
[  74.2347582] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  74.2347582] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=227, diff=1, hw=39464 hw_last=39280
[  74.9170215] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(223,421)@ 74.917032 -> 74.903658 [e 1 us, 0 rep]
[  74.9170215] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(428,203)@ 75.56388 -> 75.49927 [e 1 us, 0 rep]
[  74.9170215] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(253,217)@ 75.193672 -> 75.186773 [e 1 us, 0 rep]
[  74.9170215] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  74.9170215] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=228, diff=1, hw=39647 hw_last=39464
[  75.5961626] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-113,-15)@ 75.596175 -> 75.596656 [e 1 us, 0 rep]
[  75.5961626] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(136,439)@ 75.736570 -> 75.722629 [e 1 us, 0 rep]
[  75.5961626] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(392,222)@ 75.875934 -> 75.868871 [e 1 us, 0 rep]
[  75.5961626] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  75.5961626] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=229, diff=1, hw=39831 hw_last=39647
[  76.2784252] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-250,-10)@ 76.278435 -> 76.278762 [e 1 us, 0 rep]
[  76.2784252] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(323,444)@ 76.418833 -> 76.404725 [e 1 us, 0 rep]
[  76.2784252] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(586,227)@ 76.558197 -> 76.550967 [e 1 us, 0 rep]
[  76.2784252] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  76.2784252] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=230, diff=1, hw=40015 hw_last=39831
[  76.9606868] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-551,-4)@ 76.960700 -> 76.960849 [e 2 us, 0 rep]
[  76.9606868] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(168,302)@ 77.100054 -> 77.90460 [e 1 us, 0 rep]
[  76.9606868] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(266,463)@ 77.238379 -> 77.223670 [e 1 us, 0 rep]
[  76.9606868] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  76.9606868] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=231, diff=1, hw=40198 hw_last=40015
[  77.6408699] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(650,230)@ 77.640881 -> 77.633553 [e 1 us, 0 rep]
[  77.6408699] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(722,12)@ 77.780236 -> 77.779827 [e 1 us, 0 rep]
[  77.6408699] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(776,173)@ 77.918561 -> 77.913038 [e 1 us, 0 rep]
[  77.6408699] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  77.6408699] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=232, diff=1, hw=40382 hw_last=40198
[  78.3210511] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(95,467)@ 78.321068 -> 78.306238 [e 1 us, 0 rep]
[  78.3210511] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(238,101)@ 78.459378 -> 78.456163 [e 1 us, 0 rep]
[  78.3210511] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(535,409)@ 78.598743 -> 78.585737 [e 1 us, 0 rep]
[  78.3210511] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  78.3210511] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=233, diff=1, hw=40565 hw_last=40382
[  79.0012328] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(720,176)@ 79.1243 -> 78.995628 [e 1 us, 0 rep]
[  79.0012328] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(509,189)@ 79.138520 -> 79.132500 [e 1 us, 0 rep]
[  79.0012328] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-736,-26)@ 79.277885 -> 79.278740 [e 1 us, 0 rep]
[  79.0012328] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  79.0012328] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=234, diff=1, hw=40749 hw_last=40565
[  79.6814156] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(165,413)@ 79.681430 -> 79.668313 [e 1 us, 0 rep]
[  79.6814156] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(691,194)@ 79.820783 -> 79.814596 [e 1 us, 0 rep]
[  79.6814156] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-659,-21)@ 79.960147 -> 79.960839 [e 1 us, 0 rep]
[  79.6814156] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  79.6814156] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=235, diff=1, hw=40933 hw_last=40749
[  80.3636777] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(384,418)@ 80.363693 -> 80.350408 [e 1 us, 0 rep]
[  80.3636777] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(45,200)@ 80.503045 -> 80.496694 [e 1 us, 0 rep]
[  80.3636777] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(76,361)@ 80.641369 -> 80.629905 [e 1 us, 0 rep]
[  80.3636777] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  80.3636777] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=236, diff=1, hw=41116 hw_last=40933
[  81.0428190] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-279,-18)@ 81.42833 -> 81.43416 [e 1 us, 0 rep]
[  81.0428190] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(169,141)@ 81.181147 -> 81.176664 [e 1 us, 0 rep]
[  81.0428190] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(354,449)@ 81.320510 -> 81.306242 [e 1 us, 0 rep]
[  81.0428190] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  81.0428190] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=237, diff=1, hw=41299 hw_last=41116
[  81.7230016] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(633,216)@ 81.723012 -> 81.716129 [e 1 us, 0 rep]
[  81.7230016] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-742,0)@ 81.862369 -> 81.862398 [e 1 us, 0 rep]
[  81.7230016] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(208,160)@ 82.693 -> 81.995606 [e 1 us, 0 rep]
[  81.7230016] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  81.7230016] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=238, diff=1, hw=41483 hw_last=41299
[  82.4000636] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(214,11)@ 82.400078 -> 82.399720 [e 1 us, 0 rep]
[  82.4000636] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(479,170)@ 82.538390 -> 82.532974 [e 1 us, 0 rep]
[  82.4000636] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(124,479)@ 82.677756 -> 82.662544 [e 1 us, 0 rep]
[  82.4000636] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  82.4000636] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=239, diff=1, hw=41665 hw_last=41483
[  83.0802462] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(326,246)@ 83.80256 -> 83.72434 [e 1 us, 0 rep]
[  83.0802462] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(107,259)@ 83.217533 -> 83.209306 [e 1 us, 0 rep]
[  83.0802462] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(301,42)@ 83.356896 -> 83.355551 [e 1 us, 0 rep]
[  83.0802462] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  83.0802462] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=240, diff=1, hw=41849 hw_last=41665
[  83.7583474] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(229,188)@ 83.758364 -> 83.752386 [e 1 us, 0 rep]
[  83.7583474] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(622,494)@ 83.897715 -> 83.882008 [e 1 us, 0 rep]
[  83.7583474] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(49,278)@ 84.37079 -> 84.28251 [e 1 us, 0 rep]
[  83.7583474] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  83.7583474] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=241, diff=1, hw=42032 hw_last=41849
[  84.4364495] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(170,129)@ 84.436464 -> 84.432362 [e 1 us, 0 rep]
[  84.4364495] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(650,435)@ 84.575816 -> 84.561981 [e 1 us, 0 rep]
[  84.4364495] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(135,219)@ 84.715181 -> 84.708223 [e 1 us, 0 rep]
[  84.4364495] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  84.4364495] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=242, diff=1, hw=42215 hw_last=42032
[  85.1176721] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-5,-13)@ 85.117685 -> 85.118098 [e 1 us, 0 rep]
[  85.1176721] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(430,146)@ 85.255999 -> 85.251347 [e 1 us, 0 rep]
[  85.1176721] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(775,454)@ 85.395364 -> 85.380920 [e 1 us, 0 rep]
[  85.1176721] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  85.1176721] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=243, diff=1, hw=42398 hw_last=42215
[  85.7978538] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(101,222)@ 85.797864 -> 85.790812 [e 1 us, 0 rep]
[  85.7978538] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(405,4)@ 85.937221 -> 85.937078 [e 1 us, 0 rep]
[  85.7978538] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(214,18)@ 86.74505 -> 86.73925 [e 1 us, 0 rep]
[  85.7978538] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  85.7978538] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=244, diff=1, hw=42582 hw_last=42398
[  86.4738758] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(168,394)@ 86.473889 -> 86.461374 [e 1 us, 0 rep]
[  86.4738758] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(48,176)@ 86.613243 -> 86.607654 [e 1 us, 0 rep]
[  86.4738758] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(156,337)@ 86.751567 -> 86.740863 [e 1 us, 0 rep]
[  86.4738758] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=182 refcount 1, vblirq 1
[  86.4738758] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=245, diff=1, hw=42764 hw_last=42582
[  87.1540575] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(491,104)@ 87.154069 -> 87.150748 [e 1 us, 0 rep]
[  87.1540575] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(562,411)@ 87.293425 -> 87.280355 [e 1 us, 0 rep]
[  87.1540575] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(43,195)@ 87.432789 -> 87.426597 [e 1 us, 0 rep]
[  87.1540575] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  87.1540575] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=246, diff=1, hw=42948 hw_last=42764
[  87.8342403] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(526,340)@ 87.834254 -> 87.823440 [e 1 us, 0 rep]
[  87.8342403] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(222,122)@ 87.973606 -> 87.969724 [e 1 us, 0 rep]
[  87.8342403] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(595,430)@ 88.112971 -> 88.99297 [e 1 us, 0 rep]
[  87.8342403] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  87.8342403] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=247, diff=1, hw=43131 hw_last=42948
[  88.5144209] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(757,50)@ 88.514434 -> 88.512816 [e 1 us, 0 rep]
[  88.5144209] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(644,210)@ 88.652749 -> 88.646057 [e 1 us, 0 rep]
[  88.5144209] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-725,-5)@ 88.792113 -> 88.792300 [e 1 us, 0 rep]
[  88.5144209] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  88.5144209] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=248, diff=1, hw=43315 hw_last=43131
[  89.1946040] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(635,286)@ 89.194617 -> 89.185512 [e 1 us, 0 rep]
[  89.1946040] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(545,68)@ 89.333971 -> 89.331791 [e 1 us, 0 rep]
[  89.1946040] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(675,229)@ 89.472296 -> 89.464999 [e 1 us, 0 rep]
[  89.1946040] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  89.1946040] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=249, diff=1, hw=43498 hw_last=43315
[  89.8747857] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-536,-2)@ 89.874798 -> 89.874883 [e 1 us, 0 rep]
[  89.8747857] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(319,304)@ 90.14153 -> 90.4490 [e 1 us, 0 rep]
[  89.8747857] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(697,170)@ 90.150397 -> 90.144973 [e 1 us, 0 rep]
[  89.8747857] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  89.8747857] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=250, diff=1, hw=43681 hw_last=43498
[  90.5528878] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(60,464)@ 90.552904 -> 90.538172 [e 1 us, 0 rep]
[  90.5528878] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(107,98)@ 90.691214 -> 90.688099 [e 1 us, 0 rep]
[  90.5528878] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(263,259)@ 90.829539 -> 90.821307 [e 1 us, 0 rep]
[  90.5528878] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  90.5528878] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=251, diff=1, hw=43864 hw_last=43681
[  91.2320297] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(204,27)@ 91.232045 -> 91.231180 [e 1 us, 0 rep]
[  91.2320297] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(403,186)@ 91.370356 -> 91.364436 [e 1 us, 0 rep]
[  91.2320297] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(721,494)@ 91.509721 -> 91.494010 [e 1 us, 0 rep]
[  91.2320297] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  91.2320297] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=252, diff=1, hw=44047 hw_last=43864
[  91.9122122] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(519,262)@ 91.912225 -> 91.903887 [e 1 us, 0 rep]
[  91.9122122] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(465,44)@ 92.51579 -> 92.50164 [e 1 us, 0 rep]
[  91.9122122] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(49,436)@ 92.187823 -> 92.173980 [e 1 us, 0 rep]
[  91.9122122] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  91.9122122] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=253, diff=1, hw=44230 hw_last=44047
[  92.5892731] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(756,55)@ 92.589283 -> 92.587507 [e 1 us, 0 rep]
[  92.5892731] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(145,216)@ 92.727601 -> 92.720738 [e 1 us, 0 rep]
[  92.5892731] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-359,0)@ 92.866966 -> 92.866980 [e 1 us, 0 rep]
[  92.5892731] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  92.5892731] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=254, diff=1, hw=44414 hw_last=44230
[  93.2684150] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(454,144)@ 93.268427 -> 93.263837 [e 2 us, 0 rep]
[  93.2684150] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(631,451)@ 93.407783 -> 93.393441 [e 1 us, 0 rep]
[  93.2684150] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(89,235)@ 93.547147 -> 93.539683 [e 1 us, 0 rep]
[  93.2684150] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  93.2684150] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=255, diff=1, hw=44597 hw_last=44414
[  93.9485981] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(285,380)@ 93.948610 -> 93.936535 [e 1 us, 0 rep]
[  93.9485981] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(287,162)@ 94.87965 -> 94.82810 [e 1 us, 0 rep]
[  93.9485981] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(167,176)@ 94.225249 -> 94.219655 [e 1 us, 0 rep]
[  93.9485981] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  93.9485981] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=256, diff=1, hw=44780 hw_last=44597
[  94.6277395] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(259,469)@ 94.627756 -> 94.612857 [e 1 us, 0 rep]
[  94.6277395] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(688,250)@ 94.767107 -> 94.759143 [e 1 us, 0 rep]
[  94.6277395] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(116,34)@ 94.906471 -> 94.905387 [e 1 us, 0 rep]
[  94.6277395] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  94.6277395] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=257, diff=1, hw=44964 hw_last=44780
[  95.3079220] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(271,179)@ 95.307933 -> 95.302240 [e 1 us, 0 rep]
[  95.3079220] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(371,486)@ 95.447289 -> 95.431846 [e 1 us, 0 rep]
[  95.3079220] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(723,269)@ 95.586654 -> 95.578085 [e 1 us, 0 rep]
[  95.3079220] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  95.3079220] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=258, diff=1, hw=45147 hw_last=44964
[  95.9891438] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(704,37)@ 95.989160 -> 95.987957 [e 1 us, 0 rep]
[  95.9891438] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(29,197)@ 96.127470 -> 96.121215 [e 1 us, 0 rep]
[  95.9891438] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(194,358)@ 96.265795 -> 96.254423 [e 1 us, 0 rep]
[  95.9891438] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  95.9891438] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=259, diff=1, hw=45330 hw_last=45147
[  96.6682863] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(213,126)@ 96.668302 -> 96.664293 [e 1 us, 0 rep]
[  96.6682863] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(718,432)@ 96.807654 -> 96.793911 [e 1 us, 0 rep]
[  96.6682863] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(96,216)@ 96.947017 -> 96.940156 [e 1 us, 0 rep]
[  96.6682863] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  96.6682863] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=260, diff=1, hw=45514 hw_last=45330
[  97.3484682] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(21,362)@ 97.348484 -> 97.336992 [e 1 us, 0 rep]
[  97.3484682] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-658,-3)@ 97.486795 -> 97.486916 [e 1 us, 0 rep]
[  97.3484682] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(357,304)@ 97.626159 -> 97.616494 [e 1 us, 0 rep]
[  97.3484682] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  97.3484682] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=261, diff=1, hw=45697 hw_last=45514
[  98.0286502] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(703,71)@ 98.28661 -> 98.26379 [e 1 us, 0 rep]
[  98.0286502] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(253,462)@ 98.164897 -> 98.150220 [e 1 us, 0 rep]
[  98.0286502] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(573,245)@ 98.304262 -> 98.296461 [e 1 us, 0 rep]
[  98.0286502] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  98.0286502] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=262, diff=1, hw=45880 hw_last=45697
[  98.7067523] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(59,13)@ 98.706763 -> 98.706348 [e 1 us, 0 rep]
[  98.7067523] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(524,25)@ 98.844039 -> 98.843224 [e 1 us, 0 rep]
[  98.7067523] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(590,186)@ 98.982363 -> 98.976435 [e 1 us, 0 rep]
[  98.7067523] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=183 refcount 1, vblirq 1
[  98.7067523] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=263, diff=1, hw=46063 hw_last=45880
[  99.3848533] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(534,479)@ 99.384868 -> 99.369641 [e 1 us, 0 rep]
[  99.3848533] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(335,261)@ 99.524222 -> 99.515923 [e 1 us, 0 rep]
[  99.3848533] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(590,44)@ 99.663586 -> 99.662165 [e 1 us, 0 rep]
[  99.3848533] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[  99.3848533] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=264, diff=1, hw=46247 hw_last=46063
[ 100.0650364] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(620,190)@ 100.65054 -> 100.58997 [e 1 us, 0 rep]
[ 100.0650364] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-767,-27)@ 100.204403 -> 100.205291 [e 1 us, 0 rep]
[ 100.0650364] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(99,197)@ 100.346887 -> 100.340629 [e 1 us, 0 rep]
[ 100.0650364] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[ 100.0650364] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=265, diff=1, hw=46431 hw_last=46247
[ 100.7504188] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(654,111)@ 100.750429 -> 100.746880 [e 1 us, 0 rep]
[ 100.7504188] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(396,188)@ 100.891865 -> 100.885881 [e 1 us, 0 rep]
[ 100.7504188] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(364,266)@ 101.33310 -> 101.24851 [e 1 us, 0 rep]
[ 100.7504188] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 100.7504188] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=266, diff=1, hw=46616 hw_last=46431
[ 101.4358003] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(229,34)@ 101.435815 -> 101.434727 [e 1 us, 0 rep]
[ 101.4358003] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(107,488)@ 101.576207 -> 101.560711 [e 1 us, 0 rep]
[ 101.4358003] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(97,41)@ 101.717652 -> 101.716347 [e 1 us, 0 rep]
[ 101.4358003] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 101.4358003] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=267, diff=1, hw=46801 hw_last=46616
[ 102.1201426] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(278,333)@ 102.120153 -> 102.109570 [e 1 us, 0 rep]
[ 102.1201426] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(102,410)@ 102.261589 -> 102.248569 [e 1 us, 0 rep]
[ 102.1201426] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(50,488)@ 102.403034 -> 102.387540 [e 1 us, 0 rep]
[ 102.1201426] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[ 102.1201426] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=268, diff=1, hw=46985 hw_last=46801
[ 102.8065653] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(3,403)@ 102.806578 -> 102.793784 [e 1 us, 0 rep]
[ 102.8065653] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(784,184)@ 102.945932 -> 102.940060 [e 1 us, 0 rep]
[ 102.8065653] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(635,262)@ 103.87376 -> 103.79034 [e 1 us, 0 rep]
[ 102.8065653] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 102.8065653] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=269, diff=1, hw=47170 hw_last=46985
[ 103.4898669] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(134,30)@ 103.489878 -> 103.488920 [e 1 us, 0 rep]
[ 103.4898669] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(566,484)@ 103.630275 -> 103.614887 [e 1 us, 0 rep]
[ 103.4898669] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(449,37)@ 103.771719 -> 103.770527 [e 1 us, 0 rep]
[ 103.4898669] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 103.4898669] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=270, diff=1, hw=47355 hw_last=47170
[ 104.1752490] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(625,477)@ 104.175265 -> 104.160097 [e 1 us, 0 rep]
[ 104.1752490] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(647,28)@ 104.316696 -> 104.315782 [e 1 us, 0 rep]
[ 104.1752490] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(443,484)@ 104.457101 -> 104.441719 [e 1 us, 0 rep]
[ 104.1752490] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[ 104.1752490] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=271, diff=1, hw=47539 hw_last=47355
[ 104.8616709] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(170,21)@ 104.861681 -> 104.861008 [e 1 us, 0 rep]
[ 104.8616709] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(656,475)@ 105.2078 -> 104.986973 [e 1 us, 0 rep]
[ 104.8616709] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(127,259)@ 105.141443 -> 105.133216 [e 1 us, 0 rep]
[ 104.8616709] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 104.8616709] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=272, diff=1, hw=47724 hw_last=47539
[ 105.5460134] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(247,321)@ 105.546026 -> 105.535826 [e 1 us, 0 rep]
[ 105.5460134] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(654,397)@ 105.687461 -> 105.674832 [e 1 us, 0 rep]
[ 105.5460134] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(587,475)@ 105.828906 -> 105.813803 [e 1 us, 0 rep]
[ 105.5460134] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 105.5460134] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=273, diff=1, hw=47909 hw_last=47724
[ 106.2334760] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(346,12)@ 106.233486 -> 106.233091 [e 1 us, 0 rep]
[ 106.2334760] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(64,467)@ 106.373883 -> 106.359055 [e 1 us, 0 rep]
[ 106.2334760] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(556,397)@ 106.514288 -> 106.501662 [e 1 us, 0 rep]
[ 106.2334760] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 106.2334760] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=274, diff=1, hw=48094 hw_last=47909
[ 106.9188580] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(629,459)@ 106.918871 -> 106.904274 [e 1 us, 0 rep]
[ 106.9188580] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(280,11)@ 107.60306 -> 107.59945 [e 1 us, 0 rep]
[ 106.9188580] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(238,172)@ 107.198629 -> 107.193159 [e 1 us, 0 rep]
[ 106.9188580] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 106.9188580] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=275, diff=1, hw=48279 hw_last=48094
[ 107.6032006] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(355,234)@ 107.603212 -> 107.595770 [e 1 us, 0 rep]
[ 107.6032006] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(788,310)@ 107.744647 -> 107.734775 [e 1 us, 0 rep]
[ 107.6032006] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(745,388)@ 107.886092 -> 107.873745 [e 1 us, 0 rep]
[ 107.6032006] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 107.6032006] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=276, diff=1, hw=48464 hw_last=48279
[ 108.2906626] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(13,451)@ 108.290675 -> 108.276357 [e 1 us, 0 rep]
[ 108.2906626] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(119,380)@ 108.431069 -> 108.419001 [e 1 us, 0 rep]
[ 108.2906626] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(146,458)@ 108.572515 -> 108.557969 [e 1 us, 0 rep]
[ 108.2906626] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 108.2906626] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=277, diff=1, hw=48649 hw_last=48464
[ 108.9770844] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-450,-4)@ 108.977099 -> 108.977244 [e 1 us, 0 rep]
[ 108.9770844] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(608,71)@ 109.118532 -> 109.116254 [e 1 us, 0 rep]
[ 108.9770844] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(301,2)@ 109.258937 -> 109.258861 [e 1 us, 0 rep]
[ 108.9770844] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=186 refcount 1, vblirq 1
[ 108.9770844] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=278, diff=1, hw=48835 hw_last=48649
[ 109.6614264] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(782,294)@ 109.661440 -> 109.652075 [e 1 us, 0 rep]
[ 109.6614264] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(447,371)@ 109.802875 -> 109.791079 [e 1 us, 0 rep]
[ 109.6614264] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(327,449)@ 109.944319 -> 109.930052 [e 1 us, 0 rep]
[ 109.6614264] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 1, vblirq 1
[ 109.6614264] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=279, diff=1, hw=49019 hw_last=48835
[ 110.3488890] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-205,-13)@ 110.348904 -> 110.349325 [e 1 us, 0 rep]
[ 110.3488890] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(162,210)@ 110.491376 -> 110.484703 [e 1 us, 0 rep]
[ 110.3488890] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(77,288)@ 110.632821 -> 110.623675 [e 1 us, 0 rep]
[ 110.3488890] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=186 refcount 1, vblirq 1
[ 110.3488890] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=280, diff=1, hw=49205 hw_last=49019
[ 111.0363517] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(167,203)@ 111.36366 -> 111.29915 [e 1 us, 0 rep]
[ 111.0363517] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x7 p(-120,-15)@ 111.175718 -> 111.176199 [e 1 us, 0 rep]
[ 111.0363517] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(53,210)@ 111.318203 -> 111.311534 [e 1 us, 0 rep]
[ 111.0363517] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 111.0363517] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=281, diff=1, hw=49390 hw_last=49205
[ 111.7217333] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(104,125)@ 111.721748 -> 111.717775 [e 1 us, 0 rep]
[ 111.7217333] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(381,201)@ 111.863181 -> 111.856785 [e 1 us, 0 rep]
[ 111.7217333] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(203,279)@ 112.4625 -> 111.995760 [e 1 us, 0 rep]
[ 111.7217333] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=185 refcount 1, vblirq 1
[ 111.7217333] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=282, diff=1, hw=49575 hw_last=49390
[ 112.4071165] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(34,47)@ 112.407129 -> 112.405636 [e 1 us, 0 rep]
[ 112.4071165] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(766,353)@ 112.546484 -> 112.535247 [e 1 us, 0 rep]
[ 112.4071165] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(593,431)@ 112.687927 -> 112.674221 [e 1 us, 0 rep]
[ 112.4071165] DRM debug in drm_update_vblank_count: clamping vblank bump to 1 on crtc 1: diffr=184 refcount 0, vblirq 1
[ 112.4071165] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=283, diff=1, hw=49759 hw_last=49575
[ 113.0924977] DRM debug in drm_vblank_off: crtc 1, vblank enabled 1, inmodeset 0
[ 113.0924977] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(412,115)@ 113.178824 -> 113.175157 [e 1 us, 0 rep]
[ 113.0924977] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(225,193)@ 113.320268 -> 113.314132 [e 1 us, 0 rep]
[ 113.0924977] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 1 : v 0x5 p(141,271)@ 113.461713 -> 113.453104 [e 1 us, 0 rep]
[ 113.0924977] DRM debug in drm_update_vblank_count: updating vblank count on crtc 1: current=284, diff=209, hw=49968 hw_last=49759
[ 113.7414796] DRM debug in intel_disable_pipe: disabling pipe B
[ 113.8112919] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) A: 28
[ 113.8964446] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) B: 31
[ 113.9796468] DRM debug in i9xx_update_wm: FIFO watermarks - A: 26, B: 29
[ 114.0586895] DRM debug in intel_set_memory_cxsr: memory self-refresh is disabled
[ 114.1460526] DRM debug in i9xx_update_wm: Setting FIFO watermarks - A: 26, B: 29, C: 2, SR 1
[ 114.2458957] DRM debug in intel_sanitize_crtc: [CRTC:23] hw state adjusted, was enabled, now disabled
[ 114.3550984] DRM debug in drm_property_unreference_blob: 0xffffc37ab321f2d0: blob ID: 27 (1)
[ 114.4549418] DRM debug in drm_atomic_set_mode_for_crtc: Set [NOMODE] for CRTC state 0xffffc37b3b27c408
[ 114.5651852] DRM debug in intel_dump_pipe_config: [CRTC:23][setup_hw_state] config 0xffffc37b3b27c408 for pipe B
[ 114.6858295] DRM debug in intel_dump_pipe_config: cpu_transcoder: B
[ 114.7596724] DRM debug in intel_dump_pipe_config: pipe bpp: 0, dithering: 0
[ 114.8418343] DRM debug in intel_dump_pipe_config: fdi/pch: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[ 114.9718382] DRM debug in intel_dump_pipe_config: dp: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[ 115.0966429] DRM debug in intel_dump_pipe_config: dp: 0, lanes: 0, gmch_m2: 0, gmch_n2: 0, link_m2: 0, link_n2: 0, tu2: 0
[ 115.2266466] DRM debug in intel_dump_pipe_config: audio: 0, infoframes: 0
[ 115.3067291] DRM debug in intel_dump_pipe_config: requested mode:
[ 115.3784919] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"" 0 0 0 0 0 0 0 0 0 0 0x0 0x0
[ 115.4835357] DRM debug in intel_dump_pipe_config: adjusted mode:
[ 115.5542578] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"640x480" 60 25200 640 656 752 800 480 490 492 525 0x40 0x0
[ 115.6894618] DRM debug in intel_dump_crtc_timings: crtc timings: 25200 640 656 752 800 480 490 492 525, type: 0x40 flags: 0x0
[ 115.8236258] DRM debug in intel_dump_pipe_config: port clock: 25200
[ 115.8974685] DRM debug in intel_dump_pipe_config: pipe src size: 640x480
[ 115.9765106] DRM debug in intel_dump_pipe_config: num_scalers: 0, scaler_users: 0x0, scaler_id: 0
[ 116.0815547] DRM debug in intel_dump_pipe_config: gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
[ 116.2157195] DRM debug in intel_dump_pipe_config: pch pfit: pos: 0x00000000, size: 0x00000000, disabled
[ 116.3270021] DRM debug in intel_dump_pipe_config: ips: 0
[ 116.3894053] DRM debug in intel_dump_pipe_config: double wide: 0
[ 116.4601277] DRM debug in intel_dump_pipe_config: dpll_hw_state: dpll: 0x84800003, dpll_md: 0x0, fp0: 0x31108, fp1: 0x31108
[ 116.5922107] DRM debug in intel_dump_pipe_config: planes on this crtc
[ 116.6681334] DRM debug in intel_dump_pipe_config: STANDARD PLANE:21 plane: 1.0 idx: 2 disabled, scaler_id = 0
[ 116.7856579] DRM debug in intel_dump_pipe_config: CURSOR PLANE:22 plane: 1.2 idx: 3 disabled, scaler_id = 0
[ 116.9011019] DRM debug in i915_gem_setup_global_gtt: clearing unused GTT space: [0, ffff000]
[ 117.0025335] DRM debug in i915_gem_context_init: fake context support initialized
[ 117.0914277] DRM debug in i915_gem_object_create_stolen: creating stolen object: size=20000
[ 117.1902302] DRM debug in intel_update_cdclk: Current CD clock rate: 400000 kHz
[ 117.2765543] kern info: [drm] initialized overlay support
[ 117.3399956] DRM debug in intel_fbdev_init_bios: pipe A not active or no fb, skipping
[ 117.4325591] DRM debug in intel_fbdev_init_bios: pipe B not active or no fb, skipping
[ 117.5251217] DRM debug in intel_fbdev_init_bios: no active fbs found, not using BIOS config
[ 117.6239242] DRM debug in drm_helper_probe_single_connector_modes_merge_bits: [CONNECTOR:24:VGA-1]
[ 117.7300078] DRM debug in intel_crt_detect: [CONNECTOR:24:VGA-1] force=1
[ 117.8128485] DRM debug in intel_get_hpd_pins: hotplug event received, stat 0x00000800, dig 0x00000800, pins 0x00000002
[ 117.9401985] DRM debug in intel_hpd_irq_storm_detect: Received HPD interrupt on PIN 1 - cnt: 0
[ 118.0421226] DRM debug in intel_crt_detect: CRT detected via hotplug
[ 118.1170050] DRM debug in drm_helper_probe_single_connector_modes_merge_bits: [CONNECTOR:24:VGA-1] status updated from 3 to 1
[ 118.4130943] DRM debug in drm_edid_to_eld: ELD: no CEA Extension found
[ 118.4892328] DRM debug in drm_helper_probe_single_connector_modes_merge_bits: [CONNECTOR:24:VGA-1] probed modes :
[ 118.6109161] DRM debug in drm_mode_debug_printmodeline: Modeline 28:"1280x1024" 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x48 0x5
[ 118.7586022] DRM debug in drm_mode_debug_printmodeline: Modeline 34:"1280x1024" 75 135000 1280 1296 1440 1688 1024 1025 1028 1066 0x40 0x5
[ 118.9062863] DRM debug in drm_mode_debug_printmodeline: Modeline 41:"1152x864" 75 108000 1152 1216 1344 1600 864 865 868 900 0x40 0x5
[ 119.0487712] DRM debug in drm_mode_debug_printmodeline: Modeline 35:"1024x768" 75 78800 1024 1040 1136 1312 768 769 772 800 0x40 0x5
[ 119.1902154] DRM debug in drm_mode_debug_printmodeline: Modeline 36:"1024x768" 70 75000 1024 1048 1184 1328 768 771 777 806 0x40 0xa
[ 119.3316599] DRM debug in drm_mode_debug_printmodeline: Modeline 37:"1024x768" 60 65000 1024 1048 1184 1344 768 771 777 806 0x40 0xa
[ 119.4731047] DRM debug in drm_mode_debug_printmodeline: Modeline 38:"832x624" 75 57284 832 864 928 1152 624 625 628 667 0x40 0xa
[ 119.6103892] DRM debug in drm_mode_debug_printmodeline: Modeline 39:"800x600" 75 49500 800 816 896 1056 600 601 604 625 0x40 0x5
[ 119.7476729] DRM debug in drm_mode_debug_printmodeline: Modeline 40:"800x600" 72 50000 800 856 976 1040 600 637 643 666 0x40 0x5
[ 119.8849576] DRM debug in drm_mode_debug_printmodeline: Modeline 29:"800x600" 60 40000 800 840 968 1056 600 601 605 628 0x40 0x5
[ 120.0222424] DRM debug in drm_mode_debug_printmodeline: Modeline 30:"640x480" 75 31500 640 656 720 840 480 481 484 500 0x40 0xa
[ 120.1584874] DRM debug in drm_mode_debug_printmodeline: Modeline 31:"640x480" 73 31500 640 664 704 832 480 489 491 520 0x40 0xa
[ 120.2947312] DRM debug in drm_mode_debug_printmodeline: Modeline 32:"640x480" 60 25200 640 656 752 800 480 490 492 525 0x40 0xa
[ 120.4309762] DRM debug in drm_mode_debug_printmodeline: Modeline 33:"720x400" 70 28320 720 738 846 900 400 412 414 449 0x40 0x6
[ 120.5672203] DRM debug in drm_setup_crtcs:
[ 120.6140224] DRM debug in drm_enable_connectors: connector 24 enabled? yes
[ 120.6972246] DRM debug in intel_fb_initial_config: looking for cmdline mode on connector VGA-1
[ 120.7991476] DRM debug in intel_fb_initial_config: looking for preferred mode on connector VGA-1 0
[ 120.9052309] DRM debug in intel_fb_initial_config: connector VGA-1 on pipe A [CRTC:20]: 1280x1024
[ 121.0102747] DRM debug in drm_setup_crtcs: desired mode 1280x1024 set on crtc 20 (0,0)
[ 121.1038776] DRM debug in intelfb_create: no BIOS fb, allocating a new one
[ 121.1850005] intelfb0 at i915drmkms0
[ 121.2285787] DRM debug in intelfb_create: allocated 1280x1024 fb: 0x00020000, bo 0xffffc37b3b0a7540
[ 121.3377811] DRM debug in drm_atomic_state_init: Allocated atomic state 0xffffc37ab30dd548
[ 121.4355449] DRM debug in drm_atomic_get_plane_state: Added [PLANE:18] 0xffffc37ab3019ac8 state to 0xffffc37ab30dd548
[ 121.5613891] DRM debug in drm_atomic_get_plane_state: Added [PLANE:19] 0xffffc37ab3019b88 state to 0xffffc37ab30dd548
[ 121.6872331] DRM debug in drm_atomic_set_crtc_for_plane: Link plane state 0xffffc37ab3019b88 to [NOCRTC]
[ 121.7995560] DRM debug in drm_atomic_set_fb_for_plane: Set [NOFB] for plane state 0xffffc37ab3019b88
[ 121.9077189] DRM debug in drm_atomic_get_plane_state: Added [PLANE:21] 0xffffc37ab3019c48 state to 0xffffc37ab30dd548
[ 122.0335631] DRM debug in drm_atomic_get_plane_state: Added [PLANE:22] 0xffffc37ab3019d08 state to 0xffffc37ab30dd548
[ 122.1594076] DRM debug in drm_atomic_set_crtc_for_plane: Link plane state 0xffffc37ab3019d08 to [NOCRTC]
[ 122.2717319] DRM debug in drm_atomic_set_fb_for_plane: Set [NOFB] for plane state 0xffffc37ab3019d08
[ 122.3798948] DRM debug in drm_property_reference_blob: 0xffffc37ab321f230: blob ID: 26 (1)
[ 122.4776587] DRM debug in drm_atomic_get_crtc_state: Added [CRTC:20] 0xffffc37b3b0ad408 state to 0xffffc37ab30dd548
[ 122.6014219] DRM debug in drm_property_unreference_blob: 0xffffc37ab321f230: blob ID: 26 (2)
[ 122.7012647] DRM debug in drm_atomic_set_mode_for_crtc: Set [MODE:1280x1024] for CRTC state 0xffffc37b3b0ad408
[ 122.8198290] DRM debug in drm_atomic_set_crtc_for_plane: Link plane state 0xffffc37ab3019ac8 to [CRTC:20]
[ 122.9331934] DRM debug in drm_framebuffer_reference: 0xffffc37ab3019a08: FB ID: 43 (1)
[ 123.0267958] DRM debug in drm_atomic_set_fb_for_plane: Set [FB:43] for plane state 0xffffc37ab3019ac8
[ 123.1359993] DRM debug in drm_atomic_get_connector_state: Added [CONNECTOR:24] 0xffffc37b3b0e0568 state to 0xffffc37ab30dd548
[ 123.2701641] DRM debug in drm_atomic_add_affected_connectors: Adding all current connectors for [CRTC:20] to 0xffffc37ab30dd548
[ 123.4064088] DRM debug in drm_atomic_set_crtc_for_connector: Link connector state 0xffffc37b3b0e0568 to [NOCRTC]
[ 123.5270527] DRM debug in drm_atomic_set_crtc_for_connector: Link connector state 0xffffc37b3b0e0568 to [CRTC:20]
[ 123.6487360] DRM debug in drm_atomic_get_crtc_state: Added [CRTC:23] 0xffffc37b3b0ad808 state to 0xffffc37ab30dd548
[ 123.7725001] DRM debug in drm_atomic_set_mode_for_crtc: Set [NOMODE] for CRTC state 0xffffc37b3b0ad808
[ 123.8827434] DRM debug in drm_atomic_set_crtc_for_plane: Link plane state 0xffffc37ab3019c48 to [NOCRTC]
[ 123.9950677] DRM debug in drm_atomic_set_fb_for_plane: Set [NOFB] for plane state 0xffffc37ab3019c48
[ 124.1032312] DRM debug in drm_atomic_add_affected_connectors: Adding all current connectors for [CRTC:20] to 0xffffc37ab30dd548
[ 124.2394753] DRM debug in drm_atomic_add_affected_connectors: Adding all current connectors for [CRTC:23] to 0xffffc37ab30dd548
[ 124.3757195] DRM debug in drm_atomic_connectors_for_crtc: State 0xffffc37ab30dd548 has 1 connectors for [CRTC:20]
[ 124.4974039] DRM debug in drm_atomic_check_only: checking 0xffffc37ab30dd548
[ 124.5806056] DRM debug in drm_atomic_helper_check_modeset: [CRTC:20] mode changed
[ 124.6690095] DRM debug in update_connector_routing: Updating routing for [CONNECTOR:24:VGA-1]
[ 124.7698925] DRM debug in update_connector_routing: [CONNECTOR:24:VGA-1] keeps [ENCODER:25:DAC-25], now on [CRTC:20]
[ 124.8946966] DRM debug in drm_atomic_helper_check_modeset: [CRTC:20] needs all connectors, enable: y, active: y
[ 125.0143006] DRM debug in drm_atomic_add_affected_connectors: Adding all current connectors for [CRTC:20] to 0xffffc37ab30dd548
[ 125.1505445] DRM debug in drm_atomic_connectors_for_crtc: State 0xffffc37ab30dd548 has 1 connectors for [CRTC:20]
[ 125.2722292] DRM debug in drm_atomic_add_affected_connectors: Adding all current connectors for [CRTC:20] to 0xffffc37ab30dd548
[ 125.4084733] DRM debug in connected_sink_compute_bpp: [CONNECTOR:24:VGA-1] checking for sink bpp constrains
[ 125.5239171] DRM debug in intel_modeset_pipe_config: hw max bpp: 24, pipe bpp: 24, dithering: 0
[ 125.6268802] DRM debug in intel_dump_pipe_config: [CRTC:20][modeset] config 0xffffc37b3b0ad408 for pipe A
[ 125.7402434] DRM debug in intel_dump_pipe_config: cpu_transcoder: A
[ 125.8140855] DRM debug in intel_dump_pipe_config: pipe bpp: 24, dithering: 0
[ 125.8972894] DRM debug in intel_dump_pipe_config: fdi/pch: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[ 126.0272933] DRM debug in intel_dump_pipe_config: dp: 0, lanes: 0, gmch_m: 0, gmch_n: 0, link_m: 0, link_n: 0, tu: 0
[ 126.1520972] DRM debug in intel_dump_pipe_config: dp: 0, lanes: 0, gmch_m2: 0, gmch_n2: 0, link_m2: 0, link_n2: 0, tu2: 0
[ 126.2821020] DRM debug in intel_dump_pipe_config: audio: 0, infoframes: 0
[ 126.3621842] DRM debug in intel_dump_pipe_config: requested mode:
[ 126.4339470] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"1280x1024" 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x48 0x5
[ 126.5805910] DRM debug in intel_dump_pipe_config: adjusted mode:
[ 126.6513137] DRM debug in drm_mode_debug_printmodeline: Modeline 0:"1280x1024" 60 108000 1280 1328 1440 1688 1024 1025 1028 1066 0x48 0x5
[ 126.7979583] DRM debug in intel_dump_crtc_timings: crtc timings: 108000 1280 1328 1440 1688 1024 1025 1028 1066, type: 0x48 flags: 0x5
[ 126.9414821] DRM debug in intel_dump_pipe_config: port clock: 108000
[ 127.0163657] DRM debug in intel_dump_pipe_config: pipe src size: 1280x1024
[ 127.0974877] DRM debug in intel_dump_pipe_config: num_scalers: 0, scaler_users: 0x0, scaler_id: 0
[ 127.2025307] DRM debug in intel_dump_pipe_config: gmch pfit: control: 0x00000000, ratios: 0x00000000, lvds border: 0x00000000
[ 127.3366955] DRM debug in intel_dump_pipe_config: pch pfit: pos: 0x00000000, size: 0x00000000, disabled
[ 127.4479792] DRM debug in intel_dump_pipe_config: ips: 0
[ 127.5103818] DRM debug in intel_dump_pipe_config: double wide: 0
[ 127.5811031] DRM debug in intel_dump_pipe_config: dpll_hw_state: dpll: 0x84800003, dpll_md: 0x0, fp0: 0x31108, fp1: 0x31108
[ 127.7131880] DRM debug in intel_dump_pipe_config: planes on this crtc
[ 127.7891097] DRM debug in intel_dump_pipe_config: STANDARD PLANE:18 plane: 0.0 idx: 0 disabled, scaler_id = 0
[ 127.9066341] DRM debug in intel_dump_pipe_config: CURSOR PLANE:19 plane: 0.1 idx: 1 disabled, scaler_id = 0
[ 128.0220784] DRM debug in intel_plane_atomic_calc_changes: [CRTC:20] has [PLANE:18] with fb 43
[ 128.1240006] DRM debug in intel_plane_atomic_calc_changes: [PLANE:18] visible 0 -> 1, off 0, on 1, ms 1
[ 128.2352851] DRM debug in drm_atomic_commit: commiting 0xffffc37ab30dd548
[ 128.3153668] DRM debug in intel_set_memory_cxsr: memory self-refresh is disabled
[ 128.4027302] DRM debug in drm_vblank_enable: enabling vblank on crtc 0, ret: 0
[ 128.4027302] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 0 : v 0x5 p(144,8)@ 128.488016 -> 128.487757 [e 1 us, 0 rep]
[ 128.4027302] DRM debug in drm_update_vblank_count: updating vblank count on crtc 0: current=1, diff=0, hw=0 hw_last=0
[ 128.8471607] warning: /usr/src/sys/external/bsd/drm2/dist/drm/drm_irq.c:1510: vblank wait timed out on crtc 0
[ 128.9627888] DRM debug in drm_vblank_off: crtc 0, vblank enabled 1, inmodeset 0
[ 128.9627888] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 0 : v 0x5 p(144,8)@ 129.49115 -> 129.48856 [e 1 us, 0 rep]
[ 128.9627888] DRM debug in drm_update_vblank_count: updating vblank count on crtc 0: current=1, diff=0, hw=0 hw_last=0
[ 129.3122404] DRM debug in intel_disable_pipe: disabling pipe A
[ 129.3808824] DRM debug in drm_calc_timestamping_constants: crtc 20: hwmode: htotal 1688, vtotal 1066, vdisplay 1024
[ 129.5077664] DRM debug in drm_calc_timestamping_constants: crtc 20: clock 108000 kHz framedur 16661185 linedur 15629
[ 129.6325694] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) A: 28
[ 129.7157733] DRM debug in intel_calculate_wm: FIFO entries required for mode: 34
[ 129.8031361] DRM debug in intel_calculate_wm: FIFO watermark level: -8
[ 129.8800981] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) B: 31
[ 129.9633008] DRM debug in i9xx_update_wm: FIFO watermarks - A: 8, B: 29
[ 130.0413038] DRM debug in intel_set_memory_cxsr: memory self-refresh is disabled
[ 130.1286668] DRM debug in i9xx_update_wm: self-refresh entries: 80
[ 130.2014677] DRM debug in i9xx_update_wm: Setting FIFO watermarks - A: 8, B: 29, C: 2, SR 15
[ 130.3013120] DRM debug in intel_set_memory_cxsr: memory self-refresh is enabled
[ 130.3876341] DRM debug in intel_enable_pipe: enabling pipe A
[ 130.4541959] DRM debug in drm_vblank_on: crtc 0, vblank enabled 0, inmodeset 1
[ 130.4541959] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 0 : v 0x5 p(360,0)@ 130.539482 -> 130.539478 [e 1 us, 0 rep]
[ 130.6788451] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) A: 28
[ 130.7620459] DRM debug in intel_calculate_wm: FIFO entries required for mode: 34
[ 130.8494096] DRM debug in intel_calculate_wm: FIFO watermark level: -8
[ 130.9263716] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) B: 31
[ 131.0095740] DRM debug in i9xx_update_wm: FIFO watermarks - A: 8, B: 29
[ 131.0875767] DRM debug in intel_set_memory_cxsr: memory self-refresh is disabled
[ 131.1749395] DRM debug in i9xx_update_wm: self-refresh entries: 80
[ 131.2477420] DRM debug in i9xx_update_wm: Setting FIFO watermarks - A: 8, B: 29, C: 2, SR 15
[ 131.3475857] DRM debug in intel_set_memory_cxsr: memory self-refresh is enabled
[ 131.4339084] DRM debug in drm_vblank_enable: enabling vblank on crtc 0, ret: 0
[ 131.4339084] DRM debug in drm_calc_vbltimestamp_from_scanoutpos: crtc 0 : v 0x5 p(360,0)@ 131.519194 -> 131.519190 [e 1 us, 0 rep]
[ 131.4339084] DRM debug in drm_update_vblank_count: updating vblank count on crtc 0: current=2, diff=0, hw=0 hw_last=0
[ 131.8783476] warning: /usr/src/sys/external/bsd/drm2/dist/drm/drm_irq.c:1510: vblank wait timed out on crtc 0
[ 131.9939665] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) A: 28
[ 132.0771698] DRM debug in intel_calculate_wm: FIFO entries required for mode: 34
[ 132.1645317] DRM debug in intel_calculate_wm: FIFO watermark level: -8
[ 132.2414940] DRM debug in i9xx_get_fifo_size: FIFO size - (0x00001d9c) B: 31
[ 132.3246973] DRM debug in i9xx_update_wm: FIFO watermarks - A: 8, B: 29
[ 132.4027003] DRM debug in intel_set_memory_cxsr: memory self-refresh is disabled
[ 132.4900619] DRM debug in i9xx_update_wm: self-refresh entries: 80
[ 132.5628653] DRM debug in i9xx_update_wm: Setting FIFO watermarks - A: 8, B: 29, C: 2, SR 15
[ 132.6627076] DRM debug in intel_set_memory_cxsr: memory self-refresh is enabled
[ 132.7987134] DRM debug in intel_connector_check_state: [CONNECTOR:24:VGA-1]
[ 132.8789317] DRM debug in check_encoder_state: [ENCODER:25:DAC-25]
[ 132.9517334] DRM debug in drm_property_unreference_blob: 0xffffc37ab321f230: blob ID: 26 (1)
[ 133.0515774] DRM debug in check_crtc_state: [CRTC:20]
[ 133.1108584] DRM debug in drm_atomic_state_default_clear: Clearing atomic state 0xffffc37ab30dd548
[ 133.2169429] DRM debug in drm_atomic_state_free: Freeing atomic state 0xffffc37ab30dd548
[ 133.3126260] DRM debug in drm_framebuffer_reference: 0xffffc37ab3019a08: FB ID: 43 (2)
[ 133.4062278] max_dotclock according to supported modes: 135000
[ 133.4817156] wsdisplay0 at intelfb0 kbdmux 1
[ 133.5319692] DRM debug in i915_hotplug_work_func: running encoder hotplug functions
[ 133.6245318] DRM debug in i915_hotplug_work_func: Connector VGA-1 (pin 1) received hotplug event.
[ 133.7296792] DRM debug in intel_crt_detect: [CONNECTOR:24:VGA-1] force=0
[ 133.8091109] DRM debug in intel_crt_detect: CRT detected via hotplug
[ 133.9091783] uhub4 at uhub0 port 1: O2 Micro (0xb97) product 7731 (0x7731), class 9/0, rev 1.10/2.10, addr 2
Mon May  8 17:43:07 GMT 2023
Starting root file system check:
/dev/rwd0a: file system is clean; not checking
swapctl: setting dump device to /dev/wd0b
swapctl: adding /dev/wd0b as swap device at priority 0
Starting file system checks:
/dev/rwd0e: file system is clean; not checking
/dev/rwd0f: file system is clean; not checking
/dev/rwd0g: file system is clean; not checking
/dev/rwd0h: file system is clean; not checking
random_seed: /var/db/entropy-file: Not present
Set[ 135.3712239] ugen0 at uhub4 port 1
[ 135.4124157] ugen0: O2 Micro (0xb97) product 7732 (0x7732), rev 1.10/2.10, addr 3
ting tty flags.
Setting sysctl variables:
ddb.onpanic: 1 -> 0
Starting [ 136.5318069] uhidev0 at uhub4 port 2 configuration 1 interface 0
[ 136.6042949] uhidev0: CHICONY (0x49f) Compaq USB Keyboard (0x51), rev 1.10/1.05, addr 4, iclass 3/1
network.
/et[ 136.7218908] ukbd0 at uhidev0
c/rc: WARNING: $hostname not set.
IPv6 mode: host
Configuring network interfaces: wm0[ 137.5623845] wskbd0 at ukbd0 mux 1
[ 137.5923959] uhidev1 at uhub4 port 2 configuration 1 interface 1
[ 137.6624249] uhidev1: CHICONY (0x49f) Compaq USB Keyboard (0x51), rev 1.10/1.05, addr 4, iclass 3/0
[ 137.8024938] uhidev1: 2 report ids
[ 137.8421413] uhid0 at uhidev1 reportid 1: input=5, output=0, feature=0
[ 137.9191011] uhid1 at uhidev1 reportid 2: input=5, output=0, feature=4
wm0: Setting hostname: plex5.backyard
.
Adding interface aliases:.
Waiting for DAD to complete for statically configured addresses...
Building databases: dev, utmp, utmpx.
[ 146.6866634] max_dotclock according to supported modes: 135000
[ 146.7667080] max_dotclock according to supported modes: 135000
[ 146.8367336] max_dotclock according to supported modes: 135000
[ 146.9067604] max_dotclock according to supported modes: 135000
Starting syslogd.
Mounting all file systems...
Clearing temporary files.
Updating fontconfig cache: done.
Checking quotas: done.
swapctl: setting dump device to /dev/wd0b
Starting virecover.
Checking for core dump...
savecore: no core dump
Starting local daemons:.
Updating motd.
Starting ntpd.
Starting powerd.
Starting sshd.
Starting postfix.
May  8 17:43:25 plex5 postfix/master[661]: fatal: open lock file /var/db/postfix/master.lock: cannot create file exclusively: Permission denied
May  8 17:43:26 plex5 postfix/master[652]: fatal: daemon initialization failure
May  8 17:43:27 plex5 postfix/postfix-script[522]: fatal: mail system startup failed
/etc/rc.d/postfix exited with code 1
Starting inetd.
Starting cron.
The following components reported failures:
    /etc/rc.d/postfix
See /var/run/rc.log for more information.
Mon May  8 17:43:28 UTC 2023