<5>[ 0.000000][ T0] Linux version 5.14.0-rc3-bluetea-test-00249-gc7d102232649 (root@integral) (gcc (Ubuntu 11.1.0-1ubuntu1~21.04) 11.1.0, GNU ld (GNU Binutils for Ubuntu) 2.36.1) #5 SMP Sat Jul 31 13:18:27 WIB 2021 <6>[ 0.000000][ T0] Command line: BOOT_IMAGE=/boot/vmlinuz-5.14.0-rc3-bluetea-test-00249-gc7d102232649 root=UUID=0693bfef-641d-4e69-8fb5-b87d413390bc ro mitigations=off <6>[ 0.000000][ T0] KERNEL supported cpus: <6>[ 0.000000][ T0] Intel GenuineIntel <6>[ 0.000000][ T0] AMD AuthenticAMD <6>[ 0.000000][ T0] Hygon HygonGenuine <6>[ 0.000000][ T0] Centaur CentaurHauls <6>[ 0.000000][ T0] zhaoxin Shanghai <6>[ 0.000000][ T0] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' <6>[ 0.000000][ T0] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' <6>[ 0.000000][ T0] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' <6>[ 0.000000][ T0] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 <6>[ 0.000000][ T0] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. <6>[ 0.000000][ T0] signal: max sigframe size: 1776 <6>[ 0.000000][ T0] BIOS-provided physical RAM map: <6>[ 0.000000][ T0] BIOS-e820: [mem 0x0000000000000000-0x000000000009f3ff] usable <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000000009f400-0x000000000009ffff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x0000000000100000-0x000000009a0bbfff] usable <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009a0bc000-0x000000009aabbfff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009aabc000-0x000000009f6befff] usable <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009f6bf000-0x000000009fabefff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009fabf000-0x000000009fbbefff] ACPI NVS <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009fbbf000-0x000000009fbfefff] ACPI data <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009fbff000-0x000000009fbfffff] usable <6>[ 0.000000][ T0] BIOS-e820: [mem 0x000000009fc00000-0x00000000dfffffff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000f0800000-0x00000000f08fffff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000f8000000-0x00000000fbffffff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec01fff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000fec10000-0x00000000fec10fff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000fed80000-0x00000000fed80fff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x00000000ff800000-0x00000000ffffffff] reserved <6>[ 0.000000][ T0] BIOS-e820: [mem 0x0000000100000000-0x000000031effffff] usable <6>[ 0.000000][ T0] NX (Execute Disable) protection: active <6>[ 0.000000][ T0] SMBIOS 2.8 present. <6>[ 0.000000][ T0] DMI: Acer Aspire ES1-421/OLVIA_BE, BIOS V1.05 07/02/2015 <6>[ 0.000000][ T0] tsc: Fast TSC calibration using PIT <6>[ 0.000000][ T0] tsc: Detected 1996.170 MHz processor <7>[ 0.018906][ T0] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved <7>[ 0.018917][ T0] e820: remove [mem 0x000a0000-0x000fffff] usable <6>[ 0.018932][ T0] last_pfn = 0x31f000 max_arch_pfn = 0x400000000 <6>[ 0.019771][ T0] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT <6>[ 0.022038][ T0] last_pfn = 0x9fc00 max_arch_pfn = 0x400000000 <6>[ 0.030299][ T0] Using GB pages for direct mapping <6>[ 0.032260][ T0] RAMDISK: [mem 0x60fec000-0x9a0bafff] <6>[ 0.032281][ T0] ACPI: Early table checksum verification disabled <6>[ 0.032298][ T0] ACPI: RSDP 0x00000000000FE020 000024 (v02 ACRSYS) <6>[ 0.032313][ T0] ACPI: XSDT 0x000000009FBFE120 000094 (v01 ACRSYS ACRPRDCT 00000001 01000013) <6>[ 0.032332][ T0] ACPI: FACP 0x000000009FBFC000 00010C (v05 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032350][ T0] ACPI: DSDT 0x000000009FBEC000 00B7C3 (v01 ACRSYS ACRPRDCT F0000000 1025 00040000) <6>[ 0.032363][ T0] ACPI: FACS 0x000000009FB65000 000040 <6>[ 0.032374][ T0] ACPI: UEFI 0x000000009FBFD000 000236 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032386][ T0] ACPI: HPET 0x000000009FBFB000 000038 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032397][ T0] ACPI: APIC 0x000000009FBFA000 000090 (v03 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032408][ T0] ACPI: MCFG 0x000000009FBF9000 00003C (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032420][ T0] ACPI: ASF! 0x000000009FBF8000 0000A5 (v32 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032431][ T0] ACPI: BOOT 0x000000009FBEB000 000028 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032443][ T0] ACPI: FPDT 0x000000009FBE9000 000044 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032454][ T0] ACPI: SSDT 0x000000009FBE7000 000CB4 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032465][ T0] ACPI: SSDT 0x000000009FBE2000 00489D (v02 ACRSYS ACRPRDCT 00000002 1025 00040000) <6>[ 0.032476][ T0] ACPI: SSDT 0x000000009FBE1000 000418 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032487][ T0] ACPI: SSDT 0x000000009FBDF000 0013F5 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032498][ T0] ACPI: SSDT 0x000000009FBDE000 00008C (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032510][ T0] ACPI: SSDT 0x000000009FBDC000 001138 (v01 ACRSYS ACRPRDCT 00000001 1025 00040000) <6>[ 0.032520][ T0] ACPI: Reserving FACP table memory at [mem 0x9fbfc000-0x9fbfc10b] <6>[ 0.032526][ T0] ACPI: Reserving DSDT table memory at [mem 0x9fbec000-0x9fbf77c2] <6>[ 0.032531][ T0] ACPI: Reserving FACS table memory at [mem 0x9fb65000-0x9fb6503f] <6>[ 0.032536][ T0] ACPI: Reserving UEFI table memory at [mem 0x9fbfd000-0x9fbfd235] <6>[ 0.032540][ T0] ACPI: Reserving HPET table memory at [mem 0x9fbfb000-0x9fbfb037] <6>[ 0.032545][ T0] ACPI: Reserving APIC table memory at [mem 0x9fbfa000-0x9fbfa08f] <6>[ 0.032550][ T0] ACPI: Reserving MCFG table memory at [mem 0x9fbf9000-0x9fbf903b] <6>[ 0.032554][ T0] ACPI: Reserving ASF! table memory at [mem 0x9fbf8000-0x9fbf80a4] <6>[ 0.032559][ T0] ACPI: Reserving BOOT table memory at [mem 0x9fbeb000-0x9fbeb027] <6>[ 0.032564][ T0] ACPI: Reserving FPDT table memory at [mem 0x9fbe9000-0x9fbe9043] <6>[ 0.032568][ T0] ACPI: Reserving SSDT table memory at [mem 0x9fbe7000-0x9fbe7cb3] <6>[ 0.032572][ T0] ACPI: Reserving SSDT table memory at [mem 0x9fbe2000-0x9fbe689c] <6>[ 0.032577][ T0] ACPI: Reserving SSDT table memory at [mem 0x9fbe1000-0x9fbe1417] <6>[ 0.032581][ T0] ACPI: Reserving SSDT table memory at [mem 0x9fbdf000-0x9fbe03f4] <6>[ 0.032586][ T0] ACPI: Reserving SSDT table memory at [mem 0x9fbde000-0x9fbde08b] <6>[ 0.032590][ T0] ACPI: Reserving SSDT table memory at [mem 0x9fbdc000-0x9fbdd137] <6>[ 0.032714][ T0] No NUMA configuration found <6>[ 0.032722][ T0] Faking a node at [mem 0x0000000000000000-0x000000031effffff] <6>[ 0.032746][ T0] NODE_DATA(0) allocated [mem 0x31efd3000-0x31effdfff] <6>[ 0.033791][ T0] Zone ranges: <6>[ 0.033797][ T0] DMA [mem 0x0000000000001000-0x0000000000ffffff] <6>[ 0.033804][ T0] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] <6>[ 0.033811][ T0] Normal [mem 0x0000000100000000-0x000000031effffff] <6>[ 0.033819][ T0] Device empty <6>[ 0.033824][ T0] Movable zone start for each node <6>[ 0.033831][ T0] Early memory node ranges <6>[ 0.033835][ T0] node 0: [mem 0x0000000000001000-0x000000000009efff] <6>[ 0.033841][ T0] node 0: [mem 0x0000000000100000-0x000000009a0bbfff] <6>[ 0.033847][ T0] node 0: [mem 0x000000009aabc000-0x000000009f6befff] <6>[ 0.033852][ T0] node 0: [mem 0x000000009fbff000-0x000000009fbfffff] <6>[ 0.033857][ T0] node 0: [mem 0x0000000100000000-0x000000031effffff] <6>[ 0.033864][ T0] Initmem setup node 0 [mem 0x0000000000001000-0x000000031effffff] <6>[ 0.033880][ T0] On node 0, zone DMA: 1 pages in unavailable ranges <6>[ 0.033968][ T0] On node 0, zone DMA: 97 pages in unavailable ranges <6>[ 0.057783][ T0] On node 0, zone DMA32: 2560 pages in unavailable ranges <6>[ 0.057881][ T0] On node 0, zone DMA32: 1344 pages in unavailable ranges <6>[ 0.140745][ T0] On node 0, zone Normal: 1024 pages in unavailable ranges <6>[ 0.140956][ T0] On node 0, zone Normal: 4096 pages in unavailable ranges <6>[ 0.141055][ T0] ACPI: PM-Timer IO Port: 0x408 <6>[ 0.141083][ T0] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1]) <6>[ 0.141089][ T0] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1]) <6>[ 0.141094][ T0] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1]) <6>[ 0.141099][ T0] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1]) <6>[ 0.141143][ T0] IOAPIC[0]: apic_id 4, version 33, address 0xfec00000, GSI 0-23 <6>[ 0.141163][ T0] IOAPIC[1]: apic_id 5, version 33, address 0xfec01000, GSI 24-55 <6>[ 0.141172][ T0] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) <6>[ 0.141180][ T0] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level) <6>[ 0.141190][ T0] ACPI: Using ACPI (MADT) for SMP configuration information <6>[ 0.141196][ T0] ACPI: HPET id: 0x10228210 base: 0xfed00000 <6>[ 0.141213][ T0] smpboot: Allowing 4 CPUs, 0 hotplug CPUs <6>[ 0.141273][ T0] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] <6>[ 0.141281][ T0] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff] <6>[ 0.141286][ T0] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000dffff] <6>[ 0.141291][ T0] PM: hibernation: Registered nosave memory: [mem 0x000e0000-0x000fffff] <6>[ 0.141297][ T0] PM: hibernation: Registered nosave memory: [mem 0x9a0bc000-0x9aabbfff] <6>[ 0.141304][ T0] PM: hibernation: Registered nosave memory: [mem 0x9f6bf000-0x9fabefff] <6>[ 0.141308][ T0] PM: hibernation: Registered nosave memory: [mem 0x9fabf000-0x9fbbefff] <6>[ 0.141313][ T0] PM: hibernation: Registered nosave memory: [mem 0x9fbbf000-0x9fbfefff] <6>[ 0.141319][ T0] PM: hibernation: Registered nosave memory: [mem 0x9fc00000-0xdfffffff] <6>[ 0.141323][ T0] PM: hibernation: Registered nosave memory: [mem 0xe0000000-0xf07fffff] <6>[ 0.141327][ T0] PM: hibernation: Registered nosave memory: [mem 0xf0800000-0xf08fffff] <6>[ 0.141331][ T0] PM: hibernation: Registered nosave memory: [mem 0xf0900000-0xf7ffffff] <6>[ 0.141336][ T0] PM: hibernation: Registered nosave memory: [mem 0xf8000000-0xfbffffff] <6>[ 0.141340][ T0] PM: hibernation: Registered nosave memory: [mem 0xfc000000-0xfebfffff] <6>[ 0.141344][ T0] PM: hibernation: Registered nosave memory: [mem 0xfec00000-0xfec01fff] <6>[ 0.141348][ T0] PM: hibernation: Registered nosave memory: [mem 0xfec02000-0xfec0ffff] <6>[ 0.141352][ T0] PM: hibernation: Registered nosave memory: [mem 0xfec10000-0xfec10fff] <6>[ 0.141357][ T0] PM: hibernation: Registered nosave memory: [mem 0xfec11000-0xfed7ffff] <6>[ 0.141361][ T0] PM: hibernation: Registered nosave memory: [mem 0xfed80000-0xfed80fff] <6>[ 0.141365][ T0] PM: hibernation: Registered nosave memory: [mem 0xfed81000-0xfedfffff] <6>[ 0.141369][ T0] PM: hibernation: Registered nosave memory: [mem 0xfee00000-0xfee00fff] <6>[ 0.141373][ T0] PM: hibernation: Registered nosave memory: [mem 0xfee01000-0xff7fffff] <6>[ 0.141378][ T0] PM: hibernation: Registered nosave memory: [mem 0xff800000-0xffffffff] <6>[ 0.141386][ T0] [mem 0xe0000000-0xf07fffff] available for PCI devices <6>[ 0.141392][ T0] Booting paravirtualized kernel on bare hardware <6>[ 0.141402][ T0] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns <6>[ 0.150925][ T0] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 <6>[ 0.151973][ T0] percpu: Embedded 59 pages/cpu s204800 r8192 d28672 u524288 <7>[ 0.152027][ T0] pcpu-alloc: s204800 r8192 d28672 u524288 alloc=1*2097152 <7>[ 0.152038][ T0] pcpu-alloc: [0] 0 1 2 3 <6>[ 0.152168][ T0] Built 1 zonelists, mobility grouping on. Total pages: 2829389 <6>[ 0.152176][ T0] Policy zone: Normal <5>[ 0.152200][ T0] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.14.0-rc3-bluetea-test-00249-gc7d102232649 root=UUID=0693bfef-641d-4e69-8fb5-b87d413390bc ro mitigations=off <5>[ 0.152297][ T0] Unknown command line parameters: BOOT_IMAGE=/boot/vmlinuz-5.14.0-rc3-bluetea-test-00249-gc7d102232649 <6>[ 0.161889][ T0] Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear) <6>[ 0.166773][ T0] Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) <6>[ 0.166886][ T0] mem auto-init: stack:off, heap alloc:off, heap free:off <6>[ 0.333535][ T0] Memory: 10234624K/11497848K available (16393K kernel code, 4336K rwdata, 5676K rodata, 3796K init, 17596K bss, 1262968K reserved, 0K cma-reserved) <6>[ 0.334201][ T0] ftrace: allocating 46954 entries in 184 pages <6>[ 0.365109][ T0] ftrace: allocated 184 pages with 4 groups <6>[ 0.365777][ T0] Running RCU self tests <6>[ 0.365795][ T0] rcu: Hierarchical RCU implementation. <6>[ 0.365800][ T0] rcu: RCU lockdep checking is enabled. <6>[ 0.365804][ T0] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. <6>[ 0.365809][ T0] Rude variant of Tasks RCU enabled. <6>[ 0.365813][ T0] Tracing variant of Tasks RCU enabled. <6>[ 0.365817][ T0] rcu: RCU calculated value of scheduler-enlistment delay is 30 jiffies. <6>[ 0.365822][ T0] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 <6>[ 0.377941][ T0] NR_IRQS: 524544, nr_irqs: 1000, preallocated irqs: 16 <5>[ 0.378616][ T0] random: crng done (trusting CPU's manufacturer) <7>[ 0.378747][ C0] spurious 8259A interrupt: IRQ7. <6>[ 0.380189][ T0] Console: colour dummy device 80x25 <6>[ 0.383750][ T0] printk: console [tty0] enabled <4>[ 0.383794][ T0] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar <4>[ 0.383823][ T0] ... MAX_LOCKDEP_SUBCLASSES: 8 <4>[ 0.383840][ T0] ... MAX_LOCK_DEPTH: 48 <4>[ 0.383858][ T0] ... MAX_LOCKDEP_KEYS: 8192 <4>[ 0.383876][ T0] ... CLASSHASH_SIZE: 4096 <4>[ 0.383894][ T0] ... MAX_LOCKDEP_ENTRIES: 32768 <4>[ 0.383912][ T0] ... MAX_LOCKDEP_CHAINS: 65536 <4>[ 0.383930][ T0] ... CHAINHASH_SIZE: 32768 <4>[ 0.383948][ T0] memory used by lock dependency info: 6365 kB <4>[ 0.383969][ T0] memory used for stack traces: 4224 kB <4>[ 0.383988][ T0] per task-struct memory footprint: 1920 bytes <4>[ 0.384010][ T0] ------------------------ <4>[ 0.384026][ T0] | Locking API testsuite: <4>[ 0.384041][ T0] ---------------------------------------------------------------------------- <4>[ 0.384069][ T0] | spin |wlock |rlock |mutex | wsem | rsem | <4>[ 0.384097][ T0] -------------------------------------------------------------------------- <4>[ 0.384132][ T0] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.390431][ T0] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.397081][ T0] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.404233][ T0] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.411362][ T0] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.418978][ T0] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.426595][ T0] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.434239][ T0] double unlock: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.440396][ T0] initialize held: ok | ok | ok | ok | ok | ok | ok | <4>[ 0.446351][ T0] -------------------------------------------------------------------------- <4>[ 0.446379][ T0] recursive read-lock: | ok | | ok | <4>[ 0.448232][ T0] recursive read-lock #2: | ok | | ok | <4>[ 0.450022][ T0] mixed read-write-lock: | ok | | ok | <4>[ 0.451810][ T0] mixed write-read-lock: | ok | | ok | <4>[ 0.453596][ T0] mixed read-lock/lock-write ABBA: | ok | | ok | <4>[ 0.455501][ T0] mixed read-lock/lock-read ABBA: | ok | | ok | <4>[ 0.457425][ T0] mixed write-lock/lock-write ABBA: | ok | | ok | <4>[ 0.459353][ T0] chain cached mixed R-L/L-W ABBA: | ok | <4>[ 0.460340][ T0] rlock W1R2/W2R3/W3R1/123: | ok | <4>[ 0.461421][ T0] rlock W1R2/W2R3/W3R1/132: | ok | <4>[ 0.462482][ T0] rlock W1R2/W2R3/W3R1/213: | ok | <4>[ 0.463538][ T0] rlock W1R2/W2R3/W3R1/231: | ok | <4>[ 0.464598][ T0] rlock W1R2/W2R3/W3R1/312: | ok | <4>[ 0.465654][ T0] rlock W1R2/W2R3/W3R1/321: | ok | <4>[ 0.466713][ T0] rlock W1W2/R2R3/W3R1/123: | ok | <4>[ 0.467769][ T0] rlock W1W2/R2R3/W3R1/132: | ok | <4>[ 0.468829][ T0] rlock W1W2/R2R3/W3R1/213: | ok | <4>[ 0.469890][ T0] rlock W1W2/R2R3/W3R1/231: | ok | <4>[ 0.470949][ T0] rlock W1W2/R2R3/W3R1/312: | ok | <4>[ 0.472005][ T0] rlock W1W2/R2R3/W3R1/321: | ok | <4>[ 0.473064][ T0] rlock W1W2/R2R3/R3W1/123: | ok | <4>[ 0.474110][ T0] rlock W1W2/R2R3/R3W1/132: | ok | <4>[ 0.475160][ T0] rlock W1W2/R2R3/R3W1/213: | ok | <4>[ 0.476206][ T0] rlock W1W2/R2R3/R3W1/231: | ok | <4>[ 0.477256][ T0] rlock W1W2/R2R3/R3W1/312: | ok | <4>[ 0.478302][ T0] rlock W1W2/R2R3/R3W1/321: | ok | <4>[ 0.479357][ T0] rlock W1R2/R2R3/W3W1/123: | ok | <4>[ 0.480404][ T0] rlock W1R2/R2R3/W3W1/132: | ok | <4>[ 0.481454][ T0] rlock W1R2/R2R3/W3W1/213: | ok | <4>[ 0.482500][ T0] rlock W1R2/R2R3/W3W1/231: | ok | <4>[ 0.483550][ T0] rlock W1R2/R2R3/W3W1/312: | ok | <4>[ 0.484597][ T0] rlock W1R2/R2R3/W3W1/321: | ok | <4>[ 0.485647][ T0] -------------------------------------------------------------------------- <4>[ 0.485675][ T0] hard-irqs-on + irq-safe-A/12: ok | ok | ok | <4>[ 0.488332][ T0] soft-irqs-on + irq-safe-A/12: ok | ok | ok | <4>[ 0.490996][ T0] hard-irqs-on + irq-safe-A/21: ok | ok | ok | <4>[ 0.493652][ T0] soft-irqs-on + irq-safe-A/21: ok | ok | ok | <4>[ 0.496308][ T0] sirq-safe-A => hirqs-on/12: ok | ok | ok | <4>[ 0.498969][ T0] sirq-safe-A => hirqs-on/21: ok | ok | ok | <4>[ 0.501631][ T0] hard-safe-A + irqs-on/12: ok | ok | ok | <4>[ 0.504287][ T0] soft-safe-A + irqs-on/12: ok | ok | ok | <4>[ 0.506946][ T0] hard-safe-A + irqs-on/21: ok | ok | ok | <4>[ 0.509607][ T0] soft-safe-A + irqs-on/21: ok | ok | ok | <4>[ 0.512265][ T0] hard-safe-A + unsafe-B #1/123: ok | ok | ok | <4>[ 0.515130][ T0] soft-safe-A + unsafe-B #1/123: ok | ok | ok | <4>[ 0.517996][ T0] hard-safe-A + unsafe-B #1/132: ok | ok | ok | <4>[ 0.520863][ T0] soft-safe-A + unsafe-B #1/132: ok | ok | ok | <4>[ 0.523725][ T0] hard-safe-A + unsafe-B #1/213: ok | ok | ok | <4>[ 0.526588][ T0] soft-safe-A + unsafe-B #1/213: ok | ok | ok | <4>[ 0.529456][ T0] hard-safe-A + unsafe-B #1/231: ok | ok | ok | <4>[ 0.532312][ T0] soft-safe-A + unsafe-B #1/231: ok | ok | ok | <4>[ 0.535171][ T0] hard-safe-A + unsafe-B #1/312: ok | ok | ok | <4>[ 0.537895][ T0] soft-safe-A + unsafe-B #1/312: ok | ok | ok | <4>[ 0.540625][ T0] hard-safe-A + unsafe-B #1/321: ok | ok | ok | <4>[ 0.543480][ T0] soft-safe-A + unsafe-B #1/321: ok | ok | ok | <4>[ 0.546339][ T0] hard-safe-A + unsafe-B #2/123: ok | ok | ok | <4>[ 0.549200][ T0] soft-safe-A + unsafe-B #2/123: ok | ok | ok | <4>[ 0.552061][ T0] hard-safe-A + unsafe-B #2/132: ok | ok | ok | <4>[ 0.554915][ T0] soft-safe-A + unsafe-B #2/132: ok | ok | ok | <4>[ 0.557776][ T0] hard-safe-A + unsafe-B #2/213: ok | ok | ok | <4>[ 0.560634][ T0] soft-safe-A + unsafe-B #2/213: ok | ok | ok | <4>[ 0.563495][ T0] hard-safe-A + unsafe-B #2/231: ok | ok | ok | <4>[ 0.566346][ T0] soft-safe-A + unsafe-B #2/231: ok | ok | ok | <4>[ 0.569201][ T0] hard-safe-A + unsafe-B #2/312: ok | ok | ok | <4>[ 0.572040][ T0] soft-safe-A + unsafe-B #2/312: ok | ok | ok | <4>[ 0.574888][ T0] hard-safe-A + unsafe-B #2/321: ok | ok | ok | <4>[ 0.577728][ T0] soft-safe-A + unsafe-B #2/321: ok | ok | ok | <4>[ 0.580578][ T0] hard-irq lock-inversion/123: ok | ok | ok | <4>[ 0.583436][ T0] soft-irq lock-inversion/123: ok | ok | ok | <4>[ 0.586286][ T0] hard-irq lock-inversion/132: ok | ok | ok | <4>[ 0.589142][ T0] soft-irq lock-inversion/132: ok | ok | ok | <4>[ 0.592000][ T0] hard-irq lock-inversion/213: ok | ok | ok | <4>[ 0.594856][ T0] soft-irq lock-inversion/213: ok | ok | ok | <4>[ 0.597706][ T0] hard-irq lock-inversion/231: ok | ok | ok | <4>[ 0.600568][ T0] soft-irq lock-inversion/231: ok | ok | ok | <4>[ 0.603419][ T0] hard-irq lock-inversion/312: ok | ok | ok | <4>[ 0.606381][ T0] soft-irq lock-inversion/312: ok | ok | ok | <4>[ 0.609498][ T0] hard-irq lock-inversion/321: ok | ok | ok | <4>[ 0.612421][ T0] soft-irq lock-inversion/321: ok | ok | ok | <4>[ 0.615285][ T0] hard-irq read-recursion/123: | ok | ok | <4>[ 0.617132][ T0] soft-irq read-recursion/123: | ok | ok | <4>[ 0.618992][ T0] hard-irq read-recursion/132: | ok | ok | <4>[ 0.620907][ T0] soft-irq read-recursion/132: | ok | ok | <4>[ 0.622830][ T0] hard-irq read-recursion/213: | ok | ok | <4>[ 0.624677][ T0] soft-irq read-recursion/213: | ok | ok | <4>[ 0.626534][ T0] hard-irq read-recursion/231: | ok | ok | <4>[ 0.628380][ T0] soft-irq read-recursion/231: | ok | ok | <4>[ 0.630240][ T0] hard-irq read-recursion/312: | ok | ok | <4>[ 0.632150][ T0] soft-irq read-recursion/312: | ok | ok | <4>[ 0.634074][ T0] hard-irq read-recursion/321: | ok | ok | <4>[ 0.635984][ T0] soft-irq read-recursion/321: | ok | ok | <4>[ 0.637905][ T0] hard-irq read-recursion #2/123: | ok | ok | <4>[ 0.639820][ T0] soft-irq read-recursion #2/123: | ok | ok | <4>[ 0.641744][ T0] hard-irq read-recursion #2/132: | ok | ok | <4>[ 0.643690][ T0] soft-irq read-recursion #2/132: | ok | ok | <4>[ 0.645786][ T0] hard-irq read-recursion #2/213: | ok | ok | <4>[ 0.647868][ T0] soft-irq read-recursion #2/213: | ok | ok | <4>[ 0.649839][ T0] hard-irq read-recursion #2/231: | ok | ok | <4>[ 0.651747][ T0] soft-irq read-recursion #2/231: | ok | ok | <4>[ 0.653668][ T0] hard-irq read-recursion #2/312: | ok | ok | <4>[ 0.655574][ T0] soft-irq read-recursion #2/312: | ok | ok | <4>[ 0.657494][ T0] hard-irq read-recursion #2/321: | ok | ok | <4>[ 0.659406][ T0] soft-irq read-recursion #2/321: | ok | ok | <4>[ 0.661328][ T0] hard-irq read-recursion #3/123: | ok | ok | <4>[ 0.663239][ T0] soft-irq read-recursion #3/123: | ok | ok | <4>[ 0.665162][ T0] hard-irq read-recursion #3/132: | ok | ok | <4>[ 0.667070][ T0] soft-irq read-recursion #3/132: | ok | ok | <4>[ 0.668995][ T0] hard-irq read-recursion #3/213: | ok | ok | <4>[ 0.670909][ T0] soft-irq read-recursion #3/213: | ok | ok | <4>[ 0.672832][ T0] hard-irq read-recursion #3/231: | ok | ok | <4>[ 0.674743][ T0] soft-irq read-recursion #3/231: | ok | ok | <4>[ 0.676667][ T0] hard-irq read-recursion #3/312: | ok | ok | <4>[ 0.678576][ T0] soft-irq read-recursion #3/312: | ok | ok | <4>[ 0.680504][ T0] hard-irq read-recursion #3/321: | ok | ok | <4>[ 0.682427][ T0] soft-irq read-recursion #3/321: | ok | ok | <4>[ 0.684526][ T0] -------------------------------------------------------------------------- <4>[ 0.684558][ T0] | Wound/wait tests | <4>[ 0.684574][ T0] --------------------- <4>[ 0.684591][ T0] ww api failures: ok | ok | ok | <4>[ 0.687669][ T0] ww contexts mixing: ok | ok | <4>[ 0.689515][ T0] finishing ww context: ok | ok | ok | ok | <4>[ 0.693174][ T0] locking mismatches: ok | ok | ok | <4>[ 0.695953][ T0] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | <4>[ 0.705486][ T0] spinlock nest unlocked: ok | <4>[ 0.706385][ T0] spinlock nest test: ok | <4>[ 0.707420][ T0] ----------------------------------------------------- <4>[ 0.707444][ T0] |block | try |context| <4>[ 0.707469][ T0] ----------------------------------------------------- <4>[ 0.707492][ T0] context: ok | ok | ok | <4>[ 0.710358][ T0] try: ok | ok | ok | <4>[ 0.713074][ T0] block: ok | ok | ok | <4>[ 0.715794][ T0] spinlock: ok | ok | ok | <4>[ 0.718721][ T0] -------------------------------------------------------------------------- <4>[ 0.718752][ T0] | queued read lock tests | <4>[ 0.718769][ T0] --------------------------- <4>[ 0.718787][ T0] hardirq read-lock/lock-read: ok | <4>[ 0.719763][ T0] hardirq lock-read/read-lock: ok | <4>[ 0.720766][ T0] hardirq inversion: ok | <4>[ 0.721822][ T0] -------------------- <4>[ 0.721840][ T0] | fs_reclaim tests | <4>[ 0.721857][ T0] -------------------- <4>[ 0.721874][ T0] correct nesting: ok | <4>[ 0.722802][ T0] wrong nesting: ok | <4>[ 0.723714][ T0] protected nesting: ok | <4>[ 0.724622][ T0] -------------------------------------------------------------------------- <4>[ 0.724655][ T0] | local_lock tests | <4>[ 0.724672][ T0] --------------------- <4>[ 0.724690][ T0] local_lock inversion 2: ok | <4>[ 0.725732][ T0] local_lock inversion 3A: ok | <4>[ 0.726769][ T0] local_lock inversion 3B: ok | <4>[ 0.727808][ T0] hardirq_unsafe_softirq_safe: ok | <4>[ 0.728980][ T0] ------------------------------------------------------- <4>[ 0.729004][ T0] Good, all 358 testcases passed! | <4>[ 0.729024][ T0] --------------------------------- <6>[ 0.729149][ T0] ACPI: Core revision 20210604 <6>[ 0.729762][ T0] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484873504 ns <6>[ 0.729882][ T0] APIC: Switch to symmetric I/O mode setup <6>[ 0.730800][ T0] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 <6>[ 0.746531][ T0] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x398c1844f78, max_idle_ns: 881590710043 ns <6>[ 0.746625][ T0] Calibrating delay loop (skipped), value calculated using timer frequency.. 3993.68 BogoMIPS (lpj=6653900) <6>[ 0.746669][ T0] pid_max: default: 32768 minimum: 301 <6>[ 0.746845][ T0] LSM: Security Framework initializing <6>[ 0.746884][ T0] Yama: becoming mindful. <6>[ 0.747051][ T0] AppArmor: AppArmor initialized <6>[ 0.747315][ T0] Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) <6>[ 0.747499][ T0] Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear) <6>[ 0.749253][ T0] BIOS may not properly restore RDRAND after suspend, hiding RDRAND via CPUID. Use rdrand=force to reenable. <6>[ 0.749347][ T0] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 4 <6>[ 0.749373][ T0] Last level dTLB entries: 4KB 512, 2MB 256, 4MB 128, 1GB 0 <6>[ 0.749411][ T0] Speculative Store Bypass: Vulnerable <6>[ 0.754788][ T0] Freeing SMP alternatives memory: 40K <6>[ 0.865264][ T1] smpboot: CPU0: AMD A8-6410 APU with AMD Radeon R5 Graphics (family: 0x16, model: 0x30, stepping: 0x1) <6>[ 0.866592][ T1] Running RCU-tasks wait API self tests <6>[ 0.866592][ T1] Performance Events: AMD PMU driver. <6>[ 0.866592][ T1] ... version: 0 <6>[ 0.866592][ T1] ... bit width: 48 <6>[ 0.866592][ T1] ... generic registers: 4 <6>[ 0.866592][ T1] ... value mask: 0000ffffffffffff <6>[ 0.866592][ T1] ... max period: 00007fffffffffff <6>[ 0.866592][ T1] ... fixed-purpose events: 0 <6>[ 0.866592][ T1] ... event mask: 000000000000000f <6>[ 0.866592][ T1] rcu: Hierarchical SRCU implementation. <6>[ 0.868114][ T7] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. <6>[ 0.868695][ T1] smp: Bringing up secondary CPUs ... <6>[ 0.870438][ T1] x86: Booting SMP configuration: <6>[ 0.870487][ T1] .... node #0, CPUs: #1 <0>[ 0.354833][ C1] __common_interrupt: 1.55 No irq handler for vector <4>[ 0.874889][ T1] #2 <0>[ 0.354833][ C2] __common_interrupt: 2.55 No irq handler for vector <4>[ 0.878029][ T1] #3 <0>[ 0.354833][ C3] __common_interrupt: 3.55 No irq handler for vector <6>[ 0.882164][ T1] smp: Brought up 1 node, 4 CPUs <6>[ 0.882164][ T1] smpboot: Max logical packages: 1 <4>[ 0.882164][ T1] ---------------- <4>[ 0.882164][ T1] | NMI testsuite: <4>[ 0.882164][ T1] -------------------- <4>[ 0.882164][ T1] remote IPI: ok | <4>[ 0.882164][ T1] local IPI: ok | <4>[ 0.882164][ T1] -------------------- <4>[ 0.882164][ T1] Good, all 2 testcases passed! | <4>[ 0.882164][ T1] --------------------------------- <6>[ 0.882164][ T1] smpboot: Total of 4 processors activated (15975.75 BogoMIPS) <6>[ 0.883309][ T11] Callback from call_rcu_tasks_trace() invoked. <6>[ 0.887651][ T1] devtmpfs: initialized <6>[ 0.887651][ T1] x86/mm: Memory block size: 128MB <6>[ 0.898113][ T1] ACPI: PM: Registering ACPI NVS region [mem 0x9fabf000-0x9fbbefff] (1048576 bytes) <6>[ 0.898113][ T1] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns <6>[ 0.898113][ T1] futex hash table entries: 1024 (order: 5, 131072 bytes, linear) <6>[ 0.898113][ T1] Running postponed tracer tests: <6>[ 0.898113][ T1] Testing tracer function: <6>[ 1.219551][ T10] Callback from call_rcu_tasks_rude() invoked. <4>[ 1.595129][ T1] PASSED <6>[ 1.595167][ T1] Testing dynamic ftrace: PASSED <6>[ 1.840036][ T1] Testing dynamic ftrace ops #1: <4>[ 1.970555][ T1] (1 0 1 0 0) <4>[ 1.970596][ T1] (1 1 2 0 0) <4>[ 2.267916][ T1] (2 1 3 0 3075049) <4>[ 2.267968][ T1] (2 2 4 0 3075800) PASSED <6>[ 2.547125][ T1] Testing dynamic ftrace ops #2: <4>[ 2.978651][ T1] (1 0 1 3071331 0) <4>[ 2.978705][ T1] (1 1 2 3071781 0) <4>[ 3.603452][ T1] (2 1 3 7 3138315) <4>[ 3.603546][ T1] (2 2 4 437 3138744) PASSED <6>[ 4.283832][ T1] Testing ftrace recursion: PASSED <6>[ 4.318077][ T1] Testing ftrace recursion safe: PASSED <6>[ 4.353595][ T1] Testing ftrace regs: PASSED <6>[ 4.389399][ T1] Testing tracer nop: PASSED <6>[ 4.389540][ T1] Testing tracer wakeup: PASSED <6>[ 4.973528][ T1] Testing tracer wakeup_rt: PASSED <6>[ 5.562885][ T1] Testing tracer wakeup_dl: PASSED <6>[ 6.144310][ T1] Testing tracer function_graph: PASSED <6>[ 8.813357][ T1] pinctrl core: initialized pinctrl subsystem <6>[ 8.814615][ T1] PM: RTC time: 10:56:09, date: 2021-08-04 <6>[ 8.815324][ T1] NET: Registered PF_NETLINK/PF_ROUTE protocol family <6>[ 8.816209][ T1] DMA: preallocated 2048 KiB GFP_KERNEL pool for atomic allocations <6>[ 8.816266][ T1] DMA: preallocated 2048 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations <6>[ 8.816331][ T1] DMA: preallocated 2048 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations <6>[ 8.816463][ T1] audit: initializing netlink subsys (disabled) <5>[ 8.816659][ T44] audit: type=2000 audit(1628074568.086:1): state=initialized audit_enabled=0 res=1 <6>[ 8.817902][ T1] thermal_sys: Registered thermal governor 'fair_share' <6>[ 8.817908][ T1] thermal_sys: Registered thermal governor 'bang_bang' <6>[ 8.817943][ T1] thermal_sys: Registered thermal governor 'step_wise' <6>[ 8.817975][ T1] thermal_sys: Registered thermal governor 'user_space' <6>[ 8.818008][ T1] thermal_sys: Registered thermal governor 'power_allocator' <6>[ 8.818087][ T1] EISA bus registered <6>[ 8.818224][ T1] cpuidle: using governor ladder <6>[ 8.818296][ T1] cpuidle: using governor menu <6>[ 8.818422][ T1] Simple Boot Flag at 0x44 set to 0x1 <6>[ 8.818422][ T1] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it <6>[ 8.818422][ T1] ACPI: bus type PCI registered <6>[ 8.818422][ T1] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 <6>[ 8.818422][ T1] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf8000000-0xfbffffff] (base 0xf8000000) <6>[ 8.818422][ T1] PCI: MMCONFIG at [mem 0xf8000000-0xfbffffff] reserved in E820 <6>[ 8.818422][ T1] PCI: Using configuration type 1 for base access <4>[ 8.821630][ T1] mtrr: your CPUs had inconsistent variable MTRR settings <6>[ 8.821664][ T1] mtrr: probably your BIOS does not setup all CPUs. <6>[ 8.821694][ T1] mtrr: corrected configuration. <6>[ 8.834517][ T1] Kprobes globally optimized <6>[ 8.834583][ T1] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages <6>[ 8.834583][ T1] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages <6>[ 8.844095][ T1] fbcon: Taking over console <4>[ 8.844294][ T1] ACPI: PRMT not present <6>[ 8.844318][ T1] PRM: found 4294967277 modules <6>[ 8.844450][ T1] ACPI: Added _OSI(Module Device) <6>[ 8.844478][ T1] ACPI: Added _OSI(Processor Device) <6>[ 8.844504][ T1] ACPI: Added _OSI(3.0 _SCP Extensions) <6>[ 8.844532][ T1] ACPI: Added _OSI(Processor Aggregator Device) <6>[ 8.844565][ T1] ACPI: Added _OSI(Linux-Dell-Video) <6>[ 8.844593][ T1] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) <6>[ 8.844625][ T1] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) <6>[ 8.928612][ T1] ACPI: 7 ACPI AML tables successfully acquired and loaded <5>[ 8.946059][ T1] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored <6>[ 8.958600][ T1] ACPI: EC: EC started <6>[ 8.958647][ T1] ACPI: EC: interrupt blocked <6>[ 8.961043][ T1] ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 <6>[ 8.961078][ T1] ACPI: \_SB_.PCI0.LPC0.EC0_: Boot DSDT EC used to handle transactions <6>[ 8.961117][ T1] ACPI: Interpreter enabled <6>[ 8.961236][ T1] ACPI: PM: (supports S0 S3 S4 S5) <6>[ 8.961265][ T1] ACPI: Using IOAPIC for interrupt routing <6>[ 8.962377][ T1] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug <6>[ 8.963997][ T1] ACPI: Enabled 4 GPEs in block 00 to 1F <6>[ 9.018864][ T1] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) <6>[ 9.018951][ T1] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] <6>[ 9.020427][ T1] acpi PNP0A08:00: _OSC: platform does not support [SHPCHotplug LTR] <6>[ 9.021821][ T1] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] <6>[ 9.021866][ T1] acpi PNP0A08:00: FADT indicates ASPM is unsupported, using BIOS configuration <6>[ 9.021989][ T1] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-3f] only partially covers this bridge <6>[ 9.022654][ T1] acpi PNP0A08:00: ignoring host bridge window [mem 0x000cc000-0x000cffff window] (conflicts with Video ROM [mem 0x000c0000-0x000cebff]) <6>[ 9.024158][ T1] PCI host bridge to bus 0000:00 <6>[ 9.024188][ T1] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] <6>[ 9.024229][ T1] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] <6>[ 9.024270][ T1] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] <6>[ 9.024314][ T1] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000c3fff window] <6>[ 9.024357][ T1] pci_bus 0000:00: root bus resource [mem 0x000c4000-0x000c7fff window] <6>[ 9.024400][ T1] pci_bus 0000:00: root bus resource [mem 0x000c8000-0x000cbfff window] <6>[ 9.024443][ T1] pci_bus 0000:00: root bus resource [mem 0x000d0000-0x000d3fff window] <6>[ 9.024486][ T1] pci_bus 0000:00: root bus resource [mem 0x000d4000-0x000d7fff window] <6>[ 9.024529][ T1] pci_bus 0000:00: root bus resource [mem 0x000d8000-0x000dbfff window] <6>[ 9.024571][ T1] pci_bus 0000:00: root bus resource [mem 0x000dc000-0x000dffff window] <6>[ 9.024614][ T1] pci_bus 0000:00: root bus resource [mem 0x000e0000-0x000e3fff window] <6>[ 9.024657][ T1] pci_bus 0000:00: root bus resource [mem 0x000e4000-0x000e7fff window] <6>[ 9.024699][ T1] pci_bus 0000:00: root bus resource [mem 0x000e8000-0x000ebfff window] <6>[ 9.024742][ T1] pci_bus 0000:00: root bus resource [mem 0x000ec000-0x000effff window] <6>[ 9.024785][ T1] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xf7ffffff window] <6>[ 9.024827][ T1] pci_bus 0000:00: root bus resource [mem 0xfc000000-0xfed3ffff window] <6>[ 9.024870][ T1] pci_bus 0000:00: root bus resource [mem 0xfed45000-0xffffffff window] <6>[ 9.024914][ T1] pci_bus 0000:00: root bus resource [bus 00-ff] <6>[ 9.025033][ T1] pci 0000:00:00.0: [1022:1566] type 00 class 0x060000 <6>[ 9.025655][ T1] pci 0000:00:01.0: [1002:9851] type 00 class 0x030000 <6>[ 9.025709][ T1] pci 0000:00:01.0: reg 0x10: [mem 0xe0000000-0xefffffff 64bit pref] <6>[ 9.025761][ T1] pci 0000:00:01.0: reg 0x18: [mem 0xf0000000-0xf07fffff 64bit pref] <6>[ 9.025809][ T1] pci 0000:00:01.0: reg 0x20: [io 0x3000-0x30ff] <6>[ 9.025849][ T1] pci 0000:00:01.0: reg 0x24: [mem 0xf0c00000-0xf0c3ffff] <6>[ 9.025892][ T1] pci 0000:00:01.0: reg 0x30: [mem 0xfffe0000-0xffffffff pref] <6>[ 9.025939][ T1] pci 0000:00:01.0: enabling Extended Tags <6>[ 9.026051][ T1] pci 0000:00:01.0: supports D1 D2 <6>[ 9.026080][ T1] pci 0000:00:01.0: PME# supported from D1 D2 D3hot <6>[ 9.027126][ T1] pci 0000:00:01.1: [1002:9840] type 00 class 0x040300 <6>[ 9.027179][ T1] pci 0000:00:01.1: reg 0x10: [mem 0xf0c60000-0xf0c63fff 64bit] <6>[ 9.027258][ T1] pci 0000:00:01.1: enabling Extended Tags <6>[ 9.027352][ T1] pci 0000:00:01.1: supports D1 D2 <6>[ 9.027856][ T1] pci 0000:00:02.0: [1022:156b] type 00 class 0x060000 <6>[ 9.028372][ T1] pci 0000:00:02.3: [1022:1439] type 01 class 0x060400 <6>[ 9.028455][ T1] pci 0000:00:02.3: enabling Extended Tags <6>[ 9.028558][ T1] pci 0000:00:02.3: PME# supported from D0 D3hot D3cold <6>[ 9.029472][ T1] pci 0000:00:02.4: [1022:1439] type 01 class 0x060400 <6>[ 9.029555][ T1] pci 0000:00:02.4: enabling Extended Tags <6>[ 9.029656][ T1] pci 0000:00:02.4: PME# supported from D0 D3hot D3cold <6>[ 9.030456][ T1] pci 0000:00:08.0: [1022:1537] type 00 class 0x108000 <6>[ 9.030507][ T1] pci 0000:00:08.0: reg 0x10: [mem 0xf0c40000-0xf0c5ffff 64bit pref] <6>[ 9.030555][ T1] pci 0000:00:08.0: reg 0x18: [mem 0xf0900000-0xf09fffff] <6>[ 9.030597][ T1] pci 0000:00:08.0: reg 0x1c: [mem 0xf0c70000-0xf0c70fff] <6>[ 9.030645][ T1] pci 0000:00:08.0: reg 0x24: [mem 0xf0c6a000-0xf0c6bfff] <6>[ 9.031185][ T1] pci 0000:00:10.0: [1022:7814] type 00 class 0x0c0330 <6>[ 9.031245][ T1] pci 0000:00:10.0: reg 0x10: [mem 0xf0c68000-0xf0c69fff 64bit] <6>[ 9.031425][ T1] pci 0000:00:10.0: PME# supported from D0 D3hot D3cold <6>[ 9.032179][ T1] pci 0000:00:11.0: [1022:7801] type 00 class 0x010601 <6>[ 9.032231][ T1] pci 0000:00:11.0: reg 0x10: [io 0x3118-0x311f] <6>[ 9.032273][ T1] pci 0000:00:11.0: reg 0x14: [io 0x3124-0x3127] <6>[ 9.032315][ T1] pci 0000:00:11.0: reg 0x18: [io 0x3110-0x3117] <6>[ 9.032356][ T1] pci 0000:00:11.0: reg 0x1c: [io 0x3120-0x3123] <6>[ 9.032398][ T1] pci 0000:00:11.0: reg 0x20: [io 0x3100-0x310f] <6>[ 9.032439][ T1] pci 0000:00:11.0: reg 0x24: [mem 0xf0c6f000-0xf0c6f3ff] <6>[ 9.032543][ T1] pci 0000:00:11.0: PME# supported from D3hot <6>[ 9.033275][ T1] pci 0000:00:12.0: [1022:7808] type 00 class 0x0c0320 <6>[ 9.033328][ T1] pci 0000:00:12.0: reg 0x10: [mem 0xf0c6e000-0xf0c6e0ff] <6>[ 9.033466][ T1] pci 0000:00:12.0: supports D1 D2 <6>[ 9.033494][ T1] pci 0000:00:12.0: PME# supported from D0 D1 D2 D3hot D3cold <6>[ 9.034215][ T1] pci 0000:00:13.0: [1022:7808] type 00 class 0x0c0320 <6>[ 9.034267][ T1] pci 0000:00:13.0: reg 0x10: [mem 0xf0c6d000-0xf0c6d0ff] <6>[ 9.034405][ T1] pci 0000:00:13.0: supports D1 D2 <6>[ 9.034433][ T1] pci 0000:00:13.0: PME# supported from D0 D1 D2 D3hot D3cold <6>[ 9.035145][ T1] pci 0000:00:14.0: [1022:780b] type 00 class 0x0c0500 <6>[ 9.035914][ T1] pci 0000:00:14.2: [1022:780d] type 00 class 0x040300 <6>[ 9.035971][ T1] pci 0000:00:14.2: reg 0x10: [mem 0xf0c64000-0xf0c67fff 64bit] <6>[ 9.036105][ T1] pci 0000:00:14.2: PME# supported from D0 D3hot D3cold <6>[ 9.036828][ T1] pci 0000:00:14.3: [1022:780e] type 00 class 0x060100 <6>[ 9.037617][ T1] pci 0000:00:14.7: [1022:7813] type 00 class 0x080501 <6>[ 9.037675][ T1] pci 0000:00:14.7: reg 0x10: [mem 0xf0c6c000-0xf0c6c0ff 64bit] <6>[ 9.037802][ T1] pci 0000:00:14.7: PME# supported from D3cold <6>[ 9.038303][ T1] pci 0000:00:18.0: [1022:1580] type 00 class 0x060000 <6>[ 9.038811][ T1] pci 0000:00:18.1: [1022:1581] type 00 class 0x060000 <6>[ 9.039312][ T1] pci 0000:00:18.2: [1022:1582] type 00 class 0x060000 <6>[ 9.039819][ T1] pci 0000:00:18.3: [1022:1583] type 00 class 0x060000 <6>[ 9.040555][ T1] pci 0000:00:18.4: [1022:1584] type 00 class 0x060000 <6>[ 9.041061][ T1] pci 0000:00:18.5: [1022:1585] type 00 class 0x060000 <6>[ 9.041812][ T1] pci 0000:01:00.0: [10ec:5287] type 00 class 0xff0000 <6>[ 9.041870][ T1] pci 0000:01:00.0: reg 0x10: [mem 0xf0b05000-0xf0b05fff] <6>[ 9.041972][ T1] pci 0000:01:00.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] <6>[ 9.042149][ T1] pci 0000:01:00.0: supports D1 D2 <6>[ 9.042177][ T1] pci 0000:01:00.0: PME# supported from D1 D2 D3hot D3cold <6>[ 9.042806][ T1] pci 0000:01:00.1: [10ec:8168] type 00 class 0x020000 <6>[ 9.042863][ T1] pci 0000:01:00.1: reg 0x10: [io 0x2000-0x20ff] <6>[ 9.042925][ T1] pci 0000:01:00.1: reg 0x18: [mem 0xf0b04000-0xf0b04fff 64bit] <6>[ 9.042982][ T1] pci 0000:01:00.1: reg 0x20: [mem 0xf0b00000-0xf0b03fff 64bit] <6>[ 9.043160][ T1] pci 0000:01:00.1: supports D1 D2 <6>[ 9.043188][ T1] pci 0000:01:00.1: PME# supported from D0 D1 D2 D3hot D3cold <6>[ 9.043667][ T1] pci 0000:00:02.3: PCI bridge to [bus 01] <6>[ 9.043704][ T1] pci 0000:00:02.3: bridge window [io 0x2000-0x2fff] <6>[ 9.043742][ T1] pci 0000:00:02.3: bridge window [mem 0xf0b00000-0xf0bfffff] <6>[ 9.044053][ T1] pci 0000:02:00.0: [14e4:4365] type 00 class 0x028000 <6>[ 9.044117][ T1] pci 0000:02:00.0: reg 0x10: [mem 0xf0a00000-0xf0a07fff 64bit] <6>[ 9.044223][ T1] pci 0000:02:00.0: enabling Extended Tags <6>[ 9.044379][ T1] pci 0000:02:00.0: supports D1 D2 <6>[ 9.044407][ T1] pci 0000:02:00.0: PME# supported from D0 D3hot D3cold <6>[ 9.045019][ T1] pci 0000:00:02.4: PCI bridge to [bus 02] <6>[ 9.045058][ T1] pci 0000:00:02.4: bridge window [mem 0xf0a00000-0xf0afffff] <6>[ 9.050411][ T1] ACPI: PCI: Interrupt link LNKA configured for IRQ 0 <6>[ 9.050890][ T1] ACPI: PCI: Interrupt link LNKB configured for IRQ 0 <6>[ 9.051418][ T1] ACPI: PCI: Interrupt link LNKC configured for IRQ 0 <6>[ 9.051944][ T1] ACPI: PCI: Interrupt link LNKD configured for IRQ 0 <6>[ 9.052400][ T1] ACPI: PCI: Interrupt link LNKE configured for IRQ 0 <6>[ 9.052770][ T1] ACPI: PCI: Interrupt link LNKF configured for IRQ 0 <6>[ 9.053139][ T1] ACPI: PCI: Interrupt link LNKG configured for IRQ 0 <6>[ 9.053520][ T1] ACPI: PCI: Interrupt link LNKH configured for IRQ 0 <6>[ 9.061830][ T1] ACPI: EC: interrupt unblocked <6>[ 9.061886][ T1] ACPI: EC: event unblocked <6>[ 9.061929][ T1] ACPI: EC: EC_CMD/EC_SC=0x66, EC_DATA=0x62 <6>[ 9.061969][ T1] ACPI: EC: GPE=0x3 <6>[ 9.061997][ T1] ACPI: \_SB_.PCI0.LPC0.EC0_: Boot DSDT EC initialization complete <6>[ 9.062046][ T1] ACPI: \_SB_.PCI0.LPC0.EC0_: EC: Used to handle transactions and events <6>[ 9.063369][ T1] iommu: Default domain type: Translated <6>[ 9.063587][ T1] pci 0000:00:01.0: vgaarb: setting as boot VGA device <6>[ 9.063587][ T1] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none <6>[ 9.063587][ T1] pci 0000:00:01.0: vgaarb: bridge control possible <6>[ 9.063587][ T1] vgaarb: loaded <5>[ 9.065094][ T1] SCSI subsystem initialized <7>[ 9.065151][ T1] libata version 3.00 loaded. <6>[ 9.065151][ T1] ACPI: bus type USB registered <6>[ 9.065151][ T1] usbcore: registered new interface driver usbfs <6>[ 9.065151][ T1] usbcore: registered new interface driver hub <6>[ 9.065151][ T1] usbcore: registered new device driver usb <6>[ 9.065151][ T1] pps_core: LinuxPPS API ver. 1 registered <6>[ 9.065151][ T1] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <6>[ 9.065151][ T1] PTP clock support registered <6>[ 9.066654][ T1] EDAC MC: Ver: 3.0.0 <6>[ 9.067859][ T1] NetLabel: Initializing <6>[ 9.067885][ T1] NetLabel: domain hash size = 128 <6>[ 9.067912][ T1] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO <6>[ 9.068038][ T1] NetLabel: unlabeled traffic allowed by default <6>[ 9.070067][ T1] PCI: Using ACPI for IRQ routing <7>[ 9.071127][ T1] PCI: pci_cache_line_size set to 64 bytes <7>[ 9.071238][ T1] e820: reserve RAM buffer [mem 0x0009f400-0x0009ffff] <7>[ 9.071265][ T1] e820: reserve RAM buffer [mem 0x9a0bc000-0x9bffffff] <7>[ 9.071271][ T1] e820: reserve RAM buffer [mem 0x9f6bf000-0x9fffffff] <7>[ 9.071276][ T1] e820: reserve RAM buffer [mem 0x9fc00000-0x9fffffff] <7>[ 9.071281][ T1] e820: reserve RAM buffer [mem 0x31f000000-0x31fffffff] <6>[ 9.071309][ T1] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 <6>[ 9.071309][ T1] hpet0: 3 comparators, 32-bit 14.318180 MHz counter <6>[ 9.073929][ T1] clocksource: Switched to clocksource tsc-early <5>[ 9.239535][ T1] VFS: Disk quotas dquot_6.6.0 <6>[ 9.239727][ T1] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) <6>[ 9.241375][ T1] AppArmor: AppArmor Filesystem Enabled <6>[ 9.241659][ T1] pnp: PnP ACPI init <6>[ 9.242588][ T1] system 00:00: [mem 0xfec00000-0xfec01fff] could not be reserved <6>[ 9.242641][ T1] system 00:00: [mem 0xfee00000-0xfee00fff] has been reserved <6>[ 9.244927][ T1] system 00:04: [io 0x0400-0x04cf] has been reserved <6>[ 9.244971][ T1] system 00:04: [io 0x04d0-0x04d1] has been reserved <6>[ 9.245013][ T1] system 00:04: [io 0x04d6] has been reserved <6>[ 9.245051][ T1] system 00:04: [io 0x0680-0x06ff] has been reserved <6>[ 9.245092][ T1] system 00:04: [io 0x077a] has been reserved <6>[ 9.245130][ T1] system 00:04: [io 0x0c00-0x0c01] has been reserved <6>[ 9.245171][ T1] system 00:04: [io 0x0c14] has been reserved <6>[ 9.245209][ T1] system 00:04: [io 0x0c50-0x0c52] has been reserved <6>[ 9.245250][ T1] system 00:04: [io 0x0c6c] has been reserved <6>[ 9.245288][ T1] system 00:04: [io 0x0c6f] has been reserved <6>[ 9.245326][ T1] system 00:04: [io 0x0cd0-0x0cdb] has been reserved <6>[ 9.245367][ T1] system 00:04: [io 0x0840-0x0847] has been reserved <6>[ 9.245873][ T1] system 00:05: [mem 0x000e0000-0x000fffff] could not be reserved <6>[ 9.245921][ T1] system 00:05: [mem 0xff800000-0xffffffff] has been reserved <6>[ 9.253781][ T1] pnp: PnP ACPI: found 6 devices <6>[ 9.269792][ T1] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns <6>[ 9.270154][ T1] NET: Registered PF_INET protocol family <6>[ 9.270771][ T1] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) <6>[ 9.274352][ T1] tcp_listen_portaddr_hash hash table entries: 8192 (order: 7, 655360 bytes, linear) <6>[ 9.274889][ T1] TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear) <6>[ 9.275501][ T1] TCP bind hash table entries: 65536 (order: 10, 4718592 bytes, vmalloc) <6>[ 9.278299][ T1] TCP: Hash tables configured (established 131072 bind 65536) <6>[ 9.279444][ T1] MPTCP token hash table entries: 16384 (order: 8, 1441792 bytes, linear) <6>[ 9.280457][ T1] UDP hash table entries: 8192 (order: 8, 1310720 bytes, linear) <6>[ 9.281287][ T1] UDP-Lite hash table entries: 8192 (order: 8, 1310720 bytes, linear) <6>[ 9.282392][ T1] NET: Registered PF_UNIX/PF_LOCAL protocol family <6>[ 9.282452][ T1] NET: Registered PF_XDP protocol family <6>[ 9.282493][ T1] pci 0000:00:01.0: can't claim BAR 6 [mem 0xfffe0000-0xffffffff pref]: address conflict with Reserved [mem 0xff800000-0xffffffff] <6>[ 9.282559][ T1] pci 0000:01:00.0: can't claim BAR 6 [mem 0xffff0000-0xffffffff pref]: no compatible bridge window <6>[ 9.282650][ T1] pci 0000:00:01.0: BAR 6: assigned [mem 0xf0c80000-0xf0c9ffff pref] <6>[ 9.282696][ T1] pci 0000:01:00.0: BAR 6: assigned [mem 0xf0b10000-0xf0b1ffff pref] <6>[ 9.282737][ T1] pci 0000:00:02.3: PCI bridge to [bus 01] <6>[ 9.282769][ T1] pci 0000:00:02.3: bridge window [io 0x2000-0x2fff] <6>[ 9.282806][ T1] pci 0000:00:02.3: bridge window [mem 0xf0b00000-0xf0bfffff] <6>[ 9.282850][ T1] pci 0000:00:02.4: PCI bridge to [bus 02] <6>[ 9.282882][ T1] pci 0000:00:02.4: bridge window [mem 0xf0a00000-0xf0afffff] <6>[ 9.282928][ T1] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] <6>[ 9.282962][ T1] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] <6>[ 9.282996][ T1] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] <6>[ 9.283033][ T1] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000c3fff window] <6>[ 9.283070][ T1] pci_bus 0000:00: resource 8 [mem 0x000c4000-0x000c7fff window] <6>[ 9.283107][ T1] pci_bus 0000:00: resource 9 [mem 0x000c8000-0x000cbfff window] <6>[ 9.283144][ T1] pci_bus 0000:00: resource 10 [mem 0x000d0000-0x000d3fff window] <6>[ 9.283182][ T1] pci_bus 0000:00: resource 11 [mem 0x000d4000-0x000d7fff window] <6>[ 9.283220][ T1] pci_bus 0000:00: resource 12 [mem 0x000d8000-0x000dbfff window] <6>[ 9.283257][ T1] pci_bus 0000:00: resource 13 [mem 0x000dc000-0x000dffff window] <6>[ 9.283329][ T1] pci_bus 0000:00: resource 14 [mem 0x000e0000-0x000e3fff window] <6>[ 9.283367][ T1] pci_bus 0000:00: resource 15 [mem 0x000e4000-0x000e7fff window] <6>[ 9.283404][ T1] pci_bus 0000:00: resource 16 [mem 0x000e8000-0x000ebfff window] <6>[ 9.283442][ T1] pci_bus 0000:00: resource 17 [mem 0x000ec000-0x000effff window] <6>[ 9.283479][ T1] pci_bus 0000:00: resource 18 [mem 0xe0000000-0xf7ffffff window] <6>[ 9.283517][ T1] pci_bus 0000:00: resource 19 [mem 0xfc000000-0xfed3ffff window] <6>[ 9.283554][ T1] pci_bus 0000:00: resource 20 [mem 0xfed45000-0xffffffff window] <6>[ 9.283592][ T1] pci_bus 0000:01: resource 0 [io 0x2000-0x2fff] <6>[ 9.283623][ T1] pci_bus 0000:01: resource 1 [mem 0xf0b00000-0xf0bfffff] <6>[ 9.283657][ T1] pci_bus 0000:02: resource 1 [mem 0xf0a00000-0xf0afffff] <6>[ 9.284164][ T1] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] <6>[ 9.284501][ T1] pci 0000:00:01.1: D0 power state depends on 0000:00:01.0 <6>[ 9.297500][ T1] pci 0000:00:10.0: quirk_usb_early_handoff+0x0/0x720 took 12578 usecs <6>[ 9.298656][ T1] pci 0000:00:12.0: PME# does not work under D3, disabling it <6>[ 9.299745][ T1] pci 0000:00:13.0: PME# does not work under D3, disabling it <6>[ 9.299874][ T1] PCI: CLS 64 bytes, default 64 <6>[ 9.299982][ T1] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) <6>[ 9.300019][ T1] software IO TLB: mapped [mem 0x000000009b6bf000-0x000000009f6bf000] (64MB) <6>[ 9.300123][ T1] amd_uncore: 4 amd_nb counters detected <6>[ 9.300166][ T1] amd_uncore: 4 amd_l2 counters detected <6>[ 9.300540][ T1] LVT offset 0 assigned for vector 0x400 <6>[ 9.300654][ T8] Trying to unpack rootfs image as initramfs... <6>[ 9.300709][ T1] perf: AMD IBS detected (0x000000ff) <6>[ 9.301091][ T1] simple-framebuffer simple-framebuffer.0: framebuffer at 0xe0000000, 0x420000 bytes <6>[ 9.301144][ T1] simple-framebuffer simple-framebuffer.0: format=x8r8g8b8, mode=1366x768x32, linelength=5632 <6>[ 9.302163][ T1] Console: switching to colour frame buffer device 170x48 <6>[ 9.307021][ T1] simple-framebuffer simple-framebuffer.0: fb0: simplefb registered! <5>[ 9.311071][ T1] Initialise system trusted keyrings <6>[ 9.311549][ T1] workingset: timestamp_bits=36 max_order=22 bucket_order=0 <6>[ 9.311984][ T1] zbud: loaded <6>[ 9.313118][ T1] squashfs: version 4.0 (2009/01/31) Phillip Lougher <6>[ 9.314023][ T1] fuse: init (API version 7.34) <5>[ 9.335228][ T1] Key type asymmetric registered <5>[ 9.335359][ T1] Asymmetric key parser 'x509' registered <6>[ 9.335495][ T1] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) <6>[ 9.335914][ T1] io scheduler mq-deadline registered <6>[ 9.338247][ T1] pcieport 0000:00:02.3: PME: Signaling with IRQ 25 <6>[ 9.339714][ T1] pcieport 0000:00:02.4: PME: Signaling with IRQ 27 <6>[ 9.340329][ T1] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 <6>[ 9.342794][ T1] ACPI: AC: AC Adapter [ACAD] (on-line) <4>[ 9.344193][ T1] sysfs: cannot create duplicate filename '/class/power_supply/ACAD' <4>[ 9.344293][ T1] CPU: 2 PID: 1 Comm: swapper/0 Not tainted 5.14.0-rc3-bluetea-test-00249-gc7d102232649 #5 <4>[ 9.344412][ T1] Hardware name: Acer Aspire ES1-421/OLVIA_BE, BIOS V1.05 07/02/2015 <4>[ 9.344506][ T1] Call Trace: <4>[ 9.344553][ T1] dump_stack_lvl+0x6a/0x9a <4>[ 9.344618][ T1] sysfs_warn_dup.cold+0x17/0x24 <4>[ 9.344686][ T1] sysfs_do_create_link_sd+0xbe/0xd0 <4>[ 9.344760][ T1] device_add+0x272/0x970 <4>[ 9.344817][ T1] ? lockdep_init_map_type+0x47/0x240 <4>[ 9.346427][ T1] ? lockdep_init_map_type+0x47/0x240 <4>[ 9.346606][ T1] __power_supply_register+0x2a8/0x4b0 <4>[ 9.346606][ T1] acpi_ac_add+0x135/0x170 <4>[ 9.346606][ T1] acpi_device_probe+0x44/0x150 <4>[ 9.346606][ T1] really_probe+0x1db/0x3c0 <4>[ 9.346606][ T1] __driver_probe_device+0xfe/0x180 <4>[ 9.346606][ T1] driver_probe_device+0x1e/0x90 <4>[ 9.346606][ T1] __driver_attach+0xc1/0x1d0 <4>[ 9.346606][ T1] ? __device_attach_driver+0xd0/0xd0 <4>[ 9.346606][ T1] ? __device_attach_driver+0xd0/0xd0 <4>[ 9.346606][ T1] bus_for_each_dev+0x78/0xc0 <4>[ 9.346606][ T1] bus_add_driver+0x12e/0x1f0 <4>[ 9.346606][ T1] driver_register+0x8f/0xe0 <4>[ 9.346606][ T1] ? ac_do_not_check_pmic_quirk+0x12/0x12 <4>[ 9.346606][ T1] acpi_ac_init+0x93/0xa3 <4>[ 9.346606][ T1] do_one_initcall+0x58/0x2d0 <4>[ 9.346606][ T1] ? rcu_read_lock_sched_held+0x3f/0x80 <4>[ 9.346606][ T1] kernel_init_freeable+0x2a7/0x30e <4>[ 9.346606][ T1] ? rest_init+0x280/0x280 <4>[ 9.346606][ T1] kernel_init+0x16/0x120 <4>[ 9.346606][ T1] ret_from_fork+0x1f/0x30 <4>[ 9.376975][ T1] ac: probe of ACPI0003:01 failed with error -17 <6>[ 9.378668][ T1] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 <6>[ 9.380286][ T1] ACPI: button: Power Button [PWRB] <6>[ 9.381792][ T1] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input1 <6>[ 9.383314][ T1] ACPI: button: Sleep Button [SLPB] <6>[ 9.384945][ T1] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input2 <6>[ 9.386450][ T1] ACPI: button: Lid Switch [LID] <6>[ 9.388066][ T1] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 <6>[ 9.390292][ T1] ACPI: button: Power Button [PWRF] <6>[ 9.391835][ T1] ACPI: \_PR_.C000: Found 2 idle states <6>[ 9.394221][ T1] ACPI: \_PR_.C001: Found 2 idle states <6>[ 9.396096][ T1] ACPI: \_PR_.C002: Found 2 idle states <6>[ 9.398112][ T1] ACPI: \_PR_.C003: Found 2 idle states <6>[ 9.401899][ T1] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled <6>[ 9.419574][ T1] Linux agpgart interface v0.103 <6>[ 9.452395][ T1] loop: module loaded <6>[ 9.456133][ T1] libphy: Fixed MDIO Bus: probed <6>[ 9.457360][ T1] tun: Universal TUN/TAP device driver, 1.6 <6>[ 9.457520][ T109] ACPI: battery: Slot [BAT1] (battery present) <6>[ 9.458899][ T1] PPP generic driver version 2.4.2 <6>[ 9.461668][ T1] VFIO - User Level meta-driver version: 0.3 <6>[ 9.463696][ T1] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver <6>[ 9.465025][ T1] ehci-pci: EHCI PCI platform driver <6>[ 9.467343][ T1] ehci-pci 0000:00:12.0: EHCI Host Controller <6>[ 9.468959][ T1] ehci-pci 0000:00:12.0: new USB bus registered, assigned bus number 1 <6>[ 9.470336][ T1] ehci-pci 0000:00:12.0: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround <6>[ 9.471647][ T1] ehci-pci 0000:00:12.0: debug port 2 <6>[ 9.473309][ T1] ehci-pci 0000:00:12.0: irq 18, io mem 0xf0c6e000 <6>[ 9.486739][ T1] ehci-pci 0000:00:12.0: USB 2.0 started, EHCI 1.00 <6>[ 9.488775][ T1] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.14 <6>[ 9.490205][ T1] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 9.491483][ T1] usb usb1: Product: EHCI Host Controller <6>[ 9.492859][ T1] usb usb1: Manufacturer: Linux 5.14.0-rc3-bluetea-test-00249-gc7d102232649 ehci_hcd <6>[ 9.494279][ T1] usb usb1: SerialNumber: 0000:00:12.0 <6>[ 9.497712][ T1] hub 1-0:1.0: USB hub found <6>[ 9.499386][ T1] hub 1-0:1.0: 2 ports detected <6>[ 9.503442][ T1] ehci-pci 0000:00:13.0: EHCI Host Controller <6>[ 9.504917][ T1] ehci-pci 0000:00:13.0: new USB bus registered, assigned bus number 2 <6>[ 9.506326][ T1] ehci-pci 0000:00:13.0: applying AMD SB700/SB800/Hudson-2/3 EHCI dummy qh workaround <6>[ 9.507851][ T1] ehci-pci 0000:00:13.0: debug port 2 <6>[ 9.509341][ T1] ehci-pci 0000:00:13.0: irq 18, io mem 0xf0c6d000 <6>[ 9.523546][ T1] ehci-pci 0000:00:13.0: USB 2.0 started, EHCI 1.00 <6>[ 9.525352][ T1] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.14 <6>[ 9.526951][ T1] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 9.528512][ T1] usb usb2: Product: EHCI Host Controller <6>[ 9.530058][ T1] usb usb2: Manufacturer: Linux 5.14.0-rc3-bluetea-test-00249-gc7d102232649 ehci_hcd <6>[ 9.531691][ T1] usb usb2: SerialNumber: 0000:00:13.0 <6>[ 9.534198][ T1] hub 2-0:1.0: USB hub found <6>[ 9.535830][ T1] hub 2-0:1.0: 2 ports detected <6>[ 9.538660][ T1] ehci-platform: EHCI generic platform driver <6>[ 9.540228][ T1] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver <6>[ 9.541736][ T1] ohci-pci: OHCI PCI platform driver <6>[ 9.543327][ T1] ohci-platform: OHCI generic platform driver <6>[ 9.544815][ T1] uhci_hcd: USB Universal Host Controller Interface driver <6>[ 9.546576][ T1] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:MSS0] at 0x60,0x64 irq 1,12 <6>[ 9.565837][ T1] serio: i8042 KBD port at 0x60,0x64 irq 1 <6>[ 9.567511][ T1] serio: i8042 AUX port at 0x60,0x64 irq 12 <6>[ 9.570276][ T1] mousedev: PS/2 mouse device common for all mice <6>[ 9.573672][ T1] rtc_cmos 00:01: RTC can wake from S4 <6>[ 9.576769][ T1] rtc_cmos 00:01: registered as rtc0 <6>[ 9.578450][ T1] rtc_cmos 00:01: setting system clock to 2021-08-04T10:56:10 UTC (1628074570) <6>[ 9.580270][ T1] rtc_cmos 00:01: alarms up to one month, 114 bytes nvram, hpet irqs <6>[ 9.581915][ T1] i2c /dev entries driver <6>[ 9.583719][ T1] device-mapper: uevent: version 1.0.3 <6>[ 9.585993][ T1] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com <6>[ 9.588347][ T38] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input4 <6>[ 9.590180][ T1] EDAC igen6: v2.5 <6>[ 9.591897][ T1] platform eisa.0: Probing EISA bus 0 <4>[ 9.593496][ T1] platform eisa.0: EISA: Cannot allocate resource for mainboard <4>[ 9.595048][ T1] platform eisa.0: Cannot allocate resource for EISA slot 1 <4>[ 9.596648][ T1] platform eisa.0: Cannot allocate resource for EISA slot 2 <4>[ 9.598216][ T1] platform eisa.0: Cannot allocate resource for EISA slot 3 <4>[ 9.599684][ T1] platform eisa.0: Cannot allocate resource for EISA slot 4 <4>[ 9.601222][ T1] platform eisa.0: Cannot allocate resource for EISA slot 5 <4>[ 9.602624][ T1] platform eisa.0: Cannot allocate resource for EISA slot 6 <4>[ 9.604109][ T1] platform eisa.0: Cannot allocate resource for EISA slot 7 <4>[ 9.605485][ T1] platform eisa.0: Cannot allocate resource for EISA slot 8 <6>[ 9.606910][ T1] platform eisa.0: EISA: Detected 0 cards <6>[ 9.608529][ T1] ledtrig-cpu: registered to indicate activity on CPUs <6>[ 9.610538][ T1] drop_monitor: Initializing network drop monitor service <6>[ 9.612469][ T1] NET: Registered PF_INET6 protocol family <6>[ 9.750018][ T7] usb 1-1: new high-speed USB device number 2 using ehci-pci <6>[ 9.786688][ T124] usb 2-1: new high-speed USB device number 2 using ehci-pci <6>[ 9.897363][ T7] usb 1-1: New USB device found, idVendor=0438, idProduct=7900, bcdDevice= 0.18 <6>[ 9.898735][ T7] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 <6>[ 9.901997][ T7] hub 1-1:1.0: USB hub found <6>[ 9.903623][ T7] hub 1-1:1.0: 4 ports detected <6>[ 9.933723][ T124] usb 2-1: New USB device found, idVendor=0438, idProduct=7900, bcdDevice= 0.18 <6>[ 9.935071][ T124] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 <6>[ 9.938281][ T124] hub 2-1:1.0: USB hub found <6>[ 9.940012][ T124] hub 2-1:1.0: 4 ports detected <6>[ 10.203373][ T126] usb 1-1.1: new low-speed USB device number 3 using ehci-pci <6>[ 10.303574][ T39] tsc: Refined TSC clocksource calibration: 1996.256 MHz <6>[ 10.304920][ T39] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x398cbb98ce6, max_idle_ns: 881590701561 ns <6>[ 10.306536][ T39] clocksource: Switched to clocksource tsc <6>[ 10.321241][ T126] usb 1-1.1: New USB device found, idVendor=046d, idProduct=c077, bcdDevice=72.00 <6>[ 10.322763][ T126] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 <6>[ 10.324300][ T126] usb 1-1.1: Product: USB Optical Mouse <6>[ 10.325756][ T126] usb 1-1.1: Manufacturer: Logitech <6>[ 10.403382][ T126] usb 1-1.3: new full-speed USB device number 4 using ehci-pci <6>[ 10.519973][ T126] usb 1-1.3: New USB device found, idVendor=04ca, idProduct=2012, bcdDevice= 1.12 <6>[ 10.521438][ T126] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 <6>[ 10.522932][ T126] usb 1-1.3: Product: BCM43142A0 <6>[ 10.524321][ T126] usb 1-1.3: Manufacturer: Broadcom Corp <6>[ 10.525785][ T126] usb 1-1.3: SerialNumber: ACE01065F0F0 <6>[ 10.620037][ T126] usb 1-1.4: new high-speed USB device number 5 using ehci-pci <6>[ 10.751281][ T126] usb 1-1.4: New USB device found, idVendor=04f2, idProduct=b47f, bcdDevice=40.51 <6>[ 10.752815][ T126] usb 1-1.4: New USB device strings: Mfr=3, Product=1, SerialNumber=2 <6>[ 10.754409][ T126] usb 1-1.4: Product: VGA Webcam <6>[ 10.755929][ T126] usb 1-1.4: Manufacturer: Chicony Electronics Co.,Ltd. <6>[ 10.757412][ T126] usb 1-1.4: SerialNumber: 0x0001 <6>[ 15.903676][ T8] Freeing initrd memory: 934716K <6>[ 15.936727][ T1] Segment Routing with IPv6 <6>[ 15.938157][ T1] NET: Registered PF_PACKET protocol family <5>[ 15.939673][ T1] Key type dns_resolver registered <6>[ 15.941010][ T1] x86/pm: family 0x16 cpu detected, MSR saving is needed during suspending. <6>[ 15.943983][ T1] microcode: CPU0: patch_level=0x07030105 <6>[ 15.945435][ C1] microcode: CPU1: patch_level=0x07030105 <6>[ 15.946755][ C2] microcode: CPU2: patch_level=0x07030105 <6>[ 15.948148][ C3] microcode: CPU3: patch_level=0x07030105 <6>[ 15.949777][ T1] microcode: Microcode Update Driver: v2.2. <6>[ 15.949788][ T1] IPI shorthand broadcast: enabled <6>[ 15.952581][ T1] sched_clock: Marking stable (15599663773, 351499728)->(15958257918, -7094417) <6>[ 15.955287][ T1] registered taskstats version 1 <6>[ 15.956699][ T1] Running ring buffer tests... <6>[ 26.173118][ T1] finished <6>[ 26.174651][ T1] CPU 0: <6>[ 26.176110][ T1] events: 1330938 <6>[ 26.177585][ T1] dropped bytes: 0 <6>[ 26.179020][ T1] alloced bytes: 103742468 <6>[ 26.180476][ T1] written bytes: 101626194 <6>[ 26.181897][ T1] biggest event: 294 <6>[ 26.183243][ T1] smallest event: 4 <6>[ 26.192166][ T1] read events: 12560 <6>[ 26.193440][ T1] lost events: 1318378 <6>[ 26.194670][ T1] total events: 1330938 <6>[ 26.195876][ T1] recorded len bytes: 977012 <6>[ 26.197071][ T1] recorded size bytes: 957122 <6>[ 26.198315][ T1] With dropped events, record len and size may not match <6>[ 26.198315][ T1] alloced and written from above <6>[ 26.200742][ T1] CPU 1: <6>[ 26.201865][ T1] events: 1330938 <6>[ 26.202976][ T1] dropped bytes: 0 <6>[ 26.204061][ T1] alloced bytes: 103790848 <6>[ 26.205123][ T1] written bytes: 101671184 <6>[ 26.206163][ T1] biggest event: 296 <6>[ 26.207206][ T1] smallest event: 4 <6>[ 26.215787][ T1] read events: 12419 <6>[ 26.216800][ T1] lost events: 1318519 <6>[ 26.217770][ T1] total events: 1330938 <6>[ 26.218717][ T1] recorded len bytes: 973568 <6>[ 26.219667][ T1] recorded size bytes: 954325 <6>[ 26.220620][ T1] With dropped events, record len and size may not match <6>[ 26.220620][ T1] alloced and written from above <6>[ 26.222519][ T1] CPU 2: <6>[ 26.223431][ T1] events: 1330906 <6>[ 26.224343][ T1] dropped bytes: 0 <6>[ 26.225232][ T1] alloced bytes: 103708300 <6>[ 26.226117][ T1] written bytes: 101591621 <6>[ 26.226999][ T1] biggest event: 212 <6>[ 26.227862][ T1] smallest event: 4 <6>[ 26.236254][ T1] read events: 12359 <6>[ 26.237114][ T1] lost events: 1318547 <6>[ 26.237929][ T1] total events: 1330906 <6>[ 26.238712][ T1] recorded len bytes: 976460 <6>[ 26.239499][ T1] recorded size bytes: 957085 <6>[ 26.240311][ T1] With dropped events, record len and size may not match <6>[ 26.240311][ T1] alloced and written from above <6>[ 26.241986][ T1] CPU 3: <6>[ 26.242795][ T1] events: 11487 <6>[ 26.243601][ T1] dropped bytes: 0 <6>[ 26.244365][ T1] alloced bytes: 896892 <6>[ 26.245129][ T1] written bytes: 878648 <6>[ 26.245878][ T1] biggest event: 12 <6>[ 26.246639][ T1] smallest event: 0 <6>[ 26.254418][ T1] read events: 11487 <6>[ 26.255175][ T1] lost events: 0 <6>[ 26.255922][ T1] total events: 11487 <6>[ 26.256679][ T1] recorded len bytes: 896892 <6>[ 26.257422][ T1] recorded size bytes: 878648 <6>[ 26.258175][ T1] Ring buffer PASSED! <6>[ 26.260098][ T1] Running tests on trace events: <6>[ 26.260869][ T1] Testing event initcall_finish: OK <6>[ 26.304585][ T1] Testing event initcall_start: OK <6>[ 26.351280][ T1] Testing event initcall_level: OK <6>[ 26.394608][ T1] Testing event emulate_vsyscall: OK <6>[ 26.434562][ T1] Testing event xen_cpu_set_ldt: OK <6>[ 26.477872][ T1] Testing event xen_cpu_write_gdt_entry: OK <6>[ 26.521242][ T1] Testing event xen_cpu_load_idt: OK <6>[ 26.564537][ T1] Testing event xen_cpu_write_idt_entry: OK <6>[ 26.611204][ T1] Testing event xen_cpu_write_ldt_entry: OK <6>[ 26.654626][ T1] Testing event xen_mmu_write_cr3: OK <6>[ 26.697912][ T1] Testing event xen_mmu_flush_tlb_multi: OK <6>[ 26.741284][ T1] Testing event xen_mmu_flush_tlb_one_user: OK <6>[ 26.781302][ T1] Testing event xen_mmu_pgd_unpin: OK <6>[ 26.824599][ T1] Testing event xen_mmu_pgd_pin: OK <6>[ 26.867939][ T1] Testing event xen_mmu_release_ptpage: OK <6>[ 26.914632][ T1] Testing event xen_mmu_alloc_ptpage: OK <6>[ 26.957942][ T1] Testing event xen_mmu_ptep_modify_prot_commit: OK <6>[ 27.001321][ T1] Testing event xen_mmu_ptep_modify_prot_start: OK <6>[ 27.044580][ T1] Testing event xen_mmu_set_p4d: OK <6>[ 27.087932][ T1] Testing event xen_mmu_set_pud: OK <6>[ 27.131252][ T1] Testing event xen_mmu_set_pmd: OK <6>[ 27.174563][ T1] Testing event xen_mmu_set_pte: OK <6>[ 27.214552][ T1] Testing event xen_mc_extend_args: OK <6>[ 27.257880][ T1] Testing event xen_mc_flush: OK <6>[ 27.304578][ T1] Testing event xen_mc_flush_reason: OK <6>[ 27.347916][ T1] Testing event xen_mc_callback: OK <6>[ 27.394572][ T1] Testing event xen_mc_entry_alloc: OK <6>[ 27.441238][ T1] Testing event xen_mc_entry: OK <6>[ 27.488016][ T1] Testing event xen_mc_issue: OK <6>[ 27.527870][ T1] Testing event xen_mc_batch: OK <6>[ 27.571159][ T1] Testing event hyperv_send_ipi_one: OK <6>[ 27.618065][ T1] Testing event hyperv_send_ipi_mask: OK <6>[ 27.664717][ T1] Testing event hyperv_nested_flush_guest_mapping_range: OK <6>[ 27.708044][ T1] Testing event hyperv_nested_flush_guest_mapping: OK <6>[ 27.751388][ T1] Testing event hyperv_mmu_flush_tlb_multi: OK <6>[ 27.794703][ T1] Testing event vector_free_moved: OK <6>[ 27.834458][ T1] Testing event vector_setup: OK <6>[ 27.881150][ T1] Testing event vector_teardown: OK <6>[ 27.927800][ T1] Testing event vector_deactivate: OK <6>[ 27.974446][ T1] Testing event vector_activate: OK <6>[ 28.021147][ T1] Testing event vector_alloc_managed: OK <6>[ 28.064630][ T1] Testing event vector_alloc: OK <6>[ 28.111197][ T1] Testing event vector_reserve: OK <6>[ 28.151137][ T1] Testing event vector_reserve_managed: OK <6>[ 28.194466][ T1] Testing event vector_clear: OK <6>[ 28.241196][ T1] Testing event vector_update: OK <6>[ 28.284507][ T1] Testing event vector_config: OK <6>[ 28.331185][ T1] Testing event thermal_apic_exit: OK <6>[ 28.374516][ T1] Testing event thermal_apic_entry: OK <6>[ 28.417837][ T1] Testing event deferred_error_apic_exit: OK <6>[ 28.457863][ T1] Testing event deferred_error_apic_entry: OK <6>[ 28.501199][ T1] Testing event threshold_apic_exit: OK <6>[ 28.544534][ T1] Testing event threshold_apic_entry: OK <6>[ 28.584509][ T1] Testing event call_function_single_exit: OK <6>[ 28.627853][ T1] Testing event call_function_single_entry: OK <6>[ 28.674536][ T1] Testing event call_function_exit: OK <6>[ 28.721171][ T1] Testing event call_function_entry: OK <6>[ 28.764501][ T1] Testing event reschedule_exit: OK <6>[ 28.807845][ T1] Testing event reschedule_entry: OK <6>[ 28.854497][ T1] Testing event irq_work_exit: OK <6>[ 28.897858][ T1] Testing event irq_work_entry: OK <6>[ 28.941149][ T1] Testing event x86_platform_ipi_exit: OK <6>[ 28.984502][ T1] Testing event x86_platform_ipi_entry: OK <6>[ 29.027853][ T1] Testing event error_apic_exit: OK <6>[ 29.071168][ T1] Testing event error_apic_entry: OK <6>[ 29.114533][ T1] Testing event spurious_apic_exit: OK <6>[ 29.157936][ T1] Testing event spurious_apic_entry: OK <6>[ 29.201173][ T1] Testing event local_timer_exit: OK <6>[ 29.244494][ T1] Testing event local_timer_entry: OK <6>[ 29.287879][ T1] Testing event nmi_handler: OK <6>[ 29.331204][ T1] Testing event x86_fpu_xstate_check_failed: OK <6>[ 29.374558][ T1] Testing event x86_fpu_copy_dst: OK <6>[ 29.414757][ T1] Testing event x86_fpu_copy_src: OK <6>[ 29.457980][ T1] Testing event x86_fpu_dropped: OK <6>[ 29.497991][ T1] Testing event x86_fpu_init_state: OK <6>[ 29.541115][ T1] Testing event x86_fpu_regs_deactivated: OK <6>[ 29.584500][ T1] Testing event x86_fpu_regs_activated: OK <6>[ 29.631181][ T1] Testing event x86_fpu_after_restore: OK <6>[ 29.674402][ T1] Testing event x86_fpu_before_restore: OK <6>[ 29.721115][ T1] Testing event x86_fpu_after_save: OK <6>[ 29.764464][ T1] Testing event x86_fpu_before_save: OK <6>[ 29.807820][ T1] Testing event mce_record: OK <6>[ 29.851111][ T1] Testing event pseudo_lock_l3: OK <6>[ 29.891131][ T1] Testing event pseudo_lock_l2: OK <6>[ 29.934436][ T1] Testing event pseudo_lock_mem_latency: OK <6>[ 29.977759][ T1] Testing event tlb_flush: OK <6>[ 30.024561][ T1] Testing event page_fault_kernel: OK <6>[ 30.067888][ T1] Testing event page_fault_user: OK <6>[ 30.111171][ T1] Testing event task_rename: OK <6>[ 30.154543][ T1] Testing event task_newtask: OK <6>[ 30.194512][ T1] Testing event cpuhp_exit: OK <6>[ 30.237850][ T1] Testing event cpuhp_multi_enter: OK <6>[ 30.284529][ T1] Testing event cpuhp_enter: OK <6>[ 30.331211][ T1] Testing event softirq_raise: OK <6>[ 30.374537][ T1] Testing event softirq_exit: OK <6>[ 30.417815][ T1] Testing event softirq_entry: OK <6>[ 30.464520][ T1] Testing event irq_handler_exit: OK <6>[ 30.507874][ T1] Testing event irq_handler_entry: OK <6>[ 30.551176][ T1] Testing event signal_deliver: OK <6>[ 30.597873][ T1] Testing event signal_generate: OK <6>[ 30.641173][ T1] Testing event workqueue_execute_end: OK <6>[ 30.684510][ T1] Testing event workqueue_execute_start: OK <6>[ 30.727876][ T1] Testing event workqueue_activate_work: OK <6>[ 30.771161][ T1] Testing event workqueue_queue_work: OK <6>[ 30.811199][ T1] Testing event sched_wake_idle_without_ipi: OK <6>[ 30.854584][ T1] Testing event sched_swap_numa: OK <6>[ 30.894564][ T1] Testing event sched_stick_numa: OK <6>[ 30.941228][ T1] Testing event sched_move_numa: OK <6>[ 30.981214][ T1] Testing event sched_process_hang: OK <6>[ 31.027840][ T1] Testing event sched_pi_setprio: OK <6>[ 31.071193][ T1] Testing event sched_stat_runtime: <4>[ 31.071434][ T1] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 <4>[ 31.110493][ T1] OK <6>[ 31.111285][ T1] Testing event sched_stat_blocked: OK <6>[ 31.157944][ T1] Testing event sched_stat_iowait: OK <6>[ 31.201595][ T1] Testing event sched_stat_sleep: OK <6>[ 31.241281][ T1] Testing event sched_stat_wait: OK <6>[ 31.284545][ T1] Testing event sched_process_exec: OK <6>[ 31.327911][ T1] Testing event sched_process_fork: OK <6>[ 31.367921][ T1] Testing event sched_process_wait: OK <6>[ 31.407935][ T1] Testing event sched_wait_task: OK <6>[ 31.451245][ T1] Testing event sched_process_exit: OK <6>[ 31.494594][ T1] Testing event sched_process_free: OK <6>[ 31.537908][ T1] Testing event sched_migrate_task: OK <6>[ 31.581230][ T1] Testing event sched_switch: OK <6>[ 31.624949][ T1] Testing event sched_wakeup_new: OK <6>[ 31.668342][ T1] Testing event sched_wakeup: OK <6>[ 31.708147][ T1] Testing event sched_waking: OK <6>[ 31.754873][ T1] Testing event sched_kthread_work_execute_end: OK <6>[ 31.794843][ T1] Testing event sched_kthread_work_execute_start: OK <6>[ 31.838183][ T1] Testing event sched_kthread_work_queue_work: OK <6>[ 31.884869][ T1] Testing event sched_kthread_stop_ret: OK <6>[ 31.928177][ T1] Testing event sched_kthread_stop: OK <6>[ 31.971507][ T1] Testing event lock_release: OK <6>[ 32.014653][ T1] Testing event lock_acquire: OK <6>[ 32.054717][ T1] Testing event console: OK <6>[ 32.098011][ T1] Testing event irq_matrix_free: OK <6>[ 32.144635][ T1] Testing event irq_matrix_alloc: OK <6>[ 32.187980][ T1] Testing event irq_matrix_assign: OK <6>[ 32.231321][ T1] Testing event irq_matrix_alloc_managed: OK <6>[ 32.271287][ T1] Testing event irq_matrix_remove_managed: OK <6>[ 32.317923][ T1] Testing event irq_matrix_reserve_managed: OK <6>[ 32.361287][ T1] Testing event irq_matrix_alloc_reserved: OK <6>[ 32.404599][ T1] Testing event irq_matrix_assign_system: OK <6>[ 32.447928][ T1] Testing event irq_matrix_remove_reserved: OK <6>[ 32.491326][ T1] Testing event irq_matrix_reserve: OK <6>[ 32.534610][ T1] Testing event irq_matrix_offline: OK <6>[ 32.581321][ T1] Testing event irq_matrix_online: OK <6>[ 32.624590][ T1] Testing event rcu_stall_warning: OK <6>[ 32.671271][ T1] Testing event rcu_utilization: OK <6>[ 32.714693][ T1] Testing event swiotlb_bounced: OK <6>[ 32.761288][ T1] Testing event sys_exit: OK <6>[ 32.801267][ T1] Testing event sys_enter: OK <6>[ 32.847927][ T1] Testing event tick_stop: OK <6>[ 32.894488][ T1] Testing event itimer_expire: OK <6>[ 32.941299][ T1] Testing event itimer_state: OK <6>[ 32.984832][ T1] Testing event hrtimer_cancel: OK <6>[ 33.027903][ T1] Testing event hrtimer_expire_exit: OK <6>[ 33.071180][ T1] Testing event hrtimer_expire_entry: OK <6>[ 33.114399][ T1] Testing event hrtimer_start: OK <6>[ 33.154406][ T1] Testing event hrtimer_init: OK <6>[ 33.201063][ T1] Testing event timer_cancel: OK <6>[ 33.244411][ T1] Testing event timer_expire_exit: OK <6>[ 33.284407][ T1] Testing event timer_expire_entry: OK <6>[ 33.331046][ T1] Testing event timer_start: OK <6>[ 33.374374][ T1] Testing event timer_init: OK <6>[ 33.417737][ T1] Testing event alarmtimer_cancel: OK <6>[ 33.461050][ T1] Testing event alarmtimer_start: OK <6>[ 33.504363][ T1] Testing event alarmtimer_fired: OK <6>[ 33.547702][ T1] Testing event alarmtimer_suspend: OK <6>[ 33.594410][ T1] Testing event module_request: OK <6>[ 33.637728][ T1] Testing event module_put: OK <6>[ 33.684389][ T1] Testing event module_get: OK <6>[ 33.731123][ T1] Testing event module_free: OK <6>[ 33.771116][ T1] Testing event module_load: OK <6>[ 33.814408][ T1] Testing event cgroup_notify_frozen: OK <6>[ 33.857828][ T1] Testing event cgroup_notify_populated: OK <6>[ 33.904470][ T1] Testing event cgroup_transfer_tasks: OK <6>[ 33.947793][ T1] Testing event cgroup_attach_task: OK <6>[ 33.994462][ T1] Testing event cgroup_unfreeze: OK <6>[ 34.037824][ T1] Testing event cgroup_freeze: OK <6>[ 34.077752][ T1] Testing event cgroup_rename: OK <6>[ 34.121123][ T1] Testing event cgroup_release: OK <6>[ 34.167780][ T1] Testing event cgroup_rmdir: OK <6>[ 34.214479][ T1] Testing event cgroup_mkdir: OK <6>[ 34.257763][ T1] Testing event cgroup_remount: OK <6>[ 34.301086][ T1] Testing event cgroup_destroy_root: OK <6>[ 34.344443][ T1] Testing event cgroup_setup_root: OK <6>[ 34.387773][ T1] Testing event irq_enable: OK <6>[ 34.431167][ T1] Testing event irq_disable: OK <6>[ 34.477833][ T1] Testing event ftrace_test_filter: OK <6>[ 34.517696][ T1] Testing event bpf_trace_printk: OK <6>[ 34.561104][ T1] Testing event error_report_end: OK <6>[ 34.611098][ T1] Testing event dev_pm_qos_remove_request: OK <6>[ 34.654469][ T1] Testing event dev_pm_qos_update_request: OK <6>[ 34.694648][ T1] Testing event dev_pm_qos_add_request: OK <6>[ 34.741330][ T1] Testing event pm_qos_update_flags: OK <6>[ 34.784424][ T1] Testing event pm_qos_update_target: OK <6>[ 34.824653][ T1] Testing event pm_qos_remove_request: OK <6>[ 34.871333][ T1] Testing event pm_qos_update_request: OK <6>[ 34.914615][ T1] Testing event pm_qos_add_request: OK <6>[ 34.957957][ T1] Testing event power_domain_target: OK <6>[ 35.001256][ T1] Testing event clock_set_rate: OK <6>[ 35.051107][ T1] Testing event clock_disable: OK <6>[ 35.094565][ T1] Testing event clock_enable: OK <6>[ 35.137884][ T1] Testing event wakeup_source_deactivate: OK <6>[ 35.177839][ T1] Testing event wakeup_source_activate: OK <6>[ 35.221171][ T1] Testing event suspend_resume: OK <6>[ 35.264554][ T1] Testing event device_pm_callback_end: OK <6>[ 35.307815][ T1] Testing event device_pm_callback_start: OK <6>[ 35.351108][ T1] Testing event cpu_frequency_limits: OK <6>[ 35.391102][ T1] Testing event cpu_frequency: OK <6>[ 35.434437][ T1] Testing event pstate_sample: OK <6>[ 35.477736][ T1] Testing event powernv_throttle: OK <6>[ 35.524450][ T1] Testing event cpu_idle: OK <6>[ 35.567909][ T1] Testing event rpm_return_int: OK <6>[ 35.607869][ T1] Testing event rpm_usage: OK <6>[ 35.651232][ T1] Testing event rpm_idle: OK <6>[ 35.694520][ T1] Testing event rpm_resume: OK <6>[ 35.737859][ T1] Testing event rpm_suspend: OK <6>[ 35.781172][ T1] Testing event mem_return_failed: OK <6>[ 35.824472][ T1] Testing event mem_connect: OK <6>[ 35.867885][ T1] Testing event mem_disconnect: OK <6>[ 35.911197][ T1] Testing event xdp_devmap_xmit: OK <6>[ 35.954544][ T1] Testing event xdp_cpumap_enqueue: OK <6>[ 35.994534][ T1] Testing event xdp_cpumap_kthread: OK <6>[ 36.037849][ T1] Testing event xdp_redirect_map_err: OK <6>[ 36.081182][ T1] Testing event xdp_redirect_map: OK <6>[ 36.124517][ T1] Testing event xdp_redirect_err: OK <6>[ 36.167893][ T1] Testing event xdp_redirect: OK <6>[ 36.214537][ T1] Testing event xdp_bulk_tx: OK <6>[ 36.257804][ T1] Testing event xdp_exception: OK <6>[ 36.301240][ T1] Testing event rseq_ip_fixup: OK <6>[ 36.344548][ T1] Testing event rseq_update: OK <6>[ 36.384524][ T1] Testing event file_check_and_advance_wb_err: OK <6>[ 36.427889][ T1] Testing event filemap_set_wb_err: OK <6>[ 36.471403][ T1] Testing event mm_filemap_add_to_page_cache: OK <6>[ 36.514772][ T1] Testing event mm_filemap_delete_from_page_cache: OK <6>[ 36.557853][ T1] Testing event compact_retry: OK <6>[ 36.601161][ T1] Testing event skip_task_reaping: OK <6>[ 36.644477][ T1] Testing event finish_task_reaping: OK <6>[ 36.687856][ T1] Testing event start_task_reaping: OK <6>[ 36.731159][ T1] Testing event wake_reaper: OK <6>[ 36.771190][ T1] Testing event mark_victim: OK <6>[ 36.811148][ T1] Testing event reclaim_retry_zone: OK <6>[ 36.857816][ T1] Testing event oom_score_adj_update: OK <6>[ 36.901178][ T1] Testing event mm_lru_activate: OK <6>[ 36.947853][ T1] Testing event mm_lru_insertion: OK <6>[ 36.987845][ T1] Testing event mm_vmscan_node_reclaim_end: OK <6>[ 37.031188][ T1] Testing event mm_vmscan_node_reclaim_begin: OK <6>[ 37.074699][ T1] Testing event mm_vmscan_lru_shrink_active: OK <6>[ 37.117944][ T1] Testing event mm_vmscan_lru_shrink_inactive: OK <6>[ 37.157942][ T1] Testing event mm_vmscan_writepage: OK <6>[ 37.197931][ T1] Testing event mm_vmscan_lru_isolate: OK <6>[ 37.241202][ T1] Testing event mm_shrink_slab_end: OK <6>[ 37.287876][ T1] Testing event mm_shrink_slab_start: OK <6>[ 37.327875][ T1] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK <6>[ 37.374563][ T1] Testing event mm_vmscan_memcg_reclaim_end: OK <6>[ 37.417895][ T1] Testing event mm_vmscan_direct_reclaim_end: OK <6>[ 37.457921][ T1] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK <6>[ 37.501230][ T1] Testing event mm_vmscan_memcg_reclaim_begin: OK <6>[ 37.544607][ T1] Testing event mm_vmscan_direct_reclaim_begin: OK <6>[ 37.591258][ T1] Testing event mm_vmscan_wakeup_kswapd: OK <6>[ 37.634484][ T1] Testing event mm_vmscan_kswapd_wake: OK <6>[ 37.681189][ T1] Testing event mm_vmscan_kswapd_sleep: OK <6>[ 37.731252][ T1] Testing event percpu_destroy_chunk: OK <6>[ 37.774565][ T1] Testing event percpu_create_chunk: OK <6>[ 37.817926][ T1] Testing event percpu_alloc_percpu_fail: OK <6>[ 37.861261][ T1] Testing event percpu_free_percpu: OK <6>[ 37.904597][ T1] Testing event percpu_alloc_percpu: OK <6>[ 37.947934][ T1] Testing event rss_stat: OK <6>[ 37.991283][ T1] Testing event mm_page_alloc_extfrag: OK <6>[ 38.031265][ T1] Testing event mm_page_pcpu_drain: OK <6>[ 38.077938][ T1] Testing event mm_page_alloc_zone_locked: OK <6>[ 38.121313][ T1] Testing event mm_page_alloc: OK <6>[ 38.164602][ T1] Testing event mm_page_free_batched: OK <6>[ 38.204668][ T1] Testing event mm_page_free: OK <6>[ 38.248210][ T1] Testing event kmem_cache_free: OK <6>[ 38.294888][ T1] Testing event kfree: OK <6>[ 38.337873][ T1] Testing event kmem_cache_alloc_node: OK <6>[ 38.381240][ T1] Testing event kmalloc_node: OK <6>[ 38.427923][ T1] Testing event kmem_cache_alloc: OK <6>[ 38.471265][ T1] Testing event kmalloc: OK <6>[ 38.517921][ T1] Testing event mm_compaction_kcompactd_wake: OK <6>[ 38.557917][ T1] Testing event mm_compaction_wakeup_kcompactd: OK <6>[ 38.601254][ T1] Testing event mm_compaction_kcompactd_sleep: OK <6>[ 38.647892][ T1] Testing event mm_compaction_defer_reset: OK <6>[ 38.691229][ T1] Testing event mm_compaction_defer_compaction: OK <6>[ 38.734575][ T1] Testing event mm_compaction_deferred: OK <6>[ 38.777909][ T1] Testing event mm_compaction_suitable: OK <6>[ 38.817906][ T1] Testing event mm_compaction_finished: OK <6>[ 38.861166][ T1] Testing event mm_compaction_try_to_compact_pages: OK <6>[ 38.904554][ T1] Testing event mm_compaction_end: OK <6>[ 38.944622][ T1] Testing event mm_compaction_begin: OK <6>[ 38.991273][ T1] Testing event mm_compaction_migratepages: OK <6>[ 39.034604][ T1] Testing event mm_compaction_isolate_freepages: OK <6>[ 39.077972][ T1] Testing event mm_compaction_isolate_migratepages: OK <6>[ 39.121308][ T1] Testing event mmap_lock_released: OK <6>[ 39.162546][ T1] Testing event mmap_lock_acquire_returned: OK <6>[ 39.208893][ T1] Testing event mmap_lock_start_locking: OK <6>[ 39.255478][ T1] Testing event vm_unmapped_area: OK <6>[ 39.297962][ T1] Testing event mm_migrate_pages_start: OK <6>[ 39.344634][ T1] Testing event mm_migrate_pages: OK <6>[ 39.387923][ T1] Testing event mm_collapse_huge_page_swapin: OK <6>[ 39.434674][ T1] Testing event mm_collapse_huge_page_isolate: OK <6>[ 39.481297][ T1] Testing event mm_collapse_huge_page: OK <6>[ 39.524572][ T1] Testing event mm_khugepaged_scan_pmd: OK <6>[ 39.571295][ T1] Testing event test_pages_isolated: OK <6>[ 39.617949][ T1] Testing event sb_clear_inode_writeback: OK <6>[ 39.661279][ T1] Testing event sb_mark_inode_writeback: OK <6>[ 39.704597][ T1] Testing event writeback_dirty_inode_enqueue: OK <6>[ 39.754597][ T1] Testing event writeback_lazytime_iput: OK <6>[ 39.801328][ T1] Testing event writeback_lazytime: OK <6>[ 39.841304][ T1] Testing event writeback_single_inode: OK <6>[ 39.887945][ T1] Testing event writeback_single_inode_start: OK <6>[ 39.931298][ T1] Testing event writeback_wait_iff_congested: OK <6>[ 39.974652][ T1] Testing event writeback_congestion_wait: OK <6>[ 40.018150][ T1] Testing event writeback_sb_inodes_requeue: OK <6>[ 40.061530][ T1] Testing event balance_dirty_pages: OK <6>[ 40.107863][ T1] Testing event bdi_dirty_ratelimit: OK <6>[ 40.147883][ T1] Testing event global_dirty_state: OK <6>[ 40.194546][ T1] Testing event writeback_queue_io: OK <6>[ 40.234567][ T1] Testing event wbc_writepage: OK <6>[ 40.277856][ T1] Testing event writeback_bdi_register: OK <6>[ 40.324517][ T1] Testing event writeback_wake_background: OK <6>[ 40.364561][ T1] Testing event writeback_pages_written: OK <6>[ 40.407857][ T1] Testing event writeback_wait: OK <6>[ 40.451214][ T1] Testing event writeback_written: OK <6>[ 40.494507][ T1] Testing event writeback_start: OK <6>[ 40.537873][ T1] Testing event writeback_exec: OK <6>[ 40.581177][ T1] Testing event writeback_queue: OK <6>[ 40.624500][ T1] Testing event writeback_write_inode: OK <6>[ 40.667843][ T1] Testing event writeback_write_inode_start: OK <6>[ 40.714528][ T1] Testing event flush_foreign: OK <6>[ 40.757810][ T1] Testing event track_foreign_dirty: OK <6>[ 40.797905][ T1] Testing event inode_switch_wbs: OK <6>[ 40.841168][ T1] Testing event inode_foreign_history: OK <6>[ 40.884535][ T1] Testing event writeback_dirty_inode: OK <6>[ 40.927877][ T1] Testing event writeback_dirty_inode_start: OK <6>[ 40.971243][ T1] Testing event writeback_mark_inode_dirty: OK <6>[ 41.021251][ T1] Testing event wait_on_page_writeback: OK <6>[ 41.067982][ T1] Testing event writeback_dirty_page: OK <6>[ 41.114618][ T1] Testing event io_uring_task_run: OK <6>[ 41.157938][ T1] Testing event io_uring_task_add: OK <6>[ 41.204612][ T1] Testing event io_uring_poll_wake: OK <6>[ 41.247902][ T1] Testing event io_uring_poll_arm: OK <6>[ 41.294596][ T1] Testing event io_uring_submit_sqe: OK <6>[ 41.344600][ T1] Testing event io_uring_complete: OK <6>[ 41.387897][ T1] Testing event io_uring_fail_link: OK <6>[ 41.434574][ T1] Testing event io_uring_cqring_wait: OK <6>[ 41.481263][ T1] Testing event io_uring_link: OK <6>[ 41.524559][ T1] Testing event io_uring_defer: OK <6>[ 41.567871][ T1] Testing event io_uring_queue_async_work: OK <6>[ 41.611265][ T1] Testing event io_uring_file_get: OK <6>[ 41.654558][ T1] Testing event io_uring_register: OK <6>[ 41.697871][ T1] Testing event io_uring_create: OK <6>[ 41.744592][ T1] Testing event dax_writeback_one: OK <6>[ 41.794799][ T1] Testing event dax_writeback_range_done: OK <6>[ 41.837803][ T1] Testing event dax_writeback_range: OK <6>[ 41.878058][ T1] Testing event dax_insert_mapping: OK <6>[ 41.921293][ T1] Testing event dax_insert_pfn_mkwrite: OK <6>[ 41.964459][ T1] Testing event dax_insert_pfn_mkwrite_no_entry: OK <6>[ 42.007803][ T1] Testing event dax_load_hole: OK <6>[ 42.051118][ T1] Testing event dax_pte_fault_done: OK <6>[ 42.097788][ T1] Testing event dax_pte_fault: OK <6>[ 42.141108][ T1] Testing event dax_pmd_insert_mapping: OK <6>[ 42.184484][ T1] Testing event dax_pmd_load_hole_fallback: OK <6>[ 42.227806][ T1] Testing event dax_pmd_load_hole: OK <6>[ 42.274452][ T1] Testing event dax_pmd_fault_done: OK <6>[ 42.317820][ T1] Testing event dax_pmd_fault: OK <6>[ 42.361173][ T1] Testing event leases_conflict: OK <6>[ 42.401409][ T1] Testing event generic_add_lease: OK <6>[ 42.444487][ T1] Testing event time_out_leases: OK <6>[ 42.487830][ T1] Testing event generic_delete_lease: OK <6>[ 42.534516][ T1] Testing event break_lease_unblock: OK <6>[ 42.577882][ T1] Testing event break_lease_block: OK <6>[ 42.624487][ T1] Testing event break_lease_noblock: OK <6>[ 42.667856][ T1] Testing event flock_lock_inode: OK <6>[ 42.714509][ T1] Testing event locks_remove_posix: OK <6>[ 42.757905][ T1] Testing event fcntl_setlk: OK <6>[ 42.804511][ T1] Testing event posix_lock_inode: OK <6>[ 42.847893][ T1] Testing event locks_get_lock_context: OK <6>[ 42.887899][ T1] Testing event iomap_apply: OK <6>[ 42.934527][ T1] Testing event iomap_apply_srcmap: OK <6>[ 42.981210][ T1] Testing event iomap_apply_dstmap: OK <6>[ 43.024548][ T1] Testing event iomap_dio_invalidate_fail: OK <6>[ 43.071235][ T1] Testing event iomap_invalidatepage: OK <6>[ 43.117896][ T1] Testing event iomap_releasepage: OK <6>[ 43.161198][ T1] Testing event iomap_writepage: OK <6>[ 43.207873][ T1] Testing event iomap_readahead: OK <6>[ 43.251182][ T1] Testing event iomap_readpage: OK <6>[ 43.294567][ T1] Testing event ext4_fc_track_range: OK <6>[ 43.337894][ T1] Testing event ext4_fc_track_inode: OK <6>[ 43.381220][ T1] Testing event ext4_fc_track_unlink: OK <6>[ 43.424513][ T1] Testing event ext4_fc_track_link: OK <6>[ 43.471220][ T1] Testing event ext4_fc_track_create: OK <6>[ 43.514471][ T1] Testing event ext4_fc_stats: OK <6>[ 43.558047][ T1] Testing event ext4_fc_commit_stop: OK <6>[ 43.601147][ T1] Testing event ext4_fc_commit_start: OK <6>[ 43.648071][ T1] Testing event ext4_fc_replay: OK <6>[ 43.694570][ T1] Testing event ext4_fc_replay_scan: OK <6>[ 43.737802][ T1] Testing event ext4_lazy_itable_init: OK <6>[ 43.784499][ T1] Testing event ext4_prefetch_bitmaps: OK <6>[ 43.827808][ T1] Testing event ext4_error: OK <6>[ 43.871119][ T1] Testing event ext4_shutdown: OK <6>[ 43.917823][ T1] Testing event ext4_getfsmap_mapping: OK <6>[ 43.964487][ T1] Testing event ext4_getfsmap_high_key: OK <6>[ 44.007810][ T1] Testing event ext4_getfsmap_low_key: OK <6>[ 44.051429][ T1] Testing event ext4_fsmap_mapping: OK <6>[ 44.094529][ T1] Testing event ext4_fsmap_high_key: OK <6>[ 44.134445][ T1] Testing event ext4_fsmap_low_key: OK <6>[ 44.174440][ T1] Testing event ext4_es_insert_delayed_block: OK <6>[ 44.217750][ T1] Testing event ext4_es_shrink: OK <6>[ 44.261101][ T1] Testing event ext4_insert_range: OK <6>[ 44.304441][ T1] Testing event ext4_collapse_range: OK <6>[ 44.347850][ T1] Testing event ext4_es_shrink_scan_exit: OK <6>[ 44.391165][ T1] Testing event ext4_es_shrink_scan_enter: OK <6>[ 44.437885][ T1] Testing event ext4_es_shrink_count: OK <6>[ 44.481204][ T1] Testing event ext4_es_lookup_extent_exit: OK <6>[ 44.524520][ T1] Testing event ext4_es_lookup_extent_enter: OK <6>[ 44.571185][ T1] Testing event ext4_es_find_extent_range_exit: OK <6>[ 44.614523][ T1] Testing event ext4_es_find_extent_range_enter: OK <6>[ 44.657863][ T1] Testing event ext4_es_remove_extent: OK <6>[ 44.701179][ T1] Testing event ext4_es_cache_extent: OK <6>[ 44.744500][ T1] Testing event ext4_es_insert_extent: OK <6>[ 44.787823][ T1] Testing event ext4_ext_remove_space_done: OK <6>[ 44.831195][ T1] Testing event ext4_ext_remove_space: OK <6>[ 44.874477][ T1] Testing event ext4_ext_rm_idx: OK <6>[ 44.921134][ T1] Testing event ext4_ext_rm_leaf: OK <6>[ 44.964508][ T1] Testing event ext4_remove_blocks: OK <6>[ 45.007868][ T1] Testing event ext4_ext_show_extent: OK <6>[ 45.051160][ T1] Testing event ext4_get_implied_cluster_alloc_exit: OK <6>[ 45.097902][ T1] Testing event ext4_ext_handle_unwritten_extents: OK <6>[ 45.141184][ T1] Testing event ext4_trim_all_free: OK <6>[ 45.181247][ T1] Testing event ext4_trim_extent: OK <6>[ 45.224512][ T1] Testing event ext4_journal_start_reserved: OK <6>[ 45.267848][ T1] Testing event ext4_journal_start: OK <6>[ 45.311332][ T1] Testing event ext4_load_inode: OK <6>[ 45.351364][ T1] Testing event ext4_ext_load_extent: OK <6>[ 45.391117][ T1] Testing event ext4_ind_map_blocks_exit: OK <6>[ 45.431367][ T1] Testing event ext4_ext_map_blocks_exit: OK <6>[ 45.474727][ T1] Testing event ext4_ind_map_blocks_enter: OK <6>[ 45.518067][ T1] Testing event ext4_ext_map_blocks_enter: OK <6>[ 45.561135][ T1] Testing event ext4_ext_convert_to_initialized_fastpath: OK <6>[ 45.604461][ T1] Testing event ext4_ext_convert_to_initialized_enter: OK <6>[ 45.647832][ T1] Testing event ext4_truncate_exit: OK <6>[ 45.694482][ T1] Testing event ext4_truncate_enter: OK <6>[ 45.737808][ T1] Testing event ext4_unlink_exit: OK <6>[ 45.784515][ T1] Testing event ext4_unlink_enter: OK <6>[ 45.824500][ T1] Testing event ext4_fallocate_exit: OK <6>[ 45.867840][ T1] Testing event ext4_zero_range: OK <6>[ 45.911257][ T1] Testing event ext4_punch_hole: OK <6>[ 45.954583][ T1] Testing event ext4_fallocate_enter: OK <6>[ 45.997892][ T1] Testing event ext4_read_block_bitmap_load: OK <6>[ 46.041251][ T1] Testing event ext4_load_inode_bitmap: OK <6>[ 46.084563][ T1] Testing event ext4_mb_buddy_bitmap_load: OK <6>[ 46.127929][ T1] Testing event ext4_mb_bitmap_load: OK <6>[ 46.167929][ T1] Testing event ext4_da_release_space: OK <6>[ 46.211257][ T1] Testing event ext4_da_reserve_space: OK <6>[ 46.257922][ T1] Testing event ext4_da_update_reserve_space: OK <6>[ 46.301228][ T1] Testing event ext4_forget: OK <6>[ 46.347874][ T1] Testing event ext4_mballoc_free: OK <6>[ 46.391236][ T1] Testing event ext4_mballoc_discard: OK <6>[ 46.437938][ T1] Testing event ext4_mballoc_prealloc: OK <6>[ 46.481238][ T1] Testing event ext4_mballoc_alloc: OK <6>[ 46.524591][ T1] Testing event ext4_alloc_da_blocks: OK <6>[ 46.567896][ T1] Testing event ext4_sync_fs: OK <6>[ 46.611256][ T1] Testing event ext4_sync_file_exit: OK <6>[ 46.657884][ T1] Testing event ext4_sync_file_enter: OK <6>[ 46.701224][ T1] Testing event ext4_free_blocks: OK <6>[ 46.744575][ T1] Testing event ext4_allocate_blocks: OK <6>[ 46.787876][ T1] Testing event ext4_request_blocks: OK <6>[ 46.831232][ T1] Testing event ext4_mb_discard_preallocations: OK <6>[ 46.874569][ T1] Testing event ext4_discard_preallocations: OK <6>[ 46.917938][ T1] Testing event ext4_mb_release_group_pa: OK <6>[ 46.961272][ T1] Testing event ext4_mb_release_inode_pa: OK <6>[ 47.001258][ T1] Testing event ext4_mb_new_group_pa: OK <6>[ 47.044576][ T1] Testing event ext4_mb_new_inode_pa: OK <6>[ 47.087923][ T1] Testing event ext4_discard_blocks: OK <6>[ 47.131342][ T1] Testing event ext4_journalled_invalidatepage: OK <6>[ 47.174484][ T1] Testing event ext4_invalidatepage: OK <6>[ 47.217846][ T1] Testing event ext4_releasepage: OK <6>[ 47.261146][ T1] Testing event ext4_readpage: OK <6>[ 47.304496][ T1] Testing event ext4_writepage: OK <6>[ 47.344454][ T1] Testing event ext4_writepages_result: OK <6>[ 47.387804][ T1] Testing event ext4_da_write_pages_extent: OK <6>[ 47.434519][ T1] Testing event ext4_da_write_pages: OK <6>[ 47.481170][ T1] Testing event ext4_writepages: OK <6>[ 47.527830][ T1] Testing event ext4_da_write_end: OK <6>[ 47.571176][ T1] Testing event ext4_journalled_write_end: OK <6>[ 47.614565][ T1] Testing event ext4_write_end: OK <6>[ 47.657795][ T1] Testing event ext4_da_write_begin: OK <6>[ 47.701163][ T1] Testing event ext4_write_begin: OK <6>[ 47.744466][ T1] Testing event ext4_begin_ordered_truncate: OK <6>[ 47.787807][ T1] Testing event ext4_mark_inode_dirty: OK <6>[ 47.831156][ T1] Testing event ext4_nfs_commit_metadata: OK <6>[ 47.874560][ T1] Testing event ext4_drop_inode: OK <6>[ 47.917891][ T1] Testing event ext4_evict_inode: OK <6>[ 47.961236][ T1] Testing event ext4_allocate_inode: OK <6>[ 48.004581][ T1] Testing event ext4_request_inode: OK <6>[ 48.047910][ T1] Testing event ext4_free_inode: OK <6>[ 48.091238][ T1] Testing event ext4_other_inode_update_time: OK <6>[ 48.134601][ T1] Testing event jbd2_shrink_checkpoint_list: OK <6>[ 48.174580][ T1] Testing event jbd2_shrink_scan_exit: OK <6>[ 48.214566][ T1] Testing event jbd2_shrink_scan_enter: OK <6>[ 48.261243][ T1] Testing event jbd2_shrink_count: OK <6>[ 48.304567][ T1] Testing event jbd2_lock_buffer_stall: OK <6>[ 48.347912][ T1] Testing event jbd2_write_superblock: OK <6>[ 48.391259][ T1] Testing event jbd2_update_log_tail: OK <6>[ 48.434622][ T1] Testing event jbd2_checkpoint_stats: OK <6>[ 48.477943][ T1] Testing event jbd2_run_stats: OK <6>[ 48.521282][ T1] Testing event jbd2_handle_stats: OK <6>[ 48.561289][ T1] Testing event jbd2_handle_extend: OK <6>[ 48.607923][ T1] Testing event jbd2_handle_restart: OK <6>[ 48.647961][ T1] Testing event jbd2_handle_start: OK <6>[ 48.691240][ T1] Testing event jbd2_submit_inode_data: OK <6>[ 48.737944][ T1] Testing event jbd2_end_commit: OK <6>[ 48.781232][ T1] Testing event jbd2_drop_transaction: OK <6>[ 48.827956][ T1] Testing event jbd2_commit_logging: OK <6>[ 48.871171][ T1] Testing event jbd2_commit_flushing: OK <6>[ 48.921420][ T1] Testing event jbd2_commit_locking: OK <6>[ 48.964799][ T1] Testing event jbd2_start_commit: OK <6>[ 49.004806][ T1] Testing event jbd2_checkpoint: OK <6>[ 49.044535][ T1] Testing event block_rq_remap: OK <6>[ 49.091204][ T1] Testing event block_bio_remap: OK <6>[ 49.134537][ T1] Testing event block_split: OK <6>[ 49.181189][ T1] Testing event block_unplug: OK <6>[ 49.227940][ T1] Testing event block_plug: OK <6>[ 49.274564][ T1] Testing event block_getrq: OK <6>[ 49.318044][ T1] Testing event block_bio_queue: OK <6>[ 49.361153][ T1] Testing event block_bio_frontmerge: OK <6>[ 49.404471][ T1] Testing event block_bio_backmerge: OK <6>[ 49.454490][ T1] Testing event block_bio_bounce: OK <6>[ 49.501133][ T1] Testing event block_bio_complete: OK <6>[ 49.544496][ T1] Testing event block_rq_merge: OK <6>[ 49.591237][ T1] Testing event block_rq_issue: OK <6>[ 49.631236][ T1] Testing event block_rq_insert: OK <6>[ 49.674582][ T1] Testing event block_rq_complete: OK <6>[ 49.721175][ T1] Testing event block_rq_requeue: OK <6>[ 49.767875][ T1] Testing event block_dirty_buffer: OK <6>[ 49.811227][ T1] Testing event block_touch_buffer: OK <6>[ 49.854531][ T1] Testing event iocost_iocg_forgive_debt: OK <6>[ 49.901172][ T1] Testing event iocost_ioc_vrate_adj: OK <6>[ 49.947845][ T1] Testing event iocost_inuse_adjust: OK <6>[ 49.991167][ T1] Testing event iocost_inuse_transfer: OK <6>[ 50.034503][ T1] Testing event iocost_inuse_shortage: OK <6>[ 50.077850][ T1] Testing event iocost_iocg_idle: OK <6>[ 50.121139][ T1] Testing event iocost_iocg_activate: OK <6>[ 50.167878][ T1] Testing event wbt_timer: OK <6>[ 50.211088][ T1] Testing event wbt_step: OK <6>[ 50.261124][ T1] Testing event wbt_lat: OK <6>[ 50.307774][ T1] Testing event wbt_stat: OK <6>[ 50.351183][ T1] Testing event rdpmc: OK <6>[ 50.394413][ T1] Testing event write_msr: OK <6>[ 50.437812][ T1] Testing event read_msr: OK <6>[ 50.481185][ T1] Testing event gpio_value: OK <6>[ 50.524473][ T1] Testing event gpio_direction: OK <6>[ 50.564506][ T1] Testing event pwm_get: OK <6>[ 50.607978][ T1] Testing event pwm_apply: OK <6>[ 50.651316][ T1] Testing event clk_set_duty_cycle_complete: OK <6>[ 50.694432][ T1] Testing event clk_set_duty_cycle: OK <6>[ 50.737742][ T1] Testing event clk_set_phase_complete: OK <6>[ 50.777754][ T1] Testing event clk_set_phase: OK <6>[ 50.824410][ T1] Testing event clk_set_parent_complete: OK <6>[ 50.871081][ T1] Testing event clk_set_parent: OK <6>[ 50.911056][ T1] Testing event clk_set_rate_range: OK <6>[ 50.954364][ T1] Testing event clk_set_max_rate: OK <6>[ 50.997733][ T1] Testing event clk_set_min_rate: OK <6>[ 51.041069][ T1] Testing event clk_set_rate_complete: OK <6>[ 51.084457][ T1] Testing event clk_set_rate: OK <6>[ 51.127691][ T1] Testing event clk_unprepare_complete: OK <6>[ 51.171063][ T1] Testing event clk_unprepare: OK <6>[ 51.217770][ T1] Testing event clk_prepare_complete: OK <6>[ 51.264381][ T1] Testing event clk_prepare: OK <6>[ 51.307716][ T1] Testing event clk_disable_complete: OK <6>[ 51.354447][ T1] Testing event clk_disable: OK <6>[ 51.397801][ T1] Testing event clk_enable_complete: OK <6>[ 51.444464][ T1] Testing event clk_enable: OK <6>[ 51.487770][ T1] Testing event regulator_set_voltage_complete: OK <6>[ 51.527856][ T1] Testing event regulator_set_voltage: OK <6>[ 51.571164][ T1] Testing event regulator_bypass_disable_complete: OK <6>[ 51.617863][ T1] Testing event regulator_bypass_disable: OK <6>[ 51.657802][ T1] Testing event regulator_bypass_enable_complete: OK <6>[ 51.701175][ T1] Testing event regulator_bypass_enable: OK <6>[ 51.744494][ T1] Testing event regulator_disable_complete: OK <6>[ 51.791191][ T1] Testing event regulator_disable: OK <6>[ 51.841160][ T1] Testing event regulator_enable_complete: OK <6>[ 51.884532][ T1] Testing event regulator_enable_delay: OK <6>[ 51.931151][ T1] Testing event regulator_enable: OK <6>[ 51.974518][ T1] Testing event prandom_u32: OK <6>[ 52.021187][ T1] Testing event urandom_read: OK <6>[ 52.067821][ T1] Testing event extract_entropy: OK <6>[ 52.107837][ T1] Testing event get_random_bytes_arch: OK <6>[ 52.151173][ T1] Testing event get_random_bytes: OK <6>[ 52.194476][ T1] Testing event add_disk_randomness: OK <6>[ 52.237847][ T1] Testing event add_input_randomness: OK <6>[ 52.281177][ T1] Testing event debit_entropy: OK <6>[ 52.327861][ T1] Testing event credit_entropy_bits: OK <6>[ 52.371105][ T1] Testing event mix_pool_bytes_nolock: OK <6>[ 52.414516][ T1] Testing event mix_pool_bytes: OK <6>[ 52.457808][ T1] Testing event add_device_randomness: OK <6>[ 52.501104][ T1] Testing event prq_report: OK <6>[ 52.544446][ T1] Testing event qi_submit: OK <6>[ 52.587788][ T1] Testing event io_page_fault: OK <6>[ 52.631155][ T1] Testing event unmap: OK <6>[ 52.674445][ T1] Testing event map: OK <6>[ 52.717766][ T1] Testing event detach_device_from_domain: OK <6>[ 52.764453][ T1] Testing event attach_device_to_domain: OK <6>[ 52.807871][ T1] Testing event remove_device_from_group: OK <6>[ 52.854502][ T1] Testing event add_device_to_group: OK <6>[ 52.897883][ T1] Testing event regcache_drop_region: OK <6>[ 52.941208][ T1] Testing event regmap_async_complete_done: OK <6>[ 52.981159][ T1] Testing event regmap_async_complete_start: OK <6>[ 53.021163][ T1] Testing event regmap_async_io_complete: OK <6>[ 53.064464][ T1] Testing event regmap_async_write_start: OK <6>[ 53.111207][ T1] Testing event regmap_cache_bypass: OK <6>[ 53.157932][ T1] Testing event regmap_cache_only: OK <6>[ 53.204586][ T1] Testing event regcache_sync: OK <6>[ 53.247898][ T1] Testing event regmap_hw_write_done: OK <6>[ 53.291244][ T1] Testing event regmap_hw_write_start: OK <6>[ 53.334632][ T1] Testing event regmap_hw_read_done: OK <6>[ 53.381208][ T1] Testing event regmap_hw_read_start: OK <6>[ 53.424546][ T1] Testing event regmap_reg_read_cache: OK <6>[ 53.467914][ T1] Testing event regmap_reg_read: OK <6>[ 53.514543][ T1] Testing event regmap_reg_write: OK <6>[ 53.561206][ T1] Testing event devres_log: OK <6>[ 53.607928][ T1] Testing event dma_fence_wait_end: OK <6>[ 53.654534][ T1] Testing event dma_fence_wait_start: OK <6>[ 53.697862][ T1] Testing event dma_fence_signaled: OK <6>[ 53.741214][ T1] Testing event dma_fence_enable_signal: OK <6>[ 53.787847][ T1] Testing event dma_fence_destroy: OK <6>[ 53.831205][ T1] Testing event dma_fence_init: OK <6>[ 53.874528][ T1] Testing event dma_fence_emit: OK <6>[ 53.917835][ T1] Testing event sync_timeline: OK <6>[ 53.964533][ T1] Testing event scsi_eh_wakeup: OK <6>[ 54.007892][ T1] Testing event scsi_dispatch_cmd_timeout: OK <6>[ 54.051209][ T1] Testing event scsi_dispatch_cmd_done: OK <6>[ 54.094527][ T1] Testing event scsi_dispatch_cmd_error: OK <6>[ 54.137868][ T1] Testing event scsi_dispatch_cmd_start: OK <6>[ 54.181412][ T1] Testing event ata_eh_link_autopsy_qc: OK <6>[ 54.228076][ T1] Testing event ata_eh_link_autopsy: OK <6>[ 54.274738][ T1] Testing event ata_qc_complete_done: OK <6>[ 54.321113][ T1] Testing event ata_qc_complete_failed: OK <6>[ 54.361136][ T1] Testing event ata_qc_complete_internal: OK <6>[ 54.404464][ T1] Testing event ata_qc_issue: OK <6>[ 54.447816][ T1] Testing event spi_transfer_stop: OK <6>[ 54.491073][ T1] Testing event spi_transfer_start: OK <6>[ 54.534422][ T1] Testing event spi_message_done: OK <6>[ 54.577772][ T1] Testing event spi_message_start: OK <6>[ 54.621118][ T1] Testing event spi_message_submit: OK <6>[ 54.664425][ T1] Testing event spi_set_cs: OK <6>[ 54.707814][ T1] Testing event spi_setup: OK <6>[ 54.748035][ T1] Testing event spi_controller_busy: OK <6>[ 54.788043][ T1] Testing event spi_controller_idle: OK <6>[ 54.831165][ T1] Testing event mdio_access: OK <6>[ 54.874461][ T1] Testing event xhci_dbc_giveback_request: OK <6>[ 54.917795][ T1] Testing event xhci_dbc_queue_request: OK <6>[ 54.961103][ T1] Testing event xhci_dbc_free_request: OK <6>[ 55.004439][ T1] Testing event xhci_dbc_alloc_request: OK <6>[ 55.051167][ T1] Testing event xhci_ring_host_doorbell: OK <6>[ 55.094492][ T1] Testing event xhci_ring_ep_doorbell: OK <6>[ 55.134520][ T1] Testing event xhci_hub_status_data: OK <6>[ 55.181171][ T1] Testing event xhci_get_port_status: OK <6>[ 55.224487][ T1] Testing event xhci_handle_port_status: OK <6>[ 55.267841][ T1] Testing event xhci_inc_deq: OK <6>[ 55.314508][ T1] Testing event xhci_inc_enq: OK <6>[ 55.357803][ T1] Testing event xhci_ring_expansion: OK <6>[ 55.401162][ T1] Testing event xhci_ring_free: OK <6>[ 55.444465][ T1] Testing event xhci_ring_alloc: OK <6>[ 55.487796][ T1] Testing event xhci_configure_endpoint_ctrl_ctx: OK <6>[ 55.534474][ T1] Testing event xhci_address_ctrl_ctx: OK <6>[ 55.577861][ T1] Testing event xhci_configure_endpoint: OK <6>[ 55.624489][ T1] Testing event xhci_handle_cmd_set_deq: OK <6>[ 55.671209][ T1] Testing event xhci_handle_cmd_reset_dev: OK <6>[ 55.711189][ T1] Testing event xhci_handle_cmd_addr_dev: OK <6>[ 55.757861][ T1] Testing event xhci_setup_device_slot: OK <6>[ 55.804548][ T1] Testing event xhci_discover_or_reset_device: OK <6>[ 55.851226][ T1] Testing event xhci_handle_cmd_disable_slot: OK <6>[ 55.897835][ T1] Testing event xhci_free_dev: OK <6>[ 55.938086][ T1] Testing event xhci_alloc_dev: OK <6>[ 55.981405][ T1] Testing event xhci_add_endpoint: OK <6>[ 56.024462][ T1] Testing event xhci_handle_cmd_config_ep: OK <6>[ 56.071140][ T1] Testing event xhci_handle_cmd_reset_ep: OK <6>[ 56.114464][ T1] Testing event xhci_handle_cmd_set_deq_ep: OK <6>[ 56.161164][ T1] Testing event xhci_handle_cmd_stop_ep: OK <6>[ 56.207848][ T1] Testing event xhci_urb_dequeue: OK <6>[ 56.254458][ T1] Testing event xhci_urb_giveback: OK <6>[ 56.297817][ T1] Testing event xhci_urb_enqueue: OK <6>[ 56.341183][ T1] Testing event xhci_stop_device: OK <6>[ 56.384463][ T1] Testing event xhci_setup_addressable_virt_device: OK <6>[ 56.427939][ T1] Testing event xhci_setup_device: OK <6>[ 56.471215][ T1] Testing event xhci_alloc_virt_device: OK <6>[ 56.517851][ T1] Testing event xhci_free_virt_device: OK <6>[ 56.561150][ T1] Testing event xhci_dbc_gadget_ep_queue: OK <6>[ 56.607823][ T1] Testing event xhci_dbc_handle_transfer: OK <6>[ 56.651174][ T1] Testing event xhci_dbc_handle_event: OK <6>[ 56.694510][ T1] Testing event xhci_queue_trb: OK <6>[ 56.737843][ T1] Testing event xhci_handle_transfer: OK <6>[ 56.781252][ T1] Testing event xhci_handle_command: OK <6>[ 56.827919][ T1] Testing event xhci_handle_event: OK <6>[ 56.871229][ T1] Testing event xhci_address_ctx: OK <6>[ 56.917921][ T1] Testing event xhci_dbg_ring_expansion: OK <6>[ 56.964648][ T1] Testing event xhci_dbg_init: OK <6>[ 57.011285][ T1] Testing event xhci_dbg_cancel_urb: OK <6>[ 57.054558][ T1] Testing event xhci_dbg_reset_ep: OK <6>[ 57.097890][ T1] Testing event xhci_dbg_quirks: OK <6>[ 57.141227][ T1] Testing event xhci_dbg_context_change: OK <6>[ 57.187939][ T1] Testing event xhci_dbg_address: OK <6>[ 57.231242][ T1] Testing event rtc_timer_fired: OK <6>[ 57.274569][ T1] Testing event rtc_timer_dequeue: OK <6>[ 57.321295][ T1] Testing event rtc_timer_enqueue: OK <6>[ 57.367915][ T1] Testing event rtc_read_offset: OK <6>[ 57.407886][ T1] Testing event rtc_set_offset: OK <6>[ 57.454544][ T1] Testing event rtc_alarm_irq_enable: OK <6>[ 57.501270][ T1] Testing event rtc_irq_set_state: OK <6>[ 57.547927][ T1] Testing event rtc_irq_set_freq: OK <6>[ 57.594532][ T1] Testing event rtc_read_alarm: OK <6>[ 57.637877][ T1] Testing event rtc_set_alarm: OK <6>[ 57.681131][ T1] Testing event rtc_read_time: OK <6>[ 57.721159][ T1] Testing event rtc_set_time: OK <6>[ 57.761404][ T1] Testing event i2c_result: OK <6>[ 57.804821][ T1] Testing event i2c_reply: OK <6>[ 57.847799][ T1] Testing event i2c_read: OK <6>[ 57.891132][ T1] Testing event i2c_write: OK <6>[ 57.934467][ T1] Testing event smbus_result: OK <6>[ 57.977790][ T1] Testing event smbus_reply: OK <6>[ 58.024450][ T1] Testing event smbus_read: OK <6>[ 58.071094][ T1] Testing event smbus_write: OK <6>[ 58.117767][ T1] Testing event hwmon_attr_show_string: OK <6>[ 58.164423][ T1] Testing event hwmon_attr_store: OK <6>[ 58.207754][ T1] Testing event hwmon_attr_show: OK <6>[ 58.251067][ T1] Testing event thermal_power_devfreq_limit: OK <6>[ 58.297804][ T1] Testing event thermal_power_devfreq_get_power: OK <6>[ 58.344420][ T1] Testing event thermal_zone_trip: OK <6>[ 58.391112][ T1] Testing event cdev_update: OK <6>[ 58.437850][ T1] Testing event thermal_temperature: OK <6>[ 58.477846][ T1] Testing event thermal_power_allocator_pid: OK <6>[ 58.521155][ T1] Testing event thermal_power_allocator: OK <6>[ 58.564515][ T1] Testing event mmc_request_done: OK <6>[ 58.607838][ T1] Testing event mmc_request_start: OK <6>[ 58.647852][ T1] Testing event cros_ec_request_done: OK <6>[ 58.694510][ T1] Testing event cros_ec_request_start: OK <6>[ 58.741134][ T1] Testing event devfreq_monitor: OK <6>[ 58.784559][ T1] Testing event devfreq_frequency: OK <6>[ 58.827824][ T1] Testing event memory_failure_event: OK <6>[ 58.871186][ T1] Testing event aer_event: OK <6>[ 58.914503][ T1] Testing event non_standard_event: OK <6>[ 58.954511][ T1] Testing event arm_event: OK <6>[ 58.997849][ T1] Testing event mc_event: OK <6>[ 59.041163][ T1] Testing event extlog_mem_event: OK <6>[ 59.081158][ T1] Testing event icc_set_bw_end: OK <6>[ 59.127855][ T1] Testing event icc_set_bw: OK <6>[ 59.171174][ T1] Testing event neigh_cleanup_and_release: OK <6>[ 59.214502][ T1] Testing event neigh_event_send_dead: OK <6>[ 59.257906][ T1] Testing event neigh_event_send_done: OK <6>[ 59.301161][ T1] Testing event neigh_timer_handler: OK <6>[ 59.341181][ T1] Testing event neigh_update_done: OK <6>[ 59.384491][ T1] Testing event neigh_update: OK <6>[ 59.427872][ T1] Testing event neigh_create: OK <6>[ 59.474534][ T1] Testing event page_pool_update_nid: OK <6>[ 59.514706][ T1] Testing event page_pool_state_hold: OK <6>[ 59.558062][ T1] Testing event page_pool_state_release: OK <6>[ 59.601382][ T1] Testing event page_pool_release: OK <6>[ 59.644685][ T1] Testing event br_fdb_update: OK <6>[ 59.691055][ T1] Testing event fdb_delete: OK <6>[ 59.737744][ T1] Testing event br_fdb_external_learn_add: OK <6>[ 59.784409][ T1] Testing event br_fdb_add: OK <6>[ 59.827741][ T1] Testing event qdisc_create: OK <6>[ 59.871141][ T1] Testing event qdisc_destroy: OK <6>[ 59.914500][ T1] Testing event qdisc_reset: OK <6>[ 59.954457][ T1] Testing event qdisc_enqueue: OK <6>[ 60.001092][ T1] Testing event qdisc_dequeue: OK <6>[ 60.047800][ T1] Testing event fib_table_lookup: OK <6>[ 60.091134][ T1] Testing event tcp_bad_csum: OK <6>[ 60.134474][ T1] Testing event tcp_probe: OK <6>[ 60.181172][ T1] Testing event tcp_retransmit_synack: OK <6>[ 60.221182][ T1] Testing event tcp_rcv_space_adjust: OK <6>[ 60.267867][ T1] Testing event tcp_destroy_sock: OK <6>[ 60.311193][ T1] Testing event tcp_receive_reset: OK <6>[ 60.357901][ T1] Testing event tcp_send_reset: OK <6>[ 60.397891][ T1] Testing event tcp_retransmit_skb: OK <6>[ 60.441184][ T1] Testing event udp_fail_queue_rcv_skb: OK <6>[ 60.484498][ T1] Testing event inet_sk_error_report: OK <6>[ 60.527891][ T1] Testing event inet_sock_set_state: OK <6>[ 60.574578][ T1] Testing event sock_exceed_buf_limit: OK <6>[ 60.617860][ T1] Testing event sock_rcvqueue_full: OK <6>[ 60.661236][ T1] Testing event napi_poll: OK <6>[ 60.707856][ T1] Testing event netif_receive_skb_list_exit: OK <6>[ 60.751208][ T1] Testing event netif_rx_ni_exit: OK <6>[ 60.801242][ T1] Testing event netif_rx_exit: OK <6>[ 60.844537][ T1] Testing event netif_receive_skb_exit: OK <6>[ 60.884570][ T1] Testing event napi_gro_receive_exit: OK <6>[ 60.927896][ T1] Testing event napi_gro_frags_exit: OK <6>[ 60.974561][ T1] Testing event netif_rx_ni_entry: OK <6>[ 61.021207][ T1] Testing event netif_rx_entry: OK <6>[ 61.067864][ T1] Testing event netif_receive_skb_list_entry: OK <6>[ 61.114583][ T1] Testing event netif_receive_skb_entry: OK <6>[ 61.161301][ T1] Testing event napi_gro_receive_entry: OK <6>[ 61.207829][ T1] Testing event napi_gro_frags_entry: OK <6>[ 61.254459][ T1] Testing event netif_rx: OK <6>[ 61.298066][ T1] Testing event netif_receive_skb: OK <6>[ 61.341144][ T1] Testing event net_dev_queue: OK <6>[ 61.384438][ T1] Testing event net_dev_xmit_timeout: OK <6>[ 61.431145][ T1] Testing event net_dev_xmit: OK <6>[ 61.474494][ T1] Testing event net_dev_start_xmit: OK <6>[ 61.517834][ T1] Testing event skb_copy_datagram_iovec: OK <6>[ 61.564486][ T1] Testing event consume_skb: OK <6>[ 61.611174][ T1] Testing event kfree_skb: OK <6>[ 61.651178][ T1] Testing event devlink_trap_report: OK <6>[ 61.691213][ T1] Testing event devlink_health_reporter_state_update: OK <6>[ 61.734555][ T1] Testing event devlink_health_recover_aborted: OK <6>[ 61.777894][ T1] Testing event devlink_health_report: OK <6>[ 61.817866][ T1] Testing event devlink_hwerr: OK <6>[ 61.861150][ T1] Testing event devlink_hwmsg: OK <6>[ 61.904459][ T1] Testing event netlink_extack: OK <6>[ 61.947831][ T1] Testing event bpf_test_finish: OK <6>[ 61.994567][ T1] Testing event tls_device_tx_resync_send: OK <6>[ 62.037907][ T1] Testing event tls_device_tx_resync_req: OK <6>[ 62.084574][ T1] Testing event tls_device_rx_resync_nh_delay: OK <6>[ 62.127889][ T1] Testing event tls_device_rx_resync_nh_schedule: OK <6>[ 62.167944][ T1] Testing event tls_device_rx_resync_send: OK <6>[ 62.211217][ T1] Testing event tls_device_decrypted: OK <6>[ 62.261255][ T1] Testing event tls_device_offload_set: OK <6>[ 62.304585][ T1] Testing event fib6_table_lookup: OK <6>[ 62.351262][ T1] Testing event subflow_check_data_avail: OK <6>[ 62.397892][ T1] Testing event ack_update_msk: OK <6>[ 62.437904][ T1] Testing event get_mapping_status: OK <6>[ 62.481263][ T1] Testing event mptcp_subflow_get_send: OK <6>[ 62.524616][ T1] Running tests on trace event systems: <6>[ 62.525419][ T1] Testing event system mptcp: OK <6>[ 62.564870][ T1] Testing event system fib6: OK <6>[ 62.611455][ T1] Testing event system tls: OK <6>[ 62.654913][ T1] Testing event system bpf_test_run: OK <6>[ 62.698074][ T1] Testing event system netlink: OK <6>[ 62.741407][ T1] Testing event system devlink: OK <6>[ 62.781500][ T1] Testing event system skb: OK <6>[ 62.828113][ T1] Testing event system net: OK <6>[ 62.875254][ T1] Testing event system napi: OK <6>[ 62.914765][ T1] Testing event system sock: OK <6>[ 62.961451][ T1] Testing event system udp: OK <6>[ 63.008227][ T1] Testing event system tcp: OK <6>[ 63.048534][ T1] Testing event system fib: OK <6>[ 63.091548][ T1] Testing event system qdisc: OK <6>[ 63.134692][ T1] Testing event system bridge: OK <6>[ 63.177912][ T1] Testing event system page_pool: OK <6>[ 63.224696][ T1] Testing event system neigh: OK <6>[ 63.268094][ T1] Testing event system interconnect: OK <6>[ 63.307954][ T1] Testing event system ras: OK <6>[ 63.348016][ T1] Testing event system devfreq: OK <6>[ 63.394622][ T1] Testing event system cros_ec: OK <6>[ 63.441336][ T1] Testing event system mmc: OK <6>[ 63.484623][ T1] Testing event system thermal_power_allocator: OK <6>[ 63.531325][ T1] Testing event system thermal: OK <6>[ 63.574747][ T1] Testing event system hwmon: OK <6>[ 63.621320][ T1] Testing event system smbus: OK <6>[ 63.664632][ T1] Testing event system i2c: OK <6>[ 63.708026][ T1] Testing event system rtc: OK <6>[ 63.758259][ T1] Testing event system xhci-hcd: OK <6>[ 63.806171][ T1] Testing event system mdio: OK <6>[ 63.847830][ T1] Testing event system spi: OK <6>[ 63.891453][ T1] Testing event system libata: OK <6>[ 63.941419][ T1] Testing event system scsi: OK <6>[ 63.984743][ T1] Testing event system sync_trace: OK <6>[ 64.027935][ T1] Testing event system dma_fence: OK <6>[ 64.071409][ T1] Testing event system dev: OK <6>[ 64.114611][ T1] Testing event system regmap: OK <6>[ 64.161741][ T1] Testing event system iommu: OK <6>[ 64.204770][ T1] Testing event system intel_iommu: OK <6>[ 64.244628][ T1] Testing event system random: OK <6>[ 64.294932][ T1] Testing event system regulator: OK <6>[ 64.338241][ T1] Testing event system clk: OK <6>[ 64.381784][ T1] Testing event system pwm: OK <6>[ 64.427968][ T1] Testing event system gpio: OK <6>[ 64.471301][ T1] Testing event system msr: OK <6>[ 64.514686][ T1] Testing event system wbt: OK <6>[ 64.561307][ T1] Testing event system iocost: OK <6>[ 64.604812][ T1] Testing event system block: OK <6>[ 64.648400][ T1] Testing event system jbd2: OK <6>[ 64.698500][ T1] Testing event system ext4: OK <6>[ 64.751343][ T1] Testing event system iomap: OK <6>[ 64.794999][ T1] Testing event system filelock: OK <6>[ 64.838115][ T1] Testing event system fs_dax: OK <6>[ 64.881473][ T1] Testing event system io_uring: OK <6>[ 64.924849][ T1] Testing event system writeback: OK <6>[ 64.968796][ T1] Testing event system page_isolation: OK <6>[ 65.011139][ T1] Testing event system huge_memory: OK <6>[ 65.054587][ T1] Testing event system migrate: OK <6>[ 65.101169][ T1] Testing event system mmap: OK <6>[ 65.144476][ T1] Testing event system mmap_lock: OK <6>[ 65.194007][ T1] Testing event system compaction: OK <6>[ 65.238209][ T1] Testing event system kmem: OK <6>[ 65.281600][ T1] Testing event system percpu: OK <6>[ 65.321306][ T1] Testing event system vmscan: OK <6>[ 65.364978][ T1] Testing event system pagemap: OK <6>[ 65.407853][ T1] Testing event system oom: OK <6>[ 65.451342][ T1] Testing event system filemap: OK <6>[ 65.494607][ T1] Testing event system rseq: OK <6>[ 65.537905][ T1] Testing event system xdp: OK <6>[ 65.581538][ T1] Testing event system rpm: OK <6>[ 65.624693][ T1] Testing event system power: OK <6>[ 65.665432][ T1] Testing event system error_report: OK <6>[ 65.707861][ T1] Testing event system bpf_trace: OK <6>[ 65.751235][ T1] Testing event system test: OK <6>[ 65.794496][ T1] Testing event system preemptirq: OK <6>[ 65.838030][ T1] Testing event system cgroup: OK <6>[ 65.881694][ T1] Testing event system module: OK <6>[ 65.924690][ T1] Testing event system alarmtimer: OK <6>[ 65.964646][ T1] Testing event system timer: OK <6>[ 66.008322][ T1] Testing event system raw_syscalls: OK <6>[ 66.051295][ T1] Testing event system swiotlb: OK <6>[ 66.094594][ T1] Testing event system rcu: OK <6>[ 66.137981][ T1] Testing event system irq_matrix: OK <6>[ 66.181502][ T1] Testing event system printk: OK <6>[ 66.221235][ T1] Testing event system lock: OK <6>[ 66.264697][ T1] Testing event system sched: OK <6>[ 66.308878][ T1] Testing event system workqueue: OK <6>[ 66.348010][ T1] Testing event system signal: OK <6>[ 66.387922][ T1] Testing event system irq: OK <6>[ 66.431378][ T1] Testing event system cpuhp: OK <6>[ 66.477998][ T1] Testing event system task: OK <6>[ 66.517895][ T1] Testing event system exceptions: OK <6>[ 66.561251][ T1] Testing event system tlb: OK <6>[ 66.604810][ T1] Testing event system resctrl: OK <6>[ 66.648135][ T1] Testing event system mce: OK <6>[ 66.691373][ T1] Testing event system x86_fpu: OK <6>[ 66.735005][ T1] Testing event system nmi: OK <6>[ 66.781405][ T1] Testing event system irq_vectors: OK <6>[ 66.825999][ T1] Testing event system syscalls: OK <6>[ 66.871774][ T1] Testing event system hyperv: OK <6>[ 66.914612][ T1] Testing event system xen: OK <6>[ 66.958716][ T1] Testing event system vsyscall: OK <6>[ 67.001157][ T1] Testing event system initcall: OK <6>[ 67.044602][ T1] Running tests on all trace events: <6>[ 67.045163][ T1] Testing all events: OK <6>[ 67.940474][ T1] Running tests again, along with the function tracer <6>[ 67.942872][ T1] Running tests on trace events: <6>[ 67.945223][ T1] Testing event initcall_finish: OK <6>[ 67.989741][ T1] Testing event initcall_start: OK <6>[ 68.036408][ T1] Testing event initcall_level: OK <6>[ 68.083002][ T1] Testing event emulate_vsyscall: OK <6>[ 68.133021][ T1] Testing event xen_cpu_set_ldt: OK <6>[ 68.179677][ T1] Testing event xen_cpu_write_gdt_entry: OK <6>[ 68.226386][ T1] Testing event xen_cpu_load_idt: OK <6>[ 68.273071][ T1] Testing event xen_cpu_write_idt_entry: OK <6>[ 68.320097][ T1] Testing event xen_cpu_write_ldt_entry: OK <6>[ 68.366336][ T1] Testing event xen_mmu_write_cr3: OK <6>[ 68.416356][ T1] Testing event xen_mmu_flush_tlb_multi: OK <6>[ 68.463053][ T1] Testing event xen_mmu_flush_tlb_one_user: OK <6>[ 68.509785][ T1] Testing event xen_mmu_pgd_unpin: OK <6>[ 68.556438][ T1] Testing event xen_mmu_pgd_pin: OK <6>[ 68.606412][ T1] Testing event xen_mmu_release_ptpage: OK <6>[ 68.656453][ T1] Testing event xen_mmu_alloc_ptpage: OK <6>[ 68.703113][ T1] Testing event xen_mmu_ptep_modify_prot_commit: OK <6>[ 68.759774][ T1] Testing event xen_mmu_ptep_modify_prot_start: OK <6>[ 68.806483][ T1] Testing event xen_mmu_set_p4d: OK <6>[ 68.859767][ T1] Testing event xen_mmu_set_pud: OK <6>[ 68.906426][ T1] Testing event xen_mmu_set_pmd: OK <6>[ 68.959787][ T1] Testing event xen_mmu_set_pte: OK <6>[ 69.009726][ T1] Testing event xen_mc_extend_args: OK <6>[ 69.056431][ T1] Testing event xen_mc_flush: OK <6>[ 69.109712][ T1] Testing event xen_mc_flush_reason: OK <6>[ 69.156441][ T1] Testing event xen_mc_callback: OK <6>[ 69.206444][ T1] Testing event xen_mc_entry_alloc: OK <6>[ 69.253102][ T1] Testing event xen_mc_entry: OK <6>[ 69.303446][ T1] Testing event xen_mc_issue: OK <6>[ 69.349716][ T1] Testing event xen_mc_batch: OK <6>[ 69.396509][ T1] Testing event hyperv_send_ipi_one: OK <6>[ 69.443126][ T1] Testing event hyperv_send_ipi_mask: OK <6>[ 69.489761][ T1] Testing event hyperv_nested_flush_guest_mapping_range: OK <6>[ 69.539766][ T1] Testing event hyperv_nested_flush_guest_mapping: OK <6>[ 69.589819][ T1] Testing event hyperv_mmu_flush_tlb_multi: OK <6>[ 69.639779][ T1] Testing event vector_free_moved: OK <6>[ 69.689762][ T1] Testing event vector_setup: OK <6>[ 69.739819][ T1] Testing event vector_teardown: OK <6>[ 69.789801][ T1] Testing event vector_deactivate: OK <6>[ 69.836408][ T1] Testing event vector_activate: OK <6>[ 69.886445][ T1] Testing event vector_alloc_managed: OK <6>[ 69.936478][ T1] Testing event vector_alloc: OK <6>[ 69.986457][ T1] Testing event vector_reserve: OK <6>[ 70.036465][ T1] Testing event vector_reserve_managed: OK <6>[ 70.086442][ T1] Testing event vector_clear: OK <6>[ 70.136426][ T1] Testing event vector_update: OK <6>[ 70.183088][ T1] Testing event vector_config: OK <6>[ 70.233097][ T1] Testing event thermal_apic_exit: OK <6>[ 70.283036][ T1] Testing event thermal_apic_entry: OK <6>[ 70.329710][ T1] Testing event deferred_error_apic_exit: OK <6>[ 70.376383][ T1] Testing event deferred_error_apic_entry: <6>[ 70.397015][ T124] usb 1-1.1: USB disconnect, device number 3 <4>[ 70.420744][ T1] OK <6>[ 70.422938][ T1] Testing event threshold_apic_exit: OK <6>[ 70.473073][ T1] Testing event threshold_apic_entry: OK <6>[ 70.523087][ T1] Testing event call_function_single_exit: OK <6>[ 70.566282][ T1] Testing event call_function_single_entry: OK <6>[ 70.613110][ T1] Testing event call_function_exit: OK <6>[ 70.659781][ T1] Testing event call_function_entry: OK <6>[ 70.706406][ T1] Testing event reschedule_exit: OK <6>[ 70.753102][ T1] Testing event reschedule_entry: OK <6>[ 70.799733][ T1] Testing event irq_work_exit: OK <6>[ 70.846434][ T1] Testing event irq_work_entry: OK <6>[ 70.893108][ T1] Testing event x86_platform_ipi_exit: OK <6>[ 70.939785][ T1] Testing event x86_platform_ipi_entry: OK <6>[ 70.989807][ T1] Testing event error_apic_exit: OK <6>[ 71.039783][ T1] Testing event error_apic_entry: OK <6>[ 71.086444][ T1] Testing event spurious_apic_exit: OK <6>[ 71.133112][ T1] Testing event spurious_apic_entry: OK <6>[ 71.179784][ T1] Testing event local_timer_exit: OK <6>[ 71.226688][ T1] Testing event local_timer_entry: OK <6>[ 71.273405][ T1] Testing event nmi_handler: OK <6>[ 71.319915][ T1] Testing event x86_fpu_xstate_check_failed: OK <6>[ 71.366797][ T1] Testing event x86_fpu_copy_dst: OK <6>[ 71.413445][ T1] Testing event x86_fpu_copy_src: OK <6>[ 71.460112][ T1] Testing event x86_fpu_dropped: OK <6>[ 71.506765][ T1] Testing event x86_fpu_init_state: OK <6>[ 71.556691][ T1] Testing event x86_fpu_regs_deactivated: OK <6>[ 71.603130][ T1] Testing event x86_fpu_regs_activated: OK <6>[ 71.653201][ T1] Testing event x86_fpu_after_restore: OK <6>[ 71.703423][ T1] Testing event x86_fpu_before_restore: OK <6>[ 71.750042][ T1] Testing event x86_fpu_after_save: OK <6>[ 71.796489][ T1] Testing event x86_fpu_before_save: OK <6>[ 71.843153][ T1] Testing event mce_record: OK <6>[ 71.889828][ T1] Testing event pseudo_lock_l3: <6>[ 71.913552][ T124] usb 1-1.1: new low-speed USB device number 6 using ehci-pci <4>[ 71.937468][ T1] OK <6>[ 71.939766][ T1] Testing event pseudo_lock_l2: OK <6>[ 71.986516][ T1] Testing event pseudo_lock_mem_latency: OK <6>[ 72.028767][ T124] usb 1-1.1: New USB device found, idVendor=046d, idProduct=c077, bcdDevice=72.00 <6>[ 72.029728][ T1] Testing event tlb_flush: <6>[ 72.032343][ T124] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 <6>[ 72.037648][ T124] usb 1-1.1: Product: USB Optical Mouse <6>[ 72.040377][ T124] usb 1-1.1: Manufacturer: Logitech <4>[ 72.087654][ T1] OK <6>[ 72.090240][ T1] Testing event page_fault_kernel: OK <6>[ 72.133587][ T1] Testing event page_fault_user: OK <6>[ 72.180208][ T1] Testing event task_rename: OK <6>[ 72.226696][ T1] Testing event task_newtask: OK <6>[ 72.270113][ T1] Testing event cpuhp_exit: OK <6>[ 72.320070][ T1] Testing event cpuhp_multi_enter: OK <6>[ 72.366750][ T1] Testing event cpuhp_enter: OK <6>[ 72.413397][ T1] Testing event softirq_raise: OK <6>[ 72.460058][ T1] Testing event softirq_exit: OK <6>[ 72.506714][ T1] Testing event softirq_entry: OK <6>[ 72.550098][ T1] Testing event irq_handler_exit: OK <6>[ 72.596818][ T1] Testing event irq_handler_entry: OK <6>[ 72.643414][ T1] Testing event signal_deliver: OK <6>[ 72.690192][ T1] Testing event signal_generate: OK <6>[ 72.733592][ T1] Testing event workqueue_execute_end: OK <6>[ 72.780361][ T1] Testing event workqueue_execute_start: OK <6>[ 72.826991][ T1] Testing event workqueue_activate_work: OK <6>[ 72.873715][ T1] Testing event workqueue_queue_work: OK <6>[ 72.923681][ T1] Testing event sched_wake_idle_without_ipi: OK <6>[ 72.970433][ T1] Testing event sched_swap_numa: OK <6>[ 73.016989][ T1] Testing event sched_stick_numa: OK <6>[ 73.060455][ T1] Testing event sched_move_numa: OK <6>[ 73.103719][ T1] Testing event sched_process_hang: OK <6>[ 73.150360][ T1] Testing event sched_pi_setprio: OK <6>[ 73.197006][ T1] Testing event sched_stat_runtime: OK <6>[ 73.247026][ T1] Testing event sched_stat_blocked: OK <6>[ 73.293402][ T1] Testing event sched_stat_iowait: OK <6>[ 73.340130][ T1] Testing event sched_stat_sleep: OK <6>[ 73.387009][ T1] Testing event sched_stat_wait: OK <6>[ 73.433683][ T1] Testing event sched_process_exec: OK <6>[ 73.480331][ T1] Testing event sched_process_fork: OK <6>[ 73.526985][ T1] Testing event sched_process_wait: OK <6>[ 73.573651][ T1] Testing event sched_wait_task: OK <6>[ 73.620290][ T1] Testing event sched_process_exit: OK <6>[ 73.666696][ T1] Testing event sched_process_free: OK <6>[ 73.713394][ T1] Testing event sched_migrate_task: OK <6>[ 73.760012][ T1] Testing event sched_switch: OK <6>[ 73.807151][ T1] Testing event sched_wakeup_new: OK <6>[ 73.856982][ T1] Testing event sched_wakeup: OK <6>[ 73.907023][ T1] Testing event sched_waking: OK <6>[ 73.956563][ T1] Testing event sched_kthread_work_execute_end: OK <6>[ 74.003342][ T1] Testing event sched_kthread_work_execute_start: OK <6>[ 74.049879][ T1] Testing event sched_kthread_work_queue_work: OK <6>[ 74.096436][ T1] Testing event sched_kthread_stop_ret: OK <6>[ 74.139730][ T1] Testing event sched_kthread_stop: OK <6>[ 74.189762][ T1] Testing event lock_release: OK <6>[ 74.236398][ T1] Testing event lock_acquire: OK <6>[ 74.283102][ T1] Testing event console: OK <6>[ 74.329712][ T1] Testing event irq_matrix_free: OK <6>[ 74.379719][ T1] Testing event irq_matrix_alloc: OK <6>[ 74.423047][ T1] Testing event irq_matrix_assign: OK <6>[ 74.473053][ T1] Testing event irq_matrix_alloc_managed: OK <6>[ 74.523076][ T1] Testing event irq_matrix_remove_managed: OK <6>[ 74.569714][ T1] Testing event irq_matrix_reserve_managed: OK <6>[ 74.616383][ T1] Testing event irq_matrix_alloc_reserved: OK <6>[ 74.662918][ T1] Testing event irq_matrix_assign_system: OK <6>[ 74.706345][ T1] Testing event irq_matrix_remove_reserved: OK <6>[ 74.753145][ T1] Testing event irq_matrix_reserve: OK <6>[ 74.799919][ T1] Testing event irq_matrix_offline: OK <6>[ 74.846549][ T1] Testing event irq_matrix_online: OK <6>[ 74.893355][ T1] Testing event rcu_stall_warning: OK <6>[ 74.943232][ T1] Testing event rcu_utilization: OK <6>[ 74.989893][ T1] Testing event swiotlb_bounced: OK <6>[ 75.039646][ T1] Testing event sys_exit: OK <6>[ 75.086341][ T1] Testing event sys_enter: OK <6>[ 75.133060][ T1] Testing event tick_stop: OK <6>[ 75.183221][ T1] Testing event itimer_expire: OK <6>[ 75.232999][ T1] Testing event itimer_state: OK <6>[ 75.282989][ T1] Testing event hrtimer_cancel: OK <6>[ 75.336402][ T1] Testing event hrtimer_expire_exit: OK <6>[ 75.386351][ T1] Testing event hrtimer_expire_entry: OK <6>[ 75.436371][ T1] Testing event hrtimer_start: OK <6>[ 75.486351][ T1] Testing event hrtimer_init: OK <6>[ 75.533086][ T1] Testing event timer_cancel: OK <6>[ 75.586376][ T1] Testing event timer_expire_exit: OK <6>[ 75.636365][ T1] Testing event timer_expire_entry: OK <6>[ 75.683028][ T1] Testing event timer_start: OK <6>[ 75.732974][ T1] Testing event timer_init: OK <6>[ 75.779693][ T1] Testing event alarmtimer_cancel: OK <6>[ 75.829706][ T1] Testing event alarmtimer_start: OK <6>[ 75.879698][ T1] Testing event alarmtimer_fired: OK <6>[ 75.929698][ T1] Testing event alarmtimer_suspend: OK <6>[ 75.976368][ T1] Testing event module_request: OK <6>[ 76.026378][ T1] Testing event module_put: OK <6>[ 76.069725][ T1] Testing event module_get: OK <6>[ 76.116365][ T1] Testing event module_free: OK <6>[ 76.166346][ T1] Testing event module_load: OK <6>[ 76.216345][ T1] Testing event cgroup_notify_frozen: OK <6>[ 76.263102][ T1] Testing event cgroup_notify_populated: OK <6>[ 76.309713][ T1] Testing event cgroup_transfer_tasks: OK <6>[ 76.359720][ T1] Testing event cgroup_attach_task: OK <6>[ 76.409705][ T1] Testing event cgroup_unfreeze: OK <6>[ 76.459690][ T1] Testing event cgroup_freeze: OK <6>[ 76.506425][ T1] Testing event cgroup_rename: OK <6>[ 76.556531][ T1] Testing event cgroup_release: OK <6>[ 76.603011][ T1] Testing event cgroup_rmdir: OK <6>[ 76.649672][ T1] Testing event cgroup_mkdir: OK <6>[ 76.696401][ T1] Testing event cgroup_remount: OK <6>[ 76.743237][ T1] Testing event cgroup_destroy_root: OK <6>[ 76.793203][ T1] Testing event cgroup_setup_root: OK <6>[ 76.843241][ T1] Testing event irq_enable: OK <6>[ 76.890063][ T1] Testing event irq_disable: OK <6>[ 76.936688][ T1] Testing event ftrace_test_filter: OK <6>[ 76.983135][ T1] Testing event bpf_trace_printk: OK <6>[ 77.029897][ T1] Testing event error_report_end: OK <6>[ 77.076867][ T1] Testing event dev_pm_qos_remove_request: OK <6>[ 77.123351][ T1] Testing event dev_pm_qos_update_request: OK <6>[ 77.166979][ T1] Testing event dev_pm_qos_add_request: OK <6>[ 77.209696][ T1] Testing event pm_qos_update_flags: OK <6>[ 77.263018][ T1] Testing event pm_qos_update_target: OK <6>[ 77.309693][ T1] Testing event pm_qos_remove_request: OK <6>[ 77.356386][ T1] Testing event pm_qos_update_request: OK <6>[ 77.403025][ T1] Testing event pm_qos_add_request: OK <6>[ 77.449700][ T1] Testing event power_domain_target: OK <6>[ 77.496246][ T1] Testing event clock_set_rate: OK <6>[ 77.542975][ T1] Testing event clock_disable: OK <6>[ 77.589574][ T1] Testing event clock_enable: OK <6>[ 77.636257][ T1] Testing event wakeup_source_deactivate: OK <6>[ 77.683051][ T1] Testing event wakeup_source_activate: OK <6>[ 77.729685][ T1] Testing event suspend_resume: OK <6>[ 77.780494][ T1] Testing event device_pm_callback_end: OK <6>[ 77.823148][ T1] Testing event device_pm_callback_start: OK <6>[ 77.866450][ T1] Testing event cpu_frequency_limits: OK <6>[ 77.916357][ T1] Testing event cpu_frequency: OK <6>[ 77.959857][ T1] Testing event pstate_sample: OK <6>[ 78.003075][ T1] Testing event powernv_throttle: OK <6>[ 78.049682][ T1] Testing event cpu_idle: OK <6>[ 78.096504][ T1] Testing event rpm_return_int: OK <6>[ 78.150026][ T1] Testing event rpm_usage: OK <6>[ 78.200004][ T1] Testing event rpm_idle: OK <6>[ 78.246394][ T1] Testing event rpm_resume: OK <6>[ 78.296403][ T1] Testing event rpm_suspend: OK <6>[ 78.349799][ T1] Testing event mem_return_failed: OK <6>[ 78.399891][ T1] Testing event mem_connect: OK <6>[ 78.446403][ T1] Testing event mem_disconnect: OK <6>[ 78.493072][ T1] Testing event xdp_devmap_xmit: OK <6>[ 78.539818][ T1] Testing event xdp_cpumap_enqueue: OK <6>[ 78.590054][ T1] Testing event xdp_cpumap_kthread: OK <6>[ 78.636332][ T1] Testing event xdp_redirect_map_err: OK <6>[ 78.679659][ T1] Testing event xdp_redirect_map: OK <6>[ 78.726572][ T1] Testing event xdp_redirect_err: OK <6>[ 78.776775][ T1] Testing event xdp_redirect: OK <6>[ 78.823517][ T1] Testing event xdp_bulk_tx: OK <6>[ 78.869905][ T1] Testing event xdp_exception: OK <6>[ 78.920229][ T1] Testing event rseq_ip_fixup: OK <6>[ 78.963009][ T1] Testing event rseq_update: OK <6>[ 79.006409][ T1] Testing event file_check_and_advance_wb_err: OK <6>[ 79.053123][ T1] Testing event filemap_set_wb_err: OK <6>[ 79.099789][ T1] Testing event mm_filemap_add_to_page_cache: OK <6>[ 79.146454][ T1] Testing event mm_filemap_delete_from_page_cache: OK <6>[ 79.189834][ T1] Testing event compact_retry: OK <6>[ 79.236476][ T1] Testing event skip_task_reaping: OK <6>[ 79.286488][ T1] Testing event finish_task_reaping: OK <6>[ 79.333153][ T1] Testing event start_task_reaping: OK <6>[ 79.379816][ T1] Testing event wake_reaper: OK <6>[ 79.426489][ T1] Testing event mark_victim: OK <6>[ 79.473075][ T1] Testing event reclaim_retry_zone: OK <6>[ 79.519831][ T1] Testing event oom_score_adj_update: OK <6>[ 79.566408][ T1] Testing event mm_lru_activate: OK <6>[ 79.613181][ T1] Testing event mm_lru_insertion: OK <6>[ 79.663130][ T1] Testing event mm_vmscan_node_reclaim_end: OK <6>[ 79.709858][ T1] Testing event mm_vmscan_node_reclaim_begin: OK <6>[ 79.759866][ T1] Testing event mm_vmscan_lru_shrink_active: OK <6>[ 79.806525][ T1] Testing event mm_vmscan_lru_shrink_inactive: OK <6>[ 79.853367][ T1] Testing event mm_vmscan_writepage: OK <6>[ 79.896816][ T1] Testing event mm_vmscan_lru_isolate: OK <6>[ 79.943475][ T1] Testing event mm_shrink_slab_end: OK <6>[ 79.990105][ T1] Testing event mm_shrink_slab_start: OK <6>[ 80.033535][ T1] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK <6>[ 80.076812][ T1] Testing event mm_vmscan_memcg_reclaim_end: OK <6>[ 80.120253][ T1] Testing event mm_vmscan_direct_reclaim_end: OK <6>[ 80.166784][ T1] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK <6>[ 80.210296][ T1] Testing event mm_vmscan_memcg_reclaim_begin: OK <6>[ 80.253567][ T1] Testing event mm_vmscan_direct_reclaim_begin: OK <6>[ 80.300212][ T1] Testing event mm_vmscan_wakeup_kswapd: OK <6>[ 80.343594][ T1] Testing event mm_vmscan_kswapd_wake: OK <6>[ 80.390116][ T1] Testing event mm_vmscan_kswapd_sleep: OK <6>[ 80.433527][ T1] Testing event percpu_destroy_chunk: OK <6>[ 80.483528][ T1] Testing event percpu_create_chunk: OK <6>[ 80.530229][ T1] Testing event percpu_alloc_percpu_fail: OK <6>[ 80.580169][ T1] Testing event percpu_free_percpu: OK <6>[ 80.626844][ T1] Testing event percpu_alloc_percpu: OK <6>[ 80.673533][ T1] Testing event rss_stat: OK <6>[ 80.719905][ T1] Testing event mm_page_alloc_extfrag: OK <6>[ 80.763208][ T1] Testing event mm_page_pcpu_drain: OK <6>[ 80.806515][ T1] Testing event mm_page_alloc_zone_locked: OK <6>[ 80.849883][ T1] Testing event mm_page_alloc: OK <6>[ 80.896521][ T1] Testing event mm_page_free_batched: OK <6>[ 80.946559][ T1] Testing event mm_page_free: OK <6>[ 80.996557][ T1] Testing event kmem_cache_free: OK <6>[ 81.039841][ T1] Testing event kfree: OK <6>[ 81.083222][ T1] Testing event kmem_cache_alloc_node: OK <6>[ 81.133335][ T1] Testing event kmalloc_node: OK <6>[ 81.183331][ T1] Testing event kmem_cache_alloc: OK <6>[ 81.233517][ T1] Testing event kmalloc: OK <6>[ 81.279893][ T1] Testing event mm_compaction_kcompactd_wake: OK <6>[ 81.323350][ T1] Testing event mm_compaction_wakeup_kcompactd: OK <6>[ 81.366659][ T1] Testing event mm_compaction_kcompactd_sleep: OK <6>[ 81.419865][ T1] Testing event mm_compaction_defer_reset: OK <6>[ 81.463231][ T1] Testing event mm_compaction_defer_compaction: OK <6>[ 81.513237][ T1] Testing event mm_compaction_deferred: OK <6>[ 81.556570][ T1] Testing event mm_compaction_suitable: OK <6>[ 81.599874][ T1] Testing event mm_compaction_finished: OK <6>[ 81.643190][ T1] Testing event mm_compaction_try_to_compact_pages: OK <6>[ 81.686564][ T1] Testing event mm_compaction_end: OK <6>[ 81.729867][ T1] Testing event mm_compaction_begin: OK <6>[ 81.773146][ T1] Testing event mm_compaction_migratepages: OK <6>[ 81.816721][ T1] Testing event mm_compaction_isolate_freepages: OK <6>[ 81.863507][ T1] Testing event mm_compaction_isolate_migratepages: OK <6>[ 81.910219][ T1] Testing event mmap_lock_released: OK <6>[ 81.964516][ T1] Testing event mmap_lock_acquire_returned: OK <6>[ 82.016495][ T1] Testing event mmap_lock_start_locking: OK <6>[ 82.073659][ T1] Testing event vm_unmapped_area: OK <6>[ 82.116853][ T1] Testing event mm_migrate_pages_start: OK <6>[ 82.160225][ T1] Testing event mm_migrate_pages: OK <6>[ 82.206878][ T1] Testing event mm_collapse_huge_page_swapin: OK <6>[ 82.253529][ T1] Testing event mm_collapse_huge_page_isolate: OK <6>[ 82.296870][ T1] Testing event mm_collapse_huge_page: OK <6>[ 82.343542][ T1] Testing event mm_khugepaged_scan_pmd: OK <6>[ 82.390168][ T1] Testing event test_pages_isolated: OK <6>[ 82.440158][ T1] Testing event sb_clear_inode_writeback: OK <6>[ 82.486790][ T1] Testing event sb_mark_inode_writeback: OK <6>[ 82.536505][ T1] Testing event writeback_dirty_inode_enqueue: OK <6>[ 82.583191][ T1] Testing event writeback_lazytime_iput: OK <6>[ 82.629899][ T1] Testing event writeback_lazytime: OK <6>[ 82.676560][ T1] Testing event writeback_single_inode: OK <6>[ 82.726510][ T1] Testing event writeback_single_inode_start: OK <6>[ 82.773161][ T1] Testing event writeback_wait_iff_congested: OK <6>[ 82.819896][ T1] Testing event writeback_congestion_wait: OK <6>[ 82.866568][ T1] Testing event writeback_sb_inodes_requeue: OK <6>[ 82.916564][ T1] Testing event balance_dirty_pages: OK <6>[ 82.966678][ T1] Testing event bdi_dirty_ratelimit: OK <6>[ 83.016492][ T1] Testing event global_dirty_state: OK <6>[ 83.063155][ T1] Testing event writeback_queue_io: OK <6>[ 83.116549][ T1] Testing event wbc_writepage: OK <6>[ 83.163163][ T1] Testing event writeback_bdi_register: OK <6>[ 83.213229][ T1] Testing event writeback_wake_background: OK <6>[ 83.263348][ T1] Testing event writeback_pages_written: OK <6>[ 83.309913][ T1] Testing event writeback_wait: OK <6>[ 83.356532][ T1] Testing event writeback_written: OK <6>[ 83.403199][ T1] Testing event writeback_start: OK <6>[ 83.449825][ T1] Testing event writeback_exec: OK <6>[ 83.496487][ T1] Testing event writeback_queue: OK <6>[ 83.543177][ T1] Testing event writeback_write_inode: OK <6>[ 83.590027][ T1] Testing event writeback_write_inode_start: OK <6>[ 83.633517][ T1] Testing event flush_foreign: OK <6>[ 83.676910][ T1] Testing event track_foreign_dirty: OK <6>[ 83.720171][ T1] Testing event inode_switch_wbs: OK <6>[ 83.770119][ T1] Testing event inode_foreign_history: OK <6>[ 83.816534][ T1] Testing event writeback_dirty_inode: OK <6>[ 83.866533][ T1] Testing event writeback_dirty_inode_start: OK <6>[ 83.913222][ T1] Testing event writeback_mark_inode_dirty: OK <6>[ 83.960010][ T1] Testing event wait_on_page_writeback: OK <6>[ 84.006899][ T1] Testing event writeback_dirty_page: OK <6>[ 84.053500][ T1] Testing event io_uring_task_run: OK <6>[ 84.100157][ T1] Testing event io_uring_task_add: OK <6>[ 84.146854][ T1] Testing event io_uring_poll_wake: OK <6>[ 84.190287][ T1] Testing event io_uring_poll_arm: OK <6>[ 84.236831][ T1] Testing event io_uring_submit_sqe: OK <6>[ 84.283213][ T1] Testing event io_uring_complete: OK <6>[ 84.329850][ T1] Testing event io_uring_fail_link: OK <6>[ 84.379835][ T1] Testing event io_uring_cqring_wait: OK <6>[ 84.426467][ T1] Testing event io_uring_link: OK <6>[ 84.473123][ T1] Testing event io_uring_defer: OK <6>[ 84.519801][ T1] Testing event io_uring_queue_async_work: OK <6>[ 84.569773][ T1] Testing event io_uring_file_get: OK <6>[ 84.619820][ T1] Testing event io_uring_register: OK <6>[ 84.666508][ T1] Testing event io_uring_create: OK <6>[ 84.713123][ T1] Testing event dax_writeback_one: OK <6>[ 84.759829][ T1] Testing event dax_writeback_range_done: OK <6>[ 84.806488][ T1] Testing event dax_writeback_range: OK <6>[ 84.856414][ T1] Testing event dax_insert_mapping: OK <6>[ 84.906454][ T1] Testing event dax_insert_pfn_mkwrite: OK <6>[ 84.956442][ T1] Testing event dax_insert_pfn_mkwrite_no_entry: OK <6>[ 85.003154][ T1] Testing event dax_load_hole: OK <6>[ 85.049793][ T1] Testing event dax_pte_fault_done: OK <6>[ 85.100077][ T1] Testing event dax_pte_fault: OK <6>[ 85.143156][ T1] Testing event dax_pmd_insert_mapping: OK <6>[ 85.186437][ T1] Testing event dax_pmd_load_hole_fallback: OK <6>[ 85.233158][ T1] Testing event dax_pmd_load_hole: OK <6>[ 85.279742][ T1] Testing event dax_pmd_fault_done: OK <6>[ 85.326428][ T1] Testing event dax_pmd_fault: OK <6>[ 85.373194][ T1] Testing event leases_conflict: OK <6>[ 85.420074][ T1] Testing event generic_add_lease: OK <6>[ 85.463499][ T1] Testing event time_out_leases: OK <6>[ 85.506736][ T1] Testing event generic_delete_lease: OK <6>[ 85.553419][ T1] Testing event break_lease_unblock: OK <6>[ 85.603240][ T1] Testing event break_lease_block: OK <6>[ 85.653057][ T1] Testing event break_lease_noblock: OK <6>[ 85.700208][ T1] Testing event flock_lock_inode: OK <6>[ 85.746788][ T1] Testing event locks_remove_posix: OK <6>[ 85.793446][ T1] Testing event fcntl_setlk: OK <6>[ 85.840106][ T1] Testing event posix_lock_inode: OK <6>[ 85.886765][ T1] Testing event locks_get_lock_context: OK <6>[ 85.933458][ T1] Testing event iomap_apply: OK <6>[ 85.980103][ T1] Testing event iomap_apply_srcmap: OK <6>[ 86.027459][ T1] Testing event iomap_apply_dstmap: OK <6>[ 86.073096][ T1] Testing event iomap_dio_invalidate_fail: OK <6>[ 86.126484][ T1] Testing event iomap_invalidatepage: OK <6>[ 86.173156][ T1] Testing event iomap_releasepage: OK <6>[ 86.223138][ T1] Testing event iomap_writepage: OK <6>[ 86.273122][ T1] Testing event iomap_readahead: OK <6>[ 86.319800][ T1] Testing event iomap_readpage: OK <6>[ 86.363031][ T1] Testing event ext4_fc_track_range: OK <6>[ 86.406429][ T1] Testing event ext4_fc_track_inode: OK <6>[ 86.456463][ T1] Testing event ext4_fc_track_unlink: OK <6>[ 86.506486][ T1] Testing event ext4_fc_track_link: OK <6>[ 86.556415][ T1] Testing event ext4_fc_track_create: OK <6>[ 86.606463][ T1] Testing event ext4_fc_stats: OK <6>[ 86.649781][ T1] Testing event ext4_fc_commit_stop: OK <6>[ 86.696451][ T1] Testing event ext4_fc_commit_start: OK <6>[ 86.746481][ T1] Testing event ext4_fc_replay: OK <6>[ 86.796690][ T1] Testing event ext4_fc_replay_scan: OK <6>[ 86.839785][ T1] Testing event ext4_lazy_itable_init: OK <6>[ 86.883103][ T1] Testing event ext4_prefetch_bitmaps: OK <6>[ 86.930111][ T1] Testing event ext4_error: OK <6>[ 86.976787][ T1] Testing event ext4_shutdown: OK <6>[ 87.026527][ T1] Testing event ext4_getfsmap_mapping: OK <6>[ 87.073154][ T1] Testing event ext4_getfsmap_high_key: OK <6>[ 87.123136][ T1] Testing event ext4_getfsmap_low_key: OK <6>[ 87.173119][ T1] Testing event ext4_fsmap_mapping: OK <6>[ 87.223423][ T1] Testing event ext4_fsmap_high_key: OK <6>[ 87.269792][ T1] Testing event ext4_fsmap_low_key: OK <6>[ 87.319790][ T1] Testing event ext4_es_insert_delayed_block: OK <6>[ 87.369833][ T1] Testing event ext4_es_shrink: OK <6>[ 87.419805][ T1] Testing event ext4_insert_range: OK <6>[ 87.463056][ T1] Testing event ext4_collapse_range: OK <6>[ 87.506498][ T1] Testing event ext4_es_shrink_scan_exit: OK <6>[ 87.556741][ T1] Testing event ext4_es_shrink_scan_enter: OK <6>[ 87.603119][ T1] Testing event ext4_es_shrink_count: OK <6>[ 87.653124][ T1] Testing event ext4_es_lookup_extent_exit: OK <6>[ 87.699805][ T1] Testing event ext4_es_lookup_extent_enter: OK <6>[ 87.749777][ T1] Testing event ext4_es_find_extent_range_exit: OK <6>[ 87.796789][ T1] Testing event ext4_es_find_extent_range_enter: OK <6>[ 87.846463][ T1] Testing event ext4_es_remove_extent: OK <6>[ 87.896441][ T1] Testing event ext4_es_cache_extent: OK <6>[ 87.943102][ T1] Testing event ext4_es_insert_extent: OK <6>[ 87.986436][ T1] Testing event ext4_ext_remove_space_done: OK <6>[ 88.033135][ T1] Testing event ext4_ext_remove_space: OK <6>[ 88.083117][ T1] Testing event ext4_ext_rm_idx: OK <6>[ 88.126444][ T1] Testing event ext4_ext_rm_leaf: OK <6>[ 88.179777][ T1] Testing event ext4_remove_blocks: OK <6>[ 88.226414][ T1] Testing event ext4_ext_show_extent: OK <6>[ 88.273118][ T1] Testing event ext4_get_implied_cluster_alloc_exit: OK <6>[ 88.319793][ T1] Testing event ext4_ext_handle_unwritten_extents: OK <6>[ 88.366496][ T1] Testing event ext4_trim_all_free: OK <6>[ 88.416480][ T1] Testing event ext4_trim_extent: OK <6>[ 88.463075][ T1] Testing event ext4_journal_start_reserved: OK <6>[ 88.509813][ T1] Testing event ext4_journal_start: OK <6>[ 88.556472][ T1] Testing event ext4_load_inode: OK <6>[ 88.603454][ T1] Testing event ext4_ext_load_extent: OK <6>[ 88.650108][ T1] Testing event ext4_ind_map_blocks_exit: OK <6>[ 88.696798][ T1] Testing event ext4_ext_map_blocks_exit: OK <6>[ 88.743094][ T1] Testing event ext4_ind_map_blocks_enter: OK <6>[ 88.790031][ T1] Testing event ext4_ext_map_blocks_enter: OK <6>[ 88.833477][ T1] Testing event ext4_ext_convert_to_initialized_fastpath: OK <6>[ 88.880149][ T1] Testing event ext4_ext_convert_to_initialized_enter: OK <6>[ 88.926856][ T1] Testing event ext4_truncate_exit: OK <6>[ 88.973447][ T1] Testing event ext4_truncate_enter: OK <6>[ 89.020128][ T1] Testing event ext4_unlink_exit: OK <6>[ 89.066836][ T1] Testing event ext4_unlink_enter: OK <6>[ 89.113740][ T1] Testing event ext4_fallocate_exit: OK <6>[ 89.160126][ T1] Testing event ext4_zero_range: OK <6>[ 89.206758][ T1] Testing event ext4_punch_hole: OK <6>[ 89.250134][ T1] Testing event ext4_fallocate_enter: OK <6>[ 89.293457][ T1] Testing event ext4_read_block_bitmap_load: OK <6>[ 89.336825][ T1] Testing event ext4_load_inode_bitmap: OK <6>[ 89.380124][ T1] Testing event ext4_mb_buddy_bitmap_load: OK <6>[ 89.426795][ T1] Testing event ext4_mb_bitmap_load: OK <6>[ 89.473483][ T1] Testing event ext4_da_release_space: OK <6>[ 89.516846][ T1] Testing event ext4_da_reserve_space: OK <6>[ 89.563454][ T1] Testing event ext4_da_update_reserve_space: OK <6>[ 89.609803][ T1] Testing event ext4_forget: OK <6>[ 89.656489][ T1] Testing event ext4_mballoc_free: OK <6>[ 89.706427][ T1] Testing event ext4_mballoc_discard: OK <6>[ 89.753185][ T1] Testing event ext4_mballoc_prealloc: OK <6>[ 89.799823][ T1] Testing event ext4_mballoc_alloc: OK <6>[ 89.846510][ T1] Testing event ext4_alloc_da_blocks: OK <6>[ 89.893159][ T1] Testing event ext4_sync_fs: OK <6>[ 89.943161][ T1] Testing event ext4_sync_file_exit: OK <6>[ 89.989828][ T1] Testing event ext4_sync_file_enter: OK <6>[ 90.039827][ T1] Testing event ext4_free_blocks: OK <6>[ 90.089812][ T1] Testing event ext4_allocate_blocks: OK <6>[ 90.136491][ T1] Testing event ext4_request_blocks: OK <6>[ 90.189832][ T1] Testing event ext4_mb_discard_preallocations: OK <6>[ 90.236502][ T1] Testing event ext4_discard_preallocations: OK <6>[ 90.283160][ T1] Testing event ext4_mb_release_group_pa: OK <6>[ 90.333169][ T1] Testing event ext4_mb_release_inode_pa: OK <6>[ 90.379837][ T1] Testing event ext4_mb_new_group_pa: OK <6>[ 90.429798][ T1] Testing event ext4_mb_new_inode_pa: OK <6>[ 90.479844][ T1] Testing event ext4_discard_blocks: OK <6>[ 90.529831][ T1] Testing event ext4_journalled_invalidatepage: OK <6>[ 90.576522][ T1] Testing event ext4_invalidatepage: OK <6>[ 90.623130][ T1] Testing event ext4_releasepage: OK <6>[ 90.673157][ T1] Testing event ext4_readpage: OK <6>[ 90.719789][ T1] Testing event ext4_writepage: OK <6>[ 90.769794][ T1] Testing event ext4_writepages_result: OK <6>[ 90.816501][ T1] Testing event ext4_da_write_pages_extent: OK <6>[ 90.866506][ T1] Testing event ext4_da_write_pages: OK <6>[ 90.913148][ T1] Testing event ext4_writepages: OK <6>[ 90.963153][ T1] Testing event ext4_da_write_end: OK <6>[ 91.009798][ T1] Testing event ext4_journalled_write_end: OK <6>[ 91.056563][ T1] Testing event ext4_write_end: OK <6>[ 91.103174][ T1] Testing event ext4_da_write_begin: OK <6>[ 91.153123][ T1] Testing event ext4_write_begin: OK <6>[ 91.199825][ T1] Testing event ext4_begin_ordered_truncate: OK <6>[ 91.246518][ T1] Testing event ext4_mark_inode_dirty: OK <6>[ 91.293178][ T1] Testing event ext4_nfs_commit_metadata: OK <6>[ 91.339820][ T1] Testing event ext4_drop_inode: OK <6>[ 91.386518][ T1] Testing event ext4_evict_inode: OK <6>[ 91.436498][ T1] Testing event ext4_allocate_inode: OK <6>[ 91.483170][ T1] Testing event ext4_request_inode: OK <6>[ 91.529850][ T1] Testing event ext4_free_inode: OK <6>[ 91.576505][ T1] Testing event ext4_other_inode_update_time: OK <6>[ 91.623196][ T1] Testing event jbd2_shrink_checkpoint_list: OK <6>[ 91.669862][ T1] Testing event jbd2_shrink_scan_exit: OK <6>[ 91.716522][ T1] Testing event jbd2_shrink_scan_enter: OK <6>[ 91.763175][ T1] Testing event jbd2_shrink_count: OK <6>[ 91.813195][ T1] Testing event jbd2_lock_buffer_stall: OK <6>[ 91.859850][ T1] Testing event jbd2_write_superblock: OK <6>[ 91.906519][ T1] Testing event jbd2_update_log_tail: OK <6>[ 91.953182][ T1] Testing event jbd2_checkpoint_stats: OK <6>[ 91.999836][ T1] Testing event jbd2_run_stats: OK <6>[ 92.049819][ T1] Testing event jbd2_handle_stats: OK <6>[ 92.096519][ T1] Testing event jbd2_handle_extend: OK <6>[ 92.143169][ T1] Testing event jbd2_handle_restart: OK <6>[ 92.189858][ T1] Testing event jbd2_handle_start: OK <6>[ 92.236520][ T1] Testing event jbd2_submit_inode_data: OK <6>[ 92.283200][ T1] Testing event jbd2_end_commit: OK <6>[ 92.329837][ T1] Testing event jbd2_drop_transaction: OK <6>[ 92.376560][ T1] Testing event jbd2_commit_logging: OK <6>[ 92.423489][ T1] Testing event jbd2_commit_flushing: OK <6>[ 92.470143][ T1] Testing event jbd2_commit_locking: OK <6>[ 92.513494][ T1] Testing event jbd2_start_commit: OK <6>[ 92.560076][ T1] Testing event jbd2_checkpoint: OK <6>[ 92.606778][ T1] Testing event block_rq_remap: OK <6>[ 92.650164][ T1] Testing event block_bio_remap: OK <6>[ 92.693467][ T1] Testing event block_split: OK <6>[ 92.740120][ T1] Testing event block_unplug: OK <6>[ 92.786791][ T1] Testing event block_plug: OK <6>[ 92.830079][ T1] Testing event block_getrq: OK <6>[ 92.876721][ T1] Testing event block_bio_queue: OK <6>[ 92.926670][ T1] Testing event block_bio_frontmerge: OK <6>[ 92.973147][ T1] Testing event block_bio_backmerge: OK <6>[ 93.023140][ T1] Testing event block_bio_bounce: OK <6>[ 93.069667][ T1] Testing event block_bio_complete: OK <6>[ 93.113134][ T1] Testing event block_rq_merge: OK <6>[ 93.160428][ T1] Testing event block_rq_issue: OK <6>[ 93.209785][ T1] Testing event block_rq_insert: OK <6>[ 93.256453][ T1] Testing event block_rq_complete: OK <6>[ 93.306425][ T1] Testing event block_rq_requeue: OK <6>[ 93.353092][ T1] Testing event block_dirty_buffer: OK <6>[ 93.399749][ T1] Testing event block_touch_buffer: OK <6>[ 93.446391][ T1] Testing event iocost_iocg_forgive_debt: OK <6>[ 93.496427][ T1] Testing event iocost_ioc_vrate_adj: OK <6>[ 93.546413][ T1] Testing event iocost_inuse_adjust: OK <6>[ 93.593086][ T1] Testing event iocost_inuse_transfer: OK <6>[ 93.639769][ T1] Testing event iocost_inuse_shortage: OK <6>[ 93.686394][ T1] Testing event iocost_iocg_idle: OK <6>[ 93.736431][ T1] Testing event iocost_iocg_activate: OK <6>[ 93.783078][ T1] Testing event wbt_timer: OK <6>[ 93.833064][ T1] Testing event wbt_step: OK <6>[ 93.879697][ T1] Testing event wbt_lat: OK <6>[ 93.923011][ T1] Testing event wbt_stat: OK <6>[ 93.972983][ T1] Testing event rdpmc: OK <6>[ 94.022958][ T1] Testing event write_msr: OK <6>[ 94.073133][ T1] Testing event read_msr: OK <6>[ 94.120011][ T1] Testing event gpio_value: OK <6>[ 94.166713][ T1] Testing event gpio_direction: OK <6>[ 94.210027][ T1] Testing event pwm_get: OK <6>[ 94.253223][ T1] Testing event pwm_apply: OK <6>[ 94.303343][ T1] Testing event clk_set_duty_cycle_complete: OK <6>[ 94.350030][ T1] Testing event clk_set_duty_cycle: OK <6>[ 94.393349][ T1] Testing event clk_set_phase_complete: OK <6>[ 94.440017][ T1] Testing event clk_set_phase: OK <6>[ 94.486567][ T1] Testing event clk_set_parent_complete: OK <6>[ 94.536714][ T1] Testing event clk_set_parent: OK <6>[ 94.586690][ T1] Testing event clk_set_rate_range: OK <6>[ 94.633354][ T1] Testing event clk_set_max_rate: OK <6>[ 94.676669][ T1] Testing event clk_set_min_rate: OK <6>[ 94.723348][ T1] Testing event clk_set_rate_complete: OK <6>[ 94.770027][ T1] Testing event clk_set_rate: OK <6>[ 94.816673][ T1] Testing event clk_unprepare_complete: OK <6>[ 94.863351][ T1] Testing event clk_unprepare: OK <6>[ 94.906347][ T1] Testing event clk_prepare_complete: OK <6>[ 94.953069][ T1] Testing event clk_prepare: OK <6>[ 94.999719][ T1] Testing event clk_disable_complete: OK <6>[ 95.046363][ T1] Testing event clk_disable: OK <6>[ 95.093081][ T1] Testing event clk_enable_complete: OK <6>[ 95.139736][ T1] Testing event clk_enable: OK <6>[ 95.186310][ T1] Testing event regulator_set_voltage_complete: OK <6>[ 95.233080][ T1] Testing event regulator_set_voltage: OK <6>[ 95.279726][ T1] Testing event regulator_bypass_disable_complete: OK <6>[ 95.329764][ T1] Testing event regulator_bypass_disable: OK <6>[ 95.376393][ T1] Testing event regulator_bypass_enable_complete: OK <6>[ 95.423094][ T1] Testing event regulator_bypass_enable: OK <6>[ 95.469755][ T1] Testing event regulator_disable_complete: OK <6>[ 95.516449][ T1] Testing event regulator_disable: OK <6>[ 95.563104][ T1] Testing event regulator_enable_complete: OK <6>[ 95.609761][ T1] Testing event regulator_enable_delay: OK <6>[ 95.656441][ T1] Testing event regulator_enable: OK <6>[ 95.703081][ T1] Testing event prandom_u32: OK <6>[ 95.749711][ T1] Testing event urandom_read: OK <6>[ 95.796384][ T1] Testing event extract_entropy: OK <6>[ 95.846437][ T1] Testing event get_random_bytes_arch: OK <6>[ 95.893175][ T1] Testing event get_random_bytes: OK <6>[ 95.940059][ T1] Testing event add_disk_randomness: OK <6>[ 95.986778][ T1] Testing event add_input_randomness: OK <6>[ 96.030089][ T1] Testing event debit_entropy: OK <6>[ 96.073508][ T1] Testing event credit_entropy_bits: OK <6>[ 96.116730][ T1] Testing event mix_pool_bytes_nolock: OK <6>[ 96.163428][ T1] Testing event mix_pool_bytes: OK <6>[ 96.210069][ T1] Testing event add_device_randomness: OK <6>[ 96.260031][ T1] Testing event prq_report: OK <6>[ 96.310066][ T1] Testing event qi_submit: OK <6>[ 96.360107][ T1] Testing event io_page_fault: OK <6>[ 96.406770][ T1] Testing event unmap: OK <6>[ 96.453399][ T1] Testing event map: OK <6>[ 96.500065][ T1] Testing event detach_device_from_domain: OK <6>[ 96.546805][ T1] Testing event attach_device_to_domain: OK <6>[ 96.596925][ T1] Testing event remove_device_from_group: OK <6>[ 96.646765][ T1] Testing event add_device_to_group: OK <6>[ 96.693090][ T1] Testing event regcache_drop_region: OK <6>[ 96.743105][ T1] Testing event regmap_async_complete_done: OK <6>[ 96.789748][ T1] Testing event regmap_async_complete_start: OK <6>[ 96.836448][ T1] Testing event regmap_async_io_complete: OK <6>[ 96.883128][ T1] Testing event regmap_async_write_start: OK <6>[ 96.929774][ T1] Testing event regmap_cache_bypass: OK <6>[ 96.976420][ T1] Testing event regmap_cache_only: OK <6>[ 97.023070][ T1] Testing event regcache_sync: OK <6>[ 97.069800][ T1] Testing event regmap_hw_write_done: OK <6>[ 97.116497][ T1] Testing event regmap_hw_write_start: OK <6>[ 97.163192][ T1] Testing event regmap_hw_read_done: OK <6>[ 97.209776][ T1] Testing event regmap_hw_read_start: OK <6>[ 97.259811][ T1] Testing event regmap_reg_read_cache: OK <6>[ 97.306489][ T1] Testing event regmap_reg_read: OK <6>[ 97.353130][ T1] Testing event regmap_reg_write: OK <6>[ 97.399771][ T1] Testing event devres_log: OK <6>[ 97.446548][ T1] Testing event dma_fence_wait_end: OK <6>[ 97.493137][ T1] Testing event dma_fence_wait_start: OK <6>[ 97.536667][ T1] Testing event dma_fence_signaled: OK <6>[ 97.586708][ T1] Testing event dma_fence_enable_signal: OK <6>[ 97.633401][ T1] Testing event dma_fence_destroy: OK <6>[ 97.676724][ T1] Testing event dma_fence_init: OK <6>[ 97.719819][ T1] Testing event dma_fence_emit: OK <6>[ 97.762988][ T1] Testing event sync_timeline: OK <6>[ 97.806675][ T1] Testing event scsi_eh_wakeup: OK <6>[ 97.849785][ T1] Testing event scsi_dispatch_cmd_timeout: OK <6>[ 97.896535][ T1] Testing event scsi_dispatch_cmd_done: OK <6>[ 97.940140][ T1] Testing event scsi_dispatch_cmd_error: OK <6>[ 97.986749][ T1] Testing event scsi_dispatch_cmd_start: OK <6>[ 98.033392][ T1] Testing event ata_eh_link_autopsy_qc: OK <6>[ 98.080057][ T1] Testing event ata_eh_link_autopsy: OK <6>[ 98.126726][ T1] Testing event ata_qc_complete_done: OK <6>[ 98.170204][ T1] Testing event ata_qc_complete_failed: OK <6>[ 98.213419][ T1] Testing event ata_qc_complete_internal: OK <6>[ 98.256849][ T1] Testing event ata_qc_issue: OK <6>[ 98.300055][ T1] Testing event spi_transfer_stop: OK <6>[ 98.343509][ T1] Testing event spi_transfer_start: OK <6>[ 98.390021][ T1] Testing event spi_message_done: OK <6>[ 98.433082][ T1] Testing event spi_message_start: OK <6>[ 98.479778][ T1] Testing event spi_message_submit: OK <6>[ 98.526440][ T1] Testing event spi_set_cs: OK <6>[ 98.573034][ T1] Testing event spi_setup: OK <6>[ 98.619738][ T1] Testing event spi_controller_busy: OK <6>[ 98.666425][ T1] Testing event spi_controller_idle: OK <6>[ 98.713061][ T1] Testing event mdio_access: OK <6>[ 98.759719][ T1] Testing event xhci_dbc_giveback_request: OK <6>[ 98.806415][ T1] Testing event xhci_dbc_queue_request: OK <6>[ 98.853082][ T1] Testing event xhci_dbc_free_request: OK <6>[ 98.899717][ T1] Testing event xhci_dbc_alloc_request: OK <6>[ 98.946401][ T1] Testing event xhci_ring_host_doorbell: OK <6>[ 98.993062][ T1] Testing event xhci_ring_ep_doorbell: OK <6>[ 99.039690][ T1] Testing event xhci_hub_status_data: OK <6>[ 99.083063][ T1] Testing event xhci_get_port_status: OK <6>[ 99.129735][ T1] Testing event xhci_handle_port_status: OK <6>[ 99.176404][ T1] Testing event xhci_inc_deq: OK <6>[ 99.226398][ T1] Testing event xhci_inc_enq: OK <6>[ 99.276379][ T1] Testing event xhci_ring_expansion: OK <6>[ 99.319753][ T1] Testing event xhci_ring_free: OK <6>[ 99.366409][ T1] Testing event xhci_ring_alloc: OK <6>[ 99.416440][ T1] Testing event xhci_configure_endpoint_ctrl_ctx: OK <6>[ 99.463101][ T1] Testing event xhci_address_ctrl_ctx: OK <6>[ 99.509866][ T1] Testing event xhci_configure_endpoint: OK <6>[ 99.556723][ T1] Testing event xhci_handle_cmd_set_deq: OK <6>[ 99.603424][ T1] Testing event xhci_handle_cmd_reset_dev: OK <6>[ 99.650086][ T1] Testing event xhci_handle_cmd_addr_dev: OK <6>[ 99.693487][ T1] Testing event xhci_setup_device_slot: OK <6>[ 99.736722][ T1] Testing event xhci_discover_or_reset_device: OK <6>[ 99.780171][ T1] Testing event xhci_handle_cmd_disable_slot: OK <6>[ 99.826678][ T1] Testing event xhci_free_dev: OK <6>[ 99.870073][ T1] Testing event xhci_alloc_dev: OK <6>[ 99.916747][ T1] Testing event xhci_add_endpoint: OK <6>[ 99.963361][ T1] Testing event xhci_handle_cmd_config_ep: OK <6>[ 100.010122][ T1] Testing event xhci_handle_cmd_reset_ep: OK <6>[ 100.056728][ T1] Testing event xhci_handle_cmd_set_deq_ep: OK <6>[ 100.103398][ T1] Testing event xhci_handle_cmd_stop_ep: OK <6>[ 100.146843][ T1] Testing event xhci_urb_dequeue: OK <6>[ 100.190073][ T1] Testing event xhci_urb_giveback: OK <6>[ 100.233081][ T1] Testing event xhci_urb_enqueue: OK <6>[ 100.283105][ T1] Testing event xhci_stop_device: OK <6>[ 100.329768][ T1] Testing event xhci_setup_addressable_virt_device: OK <6>[ 100.376490][ T1] Testing event xhci_setup_device: OK <6>[ 100.423158][ T1] Testing event xhci_alloc_virt_device: OK <6>[ 100.469812][ T1] Testing event xhci_free_virt_device: OK <6>[ 100.516479][ T1] Testing event xhci_dbc_gadget_ep_queue: OK <6>[ 100.563156][ T1] Testing event xhci_dbc_handle_transfer: OK <6>[ 100.609814][ T1] Testing event xhci_dbc_handle_event: OK <6>[ 100.656485][ T1] Testing event xhci_queue_trb: OK <6>[ 100.703132][ T1] Testing event xhci_handle_transfer: OK <6>[ 100.749858][ T1] Testing event xhci_handle_command: OK <6>[ 100.796499][ T1] Testing event xhci_handle_event: OK <6>[ 100.843165][ T1] Testing event xhci_address_ctx: OK <6>[ 100.893198][ T1] Testing event xhci_dbg_ring_expansion: OK <6>[ 100.939873][ T1] Testing event xhci_dbg_init: OK <6>[ 100.986475][ T1] Testing event xhci_dbg_cancel_urb: OK <6>[ 101.036524][ T1] Testing event xhci_dbg_reset_ep: OK <6>[ 101.083243][ T1] Testing event xhci_dbg_quirks: OK <6>[ 101.130177][ T1] Testing event xhci_dbg_context_change: OK <6>[ 101.176847][ T1] Testing event xhci_dbg_address: OK <6>[ 101.220232][ T1] Testing event rtc_timer_fired: OK <6>[ 101.267087][ T1] Testing event rtc_timer_dequeue: OK <6>[ 101.310370][ T1] Testing event rtc_timer_enqueue: OK <6>[ 101.360131][ T1] Testing event rtc_read_offset: OK <6>[ 101.406739][ T1] Testing event rtc_set_offset: OK <6>[ 101.456785][ T1] Testing event rtc_alarm_irq_enable: OK <6>[ 101.503443][ T1] Testing event rtc_irq_set_state: OK <6>[ 101.550097][ T1] Testing event rtc_irq_set_freq: OK <6>[ 101.600105][ T1] Testing event rtc_read_alarm: OK <6>[ 101.650101][ T1] Testing event rtc_set_alarm: OK <6>[ 101.700043][ T1] Testing event rtc_read_time: OK <6>[ 101.750076][ T1] Testing event rtc_set_time: OK <6>[ 101.796737][ T1] Testing event i2c_result: OK <6>[ 101.846882][ T1] Testing event i2c_reply: OK <6>[ 101.893621][ T1] Testing event i2c_read: OK <6>[ 101.943561][ T1] Testing event i2c_write: OK <6>[ 101.989893][ T1] Testing event smbus_result: OK <6>[ 102.036398][ T1] Testing event smbus_reply: OK <6>[ 102.086396][ T1] Testing event smbus_read: OK <6>[ 102.136359][ T1] Testing event smbus_write: OK <6>[ 102.179706][ T1] Testing event hwmon_attr_show_string: OK <6>[ 102.226400][ T1] Testing event hwmon_attr_store: OK <6>[ 102.276415][ T1] Testing event hwmon_attr_show: OK <6>[ 102.326332][ T1] Testing event thermal_power_devfreq_limit: OK <6>[ 102.373132][ T1] Testing event thermal_power_devfreq_get_power: OK <6>[ 102.419794][ T1] Testing event thermal_zone_trip: OK <6>[ 102.466434][ T1] Testing event cdev_update: OK <6>[ 102.513086][ T1] Testing event thermal_temperature: OK <6>[ 102.559782][ T1] Testing event thermal_power_allocator_pid: OK <6>[ 102.606427][ T1] Testing event thermal_power_allocator: OK <6>[ 102.653114][ T1] Testing event mmc_request_done: OK <6>[ 102.703107][ T1] Testing event mmc_request_start: OK <6>[ 102.749763][ T1] Testing event cros_ec_request_done: OK <6>[ 102.796437][ T1] Testing event cros_ec_request_start: OK <6>[ 102.843099][ T1] Testing event devfreq_monitor: OK <6>[ 102.889778][ T1] Testing event devfreq_frequency: OK <6>[ 102.936534][ T1] Testing event memory_failure_event: OK <6>[ 102.983415][ T1] Testing event aer_event: OK <6>[ 103.030028][ T1] Testing event non_standard_event: OK <6>[ 103.073420][ T1] Testing event arm_event: OK <6>[ 103.120054][ T1] Testing event mc_event: OK <6>[ 103.166708][ T1] Testing event extlog_mem_event: OK <6>[ 103.213233][ T1] Testing event icc_set_bw_end: OK <6>[ 103.263420][ T1] Testing event icc_set_bw: OK <6>[ 103.310056][ T1] Testing event neigh_cleanup_and_release: OK <6>[ 103.356769][ T1] Testing event neigh_event_send_dead: OK <6>[ 103.403415][ T1] Testing event neigh_event_send_done: OK <6>[ 103.450141][ T1] Testing event neigh_timer_handler: OK <6>[ 103.496777][ T1] Testing event neigh_update_done: OK <6>[ 103.540193][ T1] Testing event neigh_update: OK <6>[ 103.583445][ T1] Testing event neigh_create: OK <6>[ 103.630107][ T1] Testing event page_pool_update_nid: OK <6>[ 103.673528][ T1] Testing event page_pool_state_hold: OK <6>[ 103.720058][ T1] Testing event page_pool_state_release: OK <6>[ 103.763166][ T1] Testing event page_pool_release: OK <6>[ 103.809803][ T1] Testing event br_fdb_update: OK <6>[ 103.856337][ T1] Testing event fdb_delete: OK <6>[ 103.902979][ T1] Testing event br_fdb_external_learn_add: OK <6>[ 103.949678][ T1] Testing event br_fdb_add: OK <6>[ 103.993027][ T1] Testing event qdisc_create: OK <6>[ 104.039812][ T1] Testing event qdisc_destroy: OK <6>[ 104.086443][ T1] Testing event qdisc_reset: OK <6>[ 104.136470][ T1] Testing event qdisc_enqueue: OK <6>[ 104.183119][ T1] Testing event qdisc_dequeue: OK <6>[ 104.229765][ T1] Testing event fib_table_lookup: OK <6>[ 104.276469][ T1] Testing event tcp_bad_csum: OK <6>[ 104.326658][ T1] Testing event tcp_probe: OK <6>[ 104.373111][ T1] Testing event tcp_retransmit_synack: OK <6>[ 104.419802][ T1] Testing event tcp_rcv_space_adjust: OK <6>[ 104.466502][ T1] Testing event tcp_destroy_sock: OK <6>[ 104.509785][ T1] Testing event tcp_receive_reset: OK <6>[ 104.556492][ T1] Testing event tcp_send_reset: OK <6>[ 104.603504][ T1] Testing event tcp_retransmit_skb: OK <6>[ 104.646868][ T1] Testing event udp_fail_queue_rcv_skb: OK <6>[ 104.690004][ T1] Testing event inet_sk_error_report: OK <6>[ 104.733119][ T1] Testing event inet_sock_set_state: OK <6>[ 104.779905][ T1] Testing event sock_exceed_buf_limit: OK <6>[ 104.826804][ T1] Testing event sock_rcvqueue_full: OK <6>[ 104.870211][ T1] Testing event napi_poll: OK <6>[ 104.916842][ T1] Testing event netif_receive_skb_list_exit: OK <6>[ 104.963512][ T1] Testing event netif_rx_ni_exit: OK <6>[ 105.006717][ T1] Testing event netif_rx_exit: OK <6>[ 105.053461][ T1] Testing event netif_receive_skb_exit: OK <6>[ 105.096741][ T1] Testing event napi_gro_receive_exit: OK <6>[ 105.143478][ T1] Testing event napi_gro_frags_exit: OK <6>[ 105.193482][ T1] Testing event netif_rx_ni_entry: OK <6>[ 105.240117][ T1] Testing event netif_rx_entry: OK <6>[ 105.290005][ T1] Testing event netif_receive_skb_list_entry: OK <6>[ 105.336502][ T1] Testing event netif_receive_skb_entry: OK <6>[ 105.383159][ T1] Testing event napi_gro_receive_entry: OK <6>[ 105.429829][ T1] Testing event napi_gro_frags_entry: OK <6>[ 105.476826][ T1] Testing event netif_rx: OK <6>[ 105.526831][ T1] Testing event netif_receive_skb: OK <6>[ 105.576501][ T1] Testing event net_dev_queue: OK <6>[ 105.623191][ T1] Testing event net_dev_xmit_timeout: OK <6>[ 105.669837][ T1] Testing event net_dev_xmit: OK <6>[ 105.716510][ T1] Testing event net_dev_start_xmit: OK <6>[ 105.763165][ T1] Testing event skb_copy_datagram_iovec: OK <6>[ 105.806494][ T1] Testing event consume_skb: OK <6>[ 105.856658][ T1] Testing event kfree_skb: OK <6>[ 105.903173][ T1] Testing event devlink_trap_report: OK <6>[ 105.949866][ T1] Testing event devlink_health_reporter_state_update: OK <6>[ 105.996666][ T1] Testing event devlink_health_recover_aborted: OK <6>[ 106.046569][ T1] Testing event devlink_health_report: OK <6>[ 106.096493][ T1] Testing event devlink_hwerr: OK <6>[ 106.143117][ T1] Testing event devlink_hwmsg: OK <6>[ 106.193063][ T1] Testing event netlink_extack: OK <6>[ 106.239823][ T1] Testing event bpf_test_finish: OK <6>[ 106.289878][ T1] Testing event tls_device_tx_resync_send: OK <6>[ 106.336530][ T1] Testing event tls_device_tx_resync_req: OK <6>[ 106.389891][ T1] Testing event tls_device_rx_resync_nh_delay: OK <6>[ 106.436884][ T1] Testing event tls_device_rx_resync_nh_schedule: OK <6>[ 106.486887][ T1] Testing event tls_device_rx_resync_send: OK <6>[ 106.533510][ T1] Testing event tls_device_decrypted: OK <6>[ 106.580175][ T1] Testing event tls_device_offload_set: OK <6>[ 106.623533][ T1] Testing event fib6_table_lookup: OK <6>[ 106.673434][ T1] Testing event subflow_check_data_avail: OK <6>[ 106.719868][ T1] Testing event ack_update_msk: OK <6>[ 106.766821][ T1] Testing event get_mapping_status: OK <6>[ 106.813511][ T1] Testing event mptcp_subflow_get_send: OK <6>[ 106.856942][ T1] Running tests on trace event systems: <6>[ 106.859519][ T1] Testing event system mptcp: OK <6>[ 106.907315][ T1] Testing event system fib6: OK <6>[ 106.953820][ T1] Testing event system tls: OK <6>[ 107.007693][ T1] Testing event system bpf_test_run: OK <6>[ 107.057002][ T1] Testing event system netlink: OK <6>[ 107.103675][ T1] Testing event system devlink: OK <6>[ 107.150758][ T1] Testing event system skb: OK <6>[ 107.197273][ T1] Testing event system net: OK <6>[ 107.248871][ T1] Testing event system napi: OK <6>[ 107.294339][ T1] Testing event system sock: OK <6>[ 107.343580][ T1] Testing event system udp: OK <6>[ 107.393308][ T1] Testing event system tcp: OK <6>[ 107.440812][ T1] Testing event system fib: OK <6>[ 107.486708][ T1] Testing event system qdisc: OK <6>[ 107.533732][ T1] Testing event system bridge: OK <6>[ 107.579901][ T1] Testing event system page_pool: OK <6>[ 107.626925][ T1] Testing event system neigh: OK <6>[ 107.677205][ T1] Testing event system interconnect: OK <6>[ 107.723413][ T1] Testing event system ras: OK <6>[ 107.766961][ T1] Testing event system devfreq: OK <6>[ 107.813407][ T1] Testing event system cros_ec: OK <6>[ 107.860104][ T1] Testing event system mmc: OK <6>[ 107.903458][ T1] Testing event system thermal_power_allocator: OK <6>[ 107.949910][ T1] Testing event system thermal: OK <6>[ 107.996991][ T1] Testing event system hwmon: OK <6>[ 108.043453][ T1] Testing event system smbus: OK <6>[ 108.090203][ T1] Testing event system i2c: OK <6>[ 108.133576][ T1] Testing event system rtc: OK <6>[ 108.184798][ T1] Testing event system xhci-hcd: OK <6>[ 108.245573][ T1] Testing event system mdio: OK <6>[ 108.293188][ T1] Testing event system spi: OK <6>[ 108.347310][ T1] Testing event system libata: OK <6>[ 108.393702][ T1] Testing event system scsi: OK <6>[ 108.443618][ T1] Testing event system sync_trace: OK <6>[ 108.489873][ T1] Testing event system dma_fence: OK <6>[ 108.537078][ T1] Testing event system dev: OK <6>[ 108.583324][ T1] Testing event system regmap: OK <6>[ 108.631679][ T1] Testing event system iommu: OK <6>[ 108.680899][ T1] Testing event system intel_iommu: OK <6>[ 108.726869][ T1] Testing event system random: OK <6>[ 108.777725][ T1] Testing event system regulator: OK <6>[ 108.824107][ T1] Testing event system clk: OK <6>[ 108.871700][ T1] Testing event system pwm: OK <6>[ 108.919866][ T1] Testing event system gpio: OK <6>[ 108.970083][ T1] Testing event system msr: OK <6>[ 109.020155][ T1] Testing event system wbt: OK <6>[ 109.066892][ T1] Testing event system iocost: OK <6>[ 109.113788][ T1] Testing event system block: OK <6>[ 109.158381][ T1] Testing event system jbd2: OK <6>[ 109.211813][ T1] Testing event system ext4: OK <6>[ 109.277609][ T1] Testing event system iomap: OK <6>[ 109.327203][ T1] Testing event system filelock: OK <6>[ 109.370813][ T1] Testing event system fs_dax: OK <6>[ 109.414409][ T1] Testing event system io_uring: OK <6>[ 109.461105][ T1] Testing event system writeback: OK <6>[ 109.513049][ T1] Testing event system page_isolation: OK <6>[ 109.556428][ T1] Testing event system huge_memory: OK <6>[ 109.600168][ T1] Testing event system migrate: OK <6>[ 109.643218][ T1] Testing event system mmap: OK <6>[ 109.689801][ T1] Testing event system mmap_lock: OK <6>[ 109.760618][ T1] Testing event system compaction: OK <6>[ 109.807778][ T1] Testing event system kmem: OK <6>[ 109.857800][ T1] Testing event system percpu: OK <6>[ 109.903576][ T1] Testing event system vmscan: OK <6>[ 109.951630][ T1] Testing event system pagemap: OK <6>[ 109.996850][ T1] Testing event system oom: OK <6>[ 110.043815][ T1] Testing event system filemap: OK <6>[ 110.090364][ T1] Testing event system rseq: OK <6>[ 110.136829][ T1] Testing event system xdp: OK <6>[ 110.184340][ T1] Testing event system rpm: OK <6>[ 110.230616][ T1] Testing event system power: OK <6>[ 110.282790][ T1] Testing event system error_report: OK <6>[ 110.326424][ T1] Testing event system bpf_trace: OK <6>[ 110.373115][ T1] Testing event system test: OK <6>[ 110.419742][ T1] Testing event system preemptirq: OK <6>[ 110.470303][ T1] Testing event system cgroup: OK <6>[ 110.517867][ T1] Testing event system module: OK <6>[ 110.563541][ T1] Testing event system alarmtimer: OK <6>[ 110.610093][ T1] Testing event system timer: OK <6>[ 110.657884][ T1] Testing event system raw_syscalls: OK <6>[ 110.700207][ T1] Testing event system swiotlb: OK <6>[ 110.746450][ T1] Testing event system rcu: OK <6>[ 110.793442][ T1] Testing event system irq_matrix: OK <6>[ 110.840776][ T1] Testing event system printk: OK <6>[ 110.886440][ T1] Testing event system lock: OK <6>[ 110.933362][ T1] Testing event system sched: OK <6>[ 110.982804][ T1] Testing event system workqueue: OK <6>[ 111.026771][ T1] Testing event system signal: OK <6>[ 111.073377][ T1] Testing event system irq: OK <6>[ 111.120325][ T1] Testing event system cpuhp: OK <6>[ 111.163500][ T1] Testing event system task: OK <6>[ 111.206547][ T1] Testing event system exceptions: OK <6>[ 111.256698][ T1] Testing event system tlb: OK <6>[ 111.299899][ T1] Testing event system resctrl: OK <6>[ 111.346721][ T1] Testing event system mce: OK <6>[ 111.393141][ T1] Testing event system x86_fpu: OK <6>[ 111.443936][ T1] Testing event system nmi: OK <6>[ 111.489795][ T1] Testing event system irq_vectors: OK <6>[ 111.539767][ T1] Testing event system syscalls: OK <6>[ 111.592773][ T1] Testing event system hyperv: OK <6>[ 111.636861][ T1] Testing event system xen: OK <6>[ 111.686050][ T1] Testing event system vsyscall: OK <6>[ 111.736392][ T1] Testing event system initcall: OK <6>[ 111.783429][ T1] Running tests on all trace events: <6>[ 111.785532][ T1] Testing all events: OK <6>[ 112.520099][ T1] Testing ftrace filter: OK <6>[ 112.521422][ T1] trace_kprobe: Testing kprobe tracing: OK <5>[ 112.540336][ T1] Loading compiled-in X.509 certificates <5>[ 112.543113][ T1] Loaded X.509 cert 'Build time autogenerated kernel key: 19e7b965b1fc22ac8761e7dab64977b27316bc57' <6>[ 112.545964][ T1] zswap: loaded using pool zstd/zsmalloc <5>[ 112.547746][ T1] Key type ._fscrypt registered <5>[ 112.548468][ T1] Key type .fscrypt registered <5>[ 112.549227][ T1] Key type fscrypt-provisioning registered <5>[ 112.565605][ T1] Key type encrypted registered <6>[ 112.566355][ T1] AppArmor: AppArmor sha1 policy hashing enabled <6>[ 112.567234][ T1] ima: No TPM chip found, activating TPM-bypass! <5>[ 112.568086][ T1] Loading compiled-in module X.509 certificates <5>[ 112.570203][ T1] Loaded X.509 cert 'Build time autogenerated kernel key: 19e7b965b1fc22ac8761e7dab64977b27316bc57' <6>[ 112.571072][ T1] ima: Allocated hash algorithm: sha1 <6>[ 112.572039][ T1] ima: No architecture policies found <6>[ 112.573006][ T1] evm: Initialising EVM extended attributes: <6>[ 112.573908][ T1] evm: security.selinux (disabled) <6>[ 112.574783][ T1] evm: security.SMACK64 <6>[ 112.575656][ T1] evm: security.SMACK64EXEC <6>[ 112.576524][ T1] evm: security.SMACK64TRANSMUTE <6>[ 112.577406][ T1] evm: security.SMACK64MMAP <6>[ 112.578268][ T1] evm: security.apparmor <6>[ 112.579130][ T1] evm: security.ima <6>[ 112.579990][ T1] evm: security.capability <6>[ 112.580842][ T1] evm: HMAC attrs: 0x1 <6>[ 112.583863][ T1] PM: Magic number: 1:263:931 <6>[ 112.586274][ T1] acpi_cpufreq: overriding BIOS provided _PSD data <6>[ 112.589806][ T1] RAS: Correctable Errors collector initialized. <6>[ 112.600254][ T1] Freeing unused decrypted memory: 2036K <6>[ 112.603718][ T1] Freeing unused kernel image (initmem) memory: 3796K <6>[ 112.623680][ T1] Write protecting the kernel read-only data: 24576k <6>[ 112.627650][ T1] Freeing unused kernel image (text/rodata gap) memory: 2036K <6>[ 112.629200][ T1] Freeing unused kernel image (rodata/data gap) memory: 468K <6>[ 112.661067][ T1] x86/mm: Checked W+X mappings: passed, no W+X pages found. <6>[ 112.662198][ T1] Run /init as init process <7>[ 112.663315][ T1] with arguments: <7>[ 112.663320][ T1] /init <7>[ 112.663323][ T1] with environment: <7>[ 112.663326][ T1] HOME=/ <7>[ 112.663329][ T1] TERM=linux <7>[ 112.663332][ T1] BOOT_IMAGE=/boot/vmlinuz-5.14.0-rc3-bluetea-test-00249-gc7d102232649 <6>[ 113.224784][ T2043] ACPI: video: Video Device [VGA] (multi-head: yes rom: no post: no) <6>[ 113.238009][ T2043] acpi device:00: registered as cooling_device4 <6>[ 113.240480][ T2043] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input6 <6>[ 113.316247][ T2049] xhci_hcd 0000:00:10.0: xHCI Host Controller <6>[ 113.318075][ T2049] xhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 3 <6>[ 113.319860][ T2049] xhci_hcd 0000:00:10.0: hcc params 0x014040c3 hci version 0x100 quirks 0x0000000000000410 <6>[ 113.323829][ T2037] sdhci: Secure Digital Host Controller Interface driver <6>[ 113.325395][ T2037] sdhci: Copyright(c) Pierre Ossman <7>[ 113.329913][ T2044] ahci 0000:00:11.0: version 3.0 <6>[ 113.331645][ T2044] ahci 0000:00:11.0: AHCI 0001.0300 32 slots 2 ports 6 Gbps 0x3 impl SATA mode <6>[ 113.333310][ T2044] ahci 0000:00:11.0: flags: 64bit ncq sntf ilck pm led clo pmp fbs pio slum part <6>[ 113.334917][ T2049] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.14 <6>[ 113.336629][ T2049] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 113.337747][ T2044] scsi host0: ahci <6>[ 113.338339][ T2049] usb usb3: Product: xHCI Host Controller <6>[ 113.341101][ T2044] scsi host1: ahci <6>[ 113.341549][ T2049] usb usb3: Manufacturer: Linux 5.14.0-rc3-bluetea-test-00249-gc7d102232649 xhci-hcd <6>[ 113.343556][ T2044] ata1: SATA max UDMA/133 abar m1024@0xf0c6f000 port 0xf0c6f100 irq 35 <6>[ 113.344717][ T2049] usb usb3: SerialNumber: 0000:00:10.0 <6>[ 113.346368][ T2044] ata2: SATA max UDMA/133 abar m1024@0xf0c6f000 port 0xf0c6f180 irq 35 <4>[ 113.359803][ T2034] ACPI Warning: SystemIO range 0x0000000000000B00-0x0000000000000B08 conflicts with OpRegion 0x0000000000000B00-0x0000000000000B0F (\_SB.PCI0.SMBS.SMB0) (20210604/utaddress-204) <6>[ 113.363447][ T2034] ACPI: OSL: Resource conflict; ACPI support missing from driver? <6>[ 113.368275][ T2049] hub 3-0:1.0: USB hub found <6>[ 113.370367][ T2049] hub 3-0:1.0: 2 ports detected <6>[ 113.373669][ T2037] sdhci-pci 0000:00:14.7: SDHCI controller found [1022:7813] (rev 1) <6>[ 113.374187][ T2049] xhci_hcd 0000:00:10.0: xHCI Host Controller <6>[ 113.377356][ T2049] xhci_hcd 0000:00:10.0: new USB bus registered, assigned bus number 4 <6>[ 113.379237][ T2049] xhci_hcd 0000:00:10.0: Host supports USB 3.0 SuperSpeed <6>[ 113.379373][ T2037] mmc0: SDHCI controller on PCI [0000:00:14.7] using ADMA <6>[ 113.381382][ T2049] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. <6>[ 113.385152][ T2049] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.14 <6>[ 113.387172][ T2049] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 <6>[ 113.389126][ T2049] usb usb4: Product: xHCI Host Controller <6>[ 113.391145][ T2049] usb usb4: Manufacturer: Linux 5.14.0-rc3-bluetea-test-00249-gc7d102232649 xhci-hcd <6>[ 113.393163][ T2049] usb usb4: SerialNumber: 0000:00:10.0 <4>[ 113.422019][ T2041] r8169 0000:01:00.1: can't disable ASPM; OS doesn't have ASPM control <6>[ 113.449676][ T2049] hub 4-0:1.0: USB hub found <6>[ 113.452138][ T2049] hub 4-0:1.0: 2 ports detected <6>[ 113.496014][ T2041] libphy: r8169: probed <6>[ 113.498754][ T2034] cryptd: max_cpu_qlen set to 1000 <6>[ 113.499569][ T2041] r8169 0000:01:00.1 eth0: RTL8411b, f0:76:1c:c8:db:de, XID 5c8, IRQ 37 <6>[ 113.502885][ T2041] r8169 0000:01:00.1 eth0: jumbo features [frames: 9194 bytes, tx checksumming: ko] <6>[ 113.519303][ T2034] AVX version of gcm_enc/dec engaged. <6>[ 113.521468][ T2034] AES CTR mode by8 optimization enabled <6>[ 113.543690][ T2051] hid: raw HID events driver (C) Jiri Kosina <6>[ 113.566414][ T2051] usbcore: registered new interface driver usbhid <6>[ 113.568639][ T2051] usbhid: USB HID core driver <6>[ 113.726547][ T2046] r8169 0000:01:00.1 enp1s0f1: renamed from eth0 <6>[ 113.735266][ T2034] input: Logitech USB Optical Mouse as /devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.1/1-1.1:1.0/0003:046D:C077.0001/input/input8 <6>[ 113.746049][ T2034] hid-generic 0003:046D:C077.0001: input,hidraw0: USB HID v1.11 Mouse [Logitech USB Optical Mouse] on usb-0000:00:12.0-1.1/input0 <6>[ 113.819980][ T2063] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) <6>[ 113.821970][ T2061] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) <6>[ 113.824819][ T2061] ata1.00: ATA-9: WDC WD5000LPVX-22V0TT0, 01.01A01, max UDMA/133 <6>[ 113.826748][ T2061] ata1.00: 976773168 sectors, multi 0: LBA48 NCQ (depth 32), AA <6>[ 113.828760][ T2063] ata2.00: ATAPI: Slimtype DVD A DA8A6SH, GA11, max UDMA/133 <6>[ 113.831276][ T2061] ata1.00: configured for UDMA/133 <6>[ 113.833946][ T2063] ata2.00: configured for UDMA/133 <5>[ 113.844948][ T109] scsi 0:0:0:0: Direct-Access ATA WDC WD5000LPVX-2 1A01 PQ: 0 ANSI: 5 <5>[ 113.849673][ T123] sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB) <5>[ 113.849870][ T109] sd 0:0:0:0: Attached scsi generic sg0 type 0 <5>[ 113.851648][ T123] sd 0:0:0:0: [sda] 4096-byte physical blocks <5>[ 113.851703][ T123] sd 0:0:0:0: [sda] Write Protect is off <7>[ 113.857426][ T123] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 <5>[ 113.857528][ T123] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA <5>[ 113.863165][ T8] scsi 1:0:0:0: CD-ROM Slimtype DVD A DA8A6SH GA11 PQ: 0 ANSI: 5 <6>[ 114.236720][ T2035] [drm] radeon kernel modesetting enabled. <7>[ 114.238736][ T2035] checking generic (e0000000 420000) vs hw (e0000000 10000000) <6>[ 114.238742][ T2035] fb0: switching to radeondrmfb from simple <6>[ 114.242090][ T2035] Console: switching to colour dummy device 80x25 <6>[ 114.242949][ T2035] radeon 0000:00:01.0: vgaarb: deactivate vga console <6>[ 114.245675][ T2035] [drm] initializing kernel modesetting (MULLINS 0x1002:0x9851 0x1025:0x1017 0x05). <6>[ 114.245861][ T2035] [drm] doorbell mmio base: 0xF0000000 <6>[ 114.245892][ T2035] [drm] doorbell mmio size: 8388608 <4>[ 114.246042][ T2035] resource sanity check: requesting [mem 0x000c0000-0x000dffff], which spans more than PCI Bus 0000:00 [mem 0x000c0000-0x000c3fff window] <4>[ 114.246166][ T2035] caller pci_map_rom+0x7c/0x1d0 mapping multiple BARs <6>[ 114.248419][ T2035] ATOM BIOS: AMD <6>[ 114.248637][ T2035] radeon 0000:00:01.0: VRAM: 1024M 0x0000000000000000 - 0x000000003FFFFFFF (1024M used) <6>[ 114.248691][ T2035] radeon 0000:00:01.0: GTT: 2048M 0x0000000040000000 - 0x00000000BFFFFFFF <6>[ 114.248743][ T2035] [drm] Detected VRAM RAM=1024M, BAR=256M <6>[ 114.248774][ T2035] [drm] RAM width 128bits DDR <6>[ 114.249729][ T2035] [drm] radeon: 1024M of VRAM memory ready <6>[ 114.249766][ T2035] [drm] radeon: 2048M of GTT memory ready. <6>[ 114.249871][ T2035] [drm] Loading mullins Microcode <6>[ 114.251068][ T2035] [drm] Internal thermal controller without fan control <6>[ 114.251586][ T8] sr 1:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray <6>[ 114.251680][ T8] cdrom: Uniform CD-ROM driver Revision: 3.20 <6>[ 114.252788][ T2035] [drm] radeon: dpm initialized <6>[ 114.253077][ T2035] [drm] Found UVD firmware Version: 1.64 Family ID: 9 <6>[ 114.254792][ T2035] [drm] Found VCE firmware/feedback version 40.2.2 / 15! <6>[ 114.254869][ T2035] [drm] GART: num cpu pages 524288, num gpu pages 524288 <6>[ 114.294589][ T2035] [drm] PCIE GART of 2048M enabled (table at 0x000000000030E000). <6>[ 114.295015][ T2035] radeon 0000:00:01.0: WB enabled <6>[ 114.295105][ T2035] radeon 0000:00:01.0: fence driver on ring 0 use gpu addr 0x0000000040000c00 <6>[ 114.295164][ T2035] radeon 0000:00:01.0: fence driver on ring 1 use gpu addr 0x0000000040000c04 <6>[ 114.295221][ T2035] radeon 0000:00:01.0: fence driver on ring 2 use gpu addr 0x0000000040000c08 <6>[ 114.295277][ T2035] radeon 0000:00:01.0: fence driver on ring 3 use gpu addr 0x0000000040000c0c <6>[ 114.295332][ T2035] radeon 0000:00:01.0: fence driver on ring 4 use gpu addr 0x0000000040000c10 <6>[ 114.295973][ T2035] radeon 0000:00:01.0: fence driver on ring 5 use gpu addr 0x0000000000078d30 <6>[ 114.296268][ T2035] radeon 0000:00:01.0: fence driver on ring 6 use gpu addr 0x0000000040000c18 <6>[ 114.296325][ T2035] radeon 0000:00:01.0: fence driver on ring 7 use gpu addr 0x0000000040000c1c <6>[ 114.297773][ T2035] radeon 0000:00:01.0: radeon: using MSI. <6>[ 114.298097][ T2035] [drm] radeon: irq initialized. <6>[ 114.300959][ T2035] [drm] ring test on 0 succeeded in 2 usecs <6>[ 114.301108][ T2035] [drm] ring test on 1 succeeded in 2 usecs <6>[ 114.301183][ T2035] [drm] ring test on 2 succeeded in 2 usecs <6>[ 114.301419][ T2035] [drm] ring test on 3 succeeded in 4 usecs <6>[ 114.301463][ T2035] [drm] ring test on 4 succeeded in 3 usecs <6>[ 114.327624][ T2035] [drm] ring test on 5 succeeded in 1 usecs <6>[ 114.327685][ T2035] [drm] UVD initialized successfully. <6>[ 114.437856][ T2035] [drm] ring test on 6 succeeded in 13 usecs <6>[ 114.437903][ T2035] [drm] ring test on 7 succeeded in 2 usecs <6>[ 114.437941][ T2035] [drm] VCE initialized successfully. <6>[ 114.440168][ T2035] [drm] ib test on ring 0 succeeded in 0 usecs <6>[ 114.440470][ T2035] [drm] ib test on ring 1 succeeded in 0 usecs <6>[ 114.440659][ T2035] [drm] ib test on ring 2 succeeded in 0 usecs <6>[ 114.440851][ T2035] [drm] ib test on ring 3 succeeded in 0 usecs <6>[ 114.441037][ T2035] [drm] ib test on ring 4 succeeded in 0 usecs <7>[ 114.457672][ T8] sr 1:0:0:0: Attached scsi CD-ROM sr0 <5>[ 114.458302][ T8] sr 1:0:0:0: Attached scsi generic sg1 type 5 <6>[ 114.944378][ T2035] [drm] ib test on ring 5 succeeded <6>[ 114.945254][ T2035] [drm] ib test on ring 6 succeeded <6>[ 114.945757][ T2035] [drm] ib test on ring 7 succeeded <6>[ 114.956072][ T2035] [drm] radeon atom DIG backlight initialized <6>[ 114.956122][ T2035] [drm] Radeon Display Connectors <6>[ 114.956156][ T2035] [drm] Connector 0: <6>[ 114.956183][ T2035] [drm] eDP-1 <6>[ 114.956208][ T2035] [drm] HPD1 <6>[ 114.956232][ T2035] [drm] DDC: 0x6530 0x6530 0x6534 0x6534 0x6538 0x6538 0x653c 0x653c <6>[ 114.956285][ T2035] [drm] Encoders: <6>[ 114.956311][ T2035] [drm] LCD1: INTERNAL_UNIPHY <6>[ 114.956344][ T2035] [drm] Connector 1: <6>[ 114.956371][ T2035] [drm] HDMI-A-1 <6>[ 114.956396][ T2035] [drm] HPD2 <6>[ 114.956420][ T2035] [drm] DDC: 0x6540 0x6540 0x6544 0x6544 0x6548 0x6548 0x654c 0x654c <6>[ 114.956472][ T2035] [drm] Encoders: <6>[ 114.956498][ T2035] [drm] DFP1: INTERNAL_UNIPHY <6>[ 114.956530][ T2035] [drm] Connector 2: <6>[ 114.956557][ T2035] [drm] VGA-1 <6>[ 114.956581][ T2035] [drm] DDC: 0x65c0 0x65c0 0x65c4 0x65c4 0x65c8 0x65c8 0x65cc 0x65cc <6>[ 114.956673][ T2035] [drm] Encoders: <6>[ 114.956699][ T2035] [drm] CRT1: INTERNAL_KLDSCP_DAC1 <6>[ 115.622234][ T2035] [drm] fb mappable at 0xE0731000 <6>[ 115.622335][ T2035] [drm] vram apper at 0xE0000000 <6>[ 115.622368][ T2035] [drm] size 4325376 <6>[ 115.622394][ T2035] [drm] fb depth is 24 <6>[ 115.622421][ T2035] [drm] pitch is 5632 <6>[ 115.623770][ T2035] fbcon: radeon (fb0) is primary device <6>[ 115.924612][ T123] sda: sda1 sda2 sda3 sda4 < sda5 sda6 > <5>[ 116.170750][ T123] sd 0:0:0:0: [sda] Attached SCSI disk <6>[ 116.746020][ T2035] Console: switching to colour frame buffer device 170x48 <6>[ 116.751633][ T2035] radeon 0000:00:01.0: [drm] fb0: radeon frame buffer device <6>[ 116.787124][ T2035] [drm] Initialized radeon 2.50.0 20080528 for 0000:00:01.0 on minor 0 <6>[ 116.808224][ T2035] AMD-Vi: AMD IOMMUv2 driver by Joerg Roedel <6>[ 116.808583][ T2035] AMD-Vi: AMD IOMMUv2 functionality not available on this system <6>[ 118.965584][ T2035] [drm] amdgpu kernel modesetting enabled. <4>[ 119.030391][ T2035] amdgpu: CRAT table not found <6>[ 119.030499][ T2035] amdgpu: Virtual CRAT table created for CPU <6>[ 119.030907][ T2035] amdgpu: Topology: Add CPU node <6>[ 119.493264][ T2166] raid6: sse2x4 gen() 6073 MB/s <6>[ 119.549939][ T2166] raid6: sse2x4 xor() 2918 MB/s <6>[ 119.606595][ T2166] raid6: sse2x2 gen() 5937 MB/s <6>[ 119.663258][ T2166] raid6: sse2x2 xor() 3012 MB/s <6>[ 119.719927][ T2166] raid6: sse2x1 gen() 4895 MB/s <6>[ 119.776596][ T2166] raid6: sse2x1 xor() 2113 MB/s <6>[ 119.776655][ T2166] raid6: using algorithm sse2x4 gen() 6073 MB/s <6>[ 119.776722][ T2166] raid6: .... xor() 2918 MB/s, rmw enabled <6>[ 119.776785][ T2166] raid6: using ssse3x2 recovery algorithm <6>[ 119.779817][ T2166] xor: automatically using best checksumming function avx <6>[ 119.784383][ T2166] async_tx: api initialized (async) <6>[ 120.327834][ T2189] Btrfs loaded, crc32c=crc32c-intel, zoned=yes <6>[ 121.353166][ T2214] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. <30>[ 124.226483][ T1] systemd[1]: Inserted module 'autofs4' <30>[ 124.532762][ T1] systemd[1]: systemd 247.3-3ubuntu3.4 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid) <30>[ 124.553733][ T1] systemd[1]: Detected architecture x86-64. <30>[ 124.660323][ T1] systemd[1]: Set hostname to . <28>[ 129.378566][ T1] systemd[1]: /lib/systemd/system/plymouth-start.service:17: Unit configured to use KillMode=none. This is unsafe, as it disables systemd's process lifecycle management for the service. Please update your service to use a safer KillMode=, such as 'mixed' or 'control-group'. Support for KillMode=none is deprecated and will eventually be removed. <30>[ 130.198393][ T1] systemd[1]: Queued start job for default target Graphical Interface. <30>[ 130.200430][ T1] systemd[1]: Unnecessary job for /sys/devices/virtual/misc/vmbus!hv_fcopy was removed. <30>[ 130.202420][ T1] systemd[1]: Unnecessary job for /sys/devices/virtual/misc/vmbus!hv_vss was removed. <30>[ 130.249094][ T1] systemd[1]: Created slice Virtual Machine and Container Slice. <30>[ 130.267486][ T1] systemd[1]: Created slice system-modprobe.slice. <30>[ 130.282056][ T1] systemd[1]: Created slice User and Session Slice. <30>[ 130.293701][ T1] systemd[1]: Started Forward Password Requests to Wall Directory Watch. <30>[ 130.306471][ T1] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. <30>[ 130.318441][ T1] systemd[1]: Reached target User and Group Name Lookups. <30>[ 130.330114][ T1] systemd[1]: Reached target Slices. <30>[ 130.341946][ T1] systemd[1]: Reached target Libvirt guests shutdown. <30>[ 130.354417][ T1] systemd[1]: Listening on Device-mapper event daemon FIFOs. <30>[ 130.367148][ T1] systemd[1]: Listening on LVM2 poll daemon socket. <30>[ 130.389030][ T1] systemd[1]: Listening on multipathd control socket. <30>[ 130.401937][ T1] systemd[1]: Listening on Syslog Socket. <30>[ 130.414237][ T1] systemd[1]: Listening on initctl Compatibility Named Pipe. <30>[ 130.427101][ T1] systemd[1]: Listening on Journal Audit Socket. <30>[ 130.439791][ T1] systemd[1]: Listening on Journal Socket (/dev/log). <30>[ 130.452443][ T1] systemd[1]: Listening on Journal Socket. <30>[ 130.464752][ T1] systemd[1]: Listening on Network Service Netlink Socket. <30>[ 130.483914][ T1] systemd[1]: Listening on udev Control Socket. <30>[ 130.496113][ T1] systemd[1]: Listening on udev Kernel Socket. <30>[ 130.515213][ T1] systemd[1]: Mounting Huge Pages File System... <30>[ 130.535398][ T1] systemd[1]: Mounting POSIX Message Queue File System... <30>[ 130.554998][ T1] systemd[1]: Mounting Kernel Debug File System... <30>[ 130.574795][ T1] systemd[1]: Mounting Kernel Trace File System... <30>[ 130.596308][ T1] systemd[1]: Starting Journal Service... <30>[ 130.617846][ T1] systemd[1]: Starting Set the console keyboard layout... <30>[ 130.640418][ T1] systemd[1]: Starting Create list of static device nodes for the current kernel... <30>[ 130.661607][ T1] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling... <30>[ 130.680543][ T1] systemd[1]: Starting Load Kernel Module configfs... <30>[ 130.699833][ T1] systemd[1]: Starting Load Kernel Module drm... <30>[ 130.719546][ T1] systemd[1]: Starting Load Kernel Module fuse... <30>[ 130.734074][ T1] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped. <30>[ 130.826431][ T1] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. <30>[ 130.902225][ T1] systemd[1]: Starting Load Kernel Modules... <30>[ 130.920550][ T1] systemd[1]: Starting Remount Root and Kernel File Systems... <30>[ 130.939312][ T1] systemd[1]: Starting Coldplug All udev Devices... <30>[ 130.959525][ T1] systemd[1]: Starting Uncomplicated firewall... <30>[ 130.983062][ T1] systemd[1]: Mounted Huge Pages File System. <30>[ 130.995746][ T1] systemd[1]: Mounted POSIX Message Queue File System. <30>[ 131.009232][ T1] systemd[1]: Mounted Kernel Debug File System. <30>[ 131.024317][ T1] systemd[1]: Mounted Kernel Trace File System. <30>[ 131.049213][ T1] systemd[1]: Finished Create list of static device nodes for the current kernel. <30>[ 131.066133][ T1] systemd[1]: modprobe@configfs.service: Succeeded. <30>[ 131.072195][ T1] systemd[1]: Finished Load Kernel Module configfs. <30>[ 131.086507][ T1] systemd[1]: modprobe@drm.service: Succeeded. <30>[ 131.093407][ T1] systemd[1]: Finished Load Kernel Module drm. <30>[ 131.108036][ T1] systemd[1]: modprobe@fuse.service: Succeeded. <30>[ 131.114125][ T1] systemd[1]: Finished Load Kernel Module fuse. <6>[ 131.131523][ T2305] EXT4-fs (sda3): re-mounted. Opts: (null). Quota mode: none. <30>[ 131.135750][ T1] systemd[1]: Mounting FUSE Control File System... <30>[ 131.158239][ T1] systemd[1]: Mounting Kernel Configuration File System... <30>[ 131.181485][ T1] systemd[1]: Finished Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling. <30>[ 131.200898][ T1] systemd[1]: Finished Remount Root and Kernel File Systems. <30>[ 131.219021][ T1] systemd[1]: Finished Uncomplicated firewall. <30>[ 131.238082][ T1] systemd[1]: Mounted FUSE Control File System. <30>[ 131.253994][ T1] systemd[1]: Mounted Kernel Configuration File System. <30>[ 131.274235][ T1] systemd[1]: Activating swap /swap.img... <30>[ 131.350704][ T1] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. <30>[ 131.353646][ T1] systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. <30>[ 131.363796][ T1] systemd[1]: Starting Load/Save Random Seed... <30>[ 131.383590][ T1] systemd[1]: Starting Create System Users... <30>[ 131.398110][ T1] systemd[1]: Started Journal Service. <46>[ 131.505389][ T2292] systemd-journald[2292]: Received client request to flush runtime journal. <6>[ 131.668400][ T2313] Adding 4194300k swap on /swap.img. Priority:-2 extents:5 across:4472828k FS <5>[ 135.889816][ T2341] ccp 0000:00:08.0: 4 command queues available <5>[ 135.895901][ T2341] ccp 0000:00:08.0: ccp enabled <6>[ 136.472875][ T2327] acer_wmi: Acer Laptop ACPI-WMI Extras <6>[ 136.472925][ T2327] acer_wmi: Function bitmap for Communication Button: 0x800 <6>[ 136.549205][ T2327] input: Acer WMI hotkeys as /devices/virtual/input/input9 <5>[ 137.837423][ T2348] cfg80211: Loading compiled-in X.509 certificates for regulatory database <5>[ 137.845016][ T2348] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' <4>[ 138.536795][ T2348] wl: loading out-of-tree module taints kernel. <5>[ 138.545807][ T2348] wl: module verification failed: signature and/or required key missing - tainting kernel <6>[ 138.546840][ T2333] kvm: Nested Virtualization enabled <6>[ 138.546850][ T2333] SVM: kvm: Nested Paging enabled <6>[ 138.675368][ T2331] MCE: In-kernel MCE decoding enabled. <4>[ 138.681144][ T2348] wlan0: Broadcom BCM4365 802.11 Hybrid Wireless Controller 6.30.223.271 (r587334) <4>[ 138.681153][ T2348] <6>[ 138.742854][ T2337] wl 0000:02:00.0 wlp2s0: renamed from wlan0 <6>[ 139.054179][ T2349] mc: Linux media interface: v0.10 <6>[ 139.110849][ T2341] Bluetooth: Core ver 2.22 <6>[ 139.110992][ T2341] NET: Registered PF_BLUETOOTH protocol family <6>[ 139.110996][ T2341] Bluetooth: HCI device and connection manager initialized <6>[ 139.111191][ T2341] Bluetooth: HCI socket layer initialized <6>[ 139.111205][ T2341] Bluetooth: L2CAP socket layer initialized <6>[ 139.111288][ T2341] Bluetooth: SCO socket layer initialized <6>[ 139.260901][ T2349] videodev: Linux video capture interface: v2.00 <6>[ 139.626672][ T2341] usbcore: registered new interface driver btusb <6>[ 139.742985][ T2006] Bluetooth: hci0: BCM: chip id 70 <6>[ 139.744795][ T2006] Bluetooth: hci0: BCM: features 0x06 <6>[ 139.760771][ T2006] Bluetooth: hci0: integral <6>[ 139.760788][ T2006] Bluetooth: hci0: BCM43142A0 (001.001.011) build 0000 <6>[ 139.883640][ T2349] usb 1-1.4: Found UVC 1.00 device VGA Webcam (04f2:b47f) <6>[ 139.896570][ T2349] input: VGA Webcam: VGA Webcam as /devices/pci0000:00/0000:00:12.0/usb1/1-1/1-1.4/1-1.4:1.0/input/input10 <6>[ 139.897939][ T2349] usbcore: registered new interface driver uvcvideo <6>[ 139.936295][ T2340] snd_hda_intel 0000:00:01.1: Force to non-snoop mode <6>[ 140.014008][ T162] input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.1/sound/card0/input11 <3>[ 140.065182][ T2006] Bluetooth: hci0: BCM: firmware Patch file not found, tried: <3>[ 140.067797][ T2006] Bluetooth: hci0: BCM: 'brcm/BCM43142A0-04ca-2012.hcd' <3>[ 140.069644][ T2006] Bluetooth: hci0: BCM: 'brcm/BCM-04ca-2012.hcd' <6>[ 140.217090][ T2339] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC233: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker <6>[ 140.217106][ T2339] snd_hda_codec_realtek hdaudioC1D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) <6>[ 140.217112][ T2339] snd_hda_codec_realtek hdaudioC1D0: hp_outs=1 (0x21/0x0/0x0/0x0/0x0) <6>[ 140.217118][ T2339] snd_hda_codec_realtek hdaudioC1D0: mono: mono_out=0x0 <6>[ 140.217122][ T2339] snd_hda_codec_realtek hdaudioC1D0: inputs: <6>[ 140.217127][ T2339] snd_hda_codec_realtek hdaudioC1D0: Mic=0x1b <6>[ 140.492909][ T143] input: HD-Audio Generic Headphone as /devices/pci0000:00/0000:00:14.2/sound/card1/input12 <6>[ 145.042451][ T2599] alua: device handler registered <6>[ 145.097183][ T2599] emc: device handler registered <6>[ 145.154271][ T2599] rdac: device handler registered <6>[ 146.216380][ T2612] EXT4-fs (sda6): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. <6>[ 146.233576][ T2613] loop0: detected capacity change from 0 to 113504 <6>[ 146.254290][ T2616] loop1: detected capacity change from 0 to 113544 <6>[ 146.277903][ T2618] loop2: detected capacity change from 0 to 126424 <6>[ 146.514066][ T2621] loop3: detected capacity change from 0 to 126504 <6>[ 146.623223][ T2622] loop4: detected capacity change from 0 to 139600 <6>[ 146.702418][ T2625] loop5: detected capacity change from 0 to 139600 <6>[ 147.762178][ T2631] loop6: detected capacity change from 0 to 66120 <6>[ 147.781633][ T2632] loop7: detected capacity change from 0 to 66144 <5>[ 149.653864][ T44] audit: type=1400 audit(1628074710.574:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/snapd/snap-confine" pid=2665 comm="apparmor_parser" <5>[ 149.653890][ T44] audit: type=1400 audit(1628074710.574:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/snapd/snap-confine//mount-namespace-capture-helper" pid=2665 comm="apparmor_parser" <5>[ 149.731323][ T44] audit: type=1400 audit(1628074710.651:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=2671 comm="apparmor_parser" <5>[ 149.731349][ T44] audit: type=1400 audit(1628074710.651:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=2671 comm="apparmor_parser" <5>[ 149.732982][ T44] audit: type=1400 audit(1628074710.651:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="virt-aa-helper" pid=2668 comm="apparmor_parser" <5>[ 149.763124][ T44] audit: type=1400 audit(1628074710.681:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="libreoffice-oopslash" pid=2669 comm="apparmor_parser" <5>[ 149.766373][ T44] audit: type=1400 audit(1628074710.684:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="libreoffice-xpdfimport" pid=2672 comm="apparmor_parser" <5>[ 149.839158][ T44] audit: type=1400 audit(1628074710.758:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=2675 comm="apparmor_parser" <5>[ 149.839181][ T44] audit: type=1400 audit(1628074710.758:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=2675 comm="apparmor_parser" <5>[ 149.839195][ T44] audit: type=1400 audit(1628074710.758:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=2675 comm="apparmor_parser" <6>[ 160.076826][ T2715] Generic FE-GE Realtek PHY r8169-0-101:00: attached PHY driver (mii_bus:phy_addr=r8169-0-101:00, irq=MAC) <6>[ 160.271032][ T109] r8169 0000:01:00.1 enp1s0f1: Link is Down <6>[ 162.153691][ T109] r8169 0000:01:00.1 enp1s0f1: Link is Up - 100Mbps/Full - flow control rx/tx <6>[ 162.153758][ T126] IPv6: ADDRCONF(NETDEV_CHANGE): enp1s0f1: link becomes ready <6>[ 170.402069][ T2832] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 <6>[ 170.402080][ T2832] Bluetooth: BNEP filters: protocol multicast <6>[ 170.402098][ T2832] Bluetooth: BNEP socket layer initialized <6>[ 170.791229][ T2836] NET: Registered PF_ALG protocol family <3>[ 174.010772][ T38] Bluetooth: hci0: command 0x1003 tx timeout <3>[ 174.014141][ T2507] Bluetooth: hci0: unexpected event for opcode 0x1003 <6>[ 176.351077][ T2752] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready <6>[ 179.561966][ T3174] io scheduler bfq registered <6>[ 182.715433][ T3331] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. <6>[ 186.923507][ T3417] loop8: detected capacity change from 0 to 8 <4>[ 196.472507][ T44] kauditd_printk_skb: 29 callbacks suppressed <5>[ 196.472514][ T44] audit: type=1400 audit(1628074757.100:41): apparmor="DENIED" operation="capable" profile="libvirtd" pid=2915 comm="daemon-init" capability=17 capname="sys_rawio" <6>[ 210.419253][ T3676] Bluetooth: RFCOMM TTY layer initialized <6>[ 210.419294][ T3676] Bluetooth: RFCOMM socket layer initialized <6>[ 210.419333][ T3676] Bluetooth: RFCOMM ver 1.11 <6>[ 231.680035][ T3692] PM: suspend entry (deep) <6>[ 232.508097][ T3692] Filesystems sync: 0.827 seconds <6>[ 232.543785][ T3692] Freezing user space processes ... (elapsed 0.011 seconds) done. <6>[ 232.555797][ T3692] OOM killer disabled. <6>[ 232.555801][ T3692] Freezing remaining freezable tasks ... (elapsed 0.002 seconds) done. <6>[ 232.558632][ T3692] printk: Suspending console(s) (use no_console_suspend to debug) <6>[ 232.570159][ T3718] r8169 0000:01:00.1 enp1s0f1: Link is Down <5>[ 232.618854][ T109] sd 0:0:0:0: [sda] Synchronizing SCSI cache <5>[ 232.874121][ T109] sd 0:0:0:0: [sda] Stopping disk <6>[ 233.449075][ T3692] ACPI: EC: interrupt blocked <6>[ 233.479980][ T3692] ACPI: PM: Preparing to enter system sleep state S3 <6>[ 233.483313][ T3692] ACPI: EC: event blocked <6>[ 233.483324][ T3692] ACPI: EC: EC stopped <6>[ 233.483329][ T3692] ACPI: PM: Saving platform NVS memory <6>[ 233.483401][ T3692] Disabling non-boot CPUs ... <4>[ 233.496384][ T19] IRQ 42: no longer affine to CPU1 <6>[ 233.498815][ T3692] smpboot: CPU 1 is now offline <4>[ 233.510217][ T25] IRQ fixup: irq 39 move in progress, old vector 36 <4>[ 233.510280][ T25] IRQ 39: no longer affine to CPU2 <6>[ 233.511334][ T3692] smpboot: CPU 2 is now offline <4>[ 233.517287][ T31] IRQ 37: no longer affine to CPU3 <6>[ 233.519138][ T3692] smpboot: CPU 3 is now offline <6>[ 233.523605][ T3692] ACPI: PM: Low-level resume complete <6>[ 233.523682][ T3692] ACPI: EC: EC started <6>[ 233.523686][ T3692] ACPI: PM: Restoring platform NVS memory <6>[ 233.523749][ T3692] LVT offset 0 assigned for vector 0x400 <6>[ 233.525207][ T3692] LVT offset 1 assigned for vector 0xf9 <6>[ 233.525733][ T3692] Enabling non-boot CPUs ... <6>[ 233.527426][ T3692] x86: Booting SMP configuration: <6>[ 233.527459][ T3692] smpboot: Booting Node 0 Processor 1 APIC 0x1 <6>[ 233.527997][ T0] microcode: CPU1: patch_level=0x07030105 <6>[ 233.533622][ T17] ACPI: \_PR_.C001: Found 2 idle states <6>[ 233.537399][ T3692] CPU1 is up <6>[ 233.538962][ T3692] smpboot: Booting Node 0 Processor 2 APIC 0x2 <6>[ 233.539469][ T0] microcode: CPU2: patch_level=0x07030105 <6>[ 233.543089][ T23] ACPI: \_PR_.C002: Found 2 idle states <6>[ 233.545571][ T3692] CPU2 is up <6>[ 233.546939][ T3692] smpboot: Booting Node 0 Processor 3 APIC 0x3 <6>[ 233.547413][ T0] microcode: CPU3: patch_level=0x07030105 <6>[ 233.550460][ T29] ACPI: \_PR_.C003: Found 2 idle states <6>[ 233.553347][ T3692] CPU3 is up <6>[ 233.555523][ T3692] ACPI: PM: Waking up from system sleep state S3 <6>[ 233.561851][ T3692] ACPI: EC: interrupt unblocked <6>[ 233.602781][ T3692] ACPI: EC: event unblocked <5>[ 233.616521][ T3744] sd 0:0:0:0: [sda] Starting disk <6>[ 233.622530][ T3713] [drm] PCIE GART of 2048M enabled (table at 0x000000000030E000). <6>[ 233.622760][ T3713] radeon 0000:00:01.0: WB enabled <6>[ 233.622787][ T3713] radeon 0000:00:01.0: fence driver on ring 0 use gpu addr 0x0000000040000c00 <6>[ 233.622794][ T3713] radeon 0000:00:01.0: fence driver on ring 1 use gpu addr 0x0000000040000c04 <6>[ 233.622800][ T3713] radeon 0000:00:01.0: fence driver on ring 2 use gpu addr 0x0000000040000c08 <6>[ 233.622805][ T3713] radeon 0000:00:01.0: fence driver on ring 3 use gpu addr 0x0000000040000c0c <6>[ 233.622810][ T3713] radeon 0000:00:01.0: fence driver on ring 4 use gpu addr 0x0000000040000c10 <6>[ 233.623269][ T3713] radeon 0000:00:01.0: fence driver on ring 5 use gpu addr 0x0000000000078d30 <6>[ 233.623474][ T3713] radeon 0000:00:01.0: fence driver on ring 6 use gpu addr 0x0000000040000c18 <6>[ 233.623479][ T3713] radeon 0000:00:01.0: fence driver on ring 7 use gpu addr 0x0000000040000c1c <3>[ 233.624939][ T3713] debugfs: File 'radeon_ring_gfx' in directory '0' already present! <3>[ 233.625102][ T3713] debugfs: File 'radeon_ring_cp1' in directory '0' already present! <3>[ 233.625114][ T3713] debugfs: File 'radeon_ring_cp2' in directory '0' already present! <3>[ 233.625126][ T3713] debugfs: File 'radeon_ring_dma1' in directory '0' already present! <3>[ 233.625138][ T3713] debugfs: File 'radeon_ring_dma2' in directory '0' already present! <6>[ 233.628201][ T3713] [drm] ring test on 0 succeeded in 0 usecs <6>[ 233.628293][ T3713] [drm] ring test on 1 succeeded in 1 usecs <6>[ 233.628314][ T3713] [drm] ring test on 2 succeeded in 1 usecs <6>[ 233.628521][ T3713] [drm] ring test on 3 succeeded in 3 usecs <6>[ 233.628531][ T3713] [drm] ring test on 4 succeeded in 3 usecs <3>[ 233.628539][ T3713] debugfs: File 'radeon_ring_uvd' in directory '0' already present! <6>[ 233.654672][ T3713] [drm] ring test on 5 succeeded in 1 usecs <6>[ 233.654697][ T3713] [drm] UVD initialized successfully. <3>[ 233.654705][ T3713] debugfs: File 'radeon_ring_vce1' in directory '0' already present! <3>[ 233.654718][ T3713] debugfs: File 'radeon_ring_vce2' in directory '0' already present! <6>[ 233.764759][ T3713] [drm] ring test on 6 succeeded in 6 usecs <6>[ 233.764775][ T3713] [drm] ring test on 7 succeeded in 2 usecs <6>[ 233.764778][ T3713] [drm] VCE initialized successfully. <6>[ 233.765326][ T3713] [drm] ib test on ring 0 succeeded in 0 usecs <6>[ 233.765748][ T3713] [drm] ib test on ring 1 succeeded in 0 usecs <6>[ 233.765903][ T3713] [drm] ib test on ring 2 succeeded in 0 usecs <6>[ 233.766062][ T3713] [drm] ib test on ring 3 succeeded in 0 usecs <6>[ 233.766217][ T3713] [drm] ib test on ring 4 succeeded in 0 usecs <6>[ 233.826100][ T3743] r8169 0000:01:00.1 enp1s0f1: Link is Down <6>[ 233.892228][ T109] usb 1-1.3: reset full-speed USB device number 4 using ehci-pci <4>[ 234.008513][ T109] <4>[ 234.008517][ T109] ====================================================== <4>[ 234.008519][ T109] WARNING: possible circular locking dependency detected <4>[ 234.008521][ T109] 5.14.0-rc3-bluetea-test-00249-gc7d102232649 #5 Tainted: G OE <4>[ 234.008524][ T109] ------------------------------------------------------ <4>[ 234.008525][ T109] kworker/u8:1/109 is trying to acquire lock: <4>[ 234.008527][ T109] ffff88817e9b0120 (sk_lock-AF_BLUETOOTH-BTPROTO_HCI){+.+.}-{0:0}, at: hci_sock_dev_event+0x17d/0x1f0 [bluetooth] <4>[ 234.008604][ T109] <4>[ 234.008604][ T109] but task is already holding lock: <4>[ 234.008606][ T109] ffffffffa11f33c0 (hci_sk_list.lock){++++}-{2:2}, at: hci_sock_dev_event+0x141/0x1f0 [bluetooth] <4>[ 234.008669][ T109] <4>[ 234.008669][ T109] which lock already depends on the new lock. <4>[ 234.008669][ T109] <4>[ 234.008670][ T109] <4>[ 234.008670][ T109] the existing dependency chain (in reverse order) is: <4>[ 234.008671][ T109] <4>[ 234.008671][ T109] -> #1 (hci_sk_list.lock){++++}-{2:2}: <4>[ 234.008677][ T109] _raw_read_lock+0x5d/0x70 <4>[ 234.008685][ T109] hci_sock_sendmsg+0x9e1/0xac0 [bluetooth] <4>[ 234.008742][ T109] sock_sendmsg+0x33/0x40 <4>[ 234.008747][ T109] ____sys_sendmsg+0x200/0x220 <4>[ 234.008751][ T109] ___sys_sendmsg+0x81/0xc0 <4>[ 234.008755][ T109] __sys_sendmsg+0x59/0xa0 <4>[ 234.008758][ T109] do_syscall_64+0x35/0xb0 <4>[ 234.008762][ T109] entry_SYSCALL_64_after_hwframe+0x44/0xae <4>[ 234.008767][ T109] <4>[ 234.008767][ T109] -> #0 (sk_lock-AF_BLUETOOTH-BTPROTO_HCI){+.+.}-{0:0}: <4>[ 234.008773][ T109] __lock_acquire+0x119a/0x1e10 <4>[ 234.008778][ T109] lock_acquire+0xc8/0x2f0 <4>[ 234.008781][ T109] lock_sock_nested+0x5d/0x80 <4>[ 234.008785][ T109] hci_sock_dev_event+0x17d/0x1f0 [bluetooth] <4>[ 234.008842][ T109] hci_unregister_dev+0xea/0x520 [bluetooth] <4>[ 234.008895][ T109] btusb_disconnect+0x63/0x150 [btusb] <4>[ 234.008902][ T109] usb_unbind_interface+0x88/0x270 <4>[ 234.008907][ T109] __device_release_driver+0x17d/0x230 <4>[ 234.008912][ T109] device_release_driver+0x26/0x40 <4>[ 234.008915][ T109] usb_forced_unbind_intf+0x6d/0xa0 <4>[ 234.008919][ T109] unbind_marked_interfaces.isra.0+0x42/0x60 <4>[ 234.008923][ T109] usb_resume+0x5d/0x70 <4>[ 234.008926][ T109] dpm_run_callback+0x5e/0x210 <4>[ 234.008931][ T109] device_resume+0x110/0x280 <4>[ 234.008934][ T109] async_resume+0x19/0x30 <4>[ 234.008937][ T109] async_run_entry_fn+0x2a/0x130 <4>[ 234.008941][ T109] process_one_work+0x236/0x530 <4>[ 234.008946][ T109] worker_thread+0x52/0x3b0 <4>[ 234.008950][ T109] kthread+0x135/0x160 <4>[ 234.008954][ T109] ret_from_fork+0x1f/0x30 <4>[ 234.008959][ T109] <4>[ 234.008959][ T109] other info that might help us debug this: <4>[ 234.008959][ T109] <4>[ 234.008960][ T109] Possible unsafe locking scenario: <4>[ 234.008960][ T109] <4>[ 234.008961][ T109] CPU0 CPU1 <4>[ 234.008962][ T109] ---- ---- <4>[ 234.008963][ T109] lock(hci_sk_list.lock); <4>[ 234.008966][ T109] lock(sk_lock-AF_BLUETOOTH-BTPROTO_HCI); <4>[ 234.008968][ T109] lock(hci_sk_list.lock); <4>[ 234.008971][ T109] lock(sk_lock-AF_BLUETOOTH-BTPROTO_HCI); <4>[ 234.008973][ T109] <4>[ 234.008973][ T109] *** DEADLOCK *** <4>[ 234.008973][ T109] <4>[ 234.008974][ T109] 5 locks held by kworker/u8:1/109: <4>[ 234.008977][ T109] #0: ffff88810004fd38 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x1bc/0x530 <4>[ 234.008987][ T109] #1: ffffc900004d3e70 ((work_completion)(&entry->work)){+.+.}-{0:0}, at: process_one_work+0x1bc/0x530 <4>[ 234.008998][ T109] #2: ffff888100dc1a20 (&dev->mutex){....}-{3:3}, at: device_resume+0xc6/0x280 <4>[ 234.009007][ T109] #3: ffff888100dc11a8 (&dev->mutex){....}-{3:3}, at: device_release_driver+0x1c/0x40 <4>[ 234.009017][ T109] #4: ffffffffa11f33c0 (hci_sk_list.lock){++++}-{2:2}, at: hci_sock_dev_event+0x141/0x1f0 [bluetooth] <4>[ 234.009080][ T109] <4>[ 234.009080][ T109] stack backtrace: <4>[ 234.009082][ T109] CPU: 2 PID: 109 Comm: kworker/u8:1 Tainted: G OE 5.14.0-rc3-bluetea-test-00249-gc7d102232649 #5 <4>[ 234.009087][ T109] Hardware name: Acer Aspire ES1-421/OLVIA_BE, BIOS V1.05 07/02/2015 <4>[ 234.009090][ T109] Workqueue: events_unbound async_run_entry_fn <4>[ 234.009096][ T109] Call Trace: <4>[ 234.009102][ T109] dump_stack_lvl+0x6a/0x9a <4>[ 234.009109][ T109] check_noncircular+0xfe/0x110 <4>[ 234.009114][ T109] ? save_trace+0x42/0x280 <4>[ 234.009120][ T109] __lock_acquire+0x119a/0x1e10 <4>[ 234.009129][ T109] lock_acquire+0xc8/0x2f0 <4>[ 234.009134][ T109] ? hci_sock_dev_event+0x17d/0x1f0 [bluetooth] <4>[ 234.009192][ T109] ? lock_sock_nested+0x3e/0x80 <4>[ 234.009198][ T109] lock_sock_nested+0x5d/0x80 <4>[ 234.009202][ T109] ? hci_sock_dev_event+0x17d/0x1f0 [bluetooth] <4>[ 234.009260][ T109] hci_sock_dev_event+0x17d/0x1f0 [bluetooth] <4>[ 234.009318][ T109] hci_unregister_dev+0xea/0x520 [bluetooth] <4>[ 234.009372][ T109] ? lockdep_hardirqs_on_prepare+0xff/0x180 <4>[ 234.009376][ T109] ? _raw_spin_unlock_irq+0x24/0x40 <4>[ 234.009382][ T109] ? _raw_spin_unlock_irq+0x24/0x40 <4>[ 234.009386][ T109] ? usb_hcd_flush_endpoint+0x6d/0x170 <4>[ 234.009392][ T109] btusb_disconnect+0x63/0x150 [btusb] <4>[ 234.009400][ T109] usb_unbind_interface+0x88/0x270 <4>[ 234.009408][ T109] __device_release_driver+0x17d/0x230 <4>[ 234.009413][ T109] device_release_driver+0x26/0x40 <4>[ 234.009417][ T109] usb_forced_unbind_intf+0x6d/0xa0 <4>[ 234.009423][ T109] unbind_marked_interfaces.isra.0+0x42/0x60 <4>[ 234.009428][ T109] usb_resume+0x5d/0x70 <4>[ 234.009433][ T109] ? usb_dev_thaw+0x10/0x10 <4>[ 234.009438][ T109] dpm_run_callback+0x5e/0x210 <4>[ 234.009444][ T109] device_resume+0x110/0x280 <4>[ 234.009449][ T109] ? pm_dev_err+0x34/0x34 <4>[ 234.009456][ T109] async_resume+0x19/0x30 <4>[ 234.009460][ T109] async_run_entry_fn+0x2a/0x130 <4>[ 234.009465][ T109] process_one_work+0x236/0x530 <4>[ 234.009473][ T109] worker_thread+0x52/0x3b0 <4>[ 234.009478][ T109] ? process_one_work+0x530/0x530 <4>[ 234.009483][ T109] kthread+0x135/0x160 <4>[ 234.009487][ T109] ? set_kthread_struct+0x40/0x40 <4>[ 234.009492][ T109] ret_from_fork+0x1f/0x30 <6>[ 234.078429][ T123] usb 1-1.4: reset high-speed USB device number 5 using ehci-pci <6>[ 234.081780][ T2063] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300) <6>[ 234.084011][ T2063] ata2.00: configured for UDMA/133 <6>[ 234.292051][ T3713] [drm] ib test on ring 5 succeeded <6>[ 234.292758][ T3713] [drm] ib test on ring 6 succeeded <6>[ 234.293223][ T3713] [drm] ib test on ring 7 succeeded <6>[ 235.461860][ T123] r8169 0000:01:00.1 enp1s0f1: Link is Up - 100Mbps/Full - flow control rx/tx <6>[ 235.576117][ T2061] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) <6>[ 235.607394][ T2061] ata1.00: configured for UDMA/133 <6>[ 235.798127][ T3692] OOM killer enabled. <6>[ 235.799209][ T3692] Restarting tasks ... done. <6>[ 235.893426][ T3692] PM: suspend exit <6>[ 235.995602][ T2006] Bluetooth: hci0: BCM: chip id 70 <6>[ 235.999600][ T2006] Bluetooth: hci0: BCM: features 0x06 <6>[ 236.018626][ T2006] Bluetooth: hci0: BCM43142A <6>[ 236.021668][ T2006] Bluetooth: hci0: BCM43142A0 (001.001.011) build 0000 <3>[ 236.025703][ T2006] Bluetooth: hci0: BCM: firmware Patch file not found, tried: <3>[ 236.028767][ T2006] Bluetooth: hci0: BCM: 'brcm/BCM43142A0-04ca-2012.hcd' <3>[ 236.031753][ T2006] Bluetooth: hci0: BCM: 'brcm/BCM-04ca-2012.hcd' <6>[ 237.107924][ T2752] IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready <3>[ 238.187735][ T2591] Bluetooth: hci0: command 0x1003 tx timeout <3>[ 238.191275][ T2508] Bluetooth: hci0: unexpected event for opcode 0x1003