[PATCH 7/7] dyndbg: enable 'cache' of active pr_debug callsites

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



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





[Index of Archives]     [Linux Input]     [Video for Linux]     [Gstreamer Embedded]     [Mplayer Users]     [Linux USB Devel]     [Linux Audio Users]     [Linux Kernel]     [Linux SCSI]     [Yosemite Backpacking]

  Powered by Linux