[PATCH] nvme: fix use-after-free during booting

Tong Zhang ztong0001 at gmail.com
Wed Sep 23 12:32:14 EDT 2020


here you go

[    0.000000] Linux version 5.9.0-rc4+ (tong at tong-desktop) (gcc
(Ubuntu 9.3.0-10ubuntu2) 9.3.0, GNU ld (GNU Binutils for Ubuntu) 2.34)
#44 SMP Wed Sep 23 12:15:34 EDT 2020
[    0.000000] Command line: console=ttyS0 root=/dev/sda
earlyprintk=serial biosdevname=0 net.ifnames=0 loglevel=7
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    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-0x000000007ffd6fff] usable
[    0.000000] BIOS-e820: [mem 0x000000007ffd7000-0x000000007fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000017fffffff] usable
[    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 (Q35 + ICH9, 2009), BIOS
rel-1.13.0-48-gd9c812dda519-prebuilt.qemu.org 04/01/2014
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] tsc: Detected 3000.019 MHz processor
[    0.018848] last_pfn = 0x180000 max_arch_pfn = 0x400000000
[    0.020004] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT
[    0.020455] last_pfn = 0x7ffd7 max_arch_pfn = 0x400000000
[    0.033611] found SMP MP-table at [mem 0x000f5a90-0x000f5a9f]
[    0.035100] check: Scanning 1 areas for low memory corruption
[    0.046212] ACPI: Early table checksum verification disabled
[    0.046681] ACPI: RSDP 0x00000000000F5850 000014 (v00 BOCHS )
[    0.047095] ACPI: RSDT 0x000000007FFE20C2 000038 (v01 BOCHS
BXPCRSDT 00000001 BXPC 00000001)
[    0.047953] ACPI: FACP 0x000000007FFE1EB2 0000F4 (v03 BOCHS
BXPCFACP 00000001 BXPC 00000001)
[    0.048792] ACPI: DSDT 0x000000007FFE0040 001E72 (v01 BOCHS
BXPCDSDT 00000001 BXPC 00000001)
[    0.049074] ACPI: FACS 0x000000007FFE0000 000040
[    0.049256] ACPI: APIC 0x000000007FFE1FA6 000080 (v01 BOCHS
BXPCAPIC 00000001 BXPC 00000001)
[    0.049501] ACPI: HPET 0x000000007FFE2026 000038 (v01 BOCHS
BXPCHPET 00000001 BXPC 00000001)
[    0.049724] ACPI: MCFG 0x000000007FFE205E 00003C (v01 BOCHS
BXPCMCFG 00000001 BXPC 00000001)
[    0.049892] ACPI: WAET 0x000000007FFE209A 000028 (v01 BOCHS
BXPCWAET 00000001 BXPC 00000001)
[    0.058109] No NUMA configuration found
[    0.058193] Faking a node at [mem 0x0000000000000000-0x000000017fffffff]
[    0.059024] NODE_DATA(0) allocated [mem 0x17fffa000-0x17fffdfff]
[    0.061178] Zone ranges:
[    0.061271]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.061393]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.061481]   Normal   [mem 0x0000000100000000-0x000000017fffffff]
[    0.061571] Movable zone start for each node
[    0.061654] Early memory node ranges
[    0.061735]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.061939]   node   0: [mem 0x0000000000100000-0x000000007ffd6fff]
[    0.062056]   node   0: [mem 0x0000000100000000-0x000000017fffffff]
[    0.062963] Zeroed struct page in unavailable ranges: 139 pages
[    0.063146] Initmem setup node 0 [mem 0x0000000000001000-0x000000017fffffff]
[    0.701550] kasan: KernelAddressSanitizer initialized
[    0.702094] ACPI: PM-Timer IO Port: 0x608
[    0.702932] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.703636] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[    0.704018] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.704487] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.704656] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.704900] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.705041] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.705464] Using ACPI (MADT) for SMP configuration information
[    0.705676] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.706244] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.707373] PM: hibernation: Registered nosave memory: [mem
0x00000000-0x00000fff]
[    0.707547] PM: hibernation: Registered nosave memory: [mem
0x0009f000-0x0009ffff]
[    0.707729] PM: hibernation: Registered nosave memory: [mem
0x000a0000-0x000effff]
[    0.707837] PM: hibernation: Registered nosave memory: [mem
0x000f0000-0x000fffff]
[    0.708039] PM: hibernation: Registered nosave memory: [mem
0x7ffd7000-0x7fffffff]
[    0.708162] PM: hibernation: Registered nosave memory: [mem
0x80000000-0xafffffff]
[    0.708345] PM: hibernation: Registered nosave memory: [mem
0xb0000000-0xbfffffff]
[    0.708439] PM: hibernation: Registered nosave memory: [mem
0xc0000000-0xfed1bfff]
[    0.708536] PM: hibernation: Registered nosave memory: [mem
0xfed1c000-0xfed1ffff]
[    0.708666] PM: hibernation: Registered nosave memory: [mem
0xfed20000-0xfffbffff]
[    0.708758] PM: hibernation: Registered nosave memory: [mem
0xfffc0000-0xffffffff]
[    0.708962] [mem 0xc0000000-0xfed1bfff] available for PCI devices
[    0.709470] clocksource: refined-jiffies: mask: 0xffffffff
max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.803522] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64
nr_cpu_ids:2 nr_node_ids:1
[    0.805530] percpu: Embedded 60 pages/cpu s205720 r8192 d31848 u1048576
[    0.808361] Built 1 zonelists, mobility grouping on.  Total pages: 1032032
[    0.808462] Policy zone: Normal
[    0.808700] Kernel command line: console=ttyS0 root=/dev/sda
earlyprintk=serial biosdevname=0 net.ifnames=0 loglevel=7
[    0.812697] Dentry cache hash table entries: 524288 (order: 10,
4194304 bytes, linear)
[    0.813462] Inode-cache hash table entries: 262144 (order: 9,
2097152 bytes, linear)
[    0.815216] mem auto-init: stack:off, heap alloc:off, heap free:off
[    1.271674] Memory: 3464648K/4193748K available (22532K kernel
code, 6139K rwdata, 6640K rodata, 1516K init, 10404K bss, 728844K
reserved, 0K cma-reserved)
[    1.279413] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    1.291083] rcu: Hierarchical RCU implementation.
[    1.291173] rcu: RCU event tracing is enabled.
[    1.291295] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=2.
[    1.291623] rcu: RCU calculated value of scheduler-enlistment delay
is 100 jiffies.
[    1.291799] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    1.327149] NR_IRQS: 4352, nr_irqs: 440, preallocated irqs: 16
[    1.338865] random: get_random_bytes called from
start_kernel+0x1cf/0x37a with crng_init=0
[    1.344653] Console: colour VGA+ 80x25
[    1.346548] printk: console [ttyS0] enabled
[    1.346548] printk: console [ttyS0] enabled
[    1.347088] printk: bootconsole [earlyser0] disabled
[    1.347088] printk: bootconsole [earlyser0] disabled
[    1.348428] ACPI: Core revision 20200717
[    1.357694] clocksource: hpet: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 19112604467 ns
[    1.362207] APIC: Switch to symmetric I/O mode setup
[    1.371293] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    1.377155] tsc: Marking TSC unstable due to TSCs unsynchronized
[    1.377556] Calibrating delay loop (skipped), value calculated
using timer frequency.. 6000.03 BogoMIPS (lpj=3000019)
[    1.378469] pid_max: default: 32768 minimum: 301
[    1.380491] LSM: Security Framework initializing
[    1.381841] SELinux:  Initializing.
[    1.384149] Mount-cache hash table entries: 8192 (order: 4, 65536
bytes, linear)
[    1.384394] Mountpoint-cache hash table entries: 8192 (order: 4,
65536 bytes, linear)
Poking KASLR using RDTSC...
[    1.419131] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    1.419247] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    1.419639] Spectre V1 : Mitigation: usercopy/swapgs barriers and
__user pointer sanitization
[    1.419916] Spectre V2 : Mitigation: Full AMD retpoline
[    1.420019] Spectre V2 : Spectre v2 / SpectreRSB mitigation:
Filling RSB on context switch
[    1.420241] Speculative Store Bypass: Vulnerable
[    1.425424] Freeing SMP alternatives memory: 40K
[    1.542125] smpboot: CPU0: AMD QEMU Virtual CPU version 2.5+
(family: 0x6, model: 0x6, stepping: 0x3)
[    1.551683] Performance Events: PMU not available due to
virtualization, using software events only.
[    1.554388] rcu: Hierarchical SRCU implementation.
[    1.562103] smp: Bringing up secondary CPUs ...
[    1.568823] x86: Booting SMP configuration:
[    1.568992] .... node  #0, CPUs:      #1
[    0.032775] smpboot: CPU 1 Converting physical 0 to logical die 1
[    1.651665] smp: Brought up 1 node, 2 CPUs
[    1.651809] smpboot: Max logical packages: 2
[    1.651991] smpboot: Total of 2 processors activated (12000.87 BogoMIPS)
[    1.674416] devtmpfs: initialized
[    1.711999] clocksource: jiffies: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 1911260446275000 ns
[    1.712326] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
[    1.719341] PM: RTC time: 16:16:07, date: 2020-09-23
[    1.728949] NET: Registered protocol family 16
[    1.742597] audit: initializing netlink subsys (disabled)
[    1.745132] audit: type=2000 audit(1600877767.378:1):
state=initialized audit_enabled=0 res=1
[    1.759312] thermal_sys: Registered thermal governor 'step_wise'
[    1.759394] thermal_sys: Registered thermal governor 'user_space'
[    1.763867] cpuidle: using governor menu
[    1.766834] ACPI: bus type PCI registered
[    1.773341] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem
0xb0000000-0xbfffffff] (base 0xb0000000)
[    1.773756] PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved in E820
[    1.774782] PCI: Using configuration type 1 for base access
[    1.782850] mtrr: your CPUs had inconsistent fixed MTRR settings
[    1.782958] mtrr: your CPUs had inconsistent variable MTRR settings
[    1.783074] mtrr: your CPUs had inconsistent MTRRdefType settings
[    1.783239] mtrr: probably your BIOS does not setup all CPUs.
[    1.783410] mtrr: corrected configuration.
[    2.176889] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    2.183579] cryptomgr_test (25) used greatest stack depth: 30520 bytes left
[    2.219797] ACPI: Added _OSI(Module Device)
[    2.219911] ACPI: Added _OSI(Processor Device)
[    2.220008] ACPI: Added _OSI(3.0 _SCP Extensions)
[    2.220088] ACPI: Added _OSI(Processor Aggregator Device)
[    2.220512] ACPI: Added _OSI(Linux-Dell-Video)
[    2.220837] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    2.221156] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    2.731984] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    2.836494] ACPI: Interpreter enabled
[    2.838956] ACPI: (supports S0 S3 S4 S5)
[    2.839080] ACPI: Using IOAPIC for interrupt routing
[    2.842794] PCI: Using host bridge windows from ACPI; if necessary,
use "pci=nocrs" and report a bug
[    2.867957] ACPI: Enabled 1 GPEs in block 00 to 3F
[    3.437031] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    3.438076] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM
ClockPM Segments MSI HPX-Type3]
[    3.476399] acpi PNP0A08:00: _OSC: platform does not support [LTR]
[    3.512278] acpi PNP0A08:00: _OSC: OS now controls [PME PCIeCapability]
[    3.523269] PCI host bridge to bus 0000:00
[    3.523647] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    3.523959] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    3.524203] pci_bus 0000:00: root bus resource [mem
0x000a0000-0x000bffff window]
[    3.524586] pci_bus 0000:00: root bus resource [mem
0x80000000-0xafffffff window]
[    3.524959] pci_bus 0000:00: root bus resource [mem
0xc0000000-0xfebfffff window]
[    3.525429] pci_bus 0000:00: root bus resource [mem
0x180000000-0x97fffffff window]
[    3.525818] pci_bus 0000:00: root bus resource [bus 00-ff]
[    3.698109] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    3.717283] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    3.736258] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    3.754958] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    3.773476] ACPI: PCI Interrupt Link [LNKE] (IRQs 5 *10 11)
[    3.791995] ACPI: PCI Interrupt Link [LNKF] (IRQs 5 *10 11)
[    3.810534] ACPI: PCI Interrupt Link [LNKG] (IRQs 5 10 *11)
[    3.829014] ACPI: PCI Interrupt Link [LNKH] (IRQs 5 10 *11)
[    3.833555] ACPI: PCI Interrupt Link [GSIA] (IRQs *16)
[    3.836669] ACPI: PCI Interrupt Link [GSIB] (IRQs *17)
[    3.839748] ACPI: PCI Interrupt Link [GSIC] (IRQs *18)
[    3.842872] ACPI: PCI Interrupt Link [GSID] (IRQs *19)
[    3.845839] ACPI: PCI Interrupt Link [GSIE] (IRQs *20)
[    3.848854] ACPI: PCI Interrupt Link [GSIF] (IRQs *21)
[    3.851898] ACPI: PCI Interrupt Link [GSIG] (IRQs *22)
[    3.854897] ACPI: PCI Interrupt Link [GSIH] (IRQs *23)
[    3.882897] iommu: Default domain type: Translated
[    3.888782] pci 0000:00:01.0: vgaarb: setting as boot VGA device
[    3.889047] pci 0000:00:01.0: vgaarb: VGA device added:
decodes=io+mem,owns=io+mem,locks=none
[    3.889388] pci 0000:00:01.0: vgaarb: bridge control possible
[    3.889616] vgaarb: loaded
[    3.903519] SCSI subsystem initialized
[    3.909875] ACPI: bus type USB registered
[    3.912380] usbcore: registered new interface driver usbfs
[    3.913743] usbcore: registered new interface driver hub
[    3.914935] usbcore: registered new device driver usb
[    3.917480] pps_core: LinuxPPS API ver. 1 registered
[    3.917575] pps_core: Software ver. 5.3.6 - Copyright 2005-2007
Rodolfo Giometti <giometti at linux.it>
[    3.918217] PTP clock support registered
[    3.925205] Advanced Linux Sound Architecture Driver Initialized.
[    3.947695] NetLabel: Initializing
[    3.947781] NetLabel:  domain hash size = 128
[    3.947988] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    3.950552] NetLabel:  unlabeled traffic allowed by default
[    3.953389] PCI: Using ACPI for IRQ routing
[    3.960039] hpet: 3 channels of 0 reserved for per-cpu timers
[    3.960521] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    3.960716] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    3.966095] clocksource: Switched to clocksource hpet
[    7.088881] VFS: Disk quotas dquot_6.6.0
[    7.089663] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    7.096833] pnp: PnP ACPI init
[    7.181116] pnp: PnP ACPI: found 5 devices
[    7.378570] clocksource: acpi_pm: mask: 0xffffff max_cycles:
0xffffff, max_idle_ns: 2085701024 ns
[    7.380592] NET: Registered protocol family 2
[    7.396198] tcp_listen_portaddr_hash hash table entries: 2048
(order: 3, 32768 bytes, linear)
[    7.397095] TCP established hash table entries: 32768 (order: 6,
262144 bytes, linear)
[    7.399676] TCP bind hash table entries: 32768 (order: 7, 524288
bytes, linear)
[    7.401299] TCP: Hash tables configured (established 32768 bind 32768)
[    7.404132] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    7.404829] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    7.409931] NET: Registered protocol family 1
[    7.421356] RPC: Registered named UNIX socket transport module.
[    7.421593] RPC: Registered udp transport module.
[    7.421684] RPC: Registered tcp transport module.
[    7.421766] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    7.431009] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    7.431191] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    7.431324] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    7.431597] pci_bus 0000:00: resource 7 [mem 0x80000000-0xafffffff window]
[    7.431740] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window]
[    7.431910] pci_bus 0000:00: resource 9 [mem 0x180000000-0x97fffffff window]
[    7.435141] pci 0000:00:01.0: Video device with shadowed ROM at
[mem 0x000c0000-0x000dffff]
[    7.435845] PCI: CLS 0 bytes, default 64
[    7.444128] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    7.444290] software IO TLB: mapped [mem 0x7bfd7000-0x7ffd7000] (64MB)
[    7.461798] check: Scanning for low memory corruption every 60 seconds
[    7.490019] Initialise system trusted keyrings
[    7.493165] workingset: timestamp_bits=56 max_order=20 bucket_order=0
[    7.866607] NFS: Registering the id_resolver key type
[    7.867330] Key type id_resolver registered
[    7.867656] Key type id_legacy registered
[    7.912645] Key type asymmetric registered
[    7.912865] Asymmetric key parser 'x509' registered
[    7.914010] Block layer SCSI generic (bsg) driver version 0.4
loaded (major 251)
[    7.914396] io scheduler mq-deadline registered
[    7.914791] io scheduler kyber registered
[    7.938035] input: Power Button as
/devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    7.944873] ACPI: Power Button [PWRF]
[    7.959065] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    7.962682] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200)
is a 16550A
[    7.995795] Non-volatile memory driver v1.3
[    7.998278] Linux agpgart interface v0.103
[    8.216616] loop: module loaded
[    8.241363] nvme nvme0: pci function 0000:00:03.0
[    8.458066] PCI Interrupt Link [GSIH] enabled at IRQ 23
[    8.493743] PCI Interrupt Link [GSIA] enabled at IRQ 16
[    8.497349] nvme nvme0: 2/0/0 default/read/poll queues
[    8.504162] ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5
Gbps 0x3f impl SATA mode
[    8.504417] ahci 0000:00:1f.2: flags: 64bit ncq only
[    8.576699] scsi host0: ahci
[    8.593871] scsi host1: ahci
[    8.606578] scsi host2: ahci
[    8.619111] scsi host3: ahci
[    8.631667] scsi host4: ahci
[    8.644451] scsi host5: ahci
[    8.652301] ata1: SATA max UDMA/133 abar m4096 at 0xfebd8000 port
0xfebd8100 irq 27
[    8.652628] ata2: SATA max UDMA/133 abar m4096 at 0xfebd8000 port
0xfebd8180 irq 27
[    8.652770] ata3: SATA max UDMA/133 abar m4096 at 0xfebd8000 port
0xfebd8200 irq 27
[    8.652903] ata4: SATA max UDMA/133 abar m4096 at 0xfebd8000 port
0xfebd8280 irq 27
[    8.653037] ata5: SATA max UDMA/133 abar m4096 at 0xfebd8000 port
0xfebd8300 irq 27
[    8.653199] ata6: SATA max UDMA/133 abar m4096 at 0xfebd8000 port
0xfebd8380 irq 27
[    8.672358] e100: Intel(R) PRO/100 Network Driver
[    8.672528] e100: Copyright(c) 1999-2006 Intel Corporation
[    8.673309] e1000: Intel(R) PRO/1000 Network Driver
[    8.673473] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    8.674784] e1000e: Intel(R) PRO/1000 Network Driver
[    8.674892] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[    8.826920] PCI Interrupt Link [GSIG] enabled at IRQ 22
[    8.830832] e1000e 0000:00:02.0: Interrupt Throttling Rate
(ints/sec) set to dynamic conservative mode
[    8.906026] e1000e 0000:00:02.0 0000:00:02.0 (uninitialized):
registered PHC clock
[    8.969184] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    8.978842] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    8.980179] e1000e 0000:00:02.0 eth0: (PCI Express:2.5GT/s:Width
x1) 52:54:00:12:34:56
[    8.980530] e1000e 0000:00:02.0 eth0: Intel(R) PRO/1000 Network Connection
[    8.980967] e1000e 0000:00:02.0 eth0: MAC: 3, PHY: 8, PBA No: 000000-000
[    8.981779] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[    8.981996] ata1.00: 4194304 sectors, multi 16: LBA48 NCQ (depth 32)
[    8.982364] ata1.00: applying bridge limits
[    8.983792] sky2: driver version 1.30
[    8.984159] ata6: SATA link down (SStatus 0 SControl 300)
[    8.985481] ata2: SATA link down (SStatus 0 SControl 300)
[    8.985987] ata4: SATA link down (SStatus 0 SControl 300)
[    8.986325] ata5: SATA link down (SStatus 0 SControl 300)
[    8.987369] ata1.00: configured for UDMA/100
[    8.988066] ata3.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    8.988255] ata3.00: applying bridge limits
[    8.988748] ata3.00: configured for UDMA/100
[    8.994506] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    8.994677] ehci-pci: EHCI PCI platform driver
[    8.995685] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    8.995994] ohci-pci: OHCI PCI platform driver
[    8.996975] uhci_hcd: USB Universal Host Controller Interface driver
[    9.002390] usbcore: registered new interface driver usblp
[    9.003622] usbcore: registered new interface driver usb-storage
[    9.006195] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU]
at 0x60,0x64 irq 1,12
[    9.010743] serio: i8042 KBD port at 0x60,0x64 irq 1
[    9.011289] serio: i8042 AUX port at 0x60,0x64 irq 12
[    9.025246] rtc_cmos 00:04: RTC can wake from S4
[    9.028261] input: AT Translated Set 2 keyboard as
/devices/platform/i8042/serio0/input/input1
[    9.042597] rtc_cmos 00:04: registered as rtc0
[    9.046966] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes
nvram, hpet irqs
[    9.051147] random: fast init done
[    9.211116] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt
[    9.216853] i2c i2c-0: 1/1 memory slots populated (from DMI)
[    9.217056] i2c i2c-0: Memory type 0x07 not supported yet, not
instantiating SPD
[    9.227115] device-mapper: ioctl: 4.42.0-ioctl (2020-02-27)
initialised: dm-devel at redhat.com
[    9.229163] hid: raw HID events driver (C) Jiri Kosina
[    9.245742] usbcore: registered new interface driver usbhid
[    9.245876] usbhid: USB HID core driver
[    9.277372] Initializing XFRM netlink socket
[    9.293295] NET: Registered protocol family 10
[    9.318627] Segment Routing with IPv6
[    9.328766] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    9.343114] NET: Registered protocol family 17
[    9.344897] Key type dns_resolver registered
[    9.354149] IPI shorthand broadcast: enabled
[    9.359050] registered taskstats version 1
[    9.359145] Loading compiled-in X.509 certificates
[    9.419149] PM:   Magic number: 4:165:288
[    9.419587] tty tty21: hash matches
[    9.420116] printk: console [netcon0] enabled
[    9.420220] netconsole: network logging started
[    9.430080] cfg80211: Loading compiled-in X.509 certificates for
regulatory database
[    9.448716] kworker/u4:8 (85) used greatest stack depth: 28816 bytes left
[    9.471279] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    9.475243] platform regulatory.0: Direct firmware load for
regulatory.db failed with error -2
[    9.475614] input: ImExPS/2 Generic Explorer Mouse as
/devices/platform/i8042/serio1/input/input3
[    9.476017] cfg80211: failed to load regulatory.db
[    9.477338] Unstable clock detected, switching default tracing
clock to "global"
[    9.477338] If you want to keep using the local clock, then add:
[    9.477338]   "trace_clock=local"
[    9.477338] on the kernel command line
[    9.479940] ALSA device list:
[    9.480114]   No soundcards found.
[   38.716151] nvme nvme0: I/O 64 QID 2 timeout, aborting
[   68.919903] nvme nvme0: I/O 64 QID 2 timeout, reset controller
[   99.128324] nvme nvme0: I/O 14 QID 0 timeout, reset controller
[  129.475744] nvme nvme0: Abort status: 0x371
[  190.775780] nvme nvme0: I/O 13 QID 0 timeout, disable controller
[  190.916049] nvme nvme0: Identify Controller failed (-4)
[  190.916216] nvme nvme0: Removing after probe failure status: -5
[  190.922982] blk_update_request: I/O error, dev nvme0n1, sector 7 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.923335] Buffer I/O error on dev nvme0n1, logical block 7, async page read
[  190.923818] blk_update_request: I/O error, dev nvme0n1, sector 6 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.924027] Buffer I/O error on dev nvme0n1, logical block 6, async page read
[  190.924262] blk_update_request: I/O error, dev nvme0n1, sector 5 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.924817] Buffer I/O error on dev nvme0n1, logical block 5, async page read
[  190.925070] blk_update_request: I/O error, dev nvme0n1, sector 3 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.925243] Buffer I/O error on dev nvme0n1, logical block 3, async page read
[  190.925586] blk_update_request: I/O error, dev nvme0n1, sector 2 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.925776] Buffer I/O error on dev nvme0n1, logical block 2, async page read
[  190.926045] blk_update_request: I/O error, dev nvme0n1, sector 1 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.926261] Buffer I/O error on dev nvme0n1, logical block 1, async page read
[  190.926577] blk_update_request: I/O error, dev nvme0n1, sector 0 op
0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[  190.926754] Buffer I/O error on dev nvme0n1, logical block 0, async page read
[  190.927963] Buffer I/O error on dev nvme0n1, logical block 0, async page read
[  190.928221] Buffer I/O error on dev nvme0n1, logical block 1, async page read
[  190.928584] Buffer I/O error on dev nvme0n1, logical block 2, async page read
[  190.929298]  nvme0n1: unable to read partition table
[  190.948008] ------------[ cut here ]------------
[  190.948178] WARNING: CPU: 1 PID: 56 at block/genhd.c:836
__device_add_disk+0x7d6/0x7f0
[  190.948325] Modules linked in:
[  190.948680] CPU: 1 PID: 56 Comm: kworker/u4:1 Not tainted 5.9.0-rc4+ #44
[  190.948808] Hardware name: QEMU Standard PC (Q35 + ICH9, 2009),
BIOS rel-1.13.0-48-gd9c812dda519-prebuilt.qemu.org 04/01/2014
[  190.949150] Workqueue: nvme-wq nvme_scan_work
[  190.949312] RIP: 0010:__device_add_disk+0x7d6/0x7f0
[  190.949682] Code: 85 18 04 00 00 48 89 44 24 20 e9 07 fa ff ff 48
89 df e8 3d 82 cb ff 80 a5 cc 00 00 00 ef e9 4b fc ff ff 0f 0b e9 44
fc ff ff <0f> 0b e9 7c fc ff ff 0f 0b e9 7b fa ff ff 0f 0b e9 51 ff ff
ff e8
[  190.949968] RSP: 0018:ffff888158e779f0 EFLAGS: 00000202
[  190.950099] RAX: 00000000024640d2 RBX: ffff888158f2b598 RCX: ffffffffaaa4386d
[  190.950229] RDX: dffffc0000000000 RSI: 0000000000000008 RDI: ffff888158f2b5f0
[  190.950364] RBP: ffff88815880f000 R08: 0000000000000001 R09: ffffed102b1e56bf
[  190.950562] R10: ffff888158f2b5f7 R11: ffffed102b1e56be R12: ffff88815880f3d0
[  190.950694] R13: ffff88815880f090 R14: ffff88815880f3c0 R15: ffff888158f2b5f0
[  190.950872] FS:  0000000000000000(0000) GS:ffff88815b500000(0000)
knlGS:0000000000000000
[  190.951012] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[  190.951115] CR2: 0000000000000000 CR3: 000000006880e000 CR4: 00000000000006e0
[  190.951290] Call Trace:
[  190.951698]  ? blk_alloc_devt+0x140/0x140
[  190.951799]  ? __hrtimer_init+0x5f/0xf0
[  190.951889]  ? rwsem_down_read_slowpath+0x7d0/0x7d0
[  190.951990]  ? __nvme_revalidate_disk+0x244/0x480
[  190.952089]  nvme_validate_ns+0x771/0xe90
[  190.952210]  ? nvme_dev_ioctl+0x190/0x190
[  190.952297]  ? __blk_mq_free_request+0xe3/0x130
[  190.952377]  ? __nvme_submit_sync_cmd+0x153/0x300
[  190.952568]  ? kasan_unpoison_shadow+0x33/0x40
[  190.952662]  ? __kasan_kmalloc.constprop.0+0xc2/0xd0
[  190.952768]  nvme_scan_work+0x20f/0x35f
[  190.952863]  ? nvme_fw_act_work+0x210/0x210
[  190.952959]  ? sysvec_apic_timer_interrupt+0x2e/0x80
[  190.953059]  ? asm_sysvec_apic_timer_interrupt+0x12/0x20
[  190.953168]  ? nvme_fw_act_work+0x210/0x210
[  190.953260]  ? process_one_work+0x461/0x7a0
[  190.953353]  process_one_work+0x46d/0x7a0
[  190.953543]  worker_thread+0x442/0x690
[  190.953709]  ? process_one_work+0x7a0/0x7a0
[  190.953799]  kthread+0x199/0x1f0
[  190.953883]  ? kthread_create_on_node+0xd0/0xd0
[  190.953979]  ret_from_fork+0x22/0x30
[  190.954106] ---[ end trace 5722e4830586b7ce ]---
[  190.994720] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK
  2.5+ PQ: 0 ANSI: 5
[  191.011641] sd 0:0:0:0: Attached scsi generic sg0 type 0
[  191.012958] sd 0:0:0:0: [sda] 4194304 512-byte logical blocks:
(2.15 GB/2.00 GiB)
[  191.014160] sd 0:0:0:0: [sda] Write Protect is off
[  191.015722] sd 0:0:0:0: [sda] Write cache: enabled, read cache:
enabled, doesn't support DPO or FUA
[  191.019777] scsi 2:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM
  2.5+ PQ: 0 ANSI: 5
[  191.043867] sr 2:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[  191.044341] cdrom: Uniform CD-ROM driver Revision: 3.20
[  191.057775] sd 0:0:0:0: [sda] Attached SCSI disk
[  191.093652] sr 2:0:0:0: Attached scsi generic sg1 type 5
[  191.097114] md: Waiting for all devices to be available before autodetect
[  191.097243] md: If you don't use raid, use raid=noautodetect
[  191.097478] md: Autodetecting RAID arrays.
[  191.097595] md: autorun ...
[  191.097662] md: ... autorun DONE.
[  191.147528] EXT4-fs (sda): INFO: recovery required on readonly filesystem
[  191.147696] EXT4-fs (sda): write access will be enabled during recovery
[  191.566898] EXT4-fs (sda): recovery complete
[  191.582429] EXT4-fs (sda): mounted filesystem with ordered data
mode. Opts: (null)
[  191.585126] VFS: Mounted root (ext4 filesystem) readonly on device 8:0.
[  191.597594] devtmpfs: mounted
[  191.657264] Freeing unused kernel image (initmem) memory: 1516K
[  191.657740] Write protecting the kernel read-only data: 32768k
[  191.665180] Freeing unused kernel image (text/rodata gap) memory: 2040K
[  191.669028] Freeing unused kernel image (rodata/data gap) memory: 1552K
[  191.669671] Run /bin/bash as init process



On Wed, Sep 23, 2020 at 1:57 AM Christoph Hellwig <hch at lst.de> wrote:
>
> I suspect the patch below might be better.  Can you send me a full dmesg
> with this one applied?  Preferably on top of Jens' for-next branch?
>



More information about the Linux-nvme mailing list