All of lore.kernel.org
 help / color / mirror / Atom feed
* fails to boot with "BTRFS critical (device sda2): corrupt leaf: ..."
@ 2020-08-08  9:57 Lu Pi
  2020-08-08 11:45 ` Qu Wenruo
  0 siblings, 1 reply; 4+ messages in thread
From: Lu Pi @ 2020-08-08  9:57 UTC (permalink / raw)
  To: linux-btrfs

[-- Attachment #1: Type: text/plain, Size: 3643 bytes --]

Hi,

I have a system that fails to boot, with "BTRFS critical (device
sda2): corrupt leaf: " error, and open an "initramfs" shell.

I did backup /home with 'btrfs restore'. There were a few errors,
though only on cache files (Google Chrome cache files).

Now considering 'btrfs check --repair'.

I'm contacting you as recommended here:
   https://btrfs.wiki.kernel.org/index.php/Tree-checker
   "Please report to btrfs mail list <linux-btrfs@vger.kernel.org> first."
   "Please *NOT* use btrfs check --repair until instructed by a developer."


Can you advice?




BACKGROUND

- the system is Linux Mint 17

- a week ago or so, after a kernel update, the system was remounting
read-only after about 1 minute after boot. Downgrading the kernel
solved the issue.
  - 4.15.0-112-generic brought the issue
  - 4.15.0-107-generic was OK

- a few days ago, something else happened, though I'm unsure, as I'm
not the user of the system. Possibly any of these,
  - another kernel update (now I can see that 4.15.0-112 is back)
  - maybe the system was shut down by cutting electricity (?)
  - could it be also that the SSD drive is failing (?)
  - or?
  - though as a result the system fails to boot and the drive is not mountable.



SYSTEM INFORMATION
---
When reporting errors or asking for support always supply the output
of the following commands:
  uname -a
  btrfs --version
  btrfs fi show
  btrfs fi df /home # Replace /home with the mount point of your
btrfs-filesystem
  dmesg > dmesg.log
---

See below, and dmesg log enclosed


---
(initramfs) uname -a
Linux (none) 4.15.0-112-generic #113~16.04.1-Ubuntu SMP Fri Jul 10
04:37:08 UTC 2020 x86_64 GNU/Linux


(initramfs) btrfs --version
btrfs-progs v4.4


(initramfs) btrfs fi show
Label: none  uuid: f813bbe2-0bff-4923-822b-d3f6d6ebbb9e
    Total devices 1 FS bytes used 55.22GiB
    devid    1 size 107.98GiB used 85.02GiB path /dev/sda2


(initramfs) btrfs fi df /home
ERROR: can't access '/home': No such file or directory

(initramfs) btrfs fi df /
ERROR: not a btrfs filesystem: /



(initramfs) mount -t btrfs /dev/sda2 /mnt/sda/
[70391.973518] BTRFS critical (device sda2): corrupt leaf:
block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid
generation, have 9367487224930631680 expect (0, 458036]
[70391.975504] BTRFS: error (device sda2) in __btrfs_free_extent:7000:
errno=-5 IO failure
[70391.977490] BTRFS: error (device sda2) in
btrfs_run_delayed_refs:3083: errno=-5 IO failure
[70391.979490] BTRFS: error (device sda2) in btrfs_replay_log:2369:
errno=-5 IO failure (Failed to recover log tree)
[70391.980588] BTRFS error (device sda2): pending csums is 475136
[70392.023935] BTRFS error (device sda2): open_ctree failed
mount: mounting /dev/sda2 on /mnt/sda/ failed: Input/output error



(initramfs) dmesg |grep 70391
[70391.723717] BTRFS info (device sda2): disk space caching is enabled
[70391.723721] BTRFS info (device sda2): has skinny extents
[70391.763253] BTRFS info (device sda2): enabling ssd optimizations
[70391.763256] BTRFS info (device sda2): start tree-log replay
[70391.973518] BTRFS critical (device sda2): corrupt leaf:
block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid
generation, have 9367487224930631680 expect (0, 458036]
[70391.975504] BTRFS: error (device sda2) in __btrfs_free_extent:7000:
errno=-5 IO failure
[70391.977490] BTRFS: error (device sda2) in
btrfs_run_delayed_refs:3083: errno=-5 IO failure
[70391.979490] BTRFS: error (device sda2) in btrfs_replay_log:2369:
errno=-5 IO failure (Failed to recover log tree)
[70391.980588] BTRFS error (device sda2): pending csums is 475136


full dmesg log enclosed.
---

[-- Attachment #2: btrfs-sda-mount-error.dmesg.fresh-boot.log --]
[-- Type: text/x-log, Size: 48543 bytes --]

[    0.000000] Linux version 4.15.0-112-generic (buildd@lcy01-amd64-021) (gcc version 5.4.0 20160609 (Ubuntu 5.4.0-6ubuntu1~16.04.12)) #113~16.04.1-Ubuntu SMP Fri Jul 10 04:37:08 UTC 2020 (Ubuntu 4.15.0-112.113~16.04.1-generic 4.15.18)
[    0.000000] Command line: BOOT_IMAGE=/@/boot/vmlinuz-4.15.0-112-generic root=UUID=f813bbe2-0bff-4923-822b-d3f6d6ebbb9e ro rootflags=subvol=@ quiet splash vt.handoff=7
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000] x86/fpu: x87 FPU will use FXSAVE
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x00000000000983ff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000098400-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bfedf7ff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bfedf800-0x00000000bfedffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000bfee0000-0x00000000bfee2fff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x00000000bfee3000-0x00000000bfeeffff] ACPI data
[    0.000000] BIOS-e820: [mem 0x00000000bfef0000-0x00000000bfefffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000f0000000-0x00000000f3ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.4 present.
[    0.000000] DMI: HP-Pavilion GS336AA-ABF a6220.fr/Leonite2, BIOS  5.21 07/27/2007
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0xbfedf max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-EFFFF uncachable
[    0.000000]   F0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask F80000000 write-back
[    0.000000]   1 base 080000000 mask FC0000000 write-back
[    0.000000]   2 base 0BFF00000 mask FFFF00000 uncachable
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.000000] total RAM covered: 3071M
[    0.000000] Found optimal setting for mtrr clean up
[    0.000000]  gran_size: 64K 	chunk_size: 2M 	num_reg: 3  	lose cover RAM: 0G
[    0.000000] found SMP MP-table at [mem 0x000f5f10-0x000f5f1f]
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] BRK [0x6f393000, 0x6f393fff] PGTABLE
[    0.000000] BRK [0x6f394000, 0x6f394fff] PGTABLE
[    0.000000] BRK [0x6f395000, 0x6f395fff] PGTABLE
[    0.000000] BRK [0x6f396000, 0x6f396fff] PGTABLE
[    0.000000] BRK [0x6f397000, 0x6f397fff] PGTABLE
[    0.000000] BRK [0x6f398000, 0x6f398fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x3169a000-0x34b44fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F83A0 000024 (v02 HPQOEM)
[    0.000000] ACPI: XSDT 0x00000000BFEE30C0 000054 (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.000000] ACPI: FACP 0x00000000BFEE7900 0000F4 (v03 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.000000] ACPI BIOS Warning (bug): 32/64X length mismatch in FADT/Pm1aEventBlock: 32/8 (20170831/tbfadt-603)
[    0.000000] ACPI BIOS Warning (bug): 32/64X length mismatch in FADT/Pm1aControlBlock: 16/8 (20170831/tbfadt-603)
[    0.000000] ACPI BIOS Warning (bug): 32/64X length mismatch in FADT/PmTimerBlock: 32/8 (20170831/tbfadt-603)
[    0.000000] ACPI BIOS Warning (bug): 32/64X length mismatch in FADT/Gpe0Block: 64/8 (20170831/tbfadt-603)
[    0.000000] ACPI BIOS Warning (bug): Invalid length for FADT/Pm1aEventBlock: 8, using default 32 (20170831/tbfadt-708)
[    0.000000] ACPI BIOS Warning (bug): Invalid length for FADT/Pm1aControlBlock: 8, using default 16 (20170831/tbfadt-708)
[    0.000000] ACPI BIOS Warning (bug): Invalid length for FADT/PmTimerBlock: 8, using default 32 (20170831/tbfadt-708)
[    0.000000] ACPI: DSDT 0x00000000BFEE3280 00463B (v01 HPQOEM SLIC-CPC 00001000 MSFT 03000000)
[    0.000000] ACPI: FACS 0x00000000BFEE0000 000040
[    0.000000] ACPI: FACS 0x00000000BFEE0000 000040
[    0.000000] ACPI: SLIC 0x00000000BFEE7B40 000176 (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.000000] ACPI: HPET 0x00000000BFEE7D00 000038 (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000098)
[    0.000000] ACPI: MCFG 0x00000000BFEE7D80 00003C (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.000000] ACPI: APIC 0x00000000BFEE7A40 000084 (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.000000] ACPI: SSDT 0x00000000BFEE82E0 000304 (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x00000000bfedefff]
[    0.000000] NODE_DATA(0) allocated [mem 0xbfeb4000-0xbfedefff]
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000bfedefff]
[    0.000000]   Normal   empty
[    0.000000]   Device   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x0000000000097fff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x00000000bfedefff]
[    0.000000] Reserved but unavailable: 105 pages
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x00000000bfedefff]
[    0.000000] On node 0 totalpages: 786038
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3991 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 12220 pages used for memmap
[    0.000000]   DMA32 zone: 782047 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 4, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 4 CPUs, 2 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00098000-0x00098fff]
[    0.000000] PM: Registered nosave memory: [mem 0x00099000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] e820: [mem 0xbff00000-0xefffffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.000000] random: get_random_bytes called from start_kernel+0x99/0x51e with crng_init=0
[    0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] percpu: Embedded 45 pages/cpu s147456 r8192 d28672 u524288
[    0.000000] pcpu-alloc: s147456 r8192 d28672 u524288 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 773733
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: BOOT_IMAGE=/@/boot/vmlinuz-4.15.0-112-generic root=UUID=f813bbe2-0bff-4923-822b-d3f6d6ebbb9e ro rootflags=subvol=@ quiet splash vt.handoff=7
[    0.000000] Calgary: detecting Calgary via BIOS EBDA area
[    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.000000] Memory: 3011752K/3144152K available (12300K kernel code, 2480K rwdata, 4220K rodata, 2432K init, 2720K bss, 132400K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Kernel/User page tables isolation: enabled
[    0.000000] ftrace: allocating 39359 entries in 154 pages
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4.
[    0.000000] 	Tasks RCU enabled.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16
[    0.000000] vt handoff: transparent VT on vt#7
[    0.000000] Console: colour dummy device 80x25
[    0.000000] console [tty0] enabled
[    0.000000] ACPI: Core revision 20170831
[    0.000000] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns
[    0.000000] hpet clockevent registered
[    0.000000] APIC: Switch to symmetric I/O mode setup
[    0.000000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.020000] tsc: Fast TSC calibration using PIT
[    0.024000] tsc: Detected 2000.624 MHz processor
[    0.024000] Calibrating delay loop (skipped), value calculated using timer frequency.. 4001.24 BogoMIPS (lpj=8002496)
[    0.024000] pid_max: default: 32768 minimum: 301
[    0.024000] Security Framework initialized
[    0.024000] Yama: becoming mindful.
[    0.024000] AppArmor: AppArmor initialized
[    0.024000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.028397] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.028482] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.028524] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[    0.028931] CPU0: Thermal monitoring enabled (TM2)
[    0.028935] process: using mwait in idle threads
[    0.028941] Last level iTLB entries: 4KB 128, 2MB 4, 4MB 4
[    0.028942] Last level dTLB entries: 4KB 256, 2MB 0, 4MB 32, 1GB 0
[    0.028944] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.028947] Spectre V2 : Mitigation: Full generic retpoline
[    0.028948] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.028949] Speculative Store Bypass: Vulnerable
[    0.028994] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    0.029251] Freeing SMP alternatives memory: 36K
[    0.146977] smpboot: CPU0: Intel(R) Core(TM)2 Duo CPU     E4400  @ 2.00GHz (family: 0x6, model: 0xf, stepping: 0xd)
[    0.147132] Performance Events: PEBS fmt0+, Core2 events, Intel PMU driver.
[    0.147139] core: PEBS disabled due to CPU errata
[    0.147142] ... version:                2
[    0.147143] ... bit width:              40
[    0.147144] ... generic registers:      2
[    0.147146] ... value mask:             000000ffffffffff
[    0.147147] ... max period:             000000007fffffff
[    0.147148] ... fixed-purpose events:   3
[    0.147149] ... event mask:             0000000700000003
[    0.147210] Hierarchical SRCU implementation.
[    0.148000] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter.
[    0.148000] smp: Bringing up secondary CPUs ...
[    0.148000] x86: Booting SMP configuration:
[    0.148000] .... node  #0, CPUs:      #1
[    0.148043] smp: Brought up 1 node, 2 CPUs
[    0.148043] smpboot: Max logical packages: 2
[    0.148043] smpboot: Total of 2 processors activated (8002.49 BogoMIPS)
[    0.149527] devtmpfs: initialized
[    0.149527] x86/mm: Memory block size: 128MB
[    0.149527] evm: security.selinux
[    0.149527] evm: security.SMACK64
[    0.149527] evm: security.SMACK64EXEC
[    0.149527] evm: security.SMACK64TRANSMUTE
[    0.149527] evm: security.SMACK64MMAP
[    0.149527] evm: security.apparmor
[    0.149527] evm: security.ima
[    0.149527] evm: security.capability
[    0.149527] PM: Registering ACPI NVS region [mem 0xbfee0000-0xbfee2fff] (12288 bytes)
[    0.149527] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.149527] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.149527] pinctrl core: initialized pinctrl subsystem
[    0.149527] RTC time:  9:07:58, date: 08/08/20
[    0.149527] NET: Registered protocol family 16
[    0.149527] audit: initializing netlink subsys (disabled)
[    0.149527] audit: type=2000 audit(1596877678.148:1): state=initialized audit_enabled=0 res=1
[    0.149527] cpuidle: using governor ladder
[    0.149527] cpuidle: using governor menu
[    0.149527] ACPI: bus type PCI registered
[    0.149527] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.149527] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xf0000000-0xf3ffffff] (base 0xf0000000)
[    0.149527] PCI: MMCONFIG at [mem 0xf0000000-0xf3ffffff] reserved in E820
[    0.149527] PCI: Using configuration type 1 for base access
[    0.153796] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.153796] ACPI: Added _OSI(Module Device)
[    0.153796] ACPI: Added _OSI(Processor Device)
[    0.153796] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.153796] ACPI: Added _OSI(Processor Aggregator Device)
[    0.153796] ACPI: Added _OSI(Linux-Dell-Video)
[    0.153796] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.153796] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.160446] ACPI: Dynamic OEM Table Load:
[    0.160455] ACPI: SSDT 0xFFFF957E7B9C0600 0001B7 (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.160761] ACPI: Dynamic OEM Table Load:
[    0.160766] ACPI: SSDT 0xFFFF957E7B8B1E00 0000CE (v01 HPQOEM SLIC-CPC 42302E31 AWRD 00000000)
[    0.161283] ACPI: Interpreter enabled
[    0.161314] ACPI: (supports S0 S1 S3 S4 S5)
[    0.161316] ACPI: Using IOAPIC for interrupt routing
[    0.161359] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.161657] ACPI: Enabled 11 GPEs in block 00 to 1F
[    0.168466] ACPI Error: Field [ASSM] at bit offset/length 524288/32 exceeds size of target Buffer (688 bits) (20170831/dsopcode-235)
[    0.168477] No Local Variables are initialized for Method [_CRS]
[    0.168479] No Arguments are initialized for method [_CRS]
[    0.168481] ACPI Error: Method parse/execution failed \_SB.MEM._CRS, AE_AML_BUFFER_LIMIT (20170831/psparse-550)
[    0.168491] ACPI Error: Method execution failed \_SB.MEM._CRS, AE_AML_BUFFER_LIMIT (20170831/uteval-103)
[    0.169135] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.169143] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    0.169150] acpi PNP0A08:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.169166] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-3f] only partially covers this bridge
[    0.169546] PCI host bridge to bus 0000:00
[    0.169549] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.169551] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.169553] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.169555] pci_bus 0000:00: root bus resource [mem 0x000c0000-0x000dffff window]
[    0.169557] pci_bus 0000:00: root bus resource [mem 0xbff00000-0xfebfffff window]
[    0.169559] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.169571] pci 0000:00:00.0: [8086:2770] type 00 class 0x060000
[    0.169726] pci 0000:00:01.0: [8086:2771] type 01 class 0x060400
[    0.169780] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold
[    0.169930] pci 0000:00:1b.0: [8086:27d8] type 00 class 0x040300
[    0.169953] pci 0000:00:1b.0: reg 0x10: [mem 0xfdff8000-0xfdffbfff 64bit]
[    0.170035] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.170168] pci 0000:00:1d.0: [8086:27c8] type 00 class 0x0c0300
[    0.170214] pci 0000:00:1d.0: reg 0x20: [io  0xff00-0xff1f]
[    0.170361] pci 0000:00:1d.1: [8086:27c9] type 00 class 0x0c0300
[    0.170407] pci 0000:00:1d.1: reg 0x20: [io  0xfe00-0xfe1f]
[    0.170552] pci 0000:00:1d.2: [8086:27ca] type 00 class 0x0c0300
[    0.170598] pci 0000:00:1d.2: reg 0x20: [io  0xfd00-0xfd1f]
[    0.170741] pci 0000:00:1d.3: [8086:27cb] type 00 class 0x0c0300
[    0.170787] pci 0000:00:1d.3: reg 0x20: [io  0xfc00-0xfc1f]
[    0.170939] pci 0000:00:1d.7: [8086:27cc] type 00 class 0x0c0320
[    0.170960] pci 0000:00:1d.7: reg 0x10: [mem 0xfdfff000-0xfdfff3ff]
[    0.171039] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
[    0.171169] pci 0000:00:1e.0: [8086:244e] type 01 class 0x060401
[    0.171350] pci 0000:00:1f.0: [8086:27b0] type 00 class 0x060100
[    0.171573] pci 0000:00:1f.1: [8086:27df] type 00 class 0x01018a
[    0.171591] pci 0000:00:1f.1: reg 0x10: [io  0x0000-0x0007]
[    0.171600] pci 0000:00:1f.1: reg 0x14: [io  0x0000-0x0003]
[    0.171610] pci 0000:00:1f.1: reg 0x18: [io  0x0000-0x0007]
[    0.171619] pci 0000:00:1f.1: reg 0x1c: [io  0x0000-0x0003]
[    0.171628] pci 0000:00:1f.1: reg 0x20: [io  0xfb00-0xfb0f]
[    0.171648] pci 0000:00:1f.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.171650] pci 0000:00:1f.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.171652] pci 0000:00:1f.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.171654] pci 0000:00:1f.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.171780] pci 0000:00:1f.2: [8086:27c3] type 00 class 0x010400
[    0.171799] pci 0000:00:1f.2: reg 0x10: [io  0xfa00-0xfa07]
[    0.171807] pci 0000:00:1f.2: reg 0x14: [io  0xf900-0xf903]
[    0.171815] pci 0000:00:1f.2: reg 0x18: [io  0xf800-0xf807]
[    0.171823] pci 0000:00:1f.2: reg 0x1c: [io  0xf700-0xf703]
[    0.171831] pci 0000:00:1f.2: reg 0x20: [io  0xf600-0xf60f]
[    0.171840] pci 0000:00:1f.2: reg 0x24: [mem 0xfdffe000-0xfdffe3ff]
[    0.171879] pci 0000:00:1f.2: PME# supported from D3hot
[    0.172009] pci 0000:00:1f.3: [8086:27da] type 00 class 0x0c0500
[    0.172067] pci 0000:00:1f.3: reg 0x20: [io  0x0500-0x051f]
[    0.172259] pci 0000:01:00.0: [1002:94c3] type 00 class 0x030000
[    0.172281] pci 0000:01:00.0: reg 0x10: [mem 0xe0000000-0xefffffff 64bit pref]
[    0.172293] pci 0000:01:00.0: reg 0x18: [mem 0xfddf0000-0xfddfffff 64bit]
[    0.172301] pci 0000:01:00.0: reg 0x20: [io  0xde00-0xdeff]
[    0.172315] pci 0000:01:00.0: reg 0x30: [mem 0xfddc0000-0xfdddffff pref]
[    0.172322] pci 0000:01:00.0: enabling Extended Tags
[    0.172361] pci 0000:01:00.0: supports D1 D2
[    0.184023] pci 0000:00:01.0: PCI bridge to [bus 01]
[    0.184028] pci 0000:00:01.0:   bridge window [io  0xd000-0xdfff]
[    0.184033] pci 0000:00:01.0:   bridge window [mem 0xfdd00000-0xfddfffff]
[    0.184039] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xefffffff 64bit pref]
[    0.184115] pci 0000:02:01.0: [11c1:5811] type 00 class 0x0c0010
[    0.184142] pci 0000:02:01.0: reg 0x10: [mem 0xfdeff000-0xfdefffff]
[    0.184247] pci 0000:02:01.0: supports D1 D2
[    0.184250] pci 0000:02:01.0: PME# supported from D0 D1 D2 D3hot
[    0.184323] pci 0000:02:08.0: [8086:27dc] type 00 class 0x020000
[    0.184344] pci 0000:02:08.0: reg 0x10: [mem 0xfdefe000-0xfdefefff]
[    0.184354] pci 0000:02:08.0: reg 0x14: [io  0xef00-0xef3f]
[    0.184427] pci 0000:02:08.0: supports D1 D2
[    0.184428] pci 0000:02:08.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.184510] pci 0000:00:1e.0: PCI bridge to [bus 02] (subtractive decode)
[    0.184514] pci 0000:00:1e.0:   bridge window [io  0xe000-0xefff]
[    0.184518] pci 0000:00:1e.0:   bridge window [mem 0xfde00000-0xfdefffff]
[    0.184524] pci 0000:00:1e.0:   bridge window [io  0x0000-0x0cf7 window] (subtractive decode)
[    0.184526] pci 0000:00:1e.0:   bridge window [io  0x0d00-0xffff window] (subtractive decode)
[    0.184528] pci 0000:00:1e.0:   bridge window [mem 0x000a0000-0x000bffff window] (subtractive decode)
[    0.184530] pci 0000:00:1e.0:   bridge window [mem 0x000c0000-0x000dffff window] (subtractive decode)
[    0.184532] pci 0000:00:1e.0:   bridge window [mem 0xbff00000-0xfebfffff window] (subtractive decode)
[    0.185334] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 *5 7 9 10 11 12 14 15)
[    0.185456] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
[    0.185578] ACPI: PCI Interrupt Link [LNKC] (IRQs *3 4 5 7 9 10 11 12 14 15)
[    0.185698] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 7 9 10 *11 12 14 15)
[    0.185819] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 9 *10 11 12 14 15)
[    0.185939] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
[    0.186060] ACPI: PCI Interrupt Link [LNK0] (IRQs 3 4 5 7 9 10 11 12 14 15) *0, disabled.
[    0.186181] ACPI: PCI Interrupt Link [LNK1] (IRQs 3 4 5 *7 9 10 11 12 14 15)
[    0.186709] SCSI subsystem initialized
[    0.186728] libata version 3.00 loaded.
[    0.186728] pci 0000:01:00.0: vgaarb: setting as boot VGA device
[    0.186728] pci 0000:01:00.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    0.186728] pci 0000:01:00.0: vgaarb: bridge control possible
[    0.186728] vgaarb: loaded
[    0.186728] ACPI: bus type USB registered
[    0.186728] usbcore: registered new interface driver usbfs
[    0.186728] usbcore: registered new interface driver hub
[    0.186728] usbcore: registered new device driver usb
[    0.186728] EDAC MC: Ver: 3.0.0
[    0.186728] PCI: Using ACPI for IRQ routing
[    0.189065] PCI: pci_cache_line_size set to 64 bytes
[    0.189110] e820: reserve RAM buffer [mem 0x00098400-0x0009ffff]
[    0.189112] e820: reserve RAM buffer [mem 0xbfedf800-0xbfffffff]
[    0.189260] NetLabel: Initializing
[    0.189261] NetLabel:  domain hash size = 128
[    0.189262] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.189286] NetLabel:  unlabeled traffic allowed by default
[    0.189312] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.189312] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.189312] hpet0: 3 comparators, 64-bit 14.318180 MHz counter
[    0.190102] clocksource: Switched to clocksource hpet
[    0.209605] VFS: Disk quotas dquot_6.6.0
[    0.209636] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.209838] AppArmor: AppArmor Filesystem Enabled
[    0.209891] pnp: PnP ACPI init
[    0.210235] system 00:00: [io  0x04d0-0x04d1] has been reserved
[    0.210237] system 00:00: [io  0x0800-0x087f] has been reserved
[    0.210239] system 00:00: [io  0x0294-0x0297] has been reserved
[    0.210242] system 00:00: [io  0x0880-0x088f] has been reserved
[    0.210250] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.210378] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.210651] system 00:02: [io  0x0400-0x04bf] has been reserved
[    0.210658] system 00:02: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.211054] system 00:03: [mem 0xf0000000-0xf3ffffff] has been reserved
[    0.211061] system 00:03: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.211094] ACPI Error: Field [ASSM] at bit offset/length 524288/32 exceeds size of target Buffer (688 bits) (20170831/dsopcode-235)
[    0.211106] No Local Variables are initialized for Method [_CRS]
[    0.211108] No Arguments are initialized for method [_CRS]
[    0.211110] ACPI Error: Method parse/execution failed \_SB.MEM._CRS, AE_AML_BUFFER_LIMIT (20170831/psparse-550)
[    0.211121] ACPI Error: Method execution failed \_SB.MEM._CRS, AE_AML_BUFFER_LIMIT (20170831/uteval-103)
[    0.211129] pnp 00:04: can't evaluate _CRS: 12298
[    0.211172] system 00:04: Plug and Play ACPI device, IDs PNP0c01 (active)
[    0.211185] pnp: PnP ACPI: found 5 devices
[    0.218354] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.218382] pci 0000:00:01.0: PCI bridge to [bus 01]
[    0.218385] pci 0000:00:01.0:   bridge window [io  0xd000-0xdfff]
[    0.218389] pci 0000:00:01.0:   bridge window [mem 0xfdd00000-0xfddfffff]
[    0.218392] pci 0000:00:01.0:   bridge window [mem 0xe0000000-0xefffffff 64bit pref]
[    0.218398] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    0.218401] pci 0000:00:1e.0:   bridge window [io  0xe000-0xefff]
[    0.218405] pci 0000:00:1e.0:   bridge window [mem 0xfde00000-0xfdefffff]
[    0.218414] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    0.218417] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    0.218419] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.218421] pci_bus 0000:00: resource 7 [mem 0x000c0000-0x000dffff window]
[    0.218422] pci_bus 0000:00: resource 8 [mem 0xbff00000-0xfebfffff window]
[    0.218424] pci_bus 0000:01: resource 0 [io  0xd000-0xdfff]
[    0.218426] pci_bus 0000:01: resource 1 [mem 0xfdd00000-0xfddfffff]
[    0.218428] pci_bus 0000:01: resource 2 [mem 0xe0000000-0xefffffff 64bit pref]
[    0.218430] pci_bus 0000:02: resource 0 [io  0xe000-0xefff]
[    0.218432] pci_bus 0000:02: resource 1 [mem 0xfde00000-0xfdefffff]
[    0.218434] pci_bus 0000:02: resource 4 [io  0x0000-0x0cf7 window]
[    0.218435] pci_bus 0000:02: resource 5 [io  0x0d00-0xffff window]
[    0.218437] pci_bus 0000:02: resource 6 [mem 0x000a0000-0x000bffff window]
[    0.218439] pci_bus 0000:02: resource 7 [mem 0x000c0000-0x000dffff window]
[    0.218441] pci_bus 0000:02: resource 8 [mem 0xbff00000-0xfebfffff window]
[    0.218553] NET: Registered protocol family 2
[    0.218832] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[    0.219000] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[    0.219239] TCP: Hash tables configured (established 32768 bind 32768)
[    0.219303] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[    0.219337] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[    0.219417] NET: Registered protocol family 1
[    0.220515] pci 0000:01:00.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.220528] pci 0000:02:08.0: Firmware left e100 interrupts enabled; disabling
[    0.220533] PCI: CLS 32 bytes, default 64
[    0.220601] Unpacking initramfs...
[    1.413991] Freeing initrd memory: 53932K
[    1.414309] Scanning for low memory corruption every 60 seconds
[    1.415238] Initialise system trusted keyrings
[    1.415255] Key type blacklist registered
[    1.415352] workingset: timestamp_bits=36 max_order=20 bucket_order=0
[    1.417569] zbud: loaded
[    1.418342] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.418572] fuse init (API version 7.26)
[    1.420513] Key type asymmetric registered
[    1.420515] Asymmetric key parser 'x509' registered
[    1.420575] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.420620] io scheduler noop registered
[    1.420621] io scheduler deadline registered
[    1.420666] io scheduler cfq registered (default)
[    1.421216] vesafb: mode is 1400x1050x32, linelength=5632, pages=0
[    1.421217] vesafb: scrolling: redraw
[    1.421219] vesafb: Truecolor: size=0:8:8:8, shift=0:16:8:0
[    1.421239] vesafb: framebuffer at 0xe0000000, mapped to 0x        (ptrval), using 5824k, total 5824k
[    1.421406] Console: switching to colour frame buffer device 175x65
[    1.421453] fb0: VESA VGA frame buffer device
[    1.421474] intel_idle: does not run on family 6 model 15
[    1.421591] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[    1.421605] ACPI: Power Button [PWRB]
[    1.421669] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[    1.421698] ACPI: Power Button [PWRF]
[    1.422589] (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[    1.422765] thermal LNXTHERM:00: registered as thermal_zone0
[    1.422767] ACPI: Thermal Zone [THRM] (39 C)
[    1.422952] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    1.425961] Linux agpgart interface v0.103
[    1.428347] loop: module loaded
[    1.428545] ata_piix 0000:00:1f.1: version 2.13
[    1.429628] scsi host0: ata_piix
[    1.429764] scsi host1: ata_piix
[    1.429812] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0xfb00 irq 14
[    1.429814] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xfb08 irq 15
[    1.429951] libphy: Fixed MDIO Bus: probed
[    1.429953] tun: Universal TUN/TAP device driver, 1.6
[    1.429993] ata2: port disabled--ignoring
[    1.430016] PPP generic driver version 2.4.2
[    1.430086] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.430090] ehci-pci: EHCI PCI platform driver
[    1.430259] ehci-pci 0000:00:1d.7: EHCI Host Controller
[    1.430267] ehci-pci 0000:00:1d.7: new USB bus registered, assigned bus number 1
[    1.434184] ehci-pci 0000:00:1d.7: cache line size of 32 is not supported
[    1.434201] ehci-pci 0000:00:1d.7: irq 23, io mem 0xfdfff000
[    1.448036] ehci-pci 0000:00:1d.7: USB 2.0 started, EHCI 1.00
[    1.448131] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.448135] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.448139] usb usb1: Product: EHCI Host Controller
[    1.448142] usb usb1: Manufacturer: Linux 4.15.0-112-generic ehci_hcd
[    1.448145] usb usb1: SerialNumber: 0000:00:1d.7
[    1.448306] hub 1-0:1.0: USB hub found
[    1.448315] hub 1-0:1.0: 8 ports detected
[    1.448570] ehci-platform: EHCI generic platform driver
[    1.448586] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    1.448590] ohci-pci: OHCI PCI platform driver
[    1.448605] ohci-platform: OHCI generic platform driver
[    1.448615] uhci_hcd: USB Universal Host Controller Interface driver
[    1.448740] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[    1.448747] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    1.448754] uhci_hcd 0000:00:1d.0: detected 2 ports
[    1.448773] uhci_hcd 0000:00:1d.0: irq 23, io base 0x0000ff00
[    1.448840] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
[    1.448843] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.448845] usb usb2: Product: UHCI Host Controller
[    1.448847] usb usb2: Manufacturer: Linux 4.15.0-112-generic uhci_hcd
[    1.448849] usb usb2: SerialNumber: 0000:00:1d.0
[    1.448974] hub 2-0:1.0: USB hub found
[    1.448984] hub 2-0:1.0: 2 ports detected
[    1.449221] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[    1.449230] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[    1.449237] uhci_hcd 0000:00:1d.1: detected 2 ports
[    1.449263] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000fe00
[    1.449321] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[    1.449323] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.449325] usb usb3: Product: UHCI Host Controller
[    1.449327] usb usb3: Manufacturer: Linux 4.15.0-112-generic uhci_hcd
[    1.449329] usb usb3: SerialNumber: 0000:00:1d.1
[    1.449454] hub 3-0:1.0: USB hub found
[    1.449462] hub 3-0:1.0: 2 ports detected
[    1.449681] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[    1.449688] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[    1.449694] uhci_hcd 0000:00:1d.2: detected 2 ports
[    1.449720] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000fd00
[    1.449777] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
[    1.449779] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.449781] usb usb4: Product: UHCI Host Controller
[    1.449783] usb usb4: Manufacturer: Linux 4.15.0-112-generic uhci_hcd
[    1.449785] usb usb4: SerialNumber: 0000:00:1d.2
[    1.449905] hub 4-0:1.0: USB hub found
[    1.449913] hub 4-0:1.0: 2 ports detected
[    1.450133] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[    1.450140] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
[    1.450146] uhci_hcd 0000:00:1d.3: detected 2 ports
[    1.450174] uhci_hcd 0000:00:1d.3: irq 16, io base 0x0000fc00
[    1.450231] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001
[    1.450234] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.450236] usb usb5: Product: UHCI Host Controller
[    1.450238] usb usb5: Manufacturer: Linux 4.15.0-112-generic uhci_hcd
[    1.450239] usb usb5: SerialNumber: 0000:00:1d.3
[    1.450371] hub 5-0:1.0: USB hub found
[    1.450379] hub 5-0:1.0: 2 ports detected
[    1.450563] i8042: PNP: No PS/2 controller found.
[    1.450654] mousedev: PS/2 mouse device common for all mice
[    1.450854] rtc_cmos 00:01: RTC can wake from S4
[    1.450983] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
[    1.451008] rtc_cmos 00:01: alarms up to one month, 242 bytes nvram, hpet irqs
[    1.451018] i2c /dev entries driver
[    1.451103] device-mapper: uevent: version 1.0.3
[    1.451192] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel@redhat.com
[    1.451220] ledtrig-cpu: registered to indicate activity on CPUs
[    1.451684] NET: Registered protocol family 10
[    1.462928] Segment Routing with IPv6
[    1.462968] NET: Registered protocol family 17
[    1.463033] Key type dns_resolver registered
[    1.463274] mce: Using 6 MCE banks
[    1.463302] RAS: Correctable Errors collector initialized.
[    1.463361] microcode: sig=0x6fd, pf=0x1, revision=0xa1
[    1.463411] microcode: Microcode Update Driver: v2.2.
[    1.463423] sched_clock: Marking stable (1463404574, 0)->(1449044723, 14359851)
[    1.463638] registered taskstats version 1
[    1.463652] Loading compiled-in X.509 certificates
[    1.470358] Loaded X.509 cert 'Build time autogenerated kernel key: 0369c56c7146cf506bb25ad446f2635f298f8fa6'
[    1.470399] zswap: loaded using pool lzo/zbud
[    1.480956] Key type big_key registered
[    1.480962] Key type trusted registered
[    1.486144] Key type encrypted registered
[    1.486150] AppArmor: AppArmor sha1 policy hashing enabled
[    1.486154] ima: No TPM chip found, activating TPM-bypass! (rc=-19)
[    1.486162] ima: Allocated hash algorithm: sha1
[    1.486186] evm: HMAC attrs: 0x1
[    1.486447]   Magic number: 0:630:120
[    1.486457] misc device-mapper: hash matches
[    1.486616] rtc_cmos 00:01: setting system clock to 2020-08-08 09:08:00 UTC (1596877680)
[    1.487731] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    1.487733] EDD information not available.
[    1.603787] Freeing unused kernel image memory: 2432K
[    1.616034] Write protecting the kernel read-only data: 20480k
[    1.617440] Freeing unused kernel image memory: 2008K
[    1.618476] Freeing unused kernel image memory: 1924K
[    1.631870] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.631984] x86/mm: Checking user space page tables
[    1.645098] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.665807] random: udevadm: uninitialized urandom read (16 bytes read)
[    1.665826] random: systemd-udevd: uninitialized urandom read (16 bytes read)
[    1.665850] random: udevadm: uninitialized urandom read (16 bytes read)
[    1.782606] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[    1.782608] e100: Copyright(c) 1999-2006 Intel Corporation
[    1.807212] usb 1-3: new high-speed USB device number 2 using ehci-pci
[    1.813132] e100 0000:02:08.0 eth0: addr 0xfdefe000, irq 20, MAC addr 00:1d:60:9c:cb:ed
[    1.832104] [drm] radeon kernel modesetting enabled.
[    1.832182] checking generic (e0000000 5b0000) vs hw (e0000000 10000000)
[    1.832183] fb: switching to radeondrmfb from VESA VGA
[    1.832225] Console: switching to colour dummy device 80x25
[    1.833772] [drm] initializing kernel modesetting (RV610 0x1002:0x94C3 0x1462:0x1041 0x00).
[    1.834721] ATOM BIOS: 113
[    1.834742] radeon 0000:01:00.0: VRAM: 256M 0x0000000000000000 - 0x000000000FFFFFFF (256M used)
[    1.834745] radeon 0000:01:00.0: GTT: 512M 0x0000000010000000 - 0x000000002FFFFFFF
[    1.834749] [drm] Detected VRAM RAM=256M, BAR=256M
[    1.834750] [drm] RAM width 64bits DDR
[    1.862025] [TTM] Zone  kernel: Available graphics memory: 1536042 kiB
[    1.862027] [TTM] Initializing pool allocator
[    1.862034] [TTM] Initializing DMA pool allocator
[    1.862065] [drm] radeon: 256M of VRAM memory ready
[    1.862066] [drm] radeon: 512M of GTT memory ready.
[    1.862084] [drm] Loading RV610 Microcode
[    1.862181] [drm] Internal thermal controller with fan control
[    1.862236] [drm] radeon: power management initialized
[    1.862349] [drm] GART: num cpu pages 131072, num gpu pages 131072
[    1.870765] ahci 0000:00:1f.2: version 3.0
[    1.871046] ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0xf impl RAID mode
[    1.871049] ahci 0000:00:1f.2: flags: 64bit ncq led clo 
[    1.876563] e100 0000:02:08.0 enp2s8: renamed from eth0
[    1.881045] scsi host2: ahci
[    1.881373] scsi host3: ahci
[    1.881671] scsi host4: ahci
[    1.881967] scsi host5: ahci
[    1.882036] ata3: SATA max UDMA/133 abar m1024@0xfdffe000 port 0xfdffe100 irq 24
[    1.882039] ata4: SATA max UDMA/133 abar m1024@0xfdffe000 port 0xfdffe180 irq 24
[    1.882041] ata5: SATA max UDMA/133 abar m1024@0xfdffe000 port 0xfdffe200 irq 24
[    1.882043] ata6: SATA max UDMA/133 abar m1024@0xfdffe000 port 0xfdffe280 irq 24
[    1.889643] [drm] PCIE GART of 512M enabled (table at 0x0000000000142000).
[    1.889709] radeon 0000:01:00.0: WB enabled
[    1.889713] radeon 0000:01:00.0: fence driver on ring 0 use gpu addr 0x0000000010000c00 and cpu addr 0x        (ptrval)
[    1.890161] radeon 0000:01:00.0: fence driver on ring 5 use gpu addr 0x00000000000521d0 and cpu addr 0x        (ptrval)
[    1.890163] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[    1.890164] [drm] Driver supports precise vblank timestamp query.
[    1.890166] radeon 0000:01:00.0: radeon: MSI limited to 32-bit
[    1.890224] radeon 0000:01:00.0: radeon: using MSI.
[    1.890254] [drm] radeon: irq initialized.
[    1.921596] [drm] ring test on 0 succeeded in 1 usecs
[    1.928038] firewire_ohci 0000:02:01.0: added OHCI v1.0 device as card 0, 8 IR + 8 IT contexts, quirks 0x0
[    2.096262] [drm] ring test on 5 succeeded in 1 usecs
[    2.096270] [drm] UVD initialized successfully.
[    2.096442] [drm] ib test on ring 0 succeeded in 0 usecs
[    2.125498] usb 1-3: New USB device found, idVendor=0b05, idProduct=17ba
[    2.125500] usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.125502] usb 1-3: Product: 802.11n WLAN Adapter
[    2.125504] usb 1-3: Manufacturer: Realtek
[    2.125506] usb 1-3: SerialNumber: 00e04c000001
[    2.194247] ata5: SATA link down (SStatus 0 SControl 300)
[    2.194273] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    2.194289] ata6: SATA link down (SStatus 0 SControl 300)
[    2.194313] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    2.194408] ata3.00: ATA-11: Gost SSD120 120GB, SBFM61.0, max UDMA/133
[    2.194410] ata3.00: 234441648 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    2.194501] ata3.00: configured for UDMA/133
[    2.196156] scsi 2:0:0:0: Direct-Access     ATA      Gost SSD120 120G 61.0 PQ: 0 ANSI: 5
[    2.196484] sd 2:0:0:0: Attached scsi generic sg0 type 0
[    2.196648] sd 2:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB)
[    2.196665] sd 2:0:0:0: [sda] Write Protect is off
[    2.196667] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    2.196694] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.206978] ata4.00: ATAPI: ATAPI   DVD A  DH16A3L, 8H1B, max UDMA/100
[    2.220368] ata4.00: configured for UDMA/100
[    2.245876] scsi 3:0:0:0: CD-ROM            ATAPI    DVD A  DH16A3L   8H1B PQ: 0 ANSI: 5
[    2.246893]  sda: sda1 sda2
[    2.247380] sd 2:0:0:0: [sda] Attached SCSI disk
[    2.310217] sr 3:0:0:0: [sr0] scsi3-mmc drive: 40x/12x writer dvd-ram cd/rw xa/form2 cdda tray
[    2.310220] cdrom: Uniform CD-ROM driver Revision: 3.20
[    2.310422] sr 3:0:0:0: Attached scsi CD-ROM sr0
[    2.310505] sr 3:0:0:0: Attached scsi generic sg1 type 5
[    2.432017] tsc: Refined TSC clocksource calibration: 2000.567 MHz
[    2.432027] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1cd645b8b21, max_idle_ns: 440795245754 ns
[    2.460018] usb 4-2: new full-speed USB device number 2 using uhci_hcd
[    2.624186] firewire_core 0000:02:01.0: created device fw0: GUID 0011d800017e74ae, S400
[    2.662029] usb 4-2: New USB device found, idVendor=046d, idProduct=c52b
[    2.662034] usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.662038] usb 4-2: Product: USB Receiver
[    2.662041] usb 4-2: Manufacturer: Logitech
[    2.673182] hidraw: raw HID events driver (C) Jiri Kosina
[    2.686123] usbcore: registered new interface driver usbhid
[    2.686126] usbhid: USB HID core driver
[    2.691318] logitech-djreceiver 0003:046D:C52B.0003: hiddev0,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:1d.2-2/input2
[    2.752073] [drm] ib test on ring 5 succeeded
[    2.752821] [drm] Radeon Display Connectors
[    2.752823] [drm] Connector 0:
[    2.752824] [drm]   DIN-1
[    2.752825] [drm]   Encoders:
[    2.752826] [drm]     TV1: INTERNAL_KLDSCP_DAC2
[    2.752826] [drm] Connector 1:
[    2.752827] [drm]   VGA-1
[    2.752829] [drm]   DDC: 0x7e50 0x7e50 0x7e54 0x7e54 0x7e58 0x7e58 0x7e5c 0x7e5c
[    2.752830] [drm]   Encoders:
[    2.752831] [drm]     CRT2: INTERNAL_KLDSCP_DAC2
[    2.752832] [drm] Connector 2:
[    2.752832] [drm]   DVI-I-1
[    2.752833] [drm]   HPD1
[    2.752835] [drm]   DDC: 0x7e40 0x7e40 0x7e44 0x7e44 0x7e48 0x7e48 0x7e4c 0x7e4c
[    2.752835] [drm]   Encoders:
[    2.752836] [drm]     CRT1: INTERNAL_KLDSCP_DAC1
[    2.752837] [drm]     DFP1: INTERNAL_LVTM1
[    2.812880] input: Logitech Unifying Device. Wireless PID:401b as /devices/pci0000:00/0000:00:1d.2/usb4/4-2/4-2:1.2/0003:046D:C52B.0003/0003:046D:401B.0004/input/input2
[    2.813018] hid-generic 0003:046D:401B.0004: input,hidraw1: USB HID v1.11 Mouse [Logitech Unifying Device. Wireless PID:401b] on usb-0000:00:1d.2-2:1
[    2.815050] input: Logitech Unifying Device. Wireless PID:4016 as /devices/pci0000:00/0000:00:1d.2/usb4/4-2/4-2:1.2/0003:046D:C52B.0003/0003:046D:4016.0005/input/input3
[    2.819882] [drm] fb mappable at 0xE0243000
[    2.819884] [drm] vram apper at 0xE0000000
[    2.819885] [drm] size 7299072
[    2.819886] [drm] fb depth is 24
[    2.819887] [drm]    pitch is 6912
[    2.819991] fbcon: radeondrmfb (fb0) is primary device
[    2.820109] Console: switching to colour frame buffer device 210x65
[    2.820164] radeon 0000:01:00.0: fb0: radeondrmfb frame buffer device
[    2.832074] [drm] Initialized radeon 2.50.0 20080528 for 0000:01:00.0 on minor 0
[    2.852178] hid-generic 0003:046D:4016.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech Unifying Device. Wireless PID:4016] on usb-0000:00:1d.2-2:2
[    2.906139] input: Logitech M215 2nd Gen as /devices/pci0000:00/0000:00:1d.2/usb4/4-2/4-2:1.2/0003:046D:C52B.0003/0003:046D:401B.0004/input/input4
[    2.906289] logitech-hidpp-device 0003:046D:401B.0004: input,hidraw1: USB HID v1.11 Mouse [Logitech M215 2nd Gen] on usb-0000:00:1d.2-2:1
[    2.909025] random: fast init done
[    2.916320] input: Logitech K330 as /devices/pci0000:00/0000:00:1d.2/usb4/4-2/4-2:1.2/0003:046D:C52B.0003/0003:046D:4016.0005/input/input5
[    2.916416] logitech-hidpp-device 0003:046D:4016.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech K330] on usb-0000:00:1d.2-2:2
[    3.152008] usb 1-7: new high-speed USB device number 4 using ehci-pci
[    3.309908] usb 1-7: New USB device found, idVendor=058f, idProduct=6377
[    3.309912] usb 1-7: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    3.309915] usb 1-7: Product: Mass Storage Device
[    3.309918] usb 1-7: Manufacturer: Generic
[    3.309921] usb 1-7: SerialNumber: 920321111113
[    3.456389] clocksource: Switched to clocksource tsc
[    3.457000] usb-storage 1-7:1.0: USB Mass Storage device detected
[    3.457130] scsi host6: usb-storage 1-7:1.0
[    3.457238] usbcore: registered new interface driver usb-storage
[    3.459255] usbcore: registered new interface driver uas
[    3.883997] raid6: sse2x1   gen()  3212 MB/s
[    3.931981] raid6: sse2x1   xor()  3301 MB/s
[    3.979982] raid6: sse2x2   gen()  3670 MB/s
[    4.027981] raid6: sse2x2   xor()  4024 MB/s
[    4.075976] raid6: sse2x4   gen()  5787 MB/s
[    4.123981] raid6: sse2x4   xor()  4566 MB/s
[    4.123983] raid6: using algorithm sse2x4 gen() 5787 MB/s
[    4.123984] raid6: .... xor() 4566 MB/s, rmw enabled
[    4.123985] raid6: using ssse3x2 recovery algorithm
[    4.132489] xor: measuring software checksum speed
[    4.171978]    prefetch64-sse:  7766.000 MB/sec
[    4.211976]    generic_sse:  6914.000 MB/sec
[    4.211977] xor: using function: prefetch64-sse (7766.000 MB/sec)
[    4.242125] Btrfs loaded, crc32c=crc32c-generic
[    4.361740] BTRFS: device fsid f813bbe2-0bff-4923-822b-d3f6d6ebbb9e devid 1 transid 458035 /dev/sda2
[    4.403940] BTRFS info (device sda2): disk space caching is enabled
[    4.403944] BTRFS info (device sda2): has skinny extents
[    4.442535] BTRFS info (device sda2): enabling ssd optimizations
[    4.442539] BTRFS info (device sda2): start tree-log replay
[    4.480843] scsi 6:0:0:0: Direct-Access     Generic  USB SD Reader    1.00 PQ: 0 ANSI: 0
[    4.481478] scsi 6:0:0:1: Direct-Access     Generic  USB CF Reader    1.01 PQ: 0 ANSI: 0
[    4.482080] scsi 6:0:0:2: Direct-Access     Generic  USB SM Reader    1.02 PQ: 0 ANSI: 0
[    4.482723] scsi 6:0:0:3: Direct-Access     Generic  USB MS Reader    1.03 PQ: 0 ANSI: 0
[    4.483227] sd 6:0:0:0: Attached scsi generic sg2 type 0
[    4.484114] sd 6:0:0:1: Attached scsi generic sg3 type 0
[    4.484871] sd 6:0:0:2: Attached scsi generic sg4 type 0
[    4.488966] sd 6:0:0:1: [sdc] Attached SCSI removable disk
[    4.490345] sd 6:0:0:0: [sdb] Attached SCSI removable disk
[    4.490563] sd 6:0:0:3: Attached scsi generic sg5 type 0
[    4.496468] sd 6:0:0:3: [sde] Attached SCSI removable disk
[    4.500836] sd 6:0:0:2: [sdd] Attached SCSI removable disk
[    4.656758] BTRFS critical (device sda2): corrupt leaf: block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid generation, have 9367487224930631680 expect (0, 458036]
[    4.656783] BTRFS: error (device sda2) in __btrfs_free_extent:7000: errno=-5 IO failure
[    4.656788] BTRFS: error (device sda2) in btrfs_run_delayed_refs:3083: errno=-5 IO failure
[    4.657768] BTRFS error (device sda2): pending csums is 475136
[    4.657781] BTRFS: error (device sda2) in btrfs_replay_log:2369: errno=-5 IO failure (Failed to recover log tree)
[    4.700025] BTRFS error (device sda2): open_ctree failed
[   14.852000] usb 1-5: new high-speed USB device number 5 using ehci-pci
[   15.009542] usb 1-5: New USB device found, idVendor=0951, idProduct=1666
[   15.009546] usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[   15.009549] usb 1-5: Product: DataTraveler 3.0
[   15.009552] usb 1-5: Manufacturer: Kingston
[   15.009555] usb 1-5: SerialNumber: AC220B280C83B0310978B46A
[   15.009851] usb-storage 1-5:1.0: USB Mass Storage device detected
[   15.010012] scsi host7: usb-storage 1-5:1.0
[   16.032864] scsi 7:0:0:0: Direct-Access     Kingston DataTraveler 3.0 PMAP PQ: 0 ANSI: 6
[   16.033149] sd 7:0:0:0: Attached scsi generic sg6 type 0
[   16.033714] sd 7:0:0:0: [sdf] 121110528 512-byte logical blocks: (62.0 GB/57.8 GiB)
[   16.034333] sd 7:0:0:0: [sdf] Write Protect is off
[   16.034337] sd 7:0:0:0: [sdf] Mode Sense: 45 00 00 00
[   16.034960] sd 7:0:0:0: [sdf] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[   17.916541] logitech-hidpp-device 0003:046D:4016.0005: HID++ 2.0 device connected.
[   18.281784] sd 7:0:0:0: [sdf] Attached SCSI removable disk
[   22.554691] random: crng init done
[   22.554694] random: 7 urandom warning(s) missed due to ratelimiting
[   66.179050] EXT4-fs (sdf): mounted filesystem with ordered data mode. Opts: (null)

^ permalink raw reply	[flat|nested] 4+ messages in thread

* Re: fails to boot with "BTRFS critical (device sda2): corrupt leaf: ..."
  2020-08-08  9:57 fails to boot with "BTRFS critical (device sda2): corrupt leaf: ..." Lu Pi
@ 2020-08-08 11:45 ` Qu Wenruo
  2020-08-08 12:17   ` Qu Wenruo
  2020-08-08 15:57   ` Lu Pi
  0 siblings, 2 replies; 4+ messages in thread
From: Qu Wenruo @ 2020-08-08 11:45 UTC (permalink / raw)
  To: Lu Pi, linux-btrfs


[-- Attachment #1.1: Type: text/plain, Size: 6154 bytes --]



On 2020/8/8 下午5:57, Lu Pi wrote:
> Hi,
> 
> I have a system that fails to boot, with "BTRFS critical (device
> sda2): corrupt leaf: " error, and open an "initramfs" shell.
> 
> I did backup /home with 'btrfs restore'. There were a few errors,
> though only on cache files (Google Chrome cache files).
> 
> Now considering 'btrfs check --repair'.
> 
> I'm contacting you as recommended here:
>    https://btrfs.wiki.kernel.org/index.php/Tree-checker
>    "Please report to btrfs mail list <linux-btrfs@vger.kernel.org> first."
>    "Please *NOT* use btrfs check --repair until instructed by a developer."
> 
> 
> Can you advice?
> 
> 
> 
> 
> BACKGROUND
> 
> - the system is Linux Mint 17
> 
> - a week ago or so, after a kernel update, the system was remounting
> read-only after about 1 minute after boot. Downgrading the kernel
> solved the issue.
>   - 4.15.0-112-generic brought the issue
>   - 4.15.0-107-generic was OK
> 
> - a few days ago, something else happened, though I'm unsure, as I'm
> not the user of the system. Possibly any of these,
>   - another kernel update (now I can see that 4.15.0-112 is back)

The kernel update is the direct cause, we added a lot of extra selftest
to ensure we detect problems before it crash the kernel.

The root cause is some even older kernel, which writes some
uninitialized data to disk.

>   - maybe the system was shut down by cutting electricity (?)
>   - could it be also that the SSD drive is failing (?)
>   - or?
>   - though as a result the system fails to boot and the drive is not mountable.
> 
> 
> 
> SYSTEM INFORMATION
> ---
> When reporting errors or asking for support always supply the output
> of the following commands:
>   uname -a
>   btrfs --version
>   btrfs fi show
>   btrfs fi df /home # Replace /home with the mount point of your
> btrfs-filesystem
>   dmesg > dmesg.log
> ---
> 
> See below, and dmesg log enclosed
> 
> 
> ---
> (initramfs) uname -a
> Linux (none) 4.15.0-112-generic #113~16.04.1-Ubuntu SMP Fri Jul 10
> 04:37:08 UTC 2020 x86_64 GNU/Linux

This is a little old, considering how many enhancement and bug fixes are
in recent kernel releases.

Thus it's recommended to use newer kernel, *after* your problem been fixed.

> 
> 
> (initramfs) btrfs --version
> btrfs-progs v4.4

Btrfs-progs is too old to even detect the problem, not to mention fix it.
It should only report the fs as healthy, if there are no other problems.

> 
> 
> (initramfs) btrfs fi show
> Label: none  uuid: f813bbe2-0bff-4923-822b-d3f6d6ebbb9e
>     Total devices 1 FS bytes used 55.22GiB
>     devid    1 size 107.98GiB used 85.02GiB path /dev/sda2
> 
> 
> (initramfs) btrfs fi df /home
> ERROR: can't access '/home': No such file or directory
> 
> (initramfs) btrfs fi df /
> ERROR: not a btrfs filesystem: /
> 
> 
> 
> (initramfs) mount -t btrfs /dev/sda2 /mnt/sda/
> [70391.973518] BTRFS critical (device sda2): corrupt leaf:
> block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid
> generation, have 9367487224930631680 expect (0, 458036]

The generation is mostly garbage, it's 0x8200000000000000L, just some
random number not really initialized.

This is a pretty old bug in older kernels.

It's the recently added extra self check detecting them.

This can be detected by "btrfs check --repair" after btrfs-progs v5.4.1,
but not yet repairable. (Haven't got a real world report before this one)


There is a way to workaround this, by locating the offending extent, and
delete it manually.

Firstly, you need to mount the fs with older kernel.

Then run the following command (maybe you need latest btrfs-progs):
# btrfs ins logical-resolve 242073600 <mnt>

Where the 242073600 is the "extent bytenr" in the dmesg output.

There are two possible output patterns:
- The path of the offending file
  Then just delete it.

- No such file or directory
  This means it's a tree block, it's going to be a little trikcy.
  You need to use btrfs ins again:
  # btrfs ins dump-tree -t 402653184 <device>

  Then search thing like this "EXTENT_DATA":
        item 6 key (257 EXTENT_DATA 0) itemoff 15813 itemsize 53
                generation 3 type 1 (regular)
                extent data disk byte 138424320 nr 1048576
                                      ^^^^^^^^^
  Then use that "138424320" to logical-resolve command again, then
  to remove all offending files.

I'll work on the btrfs check repair ability soon, before that, please
use the above workaround.

Sorry for the inconvenience and thanks for the first real world report.

Thanks,
Qu

> [70391.975504] BTRFS: error (device sda2) in __btrfs_free_extent:7000:
> errno=-5 IO failure
> [70391.977490] BTRFS: error (device sda2) in
> btrfs_run_delayed_refs:3083: errno=-5 IO failure
> [70391.979490] BTRFS: error (device sda2) in btrfs_replay_log:2369:
> errno=-5 IO failure (Failed to recover log tree)
> [70391.980588] BTRFS error (device sda2): pending csums is 475136
> [70392.023935] BTRFS error (device sda2): open_ctree failed
> mount: mounting /dev/sda2 on /mnt/sda/ failed: Input/output error
> 
> 
> 
> (initramfs) dmesg |grep 70391
> [70391.723717] BTRFS info (device sda2): disk space caching is enabled
> [70391.723721] BTRFS info (device sda2): has skinny extents
> [70391.763253] BTRFS info (device sda2): enabling ssd optimizations
> [70391.763256] BTRFS info (device sda2): start tree-log replay
> [70391.973518] BTRFS critical (device sda2): corrupt leaf:
> block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid
> generation, have 9367487224930631680 expect (0, 458036]
> [70391.975504] BTRFS: error (device sda2) in __btrfs_free_extent:7000:
> errno=-5 IO failure
> [70391.977490] BTRFS: error (device sda2) in
> btrfs_run_delayed_refs:3083: errno=-5 IO failure
> [70391.979490] BTRFS: error (device sda2) in btrfs_replay_log:2369:
> errno=-5 IO failure (Failed to recover log tree)
> [70391.980588] BTRFS error (device sda2): pending csums is 475136
> 
> 
> full dmesg log enclosed.
> ---
> 


[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 488 bytes --]

^ permalink raw reply	[flat|nested] 4+ messages in thread

* Re: fails to boot with "BTRFS critical (device sda2): corrupt leaf: ..."
  2020-08-08 11:45 ` Qu Wenruo
@ 2020-08-08 12:17   ` Qu Wenruo
  2020-08-08 15:57   ` Lu Pi
  1 sibling, 0 replies; 4+ messages in thread
From: Qu Wenruo @ 2020-08-08 12:17 UTC (permalink / raw)
  To: Lu Pi, linux-btrfs


[-- Attachment #1.1: Type: text/plain, Size: 263 bytes --]



On 2020/8/8 下午7:45, Qu Wenruo wrote:
> This can be detected by "btrfs check --repair" after btrfs-progs v5.4.1,
> but not yet repairable. (Haven't got a real world report before this one)

Typoe here, just "btrfs check" no --repair.

Thanks,
Qu


[-- Attachment #2: OpenPGP digital signature --]
[-- Type: application/pgp-signature, Size: 488 bytes --]

^ permalink raw reply	[flat|nested] 4+ messages in thread

* Re: fails to boot with "BTRFS critical (device sda2): corrupt leaf: ..."
  2020-08-08 11:45 ` Qu Wenruo
  2020-08-08 12:17   ` Qu Wenruo
@ 2020-08-08 15:57   ` Lu Pi
  1 sibling, 0 replies; 4+ messages in thread
From: Lu Pi @ 2020-08-08 15:57 UTC (permalink / raw)
  To: Qu Wenruo; +Cc: linux-btrfs

On Sat, 8 Aug 2020 at 13:46, Qu Wenruo <quwenruo.btrfs@gmx.com> wrote:
>
>
>
> On 2020/8/8 下午5:57, Lu Pi wrote:
> > Hi,
> >
> > I have a system that fails to boot, with "BTRFS critical (device
> > sda2): corrupt leaf: " error, and open an "initramfs" shell.
> >
> > I did backup /home with 'btrfs restore'. There were a few errors,
> > though only on cache files (Google Chrome cache files).
> >
> > Now considering 'btrfs check --repair'.
> >
> > I'm contacting you as recommended here:
> >    https://btrfs.wiki.kernel.org/index.php/Tree-checker
> >    "Please report to btrfs mail list <linux-btrfs@vger.kernel.org> first."
> >    "Please *NOT* use btrfs check --repair until instructed by a developer."
> >
> >
> > Can you advice?
> >
> >
> >
> >
> > BACKGROUND
> >
> > - the system is Linux Mint 17
> >
> > - a week ago or so, after a kernel update, the system was remounting
> > read-only after about 1 minute after boot. Downgrading the kernel
> > solved the issue.
> >   - 4.15.0-112-generic brought the issue
> >   - 4.15.0-107-generic was OK
> >
> > - a few days ago, something else happened, though I'm unsure, as I'm
> > not the user of the system. Possibly any of these,
> >   - another kernel update (now I can see that 4.15.0-112 is back)
>
> The kernel update is the direct cause, we added a lot of extra selftest
> to ensure we detect problems before it crash the kernel.
>
> The root cause is some even older kernel, which writes some
> uninitialized data to disk.
>
> >   - maybe the system was shut down by cutting electricity (?)
> >   - could it be also that the SSD drive is failing (?)
> >   - or?
> >   - though as a result the system fails to boot and the drive is not mountable.
> >
> >
> >
> > SYSTEM INFORMATION
> > ---
> > When reporting errors or asking for support always supply the output
> > of the following commands:
> >   uname -a
> >   btrfs --version
> >   btrfs fi show
> >   btrfs fi df /home # Replace /home with the mount point of your
> > btrfs-filesystem
> >   dmesg > dmesg.log
> > ---
> >
> > See below, and dmesg log enclosed
> >
> >
> > ---
> > (initramfs) uname -a
> > Linux (none) 4.15.0-112-generic #113~16.04.1-Ubuntu SMP Fri Jul 10
> > 04:37:08 UTC 2020 x86_64 GNU/Linux
>
> This is a little old, considering how many enhancement and bug fixes are
> in recent kernel releases.
>
> Thus it's recommended to use newer kernel, *after* your problem been fixed.
>
> >
> >
> > (initramfs) btrfs --version
> > btrfs-progs v4.4
>
> Btrfs-progs is too old to even detect the problem, not to mention fix it.
> It should only report the fs as healthy, if there are no other problems.
>
> >
> >
> > (initramfs) btrfs fi show
> > Label: none  uuid: f813bbe2-0bff-4923-822b-d3f6d6ebbb9e
> >     Total devices 1 FS bytes used 55.22GiB
> >     devid    1 size 107.98GiB used 85.02GiB path /dev/sda2
> >
> >
> > (initramfs) btrfs fi df /home
> > ERROR: can't access '/home': No such file or directory
> >
> > (initramfs) btrfs fi df /
> > ERROR: not a btrfs filesystem: /
> >
> >
> >
> > (initramfs) mount -t btrfs /dev/sda2 /mnt/sda/
> > [70391.973518] BTRFS critical (device sda2): corrupt leaf:
> > block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid
> > generation, have 9367487224930631680 expect (0, 458036]
>
> The generation is mostly garbage, it's 0x8200000000000000L, just some
> random number not really initialized.
>
> This is a pretty old bug in older kernels.
>
> It's the recently added extra self check detecting them.
>
> This can be detected by "btrfs check --repair" after btrfs-progs v5.4.1,
> but not yet repairable. (Haven't got a real world report before this one)
>
>
> There is a way to workaround this, by locating the offending extent, and
> delete it manually.
>
> Firstly, you need to mount the fs with older kernel.
>
> Then run the following command (maybe you need latest btrfs-progs):
> # btrfs ins logical-resolve 242073600 <mnt>
>
> Where the 242073600 is the "extent bytenr" in the dmesg output.
>
> There are two possible output patterns:
> - The path of the offending file
>   Then just delete it.
>
> - No such file or directory
>   This means it's a tree block, it's going to be a little trikcy.
>   You need to use btrfs ins again:
>   # btrfs ins dump-tree -t 402653184 <device>
>
>   Then search thing like this "EXTENT_DATA":
>         item 6 key (257 EXTENT_DATA 0) itemoff 15813 itemsize 53
>                 generation 3 type 1 (regular)
>                 extent data disk byte 138424320 nr 1048576
>                                       ^^^^^^^^^
>   Then use that "138424320" to logical-resolve command again, then
>   to remove all offending files.
>
> I'll work on the btrfs check repair ability soon, before that, please
> use the above workaround.

I will do that, and report back

Thank You so much for your fast and detailed answer


>
> Sorry for the inconvenience and thanks for the first real world report.

No worry, I'm glad to contribute information for a better kernel,

Thank You again, very much


>
> Thanks,
> Qu
>
> > [70391.975504] BTRFS: error (device sda2) in __btrfs_free_extent:7000:
> > errno=-5 IO failure
> > [70391.977490] BTRFS: error (device sda2) in
> > btrfs_run_delayed_refs:3083: errno=-5 IO failure
> > [70391.979490] BTRFS: error (device sda2) in btrfs_replay_log:2369:
> > errno=-5 IO failure (Failed to recover log tree)
> > [70391.980588] BTRFS error (device sda2): pending csums is 475136
> > [70392.023935] BTRFS error (device sda2): open_ctree failed
> > mount: mounting /dev/sda2 on /mnt/sda/ failed: Input/output error
> >
> >
> >
> > (initramfs) dmesg |grep 70391
> > [70391.723717] BTRFS info (device sda2): disk space caching is enabled
> > [70391.723721] BTRFS info (device sda2): has skinny extents
> > [70391.763253] BTRFS info (device sda2): enabling ssd optimizations
> > [70391.763256] BTRFS info (device sda2): start tree-log replay
> > [70391.973518] BTRFS critical (device sda2): corrupt leaf:
> > block=353828864 slot=148 extent bytenr=242073600 len=16384 invalid
> > generation, have 9367487224930631680 expect (0, 458036]
> > [70391.975504] BTRFS: error (device sda2) in __btrfs_free_extent:7000:
> > errno=-5 IO failure
> > [70391.977490] BTRFS: error (device sda2) in
> > btrfs_run_delayed_refs:3083: errno=-5 IO failure
> > [70391.979490] BTRFS: error (device sda2) in btrfs_replay_log:2369:
> > errno=-5 IO failure (Failed to recover log tree)
> > [70391.980588] BTRFS error (device sda2): pending csums is 475136
> >
> >
> > full dmesg log enclosed.
> > ---
> >
>

^ permalink raw reply	[flat|nested] 4+ messages in thread

end of thread, other threads:[~2020-08-08 15:58 UTC | newest]

Thread overview: 4+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2020-08-08  9:57 fails to boot with "BTRFS critical (device sda2): corrupt leaf: ..." Lu Pi
2020-08-08 11:45 ` Qu Wenruo
2020-08-08 12:17   ` Qu Wenruo
2020-08-08 15:57   ` Lu Pi

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.