[PATCH 7/7] dyndbg: enable 'cache' of active pr_debug callsites
From: Jim Cromie
Date: Fri Aug 07 2020 - 16:10:43 EST
in ddebug_zpool_put() dont zs_unmap the callsite, if it is enabled for
printing. This will eliminate possibly repeated un-maps then re-maps
of enabled and invoked pr-debug callsites, and will promptly retire
all other uses.
But this causes kernel to BUG
[ 1.364303] BUG: sleeping function called from invalid context at mm/slab.h:567
[jimc@frodo build-v2]$ krun -a main.dyndbg=+pmf -q=-s -q=-S
./.virtme_mods/lib/modules/0.0.0
/usr/bin/qemu-system-x86_64 -fsdev local,id=virtfs1,path=/,security_model=none,readonly,multidevs=remap -device virtio-9p-pci,fsdev=virtfs1,mount_tag=/dev/root -fsdev local,id=virtfs5,path=/usr/local/lib/python3.8/site-packages/virtme-0.1.1-py3.8.egg/virtme/guest,security_model=none,readonly,multidevs=remap -device virtio-9p-pci,fsdev=virtfs5,mount_tag=virtme.guesttools -machine accel=kvm:tcg -watchdog i6300esb -cpu host -parallel none -net none -echr 1 -serial none -chardev stdio,id=console,signal=off,mux=on -serial chardev:console -mon chardev=console -vga none -display none -kernel ./arch/x86/boot/bzImage -append 'virtme_link_mods=/home/jimc/projects/lx/linux.git/build-v2/.virtme_mods/lib/modules/0.0.0 earlyprintk=serial,ttyS0,115200 console=ttyS0 psmouse.proto=exps "virtme_stty_con=rows 25 cols 102 iutf8" TERM=xterm-256color rootfstype=9p rootflags=version=9p2000.L,trans=virtio,access=any raid=noautodetect ro nokaslr dynamic_debug.verbose=3 module.dyndbg=+pm main.dyndbg=+pmf init=/bin/sh -- -c "mount -t tmpfs run /run;mkdir -p /run/virtme/guesttools;/bin/mount -n -t 9p -o ro,version=9p2000.L,trans=virtio,access=any virtme.guesttools /run/virtme/guesttools;exec /run/virtme/guesttools/virtme-init"' -s -S
Wrong EFI loader signature.
early console in extract_kernel
input_data: 0x00000000033373a8
input_len: 0x0000000000aba748
output: 0x0000000001000000
output_len: 0x00000000025f9e28
kernel_total_size: 0x0000000002e2c000
needed_size: 0x0000000003000000
trampoline_32bit: 0x000000000009d000
KASLR disabled: 'nokaslr' on cmdline.
Decompressing Linux... Parsing ELF... No relocation needed... done.
Booting the kernel.
[ 0.000000] Linux version 5.8.0-00025-g4e76f4427bf8 (jimc@frodo) (gcc (GCC) 10.2.1 20200723 (Red Hat 10.2.1-1), GNU ld version 2.34-4.fc32) #30 SMP Thu Aug 6 16:39:03 MDT 2020
[ 0.000000] Command line: virtme_link_mods=/home/jimc/projects/lx/linux.git/build-v2/.virtme_mods/lib/modules/0.0.0 earlyprintk=serial,ttyS0,115200 console=ttyS0 psmouse.proto=exps "virtme_stty_con=rows 25 cols 102 iutf8" TERM=xterm-256color rootfstype=9p rootflags=version=9p2000.L,trans=virtio,access=any raid=noautodetect ro nokaslr dynamic_debug.verbose=3 module.dyndbg=+pm main.dyndbg=+pmf init=/bin/sh -- -c "mount -t tmpfs run /run;mkdir -p /run/virtme/guesttools;/bin/mount -n -t 9p -o ro,version=9p2000.L,trans=virtio,access=any virtme.guesttools /run/virtme/guesttools;exec /run/virtme/guesttools/virtme-init"
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64
[ 0.000000] x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64
[ 0.000000] x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000007fdffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000007fe0000-0x0000000007ffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[ 0.000000] printk: bootconsole [earlyser0] enabled
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] SMBIOS 2.8 present.
[ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.13.0-2.fc32 04/01/2014
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 3141001, primary cpu clock
[ 0.000000] kvm-clock: using sched offset of 218135524 cycles
[ 0.000900] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.003580] tsc: Detected 2591.998 MHz processor
[ 0.004611] last_pfn = 0x7fe0 max_arch_pfn = 0x400000000
[ 0.005518] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.013334] found SMP MP-table at [mem 0x000f5c30-0x000f5c3f]
[ 0.014404] check: Scanning 1 areas for low memory corruption
[ 0.015327] Using GB pages for direct mapping
[ 0.016207] ACPI: Early table checksum verification disabled
[ 0.017097] ACPI: RSDP 0x00000000000F5A70 000014 (v00 BOCHS )
[ 0.018015] ACPI: RSDT 0x0000000007FE157B 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.019607] ACPI: FACP 0x0000000007FE1457 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.021045] ACPI: DSDT 0x0000000007FE0040 001417 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.022479] ACPI: FACS 0x0000000007FE0000 000040
[ 0.023399] ACPI: APIC 0x0000000007FE14CB 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.024818] ACPI: HPET 0x0000000007FE1543 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.026390] No NUMA configuration found
[ 0.026962] Faking a node at [mem 0x0000000000000000-0x0000000007fdffff]
[ 0.027986] NODE_DATA(0) allocated [mem 0x07fdc000-0x07fdffff]
[ 0.028978] Zone ranges:
[ 0.029398] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.030489] DMA32 [mem 0x0000000001000000-0x0000000007fdffff]
[ 0.031567] Normal empty
[ 0.031954] Movable zone start for each node
[ 0.032662] Early memory node ranges
[ 0.033219] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.034078] node 0: [mem 0x0000000000100000-0x0000000007fdffff]
[ 0.035334] Zeroed struct page in unavailable ranges: 130 pages
[ 0.035336] Initmem setup node 0 [mem 0x0000000000001000-0x0000000007fdffff]
[ 0.038090] ACPI: PM-Timer IO Port: 0x608
[ 0.038770] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.039730] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.040855] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.041956] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.043186] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.044213] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.045280] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.046277] Using ACPI (MADT) for SMP configuration information
[ 0.047208] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 0.048083] TSC deadline timer available
[ 0.048763] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[ 0.049527] KVM setup pv remote TLB flush
[ 0.050297] KVM setup pv sched yield
[ 0.050864] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.052112] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.053290] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.054468] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.055881] [mem 0x08000000-0xfeffbfff] available for PCI devices
[ 0.056952] Booting paravirtualized kernel on KVM
[ 0.057938] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[ 0.059627] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:1 nr_node_ids:1
[ 0.061318] percpu: Embedded 502 pages/cpu s2015896 r8192 d32104 u2097152
[ 0.062483] KVM setup async PF for cpu 0
[ 0.063242] kvm-stealtime: cpu 0, msr 7a17cc0
[ 0.063989] Built 1 zonelists, mobility grouping on. Total pages: 32105
[ 0.065255] Policy zone: DMA32
[ 0.065776] Kernel command line: virtme_link_mods=/home/jimc/projects/lx/linux.git/build-v2/.virtme_mods/lib/modules/0.0.0 earlyprintk=serial,ttyS0,115200 console=ttyS0 psmouse.proto=exps "virtme_stty_con=rows 25 cols 102 iutf8" TERM=xterm-256color rootfstype=9p rootflags=version=9p2000.L,trans=virtio,access=any raid=noautodetect ro nokaslr dynamic_debug.verbose=3 module.dyndbg=+pm main.dyndbg=+pmf init=/bin/sh -- -c "mount -t tmpfs run /run;mkdir -p /run/virtme/guesttools;/bin/mount -n -t 9p -o ro,version=9p2000.L,trans=virtio,access=any virtme.guesttools /run/virtme/guesttools;exec /run/virtme/guesttools/virtme-init"
[ 0.075229] Dentry cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
[ 0.076569] Inode-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 0.077888] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.079127] Memory: 79000K/130552K available (16390K kernel code, 2056K rwdata, 8476K rodata, 3008K init, 13184K bss, 51552K reserved, 0K cma-reserved)
[ 0.081449] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.082840] Kernel/User page tables isolation: enabled
[ 0.088889] Running RCU self tests
[ 0.089529] rcu: Hierarchical RCU implementation.
[ 0.090444] rcu: RCU event tracing is enabled.
[ 0.091347] rcu: RCU lockdep checking is enabled.
[ 0.092084] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1.
[ 0.093238] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[ 0.094467] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[ 0.096052] NR_IRQS: 4352, nr_irqs: 256, preallocated irqs: 16
[ 0.097804] random: get_random_bytes called from start_kernel+0x36b/0x544 with crng_init=0
[ 0.097951] Console: colour *CGA 80x25
[ 0.100066] printk: console [ttyS0] enabled
[ 0.100066] printk: console [ttyS0] enabled
[ 0.101286] printk: bootconsole [earlyser0] disabled
[ 0.101286] printk: bootconsole [earlyser0] disabled
[ 0.102875] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.104018] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.104714] ... MAX_LOCK_DEPTH: 48
[ 0.105600] ... MAX_LOCKDEP_KEYS: 8192
[ 0.106572] ... CLASSHASH_SIZE: 4096
[ 0.107509] ... MAX_LOCKDEP_ENTRIES: 32768
[ 0.108265] ... MAX_LOCKDEP_CHAINS: 65536
[ 0.108892] ... CHAINHASH_SIZE: 32768
[ 0.109598] memory used by lock dependency info: 6813 kB
[ 0.110461] memory used for stack traces: 4224 kB
[ 0.111274] per task-struct memory footprint: 2688 bytes
[ 0.112067] ------------------------
[ 0.112669] | Locking API testsuite:
[ 0.113368] ----------------------------------------------------------------------------
[ 0.114770] | spin |wlock |rlock |mutex | wsem | rsem |
[ 0.116032] --------------------------------------------------------------------------
[ 0.117269] A-A deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.121931] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.126875] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.131594] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.136460] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.141138] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.146236] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.151113] double unlock: ok | ok | ok | ok | ok | ok | ok |
[ 0.155610] initialize held: ok | ok | ok | ok | ok | ok | ok |
[ 0.159968] --------------------------------------------------------------------------
[ 0.161246] recursive read-lock: | ok | | ok |
[ 0.163424] recursive read-lock #2: | ok | | ok |
[ 0.165567] mixed read-write-lock: | ok | | ok |
[ 0.167698] mixed write-read-lock: | ok | | ok |
[ 0.170041] mixed read-lock/lock-write ABBA: | ok | | ok |
[ 0.170927] mixed read-lock/lock-read ABBA: |FAILED| | ok |
[ 0.173043] mixed write-lock/lock-write ABBA: | ok | | ok |
[ 0.175456] --------------------------------------------------------------------------
[ 0.178055] hard-irqs-on + irq-safe-A/12: ok | ok | ok |
[ 0.180435] soft-irqs-on + irq-safe-A/12: ok | ok | ok |
[ 0.182596] hard-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.184786] soft-irqs-on + irq-safe-A/21: ok | ok | ok |
[ 0.187120] sirq-safe-A => hirqs-on/12: ok | ok | ok |
[ 0.189291] sirq-safe-A => hirqs-on/21: ok | ok | ok |
[ 0.191487] hard-safe-A + irqs-on/12: ok | ok | ok |
[ 0.193918] soft-safe-A + irqs-on/12: ok | ok | ok |
[ 0.196176] hard-safe-A + irqs-on/21: ok | ok | ok |
[ 0.198335] soft-safe-A + irqs-on/21: ok | ok | ok |
[ 0.200556] hard-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 0.203119] soft-safe-A + unsafe-B #1/123: ok | ok | ok |
[ 0.205467] hard-safe-A + unsafe-B #1/132: ok | ok | ok |
[ 0.208201] soft-safe-A + unsafe-B #1/132: ok | ok | ok |
[ 0.210585] hard-safe-A + unsafe-B #1/213: ok | ok | ok |
[ 0.213147] soft-safe-A + unsafe-B #1/213: ok | ok | ok |
[ 0.215550] hard-safe-A + unsafe-B #1/231: ok | ok | ok |
[ 0.217926] soft-safe-A + unsafe-B #1/231: ok | ok | ok |
[ 0.220455] hard-safe-A + unsafe-B #1/312: ok | ok | ok |
[ 0.222807] soft-safe-A + unsafe-B #1/312: ok | ok | ok |
[ 0.225246] hard-safe-A + unsafe-B #1/321: ok | ok | ok |
[ 0.227480] soft-safe-A + unsafe-B #1/321: ok | ok | ok |
[ 0.229886] hard-safe-A + unsafe-B #2/123: ok | ok | ok |
[ 0.232121] soft-safe-A + unsafe-B #2/123: ok | ok | ok |
[ 0.234488] hard-safe-A + unsafe-B #2/132: ok | ok | ok |
[ 0.237863] soft-safe-A + unsafe-B #2/132: ok | ok | ok |
[ 0.241987] hard-safe-A + unsafe-B #2/213: ok | ok | ok |
[ 0.244188] soft-safe-A + unsafe-B #2/213: ok | ok | ok |
[ 0.246434] hard-safe-A + unsafe-B #2/231: ok | ok | ok |
[ 0.248782] soft-safe-A + unsafe-B #2/231: ok | ok | ok |
[ 0.250969] hard-safe-A + unsafe-B #2/312: ok | ok | ok |
[ 0.253578] soft-safe-A + unsafe-B #2/312: ok | ok | ok |
[ 0.256138] hard-safe-A + unsafe-B #2/321: ok | ok | ok |
[ 0.258583] soft-safe-A + unsafe-B #2/321: ok | ok | ok |
[ 0.261091] hard-irq lock-inversion/123: ok | ok | ok |
[ 0.263609] soft-irq lock-inversion/123: ok | ok | ok |
[ 0.266030] hard-irq lock-inversion/132: ok | ok | ok |
[ 0.268224] soft-irq lock-inversion/132: ok | ok | ok |
[ 0.270522] hard-irq lock-inversion/213: ok | ok | ok |
[ 0.272741] soft-irq lock-inversion/213: ok | ok | ok |
[ 0.274977] hard-irq lock-inversion/231: ok | ok | ok |
[ 0.277139] soft-irq lock-inversion/231: ok | ok | ok |
[ 0.279390] hard-irq lock-inversion/312: ok | ok | ok |
[ 0.281880] soft-irq lock-inversion/312: ok | ok | ok |
[ 0.284206] hard-irq lock-inversion/321: ok | ok | ok |
[ 0.286949] soft-irq lock-inversion/321: ok | ok | ok |
[ 0.289404] hard-irq read-recursion/123: ok |
[ 0.290832] soft-irq read-recursion/123: ok |
[ 0.292139] hard-irq read-recursion/132: ok |
[ 0.293402] soft-irq read-recursion/132: ok |
[ 0.294707] hard-irq read-recursion/213: ok |
[ 0.296035] soft-irq read-recursion/213: ok |
[ 0.297515] hard-irq read-recursion/231: ok |
[ 0.298786] soft-irq read-recursion/231: ok |
[ 0.300243] hard-irq read-recursion/312: ok |
[ 0.301589] soft-irq read-recursion/312: ok |
[ 0.302907] hard-irq read-recursion/321: ok |
[ 0.304080] soft-irq read-recursion/321: ok |
[ 0.305288] --------------------------------------------------------------------------
[ 0.306410] | Wound/wait tests |
[ 0.306883] ---------------------
[ 0.307421] ww api failures: ok | ok | ok |
[ 0.309949] ww contexts mixing: ok | ok |
[ 0.311666] finishing ww context: ok | ok | ok | ok |
[ 0.314669] locking mismatches: ok | ok | ok |
[ 0.317124] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok |
[ 0.323525] spinlock nest unlocked: ok |
[ 0.324643] -----------------------------------------------------
[ 0.325504] |block | try |context|
[ 0.326631] -----------------------------------------------------
[ 0.327505] context: ok | ok | ok |
[ 0.329832] try: ok | ok | ok |
[ 0.331964] block: ok | ok | ok |
[ 0.334585] spinlock: ok | ok | ok |
[ 0.337766] -------------------------------------------------------
[ 0.338756] Good, all 261 testcases passed! |
[ 0.339394] ---------------------------------
[ 0.340334] ACPI: Core revision 20200528
[ 0.341167] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 0.342557] APIC: Switch to symmetric I/O mode setup
[ 0.343248] KVM setup pv IPIs
[ 0.345126] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.345981] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x255cb518234, max_idle_ns: 440795279333 ns
[ 0.347449] Calibrating delay loop (skipped) preset value.. 5183.99 BogoMIPS (lpj=2591998)
[ 0.348446] pid_max: default: 32768 minimum: 301
[ 0.349095] LSM: Security Framework initializing
[ 0.349460] SELinux: Initializing.
[ 0.349964] Mount-cache hash table entries: 512 (order: 0, 4096 bytes, linear)
[ 0.350446] Mountpoint-cache hash table entries: 512 (order: 0, 4096 bytes, linear)
Poking KASLR using RDRAND RDTSC...
[ 0.353011] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[ 0.353541] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.354445] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.355453] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[ 0.356449] Spectre V2 : Mitigation: Full generic retpoline
[ 0.357446] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.358444] Spectre V2 : Enabling Restricted Speculation for firmware calls
[ 0.359450] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.360447] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[ 0.361458] TAA: Mitigation: Clear CPU buffers
[ 0.362446] SRBDS: Unknown: Dependent on hypervisor status
[ 0.363444] MDS: Mitigation: Clear CPU buffers
[ 0.374194] Freeing SMP alternatives memory: 40K
[ 0.375553] smpboot: CPU0: Intel(R) Core(TM) i7-6700HQ CPU @ 2.60GHz (family: 0x6, model: 0x5e, stepping: 0x3)
[ 0.376791] Performance Events: Skylake events, full-width counters, Intel PMU driver.
[ 0.377456] ... version: 2
[ 0.378089] ... bit width: 48
[ 0.378447] ... generic registers: 4
[ 0.379067] ... value mask: 0000ffffffffffff
[ 0.379446] ... max period: 00007fffffffffff
[ 0.380291] ... fixed-purpose events: 3
[ 0.380453] ... event mask: 000000070000000f
[ 0.381618] rcu: Hierarchical SRCU implementation.
[ 0.382784] dyndbg: 6 debug prints in module main
[ 0.383485] dyndbg: 1 debug prints in module initramfs
[ 0.384338] dyndbg: 3 debug prints in module ibs
[ 0.384448] dyndbg: 2 debug prints in module pt
[ 0.385176] dyndbg: 11 debug prints in module kvm
[ 0.385448] dyndbg: 15 debug prints in module kvm_intel
[ 0.386231] dyndbg: 1 debug prints in module kvm_amd
[ 0.386471] dyndbg: 1 debug prints in module common
[ 0.387311] dyndbg: 1 debug prints in module amd
[ 0.387466] dyndbg: 1 debug prints in module hygon
[ 0.388234] dyndbg: 1 debug prints in module therm_throt
[ 0.388447] dyndbg: 2 debug prints in module mtrr
[ 0.389158] dyndbg: 7 debug prints in module generic
[ 0.389447] dyndbg: 6 debug prints in module cleanup
[ 0.390213] dyndbg: 19 debug prints in module microcode
[ 0.390447] dyndbg: 1 debug prints in module mshyperv
[ 0.391193] dyndbg: 5 debug prints in module boot
[ 0.391447] dyndbg: 21 debug prints in module smpboot
[ 0.392372] dyndbg: 1 debug prints in module tsc_sync
[ 0.392448] dyndbg: 2 debug prints in module setup_percpu
[ 0.393274] dyndbg: 30 debug prints in module vector
[ 0.393447] dyndbg: 2 debug prints in module hpet
[ 0.394139] dyndbg: 2 debug prints in module init
[ 0.394447] dyndbg: 4 debug prints in module init_64
[ 0.395237] dyndbg: 1 debug prints in module numa
[ 0.395447] dyndbg: 1 debug prints in module pti
[ 0.396142] dyndbg: 2 debug prints in module iosf_mbi
[ 0.396447] dyndbg: 4 debug prints in module params
[ 0.397197] dyndbg: 4 debug prints in module async
[ 0.397447] dyndbg: 4 debug prints in module snapshot
[ 0.398219] dyndbg: 6 debug prints in module swap
[ 0.398447] dyndbg: 1 debug prints in module manage
[ 0.399198] dyndbg: 14 debug prints in module irqdomain
[ 0.399447] dyndbg: 1 debug prints in module cpuhotplug
[ 0.400254] dyndbg: 2 debug prints in module msi
[ 0.400448] dyndbg: 2 debug prints in module freezer
[ 0.401248] dyndbg: 18 debug prints in module module
[ 0.401454] dyndbg: 1 debug prints in module cgroup
[ 0.402235] dyndbg: 1 debug prints in module util
[ 0.402466] dyndbg: 1 debug prints in module swapfile
[ 0.403376] dyndbg: 5 debug prints in module zswap
[ 0.403448] dyndbg: 6 debug prints in module mempolicy
[ 0.404214] dyndbg: 1 debug prints in module sparse
[ 0.404454] dyndbg: 1 debug prints in module sparse_vmemmap
[ 0.405374] dyndbg: 3 debug prints in module zpool
[ 0.405448] dyndbg: 1 debug prints in module fsnotify
[ 0.406112] dyndbg: 2 debug prints in module notification
[ 0.406447] dyndbg: 1 debug prints in module inotify_fsnotify
[ 0.407234] dyndbg: 5 debug prints in module inotify_user
[ 0.407448] dyndbg: 22 debug prints in module aio
[ 0.408221] dyndbg: 14 debug prints in module binfmt_misc
[ 0.408447] dyndbg: 2 debug prints in module mount
[ 0.409127] dyndbg: 1 debug prints in module dir
[ 0.409447] dyndbg: 32 debug prints in module autofs4
[ 0.410141] dyndbg: 1 debug prints in module debugfs
[ 0.410447] dyndbg: 1 debug prints in module tracefs
[ 0.411080] dyndbg: 11 debug prints in module selinux
[ 0.411447] dyndbg: 6 debug prints in module asymmetric_keys
[ 0.412295] dyndbg: 13 debug prints in module x509_key_parser
[ 0.412448] dyndbg: 21 debug prints in module pkcs7_message
[ 0.413252] dyndbg: 14 debug prints in module efi
[ 0.413447] dyndbg: 2 debug prints in module bsg
[ 0.414439] dyndbg: 1 debug prints in module percpu_refcount
[ 0.414448] dyndbg: 11 debug prints in module asn1_decoder
[ 0.415325] dyndbg: 1 debug prints in module decompress
[ 0.415447] dyndbg: 10 debug prints in module kobject
[ 0.416268] dyndbg: 6 debug prints in module kobject_uevent
[ 0.416447] dyndbg: 6 debug prints in module probe
[ 0.417267] dyndbg: 9 debug prints in module pci
[ 0.417447] dyndbg: 3 debug prints in module pci_driver
[ 0.418258] dyndbg: 2 debug prints in module setup_bus
[ 0.418447] dyndbg: 2 debug prints in module setup_irq
[ 0.419314] dyndbg: 4 debug prints in module pcieportdrv
[ 0.419448] dyndbg: 3 debug prints in module slot
[ 0.420182] dyndbg: 2 debug prints in module pci_acpi
[ 0.420448] dyndbg: 2 debug prints in module quirks
[ 0.421285] dyndbg: 1 debug prints in module pci_hotplug
[ 0.421447] dyndbg: 3 debug prints in module backlight
[ 0.422213] dyndbg: 1 debug prints in module fb
[ 0.422447] dyndbg: 7 debug prints in module tables
[ 0.423239] dyndbg: 89 debug prints in module acpi
[ 0.423465] dyndbg: 1 debug prints in module button
[ 0.424291] dyndbg: 3 debug prints in module fan
[ 0.424448] dyndbg: 1 debug prints in module video
[ 0.425451] dyndbg: 8 debug prints in module processor
[ 0.426405] dyndbg: 4 debug prints in module srat
[ 0.426449] dyndbg: 24 debug prints in module cppc_acpi
[ 0.427295] dyndbg: 7 debug prints in module pnp
[ 0.427447] dyndbg: 6 debug prints in module clk
[ 0.428223] dyndbg: 9 debug prints in module dmaengine
[ 0.428447] dyndbg: 2 debug prints in module virt_dma
[ 0.429254] dyndbg: 3 debug prints in module acpi_dma
[ 0.429447] dyndbg: 5 debug prints in module dw_dmac_core
[ 0.430364] dyndbg: 10 debug prints in module virtio_ring
[ 0.430447] dyndbg: 5 debug prints in module tty_io
[ 0.431225] dyndbg: 1 debug prints in module tty_jobctrl
[ 0.431447] dyndbg: 1 debug prints in module vt_ioctl
[ 0.432268] dyndbg: 4 debug prints in module serial_core
[ 0.432447] dyndbg: 4 debug prints in module 8250
[ 0.433246] dyndbg: 5 debug prints in module 8250_base
[ 0.433447] dyndbg: 8 debug prints in module 8250_pci
[ 0.434452] dyndbg: 1 debug prints in module 8250_exar
[ 0.435254] dyndbg: 2 debug prints in module virtio_console
[ 0.435447] dyndbg: 6 debug prints in module iommu
[ 0.436400] dyndbg: 2 debug prints in module dmar
[ 0.436447] dyndbg: 3 debug prints in module iommu
[ 0.437241] dyndbg: 1 debug prints in module drm
[ 0.437447] dyndbg: 19 debug prints in module vgaarb
[ 0.438350] dyndbg: 7 debug prints in module component
[ 0.438449] dyndbg: 16 debug prints in module core
[ 0.439245] dyndbg: 7 debug prints in module bus
[ 0.439450] dyndbg: 20 debug prints in module dd
[ 0.440184] dyndbg: 1 debug prints in module syscore
[ 0.440447] dyndbg: 5 debug prints in module class
[ 0.441198] dyndbg: 4 debug prints in module platform
[ 0.441447] dyndbg: 6 debug prints in module main
[ 0.442203] dyndbg: 1 debug prints in module wakeup
[ 0.442447] dyndbg: 6 debug prints in module clock_ops
[ 0.443391] dyndbg: 15 debug prints in module firmware_class
[ 0.443447] dyndbg: 1 debug prints in module regmap
[ 0.444227] dyndbg: 7 debug prints in module regcache
[ 0.444448] dyndbg: 1 debug prints in module regmap_debugfs
[ 0.445248] dyndbg: 1 debug prints in module dma_buf
[ 0.445448] dyndbg: 5 debug prints in module scsi_mod
[ 0.446454] dyndbg: 2 debug prints in module virtio_scsi
[ 0.447451] dyndbg: 98 debug prints in module cdrom
[ 0.448250] dyndbg: 14 debug prints in module pcmcia_core
[ 0.448449] dyndbg: 79 debug prints in module pcmcia
[ 0.449353] dyndbg: 2 debug prints in module pcmcia_rsrc
[ 0.449447] dyndbg: 13 debug prints in module yenta_socket
[ 0.450320] dyndbg: 153 debug prints in module usbcore
[ 0.450448] dyndbg: 10 debug prints in module pci_quirks
[ 0.451268] dyndbg: 53 debug prints in module ehci_hcd
[ 0.451447] dyndbg: 2 debug prints in module ehci_pci
[ 0.452336] dyndbg: 46 debug prints in module ohci_hcd
[ 0.452447] dyndbg: 11 debug prints in module uhci_hcd
[ 0.453253] dyndbg: 10 debug prints in module usb_storage
[ 0.453463] dyndbg: 6 debug prints in module libps2
[ 0.454370] dyndbg: 6 debug prints in module input_core
[ 0.454448] dyndbg: 11 debug prints in module ff_memless
[ 0.455385] dyndbg: 3 debug prints in module input_polldev
[ 0.455461] dyndbg: 5 debug prints in module atkbd
[ 0.456294] dyndbg: 27 debug prints in module psmouse
[ 0.456453] dyndbg: 11 debug prints in module rtc_core
[ 0.457263] dyndbg: 5 debug prints in module rtc_cmos
[ 0.457447] dyndbg: 1 debug prints in module i2c_boardinfo
[ 0.458203] dyndbg: 24 debug prints in module i2c_core
[ 0.458447] dyndbg: 2 debug prints in module i2c_smbus
[ 0.459317] dyndbg: 11 debug prints in module i2c_i801
[ 0.459450] dyndbg: 17 debug prints in module pps_core
[ 0.460334] dyndbg: 8 debug prints in module power_supply
[ 0.460452] dyndbg: 1 debug prints in module hwmon
[ 0.461361] dyndbg: 8 debug prints in module thermal_sys
[ 0.461448] dyndbg: 48 debug prints in module md_mod
[ 0.462303] dyndbg: 5 debug prints in module dm_mod
[ 0.462448] dyndbg: 43 debug prints in module cpufreq
[ 0.463268] dyndbg: 9 debug prints in module freq_table
[ 0.463448] dyndbg: 1 debug prints in module cpufreq_performance
[ 0.464447] dyndbg: 4 debug prints in module cpufreq_userspace
[ 0.465448] dyndbg: 23 debug prints in module acpi_cpufreq
[ 0.466434] dyndbg: 17 debug prints in module intel_pstate
[ 0.466449] dyndbg: 1 debug prints in module sysfs
[ 0.467364] dyndbg: 1 debug prints in module led_class
[ 0.467451] dyndbg: 2 debug prints in module dmi_scan
[ 0.468364] dyndbg: 4 debug prints in module esrt
[ 0.468451] dyndbg: 1 debug prints in module hid
[ 0.469273] dyndbg: 4 debug prints in module hid_pl
[ 0.469447] dyndbg: 1 debug prints in module hid_sony
[ 0.470307] dyndbg: 32 debug prints in module usbhid
[ 0.470447] dyndbg: 2 debug prints in module mailbox
[ 0.471297] dyndbg: 6 debug prints in module pcc
[ 0.471463] dyndbg: 1 debug prints in module nvmem_core
[ 0.472349] dyndbg: 3 debug prints in module snd
[ 0.472447] dyndbg: 1 debug prints in module snd_timer
[ 0.473406] dyndbg: 5 debug prints in module snd_pcm
[ 0.473449] dyndbg: 11 debug prints in module snd_seq
[ 0.474315] dyndbg: 19 debug prints in module snd_hda_codec
[ 0.474448] dyndbg: 12 debug prints in module snd_hda_intel
[ 0.475448] dyndbg: 16 debug prints in module snd_hda_core
[ 0.476447] dyndbg: 1 debug prints in module sock
[ 0.477309] dyndbg: 17 debug prints in module dev
[ 0.477447] dyndbg: 2 debug prints in module sch_api
[ 0.478271] dyndbg: 1 debug prints in module netfilter
[ 0.478450] dyndbg: 43 debug prints in module nf_conntrack
[ 0.479432] dyndbg: 1 debug prints in module nf_conntrack_netlink
[ 0.479451] dyndbg: 19 debug prints in module nf_conntrack_ftp
[ 0.480441] dyndbg: 4 debug prints in module nf_conntrack_irc
[ 0.480469] dyndbg: 2 debug prints in module nf_conntrack_sip
[ 0.481419] dyndbg: 4 debug prints in module nf_nat
[ 0.481447] dyndbg: 2 debug prints in module nf_nat_ftp
[ 0.482299] dyndbg: 1 debug prints in module nf_nat_irc
[ 0.482447] dyndbg: 2 debug prints in module x_tables
[ 0.483288] dyndbg: 4 debug prints in module xt_tcpudp
[ 0.483447] dyndbg: 3 debug prints in module route
[ 0.484234] dyndbg: 1 debug prints in module ip_fragment
[ 0.484447] dyndbg: 1 debug prints in module ip_output
[ 0.485346] dyndbg: 1 debug prints in module tcp
[ 0.485447] dyndbg: 6 debug prints in module tcp_input
[ 0.486335] dyndbg: 2 debug prints in module tcp_output
[ 0.486448] dyndbg: 3 debug prints in module tcp_timer
[ 0.487264] dyndbg: 1 debug prints in module tcp_ipv4
[ 0.487447] dyndbg: 1 debug prints in module tcp_cong
[ 0.488281] dyndbg: 1 debug prints in module tcp_rate
[ 0.488447] dyndbg: 1 debug prints in module tcp_recovery
[ 0.489337] dyndbg: 8 debug prints in module udp
[ 0.489448] dyndbg: 1 debug prints in module arp
[ 0.490308] dyndbg: 2 debug prints in module icmp
[ 0.490447] dyndbg: 1 debug prints in module devinet
[ 0.491223] dyndbg: 8 debug prints in module fib_trie
[ 0.491447] dyndbg: 27 debug prints in module ping
[ 0.492225] dyndbg: 1 debug prints in module sysctl_net_ipv4
[ 0.492447] dyndbg: 11 debug prints in module ipconfig
[ 0.493233] dyndbg: 1 debug prints in module tcp_cubic
[ 0.493449] dyndbg: 39 debug prints in module ipv6
[ 0.494276] dyndbg: 1 debug prints in module ah6
[ 0.494447] dyndbg: 1 debug prints in module esp6
[ 0.495152] dyndbg: 12 debug prints in module nf_defrag_ipv6
[ 0.495447] dyndbg: 6 debug prints in module nf_reject_ipv6
[ 0.496372] dyndbg: 2 debug prints in module sit
[ 0.496448] dyndbg: 2 debug prints in module ip6_checksum
[ 0.497306] dyndbg: 35 debug prints in module cfg80211
[ 0.497452] dyndbg: 6 debug prints in module mac80211
[ 0.498213] dyndbg: 2 debug prints in module i386
[ 0.498456] dyndbg: 2 debug prints in module fixup
[ 0.499187] dyndbg: 7 debug prints in module irq
[ 0.499448] dyndbg: 224 modules, 1994 entries and 8960 bytes in ddebug tables, 79760 bytes in __dyndbg section, 79760 bytes in __dyndbg_callsites section
[ 0.500447] dyndbg: 1994 entries. repeated entries: 1769 module 1646 file 799 func
[ 0.501449] dyndbg: virtme_link_mods="/home/jimc/projects/lx/linux.git/build-v2/.virtme_mods/lib/modules/0.0.0"
[ 0.502447] dyndbg: earlyprintk="serial,ttyS0,115200"
[ 0.503340] dyndbg: console="ttyS0"
[ 0.503448] dyndbg: psmouse.proto="exps"
[ 0.504154] dyndbg: virtme_stty_con="rows 25 cols 102 iutf8"
[ 0.504452] dyndbg: TERM="xterm-256color"
[ 0.505396] dyndbg: rootfstype="9p"
[ 0.505449] dyndbg: rootflags="version=9p2000.L,trans=virtio,access=any"
[ 0.506450] dyndbg: raid="noautodetect"
[ 0.507166] dyndbg: ro="(null)"
[ 0.507447] dyndbg: nokaslr="(null)"
[ 0.507995] dyndbg: dynamic_debug.verbose="3"
[ 0.508446] dyndbg: module.dyndbg="+pm"
[ 0.509067] dyndbg: query 0: "+pm"
[ 0.509446] dyndbg: split into words: "+pm"
[ 0.510144] dyndbg: op='+'
[ 0.510446] dyndbg: flags=0x3
[ 0.510898] dyndbg: *flagsp=0x3 *maskp=0xffffffff
[ 0.511447] dyndbg: parsed: func="" file="" module="module" format="" lineno=0-0
[ 0.512473] dyndbg: changed kernel/module.c:4001 [module]__do_sys_finit_module =pm
[ 0.513453] dyndbg: changed kernel/module.c:3980 [module]__do_sys_init_module =pm
[ 0.514452] dyndbg: changed kernel/module.c:3302 [module]move_module =pm
[ 0.515467] dyndbg: changed kernel/module.c:3284 [module]move_module =pm
[ 0.516419] dyndbg: changed kernel/module.c:2707 [module]layout_symtab =pm
[ 0.516452] dyndbg: changed kernel/module.c:2680 [module]layout_symtab =pm
[ 0.517410] dyndbg: changed kernel/module.c:2461 [module]layout_sections =pm
[ 0.517457] dyndbg: changed kernel/module.c:2448 [module]layout_sections =pm
[ 0.518471] dyndbg: changed kernel/module.c:2427 [module]layout_sections =pm
[ 0.519456] dyndbg: changed kernel/module.c:2415 [module]layout_sections =pm
[ 0.520458] dyndbg: changed kernel/module.c:2300 [module]simplify_symbols =pm
[ 0.521452] dyndbg: changed kernel/module.c:2292 [module]simplify_symbols =pm
[ 0.522452] dyndbg: changed kernel/module.c:1007 [module]__do_sys_delete_module =pm
[ 0.523454] dyndbg: changed kernel/module.c:901 [module]module_unload_free =pm
[ 0.524452] dyndbg: changed kernel/module.c:859 [module]add_module_usage =pm
[ 0.525452] dyndbg: changed kernel/module.c:844 [module]already_uses =pm
[ 0.526452] dyndbg: changed kernel/module.c:840 [module]already_uses =pm
[ 0.527451] dyndbg: changed kernel/module.c:608 [module]find_symbol =pm
[ 0.528449] dyndbg: applied: func="" file="" module="module" format="" lineno=0-0
[ 0.529461] dyndbg: processed 1 queries, with 18 matches, 0 errs
[ 0.530361] dyndbg: main.dyndbg="+pmf"
[ 0.530447] dyndbg: query 0: "+pmf"
[ 0.531105] dyndbg: split into words: "+pmf"
[ 0.531446] dyndbg: op='+'
[ 0.531876] dyndbg: flags=0x7
[ 0.532440] dyndbg: *flagsp=0x7 *maskp=0xffffffff
[ 0.532447] dyndbg: parsed: func="" file="" module="main" format="" lineno=0-0
[ 0.533416] dyndbg: changed drivers/base/power/main.c:445 [main]pm_dev_dbg =pmf
[ 0.533455] dyndbg: changed drivers/base/power/main.c:202 [main]device_pm_move_last =pmf
[ 0.534452] dyndbg: changed drivers/base/power/main.c:189 [main]device_pm_move_after =pmf
[ 0.535452] dyndbg: changed drivers/base/power/main.c:175 [main]device_pm_move_before =pmf
[ 0.536453] dyndbg: changed drivers/base/power/main.c:156 [main]device_pm_remove =pmf
[ 0.537452] dyndbg: changed drivers/base/power/main.c:135 [main]device_pm_add =pmf
[ 0.538449] dyndbg: changed init/main.c:1331 [main]run_init_process =pmf
[ 0.539386] dyndbg: changed init/main.c:1329 [main]run_init_process =pmf
[ 0.539447] dyndbg: changed init/main.c:1328 [main]run_init_process =pmf
[ 0.540435] dyndbg: changed init/main.c:1326 [main]run_init_process =pmf
[ 0.540448] dyndbg: changed init/main.c:1115 [main]initcall_blacklisted =pmf
[ 0.541447] dyndbg: changed init/main.c:1076 [main]initcall_blacklist =pmf
[ 0.542339] dyndbg: applied: func="" file="" module="main" format="" lineno=0-0
[ 0.542450] dyndbg: processed 1 queries, with 12 matches, 0 errs
[ 0.543451] dyndbg: init="/bin/sh"
[ 0.544127] smp: Bringing up secondary CPUs ...
[ 0.544449] smp: Brought up 1 node, 1 CPU
[ 0.545130] smpboot: Max logical packages: 1
[ 0.545449] smpboot: Total of 1 processors activated (5183.99 BogoMIPS)
[ 0.546839] devtmpfs: initialized
[ 0.548202] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.548452] futex hash table entries: 256 (order: 3, 32768 bytes, linear)
[ 0.549664] PM: RTC time: 00:46:43, date: 2020-08-07
[ 0.550466] thermal_sys: Registered thermal governor 'step_wise'
[ 0.550467] thermal_sys: Registered thermal governor 'user_space'
[ 0.551644] NET: Registered protocol family 16
[ 0.552713] audit: initializing netlink subsys (disabled)
[ 0.553532] audit: type=2000 audit(1596761203.662:1): state=initialized audit_enabled=0 res=1
[ 0.554971] cpuidle: using governor menu
[ 0.555555] ACPI: bus type PCI registered
[ 0.556412] PCI: Using configuration type 1 for base access
[ 0.568724] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.569690] cryptomgr_test (19) used greatest stack depth: 14840 bytes left
[ 0.572805] ACPI: Added _OSI(Module Device)
[ 0.573456] ACPI: Added _OSI(Processor Device)
[ 0.574110] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.574447] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.575295] ACPI: Added _OSI(Linux-Dell-Video)
[ 0.575506] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 0.576314] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 0.578310] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.580046] ACPI: Interpreter enabled
[ 0.580532] ACPI: (supports S0 S3 S4 S5)
[ 0.581240] ACPI: Using IOAPIC for interrupt routing
[ 0.581491] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.582716] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 0.592022] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.592457] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3]
[ 0.593500] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.594647] PCI host bridge to bus 0000:00
[ 0.595302] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.595457] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.596413] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.596448] pci_bus 0000:00: root bus resource [mem 0x08000000-0xfebfffff window]
[ 0.597447] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[ 0.598449] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.599388] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 0.600305] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 0.601408] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 0.603406] pci 0000:00:01.1: reg 0x20: [io 0xc080-0xc08f]
[ 0.604297] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.604449] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.605447] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.606447] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.607866] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 0.609461] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.610491] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.612002] pci 0000:00:02.0: [1af4:1009] type 00 class 0x000200
[ 0.613238] pci 0000:00:02.0: reg 0x10: [io 0xc000-0xc03f]
[ 0.614084] pci 0000:00:02.0: reg 0x14: [mem 0xfebfd000-0xfebfdfff]
[ 0.617136] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref]
[ 0.619337] pci 0000:00:03.0: [1af4:1009] type 00 class 0x000200
[ 0.620450] pci 0000:00:03.0: reg 0x10: [io 0xc040-0xc07f]
[ 0.622041] pci 0000:00:03.0: reg 0x14: [mem 0xfebfe000-0xfebfefff]
[ 0.625400] pci 0000:00:03.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref]
[ 0.626990] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[ 0.627717] pci 0000:00:04.0: reg 0x10: [mem 0xfebff000-0xfebff00f]
[ 0.631736] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.632626] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.633664] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.634766] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.635551] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.636710] iommu: Default domain type: Translated
[ 0.637656] vgaarb: loaded
[ 0.638518] SCSI subsystem initialized
[ 0.639275] ACPI: bus type USB registered
[ 0.639530] usbcore: registered new interface driver usbfs
[ 0.640305] usbcore: registered new interface driver hub
[ 0.640482] usbcore: registered new device driver usb
[ 0.641460] pps_core: LinuxPPS API ver. 1 registered
[ 0.642264] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@xxxxxxxx>
[ 0.642464] PTP clock support registered
[ 0.643299] Advanced Linux Sound Architecture Driver Initialized.
[ 0.644011] NetLabel: Initializing
[ 0.644447] NetLabel: domain hash size = 128
[ 0.645113] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.645484] NetLabel: unlabeled traffic allowed by default
[ 0.646506] PCI: Using ACPI for IRQ routing
[ 0.647581] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.648448] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[ 0.652478] clocksource: Switched to clocksource kvm-clock
[ 0.810605] VFS: Disk quotas dquot_6.6.0
[ 0.811509] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.812869] pnp: PnP ACPI init
[ 0.814458] pnp: PnP ACPI: found 5 devices
[ 0.824953] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 0.826824] NET: Registered protocol family 2
[ 0.828000] tcp_listen_portaddr_hash hash table entries: 64 (order: 0, 5632 bytes, linear)
[ 0.829351] TCP established hash table entries: 1024 (order: 1, 8192 bytes, linear)
[ 0.830688] TCP bind hash table entries: 1024 (order: 4, 81920 bytes, linear)
[ 0.831938] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.833143] UDP hash table entries: 256 (order: 3, 49152 bytes, linear)
[ 0.834457] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes, linear)
[ 0.835774] NET: Registered protocol family 1
[ 0.836796] RPC: Registered named UNIX socket transport module.
[ 0.837784] RPC: Registered udp transport module.
[ 0.838506] RPC: Registered tcp transport module.
[ 0.839492] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.841142] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 0.842085] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 0.842919] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 0.843899] pci_bus 0000:00: resource 7 [mem 0x08000000-0xfebfffff window]
[ 0.845022] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[ 0.846622] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 0.847617] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.848722] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 0.849796] PCI: CLS 0 bytes, default 64
[ 0.850789] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer
[ 0.852897] kvm: already loaded the other module
[ 0.853839] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x255cb518234, max_idle_ns: 440795279333 ns
[ 0.855861] check: Scanning for low memory corruption every 60 seconds
[ 0.858222] Initialise system trusted keyrings
[ 0.859187] workingset: timestamp_bits=56 max_order=15 bucket_order=0
[ 0.868673] zbud: loaded
[ 0.871222] NFS: Registering the id_resolver key type
[ 0.872176] Key type id_resolver registered
[ 0.872878] Key type id_legacy registered
[ 0.873688] 9p: Installing v9fs 9p2000 file system support
[ 0.882923] Key type asymmetric registered
[ 0.883786] Asymmetric key parser 'x509' registered
[ 0.884535] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.885639] io scheduler mq-deadline registered
[ 0.886292] io scheduler kyber registered
[ 0.887591] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 0.889220] ACPI: Power Button [PWRF]
[ 1.019699] PCI Interrupt Link [LNKB] enabled at IRQ 10
[ 1.148365] PCI Interrupt Link [LNKC] enabled at IRQ 11
[ 1.150685] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 1.152160] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 1.154491] Non-volatile memory driver v1.3
[ 1.155330] Linux agpgart interface v0.103
[ 1.173865] loop: module loaded
[ 1.176134] scsi host0: ata_piix
[ 1.177034] scsi host1: ata_piix
[ 1.177651] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc080 irq 14
[ 1.178648] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc088 irq 15
[ 1.180343] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.181262] ehci-pci: EHCI PCI platform driver
[ 1.181910] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1.183041] uhci_hcd: USB Universal Host Controller Interface driver
[ 1.184018] usbcore: registered new interface driver usb-storage
[ 1.184906] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 1.186830] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.187574] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 1.189138] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[ 1.191348] rtc_cmos 00:00: RTC can wake from S4
[ 1.193774] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[ 1.196269] rtc_cmos 00:00: registered as rtc0
[ 1.197262] rtc_cmos 00:00: setting system clock to 2020-08-07T00:46:44 UTC (1596761204)
[ 1.198746] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[ 1.200734] i6300ESB timer 0000:00:04.0: initialized. heartbeat=30 sec (nowayout=0)
[ 1.202299] device-mapper: ioctl: 4.42.0-ioctl (2020-02-27) initialised: dm-devel@xxxxxxxxxx
[ 1.203681] intel_pstate: CPU model not supported
[ 1.204836] hid: raw HID events driver (C) Jiri Kosina
[ 1.206965] usbcore: registered new interface driver usbhid
[ 1.208054] usbhid: USB HID core driver
[ 1.210148] Initializing XFRM netlink socket
[ 1.211744] NET: Registered protocol family 10
[ 1.215494] Segment Routing with IPv6
[ 1.216481] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 1.217919] NET: Registered protocol family 17
[ 1.218669] 9pnet: Installing 9P2000 support
[ 1.220942] Key type dns_resolver registered
[ 1.221784] IPI shorthand broadcast: enabled
[ 1.222360] sched_clock: Marking stable (974790714, 246701357)->(1352607600, -131115529)
[ 1.223874] registered taskstats version 1
[ 1.224404] Loading compiled-in X.509 certificates
[ 1.225739] zswap: loaded using pool zstd/zsmalloc
[ 1.271317] dyndbg: total pages: 24 compaction: 0
[ 1.350122] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[ 1.357252] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
[ 1.364303] BUG: sleeping function called from invalid context at mm/slab.h:567
[ 1.368292] in_atomic(): 1, irqs_disabled(): 0, non_block: 0, pid: 7, name: kworker/u2:0
[ 1.371245] 4 locks held by kworker/u2:0/7:
[ 1.372739] #0: ffff888007412948 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x1f5/0x5c0
[ 1.375712] #1: ffffc90000043e58 ((work_completion)(&entry->work)){+.+.}-{0:0}, at: process_one_work+0x1f5/0x5c0
[ 1.378357] #2: ffff8880061500f0 (&shost->scan_mutex){+.+.}-{4:4}, at: __scsi_add_device+0x7d/0x120
[ 1.380376] #3: ffff8880063b40b0 (&zspage->lock){.+.+}-{3:3}, at: zs_map_object+0x7b/0x2b0
[ 1.382161] CPU: 0 PID: 7 Comm: kworker/u2:0 Not tainted 5.8.0-00025-g4e76f4427bf8 #30
[ 1.383739] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.13.0-2.fc32 04/01/2014
[ 1.385230] Workqueue: events_unbound async_run_entry_fn
[ 1.386137] Call Trace:
[ 1.386587] dump_stack+0x81/0xba
[ 1.387183] ___might_sleep.cold+0xa6/0xb7
[ 1.387887] __might_sleep+0x46/0x80
[ 1.388511] __kmalloc_track_caller+0x177/0x200
[ 1.389183] kstrdup_const+0x45/0x70
[ 1.389713] __kernfs_new_node+0x4b/0x280
[ 1.390331] ? dynamic_emit_prefix+0x1da/0x1f0
[ 1.391004] ? __dynamic_pr_debug+0x8a/0xb0
[ 1.391697] kernfs_create_dir_ns+0x44/0xc0
[ 1.392366] sysfs_create_dir_ns+0x6c/0xd0
[ 1.392971] kobject_add_internal+0x102/0x300
[ 1.393627] kobject_add+0x7e/0xb0
[ 1.394112] ? device_links_flush_sync_list+0xf0/0xf0
[ 1.394805] ? get_device+0x20/0x20
[ 1.395334] device_add+0x123/0x810
[ 1.395843] ? scsi_attach_vpd+0xfb/0x110
[ 1.396412] scsi_sysfs_add_sdev+0x160/0x290
[ 1.396997] scsi_probe_and_add_lun+0x83f/0xbe0
[ 1.397659] __scsi_add_device+0x10a/0x120
[ 1.398268] ata_scsi_scan_host+0x98/0x1d0
[ 1.398811] ? wait_woken+0xb0/0xb0
[ 1.399284] async_port_probe+0x56/0x70
[ 1.399799] async_run_entry_fn+0x37/0x150
[ 1.400372] process_one_work+0x273/0x5c0
[ 1.400891] worker_thread+0x50/0x400
[ 1.401412] kthread+0x14c/0x170
[ 1.401830] ? process_one_work+0x5c0/0x5c0
[ 1.402411] ? kthread_create_worker_on_cpu+0x60/0x60
[ 1.403185] ret_from_fork+0x22/0x30
[ 1.403779]
[ 1.403983] =============================
[ 1.404536] [ BUG: Invalid wait context ]
[ 1.405132] 5.8.0-00025-g4e76f4427bf8 #30 Tainted: G W
[ 1.406196] -----------------------------
[ 1.406847] kworker/u2:0/7 is trying to lock:
[ 1.407513] ffffffff82cb6b70 (iattr_mutex){+.+.}-{4:4}, at: kernfs_xattr_get+0x25/0x60
[ 1.408997] other info that might help us debug this:
[ 1.409780] context-{5:5}
[ 1.410276] 4 locks held by kworker/u2:0/7:
[ 1.410924] #0: ffff888007412948 ((wq_completion)events_unbound){+.+.}-{0:0}, at: process_one_work+0x1f5/0x5c0
[ 1.412649] #1: ffffc90000043e58 ((work_completion)(&entry->work)){+.+.}-{0:0}, at: process_one_work+0x1f5/0x5c0
[ 1.414238] #2: ffff8880061500f0 (&shost->scan_mutex){+.+.}-{4:4}, at: __scsi_add_device+0x7d/0x120
[ 1.415713] #3: ffff8880063b40b0 (&zspage->lock){.+.+}-{3:3}, at: zs_map_object+0x7b/0x2b0
[ 1.417024] stack backtrace:
[ 1.417468] CPU: 0 PID: 7 Comm: kworker/u2:0 Tainted: G W 5.8.0-00025-g4e76f4427bf8 #30
[ 1.419019] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.13.0-2.fc32 04/01/2014
[ 1.420344] Workqueue: events_unbound async_run_entry_fn
[ 1.421154] Call Trace:
[ 1.421578] dump_stack+0x81/0xba
[ 1.422123] __lock_acquire.cold+0xc8/0x2d0
[ 1.422709] ? noop_count+0x10/0x10
[ 1.423207] ? __bfs+0xf4/0x210
[ 1.423713] lock_acquire+0xa2/0x3e0
[ 1.424358] ? kernfs_xattr_get+0x25/0x60
[ 1.425109] ? ___might_sleep+0x15c/0x190
[ 1.425777] __mutex_lock+0x8e/0x930
[ 1.426362] ? kernfs_xattr_get+0x25/0x60
[ 1.426948] ? __lock_acquire+0x1285/0x20e0
[ 1.427623] ? kernfs_xattr_get+0x25/0x60
[ 1.428183] mutex_lock_nested+0x16/0x20
[ 1.429053] kernfs_xattr_get+0x25/0x60
[ 1.429695] selinux_kernfs_init_security+0x5c/0x210
[ 1.430475] ? sched_clock+0x9/0x10
[ 1.431012] ? sched_clock_cpu+0x11/0xc0
[ 1.432023] security_kernfs_init_security+0x2a/0x40
[ 1.432866] __kernfs_new_node+0x1b7/0x280
[ 1.433500] ? dynamic_emit_prefix+0x1da/0x1f0
[ 1.434231] ? __dynamic_pr_debug+0x8a/0xb0
[ 1.434852] kernfs_create_dir_ns+0x44/0xc0
[ 1.435586] sysfs_create_dir_ns+0x6c/0xd0
[ 1.436254] kobject_add_internal+0x102/0x300
[ 1.436970] kobject_add+0x7e/0xb0
[ 1.437515] ? device_links_flush_sync_list+0xf0/0xf0
[ 1.438445] ? get_device+0x20/0x20
[ 1.438965] device_add+0x123/0x810
[ 1.439573] ? scsi_attach_vpd+0xfb/0x110
[ 1.440377] scsi_sysfs_add_sdev+0x160/0x290
[ 1.441038] scsi_probe_and_add_lun+0x83f/0xbe0
[ 1.441981] __scsi_add_device+0x10a/0x120
[ 1.442519] ata_scsi_scan_host+0x98/0x1d0
[ 1.443125] ? wait_woken+0xb0/0xb0
[ 1.443630] async_port_probe+0x56/0x70
[ 1.444163] async_run_entry_fn+0x37/0x150
[ 1.444687] process_one_work+0x273/0x5c0
[ 1.445242] worker_thread+0x50/0x400
[ 1.445739] kthread+0x14c/0x170
[ 1.446207] ? process_one_work+0x5c0/0x5c0
[ 1.446745] ? kthread_create_worker_on_cpu+0x60/0x60
[ 1.447494] ret_from_fork+0x22/0x30
[ 1.448107] main:device_pm_add: PM: Adding info for scsi:target1:0:0
[ 1.449033] kobject: 'target1:0:0' (00000000e397c75d): kobject_uevent_env
[ 1.449990] kobject: 'target1:0:0' (00000000e397c75d): fill_kobj_path: path = '/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0'
[ 1.451753] kobject: '1:0:0:0' (00000000a54577f2): kobject_add_internal: parent: 'target1:0:0', set: 'devices'
[ 1.453131] main:device_pm_add: PM: Adding info for scsi:1:0:0:0
[ 1.453999] kobject: '1:0:0:0' (00000000a54577f2): kobject_uevent_env
[ 1.454998] kobject: '1:0:0:0' (00000000a54577f2): fill_kobj_path: path = '/devices/pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0'
[ 1.456836] BUG: scheduling while atomic: kworker/u2:0/7/0x00000011
[ 1.457715] INFO: lockdep is turned off.
[ 1.458651] Modules linked in:
[ 1.459134] CPU: 0 PID: 7 Comm: kworker/u2:0 Tainted: G W 5.8.0-00025-g4e76f4427bf8 #30
[ 1.460629] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.13.0-2.fc32 04/01/2014
[ 1.462015] Workqueue: events_unbound async_run_entry_fn
[ 1.463068] Call Trace:
[ 1.463470] dump_stack+0x81/0xba
[ 1.464030] __schedule_bug.cold+0x5f/0x70
[ 1.464812] __schedule+0x80b/0x9a0
[ 1.465477] ? wait_for_completion_io+0x7e/0x110
[ 1.466188] schedule+0x69/0xe0
[ 1.466888] schedule_timeout+0xe0/0x120
[ 1.467514] ? __blk_mq_delay_run_hw_queue+0x16d/0x180
[ 1.468363] ? _raw_spin_unlock_irq+0x23/0x40
[ 1.469057] ? trace_hardirqs_on+0x2b/0xf0
[ 1.469709] io_schedule_timeout+0x4b/0x80
[ 1.470405] wait_for_completion_io+0xa9/0x110
[ 1.471149] blk_execute_rq+0x7f/0xb0
[ 1.471748] __scsi_execute+0x109/0x260
[ 1.472426] scsi_test_unit_ready+0x5b/0xe0
[ 1.473097] sr_probe+0x23b/0x600
[ 1.473608] ? kernfs_create_link+0x63/0xa0
[ 1.474256] really_probe+0x27b/0x410
[ 1.474866] driver_probe_device+0xe4/0x150
[ 1.475633] __device_attach_driver+0x6c/0xd0
[ 1.476351] ? driver_allows_async_probing+0x50/0x50
[ 1.477091] bus_for_each_drv+0x82/0xd0
[ 1.477686] __device_attach+0x111/0x1b0
[ 1.478352] device_initial_probe+0xe/0x10
[ 1.478976] bus_probe_device+0x9b/0xb0
[ 1.479601] device_add+0x3a8/0x810
[ 1.480197] scsi_sysfs_add_sdev+0x91/0x290
[ 1.480856] scsi_probe_and_add_lun+0x83f/0xbe0
[ 1.481630] __scsi_add_device+0x10a/0x120
[ 1.482328] ata_scsi_scan_host+0x98/0x1d0
[ 1.482955] ? wait_woken+0xb0/0xb0
[ 1.483459] async_port_probe+0x56/0x70
[ 1.484023] async_run_entry_fn+0x37/0x150
[ 1.484796] process_one_work+0x273/0x5c0
[ 1.485604] worker_thread+0x50/0x400
[ 1.486303] kthread+0x14c/0x170
[ 1.486858] ? process_one_work+0x5c0/0x5c0
[ 1.487708] ? kthread_create_worker_on_cpu+0x60/0x60
[ 1.488631] ret_from_fork+0x22/0x30
QEMU 4.2.1 monitor - type 'help' for more information
(qemu) dump-guest-memory -p dump.p
(qemu) q
[jimc@frodo build-v2]$ gdb vmlinux dump.p
GNU gdb (GDB) Fedora 9.1-5.fc32
...
Reading symbols from vmlinux...
[New LWP 1]
1271 for (fn = initcall_levels[level]; fn < initcall_levels[level+1]; fn++)
(gdb) bt
Signed-off-by: Jim Cromie <jim.cromie@xxxxxxxxx>
---
lib/dynamic_debug.c | 4 +++-
1 file changed, 3 insertions(+), 1 deletion(-)
diff --git a/lib/dynamic_debug.c b/lib/dynamic_debug.c
index 701d3d1fb7e7..38f7107699e9 100644
--- a/lib/dynamic_debug.c
+++ b/lib/dynamic_debug.c
@@ -172,7 +172,9 @@ static void ddebug_callsite_put(struct _ddebug *dp)
/* no site to unmap, or no means to restore */
return;
- /* always unmap for now. if !pr-debug was too hard */
+ if (dp->flags & _DPRINTK_FLAGS_PRINT)
+ return; /* keep maps of enabled pr_debugs */
+
zs_unmap_object(dd_callsite_zpool, dp->zhandle);
dp->site = NULL;
}
--
2.26.2