[ 0.000000] Command line: ip=::::snb-drag::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.2 branch=linux-devel/devel-hourly-2018041614 commit=60cc43fc888428bb2f18f08997432d426a243338 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.2/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/vmlinuz-4.17.0-rc1 max_uptime=7200 RESULT_ROOT=/result/xfstests/4HDD-btrfs-generic-group5/snb-drag/debian-x86_64-2016-08-31.cgz/x86_64-rhel-7.2/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000100-0x000000000009ebff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009ec00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001fffffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000020000000-0x00000000201fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000020200000-0x000000003fffffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000040000000-0x00000000401fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000040200000-0x00000000bac1dfff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bac1e000-0x00000000bac76fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000bac77000-0x00000000bada9fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000badaa000-0x00000000badbafff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000badbb000-0x00000000badcefff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000badcf000-0x00000000badd0fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000badd1000-0x00000000badd1fff] ACPI data [ 0.000000] BIOS-e820: [mem 0x00000000badd2000-0x00000000badd9fff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000badda000-0x00000000bade3fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000bade4000-0x00000000bae3dfff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000bae3e000-0x00000000bae80fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000bae81000-0x00000000baffffff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bb800000-0x00000000bf9fffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x00000001bfdfffff] usable [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] found SMP MP-table at [mem 0x000fcdd0-0x000fcddf] mapped at [ (ptrval)] [ 0.000000] mpc: fcaa0-fcd54 [ 0.000000] Base memory trampoline at [ (ptrval)] 97000 size 24576 [ 0.000000] reserving inaccessible SNB gfx pages [ 0.000000] BRK [0x1bef4b000, 0x1bef4bfff] PGTABLE [ 0.000000] BRK [0x1bef4c000, 0x1bef4cfff] PGTABLE [ 0.000000] BRK [0x1bef4d000, 0x1bef4dfff] PGTABLE [ 0.000000] BRK [0x1bef4e000, 0x1bef4efff] PGTABLE [ 0.000000] BRK [0x1bef4f000, 0x1bef4ffff] PGTABLE [ 0.000000] BRK [0x1bef50000, 0x1bef50fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1a6b7f000-0x1bcffffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F0420 000024 (v02 ALASKA) [ 0.000000] ACPI: XSDT 0x00000000BAC69068 00004C (v01 ALASKA A M I 01072009 AMI 00010013) [ 0.000000] ACPI: FACP 0x00000000BAC72FA0 0000F4 (v04 ALASKA A M I 01072009 AMI 00010013) [ 0.000000] ACPI: DSDT 0x00000000BAC69140 009E5F (v02 ALASKA A M I 00000000 INTL 20051117) [ 0.000000] ACPI: FACS 0x00000000BADDBF80 000040 [ 0.000000] ACPI: APIC 0x00000000BAC73098 000072 (v03 ALASKA A M I 01072009 AMI 00010013) [ 0.000000] ACPI: SSDT 0x00000000BAC73110 000102 (v01 AMICPU PROC 00000001 MSFT 03000001) [ 0.000000] ACPI: MCFG 0x00000000BAC73218 00003C (v01 ALASKA A M I 01072009 MSFT 00000097) [ 0.000000] ACPI: HPET 0x00000000BAC73258 000038 (v01 ALASKA A M I 01072009 AMI. 00000004) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fc000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x00000001bfdfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1bfdd1000-0x1bfdfbfff] [ 0.000000] cma: Reserved 200 MiB at 0x000000019a000000 [ 0.000000] tsc: Fast TSC calibration using PIT [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x00000001bfdfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009dfff] [ 0.000000] node 0: [mem 0x0000000000100000-0x000000001fffffff] [ 0.000000] node 0: [mem 0x0000000020200000-0x000000003fffffff] [ 0.000000] node 0: [mem 0x0000000040200000-0x00000000bac1dfff] [ 0.000000] node 0: [mem 0x00000000badcf000-0x00000000badd0fff] [ 0.000000] node 0: [mem 0x00000000bae81000-0x00000000baffffff] [ 0.000000] node 0: [mem 0x0000000100000000-0x00000001bfdfffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x00000001bfdfffff] [ 0.000000] On node 0 totalpages: 1550140 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 157 pages reserved [ 0.000000] DMA zone: 3997 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 11879 pages used for memmap [ 0.000000] DMA32 zone: 760223 pages, LIFO batch:31 [ 0.000000] Normal zone: 12280 pages used for memmap [ 0.000000] Normal zone: 785920 pages, LIFO batch:31 [ 0.000000] Reserved but unavailable: 104 pages [ 0.000000] Reserving Intel graphics memory at [mem 0xbba00000-0xbf9fffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x408 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] high edge lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, 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] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09 [ 0.000000] ACPI: IRQ0 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 05, APIC ID 0, APIC INT 05 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08 [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a701 base: 0xfed00000 [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff5fb000 (fec00000) [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009e000-0x0009efff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000dffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000e0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0x20000000-0x201fffff] [ 0.000000] PM: Registered nosave memory: [mem 0x40000000-0x401fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbac1e000-0xbac76fff] [ 0.000000] PM: Registered nosave memory: [mem 0xbac77000-0xbada9fff] [ 0.000000] PM: Registered nosave memory: [mem 0xbadaa000-0xbadbafff] [ 0.000000] PM: Registered nosave memory: [mem 0xbadbb000-0xbadcefff] [ 0.000000] PM: Registered nosave memory: [mem 0xbadd1000-0xbadd1fff] [ 0.000000] PM: Registered nosave memory: [mem 0xbadd2000-0xbadd9fff] [ 0.000000] PM: Registered nosave memory: [mem 0xbadda000-0xbade3fff] [ 0.000000] PM: Registered nosave memory: [mem 0xbade4000-0xbae3dfff] [ 0.000000] PM: Registered nosave memory: [mem 0xbae3e000-0xbae80fff] [ 0.000000] PM: Registered nosave memory: [mem 0xbb000000-0xbb7fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbb800000-0xbf9fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbfa00000-0xfed1bfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfed1c000-0xfed1ffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfed20000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xffffffff] [ 0.000000] e820: [mem 0xbfa00000-0xfed1bfff] 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: 1910969940391419 ns [ 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 @ (ptrval) s147032 r8192 d29096 u524288 [ 0.000000] pcpu-alloc: s147032 r8192 d29096 u524288 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 2 3 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1525760 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: ip=::::snb-drag::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.2 branch=linux-devel/devel-hourly-2018041614 commit=60cc43fc888428bb2f18f08997432d426a243338 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.2/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/vmlinuz-4.17.0-rc1 max_uptime=7200 RESULT_ROOT=/result/xfstests/4HDD-btrfs-generic-group5/snb-drag/debian-x86_64-2016-08-31.cgz/x86_64-rhel-7.2/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] Memory: 5432776K/6200560K available (12300K kernel code, 2592K rwdata, 4168K rodata, 2384K init, 2472K bss, 562984K reserved, 204800K 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 39726 entries in 156 pages [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 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] Offload RCU callbacks from CPUs: (none). [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] console [ttyS0] enabled [ 0.000000] bootconsole [earlyser0] disabled [ 0.000000] ACPI: Core revision 20180313 [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns [ 0.000000] hpet clockevent registered [ 0.001000] APIC: Switch to symmetric I/O mode setup [ 0.002000] enabled ExtINT on CPU#0 [ 0.003000] ENABLING IO-APIC IRQs [ 0.004000] init IO_APIC IRQs [ 0.005000] apic 0 pin 0 not connected [ 0.006000] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.007000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.007000] apic 0 pin 16 not connected [ 0.007000] apic 0 pin 17 not connected [ 0.007000] apic 0 pin 18 not connected [ 0.007000] apic 0 pin 19 not connected [ 0.007000] apic 0 pin 20 not connected [ 0.007000] apic 0 pin 21 not connected [ 0.007000] apic 0 pin 22 not connected [ 0.007000] apic 0 pin 23 not connected [ 0.007000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.012000] tsc: Fast TSC calibration using PIT [ 0.013000] tsc: Detected 3311.052 MHz processor [ 0.014000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x2fba14ed876, max_idle_ns: 440795351825 ns [ 0.015002] Calibrating delay loop (skipped), value calculated using timer frequency.. 6622.10 BogoMIPS (lpj=3311052) [ 0.016001] pid_max: default: 32768 minimum: 301 [ 0.017013] Security Framework initialized [ 0.018001] SELinux: Initializing. [ 0.019009] SELinux: Starting in permissive mode [ 0.021748] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes) [ 0.022630] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes) [ 0.024005] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes) [ 0.025021] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes) [ 0.026158] CPU: Physical Processor ID: 0 [ 0.027001] CPU: Processor Core ID: 0 [ 0.028007] mce: CPU supports 9 MCE banks [ 0.029006] CPU0: Thermal monitoring enabled (TM1) [ 0.030006] process: using mwait in idle threads [ 0.031002] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8 [ 0.032000] Last level dTLB entries: 4KB 512, 2MB 32, 4MB 32, 1GB 0 [ 0.033003] Spectre V2 : Mitigation: Full generic retpoline [ 0.034074] Freeing SMP alternatives memory: 40K [ 0.036187] TSC deadline timer enabled [ 0.037000] smpboot: CPU0: Intel(R) Core(TM) i5-2500K CPU @ 3.30GHz (family: 0x6, model: 0x2a, stepping: 0x7) [ 0.037060] Performance Events: PEBS fmt1+, SandyBridge events, 16-deep LBR, full-width counters, Intel PMU driver. [ 0.038003] ... version: 3 [ 0.039001] ... bit width: 48 [ 0.040000] ... generic registers: 8 [ 0.041001] ... value mask: 0000ffffffffffff [ 0.042000] ... max period: 00007fffffffffff [ 0.043000] ... fixed-purpose events: 3 [ 0.044000] ... event mask: 00000007000000ff [ 0.045031] Hierarchical SRCU implementation. [ 0.046761] NMI watchdog: Enabled. Permanently consumes one hw-PMU counter. [ 0.047018] smp: Bringing up secondary CPUs ... [ 0.048061] x86: Booting SMP configuration: [ 0.049003] .... node #0, CPUs: #1 [ 0.001000] masked ExtINT on CPU#1 [ 0.057083] #2 [ 0.001000] masked ExtINT on CPU#2 [ 0.063083] #3 [ 0.001000] masked ExtINT on CPU#3 [ 0.069021] smp: Brought up 1 node, 4 CPUs [ 0.070002] smpboot: Max logical packages: 1 [ 0.071001] smpboot: Total of 4 processors activated (26488.41 BogoMIPS) [ 0.073196] devtmpfs: initialized [ 0.074044] x86/mm: Memory block size: 128MB [ 0.075935] Built 1 zonelists, mobility grouping on. Total pages: 1409404 [ 0.076002] Policy zone: Normal [ 0.077098] evm: security.selinux [ 0.078001] evm: security.ima [ 0.079000] evm: security.capability [ 0.080128] PM: Registering ACPI NVS region [mem 0xbac1e000-0xbac76fff] (364544 bytes) [ 0.081007] PM: Registering ACPI NVS region [mem 0xbadaa000-0xbadbafff] (69632 bytes) [ 0.082002] PM: Registering ACPI NVS region [mem 0xbadda000-0xbade3fff] (40960 bytes) [ 0.083001] PM: Registering ACPI NVS region [mem 0xbae3e000-0xbae80fff] (274432 bytes) [ 0.084038] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.085006] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.086040] pinctrl core: initialized pinctrl subsystem [ 0.087079] NET: Registered protocol family 16 [ 0.088171] audit: initializing netlink subsys (disabled) [ 0.089006] audit: type=2000 audit(1142250824.089:1): state=initialized audit_enabled=0 res=1 [ 0.097002] cpuidle: using governor menu [ 0.101151] ACPI: bus type PCI registered [ 0.105002] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.112052] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xe0000000-0xe3ffffff] (base 0xe0000000) [ 0.121003] PCI: not using MMCONFIG [ 0.125001] PCI: Using configuration type 1 for base access [ 0.131027] core: PMU erratum BJ122, BV98, HSD29 workaround disabled, HT off [ 0.139095] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.140051] ACPI: Added _OSI(Module Device) [ 0.144002] ACPI: Added _OSI(Processor Device) [ 0.148001] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.154001] ACPI: Added _OSI(Processor Aggregator Device) [ 0.159001] ACPI: Added _OSI(Linux-Dell-Video) [ 0.168765] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 0.176827] ACPI: Dynamic OEM Table Load: [ 0.181004] ACPI: SSDT 0xFFFF880192D5D800 00038C (v01 AMI IST 00000001 MSFT 03000001) [ 0.189216] ACPI: Dynamic OEM Table Load: [ 0.194003] ACPI: SSDT 0xFFFF880193354600 000084 (v01 AMI CST 00000001 MSFT 03000001) [ 0.202780] ACPI: Interpreter enabled [ 0.207016] ACPI: (supports S0 S1 S3 S4 S5) [ 0.211001] ACPI: Using IOAPIC for interrupt routing [ 0.216027] PCI: MMCONFIG for domain 0000 [bus 00-3f] at [mem 0xe0000000-0xe3ffffff] (base 0xe0000000) [ 0.225071] PCI: MMCONFIG at [mem 0xe0000000-0xe3ffffff] reserved in ACPI motherboard resources [ 0.234004] pmd_set_huge: Cannot satisfy [mem 0xe0000000-0xe0200000] with a huge-page mapping due to MTRR override. [ 0.245056] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.254204] ACPI: Enabled 7 GPEs in block 00 to 3F [ 0.259209] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored [ 0.270866] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.277006] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI] [ 0.286163] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug] [ 0.293151] acpi PNP0A08:00: _OSC: OS now controls [PME AER PCIeCapability] [ 0.300010] acpi PNP0A08:00: [Firmware Info]: MMCONFIG for domain 0000 [bus 00-3f] only partially covers this bridge [ 0.311112] acpi PNP0A08:00: ignoring host bridge window [mem 0x000c8000-0x000dffff window] (conflicts with Video ROM [mem 0x000c0000-0x000cd7ff]) [ 0.324148] PCI host bridge to bus 0000:00 [ 0.328003] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.335002] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.342002] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.349002] pci_bus 0000:00: root bus resource [mem 0xbfa00000-0xffffffff window] [ 0.357002] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.363007] pci 0000:00:00.0: [8086:0100] type 00 class 0x060000 [ 0.369075] pci 0000:00:01.0: [8086:0101] type 01 class 0x060400 [ 0.375028] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold [ 0.381067] pci 0000:00:02.0: [8086:0112] type 00 class 0x030000 [ 0.387009] pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe3fffff 64bit] [ 0.394005] pci 0000:00:02.0: reg 0x18: [mem 0xc0000000-0xcfffffff 64bit pref] [ 0.401004] pci 0000:00:02.0: reg 0x20: [io 0xf000-0xf03f] [ 0.407088] pci 0000:00:16.0: [8086:1c3a] type 00 class 0x078000 [ 0.413023] pci 0000:00:16.0: reg 0x10: [mem 0xfe607000-0xfe60700f 64bit] [ 0.420068] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold [ 0.426074] pci 0000:00:1a.0: [8086:1c2d] type 00 class 0x0c0320 [ 0.432021] pci 0000:00:1a.0: reg 0x10: [mem 0xfe606000-0xfe6063ff] [ 0.439078] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold [ 0.445072] pci 0000:00:1b.0: [8086:1c20] type 00 class 0x040300 [ 0.451020] pci 0000:00:1b.0: reg 0x10: [mem 0xfe600000-0xfe603fff 64bit] [ 0.458068] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold [ 0.464067] pci 0000:00:1c.0: [8086:1c10] type 01 class 0x060400 [ 0.470078] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold [ 0.476079] pci 0000:00:1c.4: [8086:1c18] type 01 class 0x060400 [ 0.482078] pci 0000:00:1c.4: PME# supported from D0 D3hot D3cold [ 0.489076] pci 0000:00:1c.5: [8086:1c1a] type 01 class 0x060400 [ 0.495078] pci 0000:00:1c.5: PME# supported from D0 D3hot D3cold [ 0.501076] pci 0000:00:1c.6: [8086:1c1c] type 01 class 0x060400 [ 0.507078] pci 0000:00:1c.6: PME# supported from D0 D3hot D3cold [ 0.513076] pci 0000:00:1c.7: [8086:244e] type 01 class 0x060401 [ 0.520079] pci 0000:00:1c.7: PME# supported from D0 D3hot D3cold [ 0.526078] pci 0000:00:1d.0: [8086:1c26] type 00 class 0x0c0320 [ 0.532021] pci 0000:00:1d.0: reg 0x10: [mem 0xfe605000-0xfe6053ff] [ 0.538078] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold [ 0.545071] pci 0000:00:1f.0: [8086:1c4a] type 00 class 0x060100 [ 0.551161] pci 0000:00:1f.2: [8086:1c00] type 00 class 0x01018f [ 0.557015] pci 0000:00:1f.2: reg 0x10: [io 0xf110-0xf117] [ 0.563007] pci 0000:00:1f.2: reg 0x14: [io 0xf100-0xf103] [ 0.568007] pci 0000:00:1f.2: reg 0x18: [io 0xf0f0-0xf0f7] [ 0.574007] pci 0000:00:1f.2: reg 0x1c: [io 0xf0e0-0xf0e3] [ 0.579007] pci 0000:00:1f.2: reg 0x20: [io 0xf0d0-0xf0df] [ 0.585007] pci 0000:00:1f.2: reg 0x24: [io 0xf0c0-0xf0cf] [ 0.591086] pci 0000:00:1f.3: [8086:1c22] type 00 class 0x0c0500 [ 0.597017] pci 0000:00:1f.3: reg 0x10: [mem 0xfe604000-0xfe6040ff 64bit] [ 0.604019] pci 0000:00:1f.3: reg 0x20: [io 0xf040-0xf05f] [ 0.609072] pci 0000:00:1f.5: [8086:1c08] type 00 class 0x010185 [ 0.616015] pci 0000:00:1f.5: reg 0x10: [io 0xf0b0-0xf0b7] [ 0.621007] pci 0000:00:1f.5: reg 0x14: [io 0xf0a0-0xf0a3] [ 0.627007] pci 0000:00:1f.5: reg 0x18: [io 0xf090-0xf097] [ 0.632007] pci 0000:00:1f.5: reg 0x1c: [io 0xf080-0xf083] [ 0.638007] pci 0000:00:1f.5: reg 0x20: [io 0xf070-0xf07f] [ 0.644007] pci 0000:00:1f.5: reg 0x24: [io 0xf060-0xf06f] [ 0.649101] pci 0000:00:01.0: PCI bridge to [bus 01] [ 0.654039] pci 0000:00:1c.0: PCI bridge to [bus 02] [ 0.660070] pci 0000:03:00.0: [1106:0415] type 00 class 0x010185 [ 0.666033] pci 0000:03:00.0: reg 0x10: [io 0xe040-0xe047] [ 0.671014] pci 0000:03:00.0: reg 0x14: [io 0xe030-0xe033] [ 0.677014] pci 0000:03:00.0: reg 0x18: [io 0xe020-0xe027] [ 0.683014] pci 0000:03:00.0: reg 0x1c: [io 0xe010-0xe013] [ 0.688014] pci 0000:03:00.0: reg 0x20: [io 0xe000-0xe00f] [ 0.694026] pci 0000:03:00.0: reg 0x30: [mem 0xfe500000-0xfe50ffff pref] [ 0.701078] pci 0000:03:00.0: supports D1 D2 [ 0.705002] pci 0000:03:00.0: PME# supported from D1 D2 D3hot [ 0.714018] pci 0000:00:1c.4: PCI bridge to [bus 03] [ 0.719004] pci 0000:00:1c.4: bridge window [io 0xe000-0xefff] [ 0.725004] pci 0000:00:1c.4: bridge window [mem 0xfe500000-0xfe5fffff] [ 0.732062] pci 0000:04:00.0: [1b21:1042] type 00 class 0x0c0330 [ 0.738040] pci 0000:04:00.0: reg 0x10: [mem 0xfe400000-0xfe407fff 64bit] [ 0.745157] pci 0000:04:00.0: PME# supported from D3hot D3cold [ 0.751086] pci 0000:00:1c.5: PCI bridge to [bus 04] [ 0.756006] pci 0000:00:1c.5: bridge window [mem 0xfe400000-0xfe4fffff] [ 0.763057] pci 0000:05:00.0: [10ec:8168] type 00 class 0x020000 [ 0.769034] pci 0000:05:00.0: reg 0x10: [io 0xd000-0xd0ff] [ 0.775032] pci 0000:05:00.0: reg 0x18: [mem 0xd0004000-0xd0004fff 64bit pref] [ 0.782020] pci 0000:05:00.0: reg 0x20: [mem 0xd0000000-0xd0003fff 64bit pref] [ 0.789110] pci 0000:05:00.0: supports D1 D2 [ 0.794001] pci 0000:05:00.0: PME# supported from D0 D1 D2 D3hot D3cold [ 0.801101] pci 0000:00:1c.6: PCI bridge to [bus 05] [ 0.806003] pci 0000:00:1c.6: bridge window [io 0xd000-0xdfff] [ 0.812007] pci 0000:00:1c.6: bridge window [mem 0xd0000000-0xd00fffff 64bit pref] [ 0.820059] pci 0000:06:00.0: [1b21:1080] type 01 class 0x060401 [ 0.826146] pci 0000:00:1c.7: PCI bridge to [bus 06-07] (subtractive decode) [ 0.833010] pci 0000:00:1c.7: bridge window [io 0x0000-0x0cf7 window] (subtractive decode) [ 0.842002] pci 0000:00:1c.7: bridge window [io 0x0d00-0xffff window] (subtractive decode) [ 0.850002] pci 0000:00:1c.7: bridge window [mem 0x000a0000-0x000bffff window] (subtractive decode) [ 0.860002] pci 0000:00:1c.7: bridge window [mem 0xbfa00000-0xffffffff window] (subtractive decode) [ 0.869105] pci 0000:06:00.0: PCI bridge to [bus 07] (subtractive decode) [ 0.876021] pci 0000:06:00.0: bridge window [io 0x0000-0x0cf7 window] (subtractive decode) [ 0.884002] pci 0000:06:00.0: bridge window [io 0x0d00-0xffff window] (subtractive decode) [ 0.893002] pci 0000:06:00.0: bridge window [mem 0x000a0000-0x000bffff window] (subtractive decode) [ 0.902001] pci 0000:06:00.0: bridge window [mem 0xbfa00000-0xffffffff window] (subtractive decode) [ 0.912551] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 *11 12 14 15), disabled. [ 0.920052] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 *11 12 14 15), disabled. [ 0.928051] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 *10 11 12 14 15), disabled. [ 0.936051] ACPI: PCI Interrupt Link [LNKD] (IRQs *3 4 5 6 10 11 12 14 15), disabled. [ 0.944051] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 *7 10 11 12 14 15), disabled. [ 0.952051] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 *10 11 12 14 15), disabled. [ 0.961051] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 *7 10 11 12 14 15), disabled. [ 0.969052] ACPI: PCI Interrupt Link [LNKH] (IRQs *3 4 5 6 7 10 11 12 14 15), disabled. [ 0.977101] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.978000] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.992004] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.997001] vgaarb: loaded [ 1.000054] SCSI subsystem initialized [ 1.004016] ACPI: bus type USB registered [ 1.008011] usbcore: registered new interface driver usbfs [ 1.014006] usbcore: registered new interface driver hub [ 1.024016] pps_core: LinuxPPS API ver. 1 registered [ 1.029001] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.038003] PTP clock support registered [ 1.042011] EDAC MC: Ver: 3.0.0 [ 1.046070] PCI: Using ACPI for IRQ routing [ 1.051364] PCI: pci_cache_line_size set to 64 bytes [ 1.056047] e820: reserve RAM buffer [mem 0x0009ec00-0x0009ffff] [ 1.063001] e820: reserve RAM buffer [mem 0xbac1e000-0xbbffffff] [ 1.069002] e820: reserve RAM buffer [mem 0xbadd1000-0xbbffffff] [ 1.075001] e820: reserve RAM buffer [mem 0xbb000000-0xbbffffff] [ 1.081001] e820: reserve RAM buffer [mem 0x1bfe00000-0x1bfffffff] [ 1.087070] NetLabel: Initializing [ 1.090001] NetLabel: domain hash size = 128 [ 1.095001] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 1.101014] NetLabel: unlabeled traffic allowed by default [ 1.106146] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0 [ 1.113002] hpet0: 8 comparators, 64-bit 14.318180 MHz counter [ 1.121011] clocksource: Switched to clocksource tsc-early [ 1.135581] VFS: Disk quotas dquot_6.6.0 [ 1.139590] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.146577] pnp: PnP ACPI init [ 1.149839] system 00:00: [mem 0xfed10000-0xfed19fff] has been reserved [ 1.156517] system 00:00: [mem 0xe0000000-0xe3ffffff] has been reserved [ 1.163195] system 00:00: [mem 0xfed90000-0xfed93fff] has been reserved [ 1.169871] system 00:00: [mem 0xfed20000-0xfed3ffff] has been reserved [ 1.176552] system 00:00: [mem 0xfee00000-0xfee0ffff] has been reserved [ 1.183238] system 00:00: Plug and Play ACPI device, IDs PNP0c01 (active) [ 1.190174] system 00:01: [io 0x0290-0x029f] has been reserved [ 1.196159] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active) [ 1.203275] pnp 00:02: [dma 0 disabled] [ 1.207268] pnp 00:02: Plug and Play ACPI device, IDs PNP0400 (active) [ 1.213884] pnp 00:03: Plug and Play ACPI device, IDs PNP0b00 (active) [ 1.220517] system 00:04: [io 0x04d0-0x04d1] has been reserved [ 1.226501] system 00:04: Plug and Play ACPI device, IDs PNP0c02 (active) [ 1.233553] pnp 00:05: [dma 0 disabled] [ 1.237488] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 1.244272] system 00:06: [io 0x0400-0x0453] has been reserved [ 1.250257] system 00:06: [io 0x0458-0x047f] has been reserved [ 1.256244] system 00:06: [io 0x0500-0x057f] has been reserved [ 1.262224] system 00:06: [mem 0xfed1c000-0xfed1ffff] has been reserved [ 1.268897] system 00:06: [mem 0xfec00000-0xfecfffff] could not be reserved [ 1.275917] system 00:06: [mem 0xfed08000-0xfed08fff] has been reserved [ 1.282590] system 00:06: [mem 0xff000000-0xffffffff] has been reserved [ 1.289268] system 00:06: Plug and Play ACPI device, IDs PNP0c01 (active) [ 1.296174] system 00:07: [io 0x0454-0x0457] has been reserved [ 1.302163] system 00:07: Plug and Play ACPI device, IDs INT3f0d PNP0c02 (active) [ 1.309962] pnp: PnP ACPI: found 8 devices [ 1.319670] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.328681] pci 0000:00:01.0: PCI bridge to [bus 01] [ 1.333710] pci 0000:00:1c.0: PCI bridge to [bus 02] [ 1.338751] pci 0000:00:1c.4: PCI bridge to [bus 03] [ 1.343779] pci 0000:00:1c.4: bridge window [io 0xe000-0xefff] [ 1.349934] pci 0000:00:1c.4: bridge window [mem 0xfe500000-0xfe5fffff] [ 1.356796] pci 0000:00:1c.5: PCI bridge to [bus 04] [ 1.361824] pci 0000:00:1c.5: bridge window [mem 0xfe400000-0xfe4fffff] [ 1.368677] pci 0000:00:1c.6: PCI bridge to [bus 05] [ 1.373706] pci 0000:00:1c.6: bridge window [io 0xd000-0xdfff] [ 1.379865] pci 0000:00:1c.6: bridge window [mem 0xd0000000-0xd00fffff 64bit pref] [ 1.387699] pci 0000:06:00.0: PCI bridge to [bus 07] [ 1.392745] pci 0000:00:1c.7: PCI bridge to [bus 06-07] [ 1.512958] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes) [ 1.520909] TCP established hash table entries: 65536 (order: 7, 524288 bytes) [ 1.528316] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) [ 1.535218] TCP: Hash tables configured (established 65536 bind 65536) [ 1.541837] UDP hash table entries: 4096 (order: 5, 131072 bytes) [ 1.548020] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes) [ 1.554652] NET: Registered protocol family 1 [ 1.559145] RPC: Registered named UNIX socket transport module. [ 1.565133] RPC: Registered udp transport module. [ 1.569905] RPC: Registered tcp transport module. [ 1.574673] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.581182] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 1.589689] IOAPIC[0]: Set routing entry (0-23 -> 0xef -> IRQ 23 Mode:1 Active:1 Dest:1) [ 1.597979] IOAPIC[0]: Set routing entry (0-23 -> 0xef -> IRQ 23 Mode:1 Active:1 Dest:1) [ 1.606254] IOAPIC[0]: Set routing entry (0-16 -> 0xef -> IRQ 16 Mode:1 Active:1 Dest:1) [ 1.614463] IOAPIC[0]: Set routing entry (0-17 -> 0xef -> IRQ 17 Mode:1 Active:1 Dest:1) [ 1.622688] pci 0000:04:00.0: quirk_usb_early_handoff+0x0/0x6a0 took 16083 usecs [ 1.630176] PCI: CLS 64 bytes, default 64 [ 1.634274] Unpacking initramfs... [ 6.191281] Freeing initrd memory: 365060K [ 6.195445] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 6.201947] software IO TLB [mem 0xb6c1e000-0xbac1e000] (64MB) mapped at [000000009ea20922-00000000ba44f00f] [ 6.212024] RAPL PMU: API unit is 2^-32 Joules, 3 fixed counters, 163840 ms ovfl timer [ 6.220028] RAPL PMU: hw unit of domain pp0-core 2^-16 Joules [ 6.225839] RAPL PMU: hw unit of domain package 2^-16 Joules [ 6.231560] RAPL PMU: hw unit of domain pp1-gpu 2^-16 Joules [ 6.238131] Initialise system trusted keyrings [ 6.242668] workingset: timestamp_bits=36 max_order=21 bucket_order=0 [ 6.250126] zbud: loaded [ 6.253121] 9p: Installing v9fs 9p2000 file system support [ 6.258684] pstore: using deflate compression [ 6.263138] SELinux: Registering netfilter hooks [ 6.268583] NET: Registered protocol family 38 [ 6.273091] Key type asymmetric registered [ 6.277250] Asymmetric key parser 'x509' registered [ 6.282211] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 6.289706] io scheduler noop registered [ 6.293691] io scheduler deadline registered (default) [ 6.298921] io scheduler cfq registered [ 6.302816] io scheduler mq-deadline registered (default) [ 6.308279] io scheduler kyber registered [ 6.312370] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 6.319600] IOAPIC[0]: Set routing entry (0-17 -> 0xef -> IRQ 17 Mode:1 Active:1 Dest:1) [ 6.328065] IOAPIC[0]: Set routing entry (0-18 -> 0xef -> IRQ 18 Mode:1 Active:1 Dest:1) [ 6.336331] pcieport 0000:00:01.0: Signaling PME with IRQ 24 [ 6.342071] pcieport 0000:00:1c.0: Signaling PME with IRQ 25 [ 6.470688] emc: device handler registered [ 6.474880] alua: device handler registered [ 6.479132] MACsec IEEE 802.1AE [ 6.482384] libphy: Fixed MDIO Bus: probed [ 6.486585] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 6.493697] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 6.499519] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 6.505421] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 6.511423] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 6.518449] igb: Copyright (c) 2007-2014 Intel Corporation. [ 6.524098] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k [ 6.531847] ixgbe: Copyright (c) 1999-2016 Intel Corporation. [ 6.537844] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded [ 6.544052] r8169 0000:05:00.0 eth0: RTL8168e/8111e at 0x00000000ca86cf5c, 20:cf:30:f4:b6:f7, XID 0c200000 IRQ 30 [ 6.554407] r8169 0000:05:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko] [ 6.563051] usbcore: registered new interface driver catc [ 6.568521] usbcore: registered new interface driver kaweth [ 6.574158] pegasus: v0.9.3 (2013/04/25), Pegasus/Pegasus II USB Ethernet driver [ 6.581652] usbcore: registered new interface driver pegasus [ 6.587379] usbcore: registered new interface driver rtl8150 [ 6.593109] usbcore: registered new interface driver asix [ 6.598577] usbcore: registered new interface driver cdc_ether [ 6.604480] usbcore: registered new interface driver cdc_eem [ 6.610206] usbcore: registered new interface driver dm9601 [ 6.615852] usbcore: registered new interface driver smsc75xx [ 6.621669] usbcore: registered new interface driver smsc95xx [ 6.627488] usbcore: registered new interface driver gl620a [ 6.633133] usbcore: registered new interface driver net1080 [ 6.638862] usbcore: registered new interface driver plusb [ 6.644888] usbcore: registered new interface driver rndis_host [ 6.650883] usbcore: registered new interface driver cdc_subset [ 6.656871] usbcore: registered new interface driver zaurus [ 6.662513] usbcore: registered new interface driver MOSCHIP usb-ethernet driver [ 6.670004] usbcore: registered new interface driver int51x1 [ 6.675739] usbcore: registered new interface driver ipheth [ 6.681382] usbcore: registered new interface driver sierra_net [ 6.687473] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 6.694074] ehci-pci: EHCI PCI platform driver [ 6.698664] IOAPIC[0]: Set routing entry (0-23 -> 0xef -> IRQ 23 Mode:1 Active:1 Dest:1) [ 6.706865] ehci-pci 0000:00:1a.0: EHCI Host Controller [ 6.712267] ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 1 [ 6.719772] ehci-pci 0000:00:1a.0: debug port 2 [ 6.728302] ehci-pci 0000:00:1a.0: cache line size of 64 is not supported [ 6.735165] ehci-pci 0000:00:1a.0: irq 23, io mem 0xfe606000 [ 6.747063] ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00 [ 6.752929] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.17 [ 6.878227] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 6.884483] ohci-pci: OHCI PCI platform driver [ 6.888998] uhci_hcd: USB Universal Host Controller Interface driver [ 6.895516] xhci_hcd 0000:04:00.0: xHCI Host Controller [ 6.900963] xhci_hcd 0000:04:00.0: new USB bus registered, assigned bus number 3 [ 6.915435] xhci_hcd 0000:04:00.0: hcc params 0x0200f180 hci version 0x96 quirks 0x00080000 [ 6.924085] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.17 [ 6.932445] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 6.939755] usb usb3: Product: xHCI Host Controller [ 6.944702] usb usb3: Manufacturer: Linux 4.17.0-rc1 xhci-hcd [ 6.950518] usb usb3: SerialNumber: 0000:04:00.0 [ 6.955416] hub 3-0:1.0: USB hub found [ 6.959249] hub 3-0:1.0: 2 ports detected [ 6.963428] xhci_hcd 0000:04:00.0: xHCI Host Controller [ 6.968876] xhci_hcd 0000:04:00.0: new USB bus registered, assigned bus number 4 [ 6.976382] xhci_hcd 0000:04:00.0: Host supports USB 3.0 SuperSpeed [ 6.982832] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 6.991062] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.17 [ 6.999422] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 7.006735] usb usb4: Product: xHCI Host Controller [ 7.011681] usb usb4: Manufacturer: Linux 4.17.0-rc1 xhci-hcd [ 7.017497] usb usb4: SerialNumber: 0000:04:00.0 [ 7.022327] hub 4-0:1.0: USB hub found [ 7.026156] hub 4-0:1.0: 2 ports detected [ 7.030357] usbcore: registered new interface driver usbserial_generic [ 7.036964] usbserial: USB Serial support registered for generic [ 7.043059] i8042: PNP: No PS/2 controller found. [ 7.047900] mousedev: PS/2 mouse device common for all mice [ 7.053739] rtc_cmos 00:03: RTC can wake from S4 [ 7.058601] rtc_cmos 00:03: registered as rtc0 [ 7.063123] rtc_cmos 00:03: alarms up to one month, y3k, 114 bytes nvram, hpet irqs [ 7.070973] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt [ 7.076964] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11 [ 7.082621] iTCO_wdt: Found a Cougar Point TCO device (Version=2, TCOBASE=0x0460) [ 7.090590] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0) [ 7.096683] iTCO_vendor_support: vendor-support=0 [ 7.101459] intel_pstate: Intel P-state driver initializing [ 7.107752] hidraw: raw HID events driver (C) Jiri Kosina [ 7.113359] usbcore: registered new interface driver usbhid [ 7.119013] usbhid: USB HID core driver [ 7.123033] drop_monitor: Initializing network drop monitor service [ 7.129460] Initializing XFRM netlink socket [ 7.133862] NET: Registered protocol family 10 [ 7.138669] Segment Routing with IPv6 [ 7.142404] usb 1-1: new high-speed USB device number 2 using ehci-pci [ 7.146030] NET: Registered protocol family 17 [ 7.153772] 9pnet: Installing 9P2000 support [ 7.159072] microcode: sig=0x206a7, pf=0x2, revision=0x2d [ 7.164694] microcode: Microcode Update Driver: v2.2. [ 7.164761] ... APIC ID: 00000000 (0) [ 7.165759] ... APIC VERSION: 01060015 [ 7.165759] 0000000000000000000000000000000000000000000000000000000000000000 [ 7.183030] 0000000000000024000400000000000000000000000000000000000000000000 [ 7.183030] 0000000000000000000000000000000000000000000000000000000000001000 [ 7.199177] number of MP IRQ sources: 15. [ 7.203249] number of IO-APIC #0 registers: 24. [ 7.207840] testing the IO APIC....................... [ 7.210017] usb 2-1: new high-speed USB device number 2 using ehci-pci [ 7.213048] IO APIC #0...... [ 7.222572] .... register #00: 00000000 [ 7.226475] ....... : physical APIC id: 00 [ 7.230895] ....... : Delivery Type: 0 [ 7.234967] ....... : LTS : 0 [ 7.239042] .... register #01: 00170020 [ 7.242941] ....... : max redirection entries: 17 [ 7.248058] ....... : PRQ implemented: 0 [ 7.252387] ....... : IO APIC version: 20 [ 7.256808] .... IRQ redirection table: [ 7.260708] IOAPIC 0: [ 7.263019] tsc: Refined TSC clocksource calibration: 3311.143 MHz [ 7.263060] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.269306] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2fba6a927c6, max_idle_ns: 440795300754 ns [ 7.277218] pin01, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.277223] pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(0) [ 7.277228] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.311053] pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.318975] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.326896] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.334820] pin07, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.342740] pin08, enabled , edge , high, V(24), IRR(0), S(0), logical , D(04), M(0) [ 7.350662] pin09, enabled , level, high, V(21), IRR(0), S(0), logical , D(02), M(0) [ 7.358583] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.366504] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.374425] pin0c, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.382347] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.390270] pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.398192] pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.406114] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.414035] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.421956] pin12, enabled , level, low , V(24), IRR(0), S(0), logical , D(08), M(0) [ 7.429877] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.437798] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.445721] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 7.453641] pin16, disabled, edge , high, V(0A), IRR(0), S(0), logical , D(FF), M(2) [ 7.461567] pin17, enabled , level, low , V(22), IRR(0), S(0), logical , D(01), M(0) [ 7.469482] IRQ to pin mappings: [ 7.472770] IRQ0 -> 0:2 [ 7.475281] IRQ1 -> 0:1 [ 7.477796] IRQ3 -> 0:3 [ 7.480310] IRQ4 -> 0:4 [ 7.482824] IRQ5 -> 0:5 [ 7.485802] IRQ6 -> 0:6 [ 7.488317] IRQ7 -> 0:7 [ 7.490830] IRQ8 -> 0:8 [ 7.493343] IRQ9 -> 0:9 [ 7.495858] IRQ10 -> 0:10 [ 7.498544] IRQ11 -> 0:11 [ 7.501233] IRQ12 -> 0:12 [ 7.503919] IRQ13 -> 0:13 [ 7.506604] IRQ14 -> 0:14 [ 7.509292] IRQ15 -> 0:15 [ 7.511978] IRQ16 -> 0:16 [ 7.514664] IRQ17 -> 0:17 [ 7.517352] IRQ18 -> 0:18 [ 7.520040] IRQ21 -> 0:21 [ 7.522727] IRQ23 -> 0:23 [ 7.525413] .................................... done. [ 7.530620] clocksource: Switched to clocksource tsc [ 7.530626] sched_clock: Marking stable (7530614973, 0)->(7776196469, -245581496) [ 7.542382] usb 1-1: New USB device found, idVendor=8087, idProduct=0024, bcdDevice= 0.00 [ 7.551482] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 7.558932] hub 1-1:1.0: USB hub found [ 7.562841] hub 1-1:1.0: 6 ports detected [ 7.562960] registered taskstats version 1 [ 7.566919] usb 2-1: New USB device found, idVendor=8087, idProduct=0024, bcdDevice= 0.00 [ 7.566921] usb 2-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 [ 7.571081] Loading compiled-in X.509 certificates [ 7.571093] zswap: loaded using pool lzo/zbud [ 7.582014] usb 3-1: new full-speed USB device number 2 using xhci_hcd [ 7.602443] hub 2-1:1.0: USB hub found [ 7.606537] hub 2-1:1.0: 8 ports detected [ 7.608547] Key type big_key registered [ 7.625562] ima: No TPM chip found, activating TPM-bypass! (rc=-19) [ 7.631902] ima: Allocated hash algorithm: sha1 [ 7.636508] evm: HMAC attrs: 0x1 [ 7.640113] rtc_cmos 00:03: setting system clock to 2006-03-13 11:53:52 UTC (1142250832) [ 7.648557] r8169 0000:05:00.0: Direct firmware load for rtl_nic/rtl8168e-2.fw failed with error -2 [ 7.657692] r8169 0000:05:00.0: Falling back to user helper [ 7.810711] usb 3-1: New USB device found, idVendor=14dd, idProduct=1005, bcdDevice= 0.00 [ 7.819008] usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [ 7.826210] usb 3-1: Product: D2CIM-VUSB [ 7.830202] usb 3-1: Manufacturer: Raritan [ 7.834363] usb 3-1: SerialNumber: 7C07478CDFA42B6 [ 7.851222] input: Raritan D2CIM-VUSB as /devices/pci0000:00/0000:00:1c.5/0000:04:00.0/usb3/3-1/3-1:1.0/0003:14DD:1005.0001/input/input2 [ 7.915565] hid-generic 0003:14DD:1005.0001: input,hidraw0: USB HID v1.11 Keyboard [Raritan D2CIM-VUSB] on usb-0000:04:00.0-1/input0 [ 70.623091] r8169 0000:05:00.0 eth0: unable to load firmware patch rtl_nic/rtl8168e-2.fw (-11) [ 70.645843] r8169 0000:05:00.0 eth0: link down [ 70.650383] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 72.749522] r8169 0000:05:00.0 eth0: link up [ 72.753881] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 72.762059] Sending DHCP requests .., OK [ 75.492080] IP-Config: Got DHCP answer from 192.168.1.1, my address is 192.168.1.171 [ 75.499934] IP-Config: Complete: [ 75.503228] device=eth0, hwaddr=20:cf:30:f4:b6:f7, ipaddr=192.168.1.171, mask=255.255.255.0, gw=192.168.1.1 [ 75.513487] host=snb-drag, domain=lkp.intel.com, nis-domain=(none) [ 75.520169] bootserver=192.168.1.1, rootserver=192.168.1.1, rootpath= nameserver0=192.168.1.1 [ 75.531364] Freeing unused kernel memory: 2384K [ 75.535964] Write protecting the kernel read-only data: 20480k [ 75.542424] Freeing unused kernel memory: 2012K [ 75.550091] Freeing unused kernel memory: 1976K [ 75.554687] rodata_test: all tests were successful [ 75.561947] systemd[1]: RTC configured in localtime, applying delta of 480 minutes to system time. [ 75.572389] systemd[1]: System time before build time, advancing clock. [ 75.666838] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 75.694983] cryptd: max_cpu_qlen set to 1000 [ 75.704413] input: PC Speaker as /devices/platform/pcspkr/input/input3 [ 75.713386] parport_pc 00:02: reported by Plug and Play ACPI [ 75.713414] AVX version of gcm_enc/dec engaged. [ 75.719366] parport0: PC-style at 0x378 [ 75.725207] AES CTR mode by8 optimization enabled [ 75.736693] , irq 5 [PCSPP] [ 75.742756] libata version 3.00 loaded. [ 75.748597] Error: Driver 'pcspkr' is already registered, aborting... [ 75.755994] pata_via 0000:03:00.0: version 0.3.4 [ 75.761470] ppdev: user-space parallel port driver [ 75.767830] asus_wmi: ASUS WMI generic driver loaded [ 75.772076] scsi host0: pata_via [ 75.778952] ata_piix 0000:00:1f.2: version 2.13 [ 75.784844] scsi host1: pata_via [ 75.786544] IOAPIC[0]: Set routing entry (0-20 -> 0xef -> IRQ 20 Mode:1 Active:1 Dest:1) [ 75.789532] ata1: PATA max UDMA/133 cmd 0xe040 ctl 0xe030 bmdma 0xe000 irq 16 [ 75.799030] ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ] [ 75.807582] ata2: PATA max UDMA/133 cmd 0xe020 ctl 0xe010 bmdma 0xe008 irq 16 [ 75.827767] asus_wmi: Initialization: 0x0 [ 75.832496] asus_wmi: BIOS WMI version: 0.9 [ 75.838134] asus_wmi: SFUN value: 0x0 [ 75.843622] input: Eee PC WMI hotkeys as /devices/platform/eeepc-wmi/input/input4 [ 75.853986] asus_wmi: Number of fans: 1 [ 75.861611] intel_rapl: Found RAPL domain package [ 75.867785] intel_rapl: Found RAPL domain core [ 75.873648] intel_rapl: Found RAPL domain uncore [ 75.879680] intel_rapl: RAPL package 0 domain package locked by BIOS [ 75.977286] scsi host2: ata_piix [ 75.982329] scsi host3: ata_piix [ 75.986724] ata3: SATA max UDMA/133 cmd 0xf110 ctl 0xf100 bmdma 0xf0d0 irq 20 [ 75.995256] ata4: SATA max UDMA/133 cmd 0xf0f0 ctl 0xf0e0 bmdma 0xf0d8 irq 20 [ 76.003962] ata_piix 0000:00:1f.5: MAP [ P0 -- P1 -- ] [ 76.168935] scsi host4: ata_piix [ 76.173260] scsi host5: ata_piix [ 76.176676] ata5: SATA max UDMA/133 cmd 0xf0b0 ctl 0xf0a0 bmdma 0xf070 irq 20 [ 76.185189] ata6: SATA max UDMA/133 cmd 0xf090 ctl 0xf080 bmdma 0xf078 irq 20 [ 76.194423] [drm] Replacing VGA console driver [ 76.200137] Console: switching to colour dummy device 80x25 [ 76.207479] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013). [ 76.214484] [drm] Driver supports precise vblank timestamp query. [ 76.221164] i915 0000:00:02.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=io+mem:owns=io+mem [ 76.234995] [drm] Initialized i915 1.6.0 20180308 for 0000:00:02.0 on minor 0 [ 76.243108] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no) [ 76.250643] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input5 [ 76.261344] IOAPIC[0]: Set routing entry (0-22 -> 0xef -> IRQ 22 Mode:1 Active:1 Dest:1) [ 76.334245] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff [ 76.340805] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff [ 76.347692] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff [ 76.354242] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff [ 76.354243] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff [ 76.505038] snd_hda_intel 0000:00:1b.0: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915]) [ 76.514424] fbcon: inteldrmfb (fb0) is primary device [ 76.533402] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC892: line_outs=4 (0x14/0x15/0x16/0x17/0x0) type:line [ 76.533405] snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) [ 76.533406] snd_hda_codec_realtek hdaudioC0D0: hp_outs=1 (0x1b/0x0/0x0/0x0/0x0) [ 76.533407] snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0 [ 76.533408] snd_hda_codec_realtek hdaudioC0D0: dig-out=0x11/0x1e [ 76.533409] snd_hda_codec_realtek hdaudioC0D0: inputs: [ 76.533411] snd_hda_codec_realtek hdaudioC0D0: Front Mic=0x19 [ 76.533412] snd_hda_codec_realtek hdaudioC0D0: Rear Mic=0x18 [ 76.533413] snd_hda_codec_realtek hdaudioC0D0: Line=0x1a [ 76.555483] input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input6 [ 76.555522] input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input7 [ 76.555556] input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:1b.0/sound/card0/input8 [ 76.555588] input: HDA Intel PCH Line Out Front as /devices/pci0000:00/0000:00:1b.0/sound/card0/input9 [ 76.555621] input: HDA Intel PCH Line Out Surround as /devices/pci0000:00/0000:00:1b.0/sound/card0/input10 [ 76.555653] input: HDA Intel PCH Line Out CLFE as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11 [ 76.555684] input: HDA Intel PCH Line Out Side as /devices/pci0000:00/0000:00:1b.0/sound/card0/input12 [ 76.555715] input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input13 [ 76.555748] input: HDA Intel PCH HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:1b.0/sound/card0/input14 [ 76.579465] Console: switching to colour frame buffer device 128x48 [ 76.756152] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device [ 77.039034] ata4.00: failed to resume link (SControl 0) [ 77.231017] ata6: failed to resume link (SControl 0) [ 77.246925] ata6: SATA link down (SStatus 4 SControl 0) [ 77.343066] ata3.01: failed to resume link (SControl 0) [ 77.503098] ata3.00: SATA link up 6.0 Gbps (SStatus 133 SControl 300) [ 77.510249] ata3.01: SATA link down (SStatus 0 SControl 0) [ 77.519180] ata3.00: ATA-9: WDC WD20EZRX-00D8PB0, 80.00A80, max UDMA/133 [ 77.526750] ata3.00: 3907029168 sectors, multi 16: LBA48 NCQ (depth 0/32) [ 77.537330] ata3.00: configured for UDMA/133 [ 77.542515] scsi 2:0:0:0: Direct-Access ATA WDC WD20EZRX-00D 0A80 PQ: 0 ANSI: 5 [ 78.079038] ata4.01: failed to resume link (SControl 0) [ 78.095953] ata4.00: SATA link down (SStatus 4 SControl 0) [ 78.102255] ata4.01: SATA link down (SStatus 0 SControl 0) [ 78.510211] ata5: SATA link down (SStatus 1 SControl 300) [ 78.523862] scsi 2:0:0:0: Attached scsi generic sg0 type 0 [ 78.531887] sd 2:0:0:0: [sda] 3907029168 512-byte logical blocks: (2.00 TB/1.82 TiB) [ 78.540453] sd 2:0:0:0: [sda] 4096-byte physical blocks [ 78.546599] sd 2:0:0:0: [sda] Write Protect is off [ 78.552217] sd 2:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 78.558257] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 79.309260] sda: sda1 sda2 sda3 sda4 < sda5 sda6 sda7 sda8 sda9 sda10 sda11 > [ 79.318603] sd 2:0:0:0: [sda] Attached SCSI disk [ 80.066588] random: crng init done [ 80.933014] raid6: sse2x1 gen() 8894 MB/s [ 80.955015] raid6: sse2x1 xor() 7023 MB/s [ 80.977013] raid6: sse2x2 gen() 11144 MB/s [ 80.999016] raid6: sse2x2 xor() 8001 MB/s [ 81.021017] raid6: sse2x4 gen() 13222 MB/s [ 81.043011] raid6: sse2x4 xor() 7242 MB/s [ 81.048055] raid6: using algorithm sse2x4 gen() 13222 MB/s [ 81.054345] raid6: .... xor() 7242 MB/s, rmw enabled [ 81.060120] raid6: using ssse3x2 recovery algorithm [ 81.076192] xor: automatically using best checksumming function avx [ 81.112920] Btrfs loaded, crc32c=crc32c-generic [ 81.119107] BTRFS: device fsid 1a996b8e-731b-4544-a67e-610f318a6ee5 devid 4 transid 5 /dev/sda9 [ 81.129061] BTRFS: device fsid a33c612a-1ae1-4374-90cf-3ece9125b30c devid 1 transid 19 /dev/sda5 [ 81.139162] BTRFS: device fsid d3a01489-3aca-4195-af4b-c375d8853726 devid 1 transid 9 /dev/sda6 [ 81.150661] BTRFS: device fsid 1a996b8e-731b-4544-a67e-610f318a6ee5 devid 5 transid 5 /dev/sda10 [ 81.161195] BTRFS: device fsid 9c7df7b4-1795-4117-b623-5953f4157900 devid 1 transid 17892 /dev/sda2 [ 82.451900] Kernel tests: Boot OK! [ 82.451904] [ 83.738953] BTRFS info (device sda2): disk space caching is enabled [ 83.745875] BTRFS info (device sda2): has skinny extents [ 84.208452] netpoll: netconsole: local port 6665 [ 84.213687] netpoll: netconsole: local IPv4 address 0.0.0.0 [ 84.219869] netpoll: netconsole: interface 'eth0' [ 84.225184] netpoll: netconsole: remote port 6648 [ 84.230506] netpoll: netconsole: remote IPv4 address 192.168.1.1 [ 84.237122] netpoll: netconsole: remote ethernet address ff:ff:ff:ff:ff:ff [ 84.244604] netpoll: netconsole: local IP 192.168.1.171 [ 84.252724] console [netcon0] enabled [ 84.256948] netconsole: network logging started [ 85.911941] install debs round one: dpkg -i --force-confdef --force-depends /opt/deb/mime-support_3.60_all.deb [ 85.911944] [ 85.925395] /opt/deb/libssl1.1_1.1.0g-2_amd64.deb [ 85.925396] [ 85.933663] /opt/deb/gawk_1%3a4.1.4+dfsg-1+b1_amd64.deb [ 85.933664] [ 85.942409] /opt/deb/libattr1_1%3a2.4.47-2+b2_amd64.deb [ 85.942410] [ 85.951610] /opt/deb/attr_1%3a2.4.47-2+b2_amd64.deb [ 85.951613] [ 85.961344] /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb [ 85.961347] [ 85.970906] /opt/deb/sysstat_11.6.0-1_amd64.deb [ 85.970909] [ 85.980977] Selecting previously unselected package mime-support. [ 85.980979] [ 85.991391] (Reading database ... 2202 files and directories currently installed.) [ 85.991394] [ 86.002796] Preparing to unpack .../deb/mime-support_3.60_all.deb ... [ 86.002797] [ 86.012557] Unpacking mime-support (3.60) ... [ 86.012558] [ 86.021604] Selecting previously unselected package libssl1.1:amd64. [ 86.021607] [ 86.032667] Preparing to unpack .../libssl1.1_1.1.0g-2_amd64.deb ... [ 86.032668] [ 86.042771] Unpacking libssl1.1:amd64 (1.1.0g-2) ... [ 86.042773] [ 86.052774] Preparing to unpack .../gawk_1%3a4.1.4+dfsg-1+b1_amd64.deb ... [ 86.052776] [ 86.063954] Unpacking gawk (1:4.1.4+dfsg-1+b1) over (1:4.1.1+dfsg-1) ... [ 86.063957] [ 86.076126] Preparing to unpack .../libattr1_1%3a2.4.47-2+b2_amd64.deb ... [ 86.076129] [ 86.087104] Unpacking libattr1:amd64 (1:2.4.47-2+b2) over (1:2.4.47-2) ... [ 86.087105] [ 86.098050] Selecting previously unselected package attr. [ 86.098052] [ 86.108260] Preparing to unpack .../attr_1%3a2.4.47-2+b2_amd64.deb ... [ 86.108262] [ 86.118769] Unpacking attr (1:2.4.47-2+b2) ... [ 86.118770] [ 86.128169] Preparing to unpack .../gawk_1%3a4.1.4+dfsg-1_amd64.deb ... [ 86.128170] [ 86.138809] Unpacking gawk (1:4.1.4+dfsg-1) over (1:4.1.4+dfsg-1+b1) ... [ 86.138811] [ 86.149418] Selecting previously unselected package sysstat. [ 86.149420] [ 86.159854] Preparing to unpack .../deb/sysstat_11.6.0-1_amd64.deb ... [ 86.159855] [ 86.169793] Unpacking sysstat (11.6.0-1) ... [ 86.169795] [ 86.178861] More than one copy of package gawk has been unpacked [ 86.178862] [ 86.188718] in this run ! Only configuring it once. [ 86.188720] [ 86.198020] Setting up mime-support (3.60) ... [ 86.198022] [ 86.206678] Setting up libssl1.1:amd64 (1.1.0g-2) ... [ 86.206681] [ 86.216713] Setting up libattr1:amd64 (1:2.4.47-2+b2) ... [ 86.216715] [ 86.226608] Setting up attr (1:2.4.47-2+b2) ... [ 86.226610] [ 86.236319] Setting up sysstat (11.6.0-1) ... [ 86.236321] [ 86.244703] Setting up gawk (1:4.1.4+dfsg-1) ... [ 86.244704] [ 86.253028] Processing triggers for libc-bin (2.23-5) ... [ 86.253030] [ 86.262652] Processing triggers for systemd (231-5) ... [ 86.262654] [ 86.273884] 16 Apr 23:53:39 ntpdate[518]: step time server 192.168.1.1 offset 54417823.981575 sec [ 86.273886] [ 86.286007] /lkp/lkp/src/bin/run-lkp [ 86.286008] [ 88.531749] RESULT_ROOT=/result/xfstests/4HDD-btrfs-generic-group5/snb-drag/debian-x86_64-2016-08-31.cgz/x86_64-rhel-7.2/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/0 [ 88.531753] [ 88.553235] job=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml [ 88.553236] [ 88.570163] mount.nfs: try 1 time... [ 88.570164] [ 103.719014] run-job /lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml [ 103.719017] [ 103.928611] device-mapper: uevent: version 1.0.3 [ 103.933983] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 105.656829] BTRFS: device fsid 94606b37-26d5-404a-81a3-955885bb5cd6 devid 1 transid 5 /dev/sda8 [ 105.681385] BTRFS: device fsid 3357a0a7-5e02-4b53-a95b-af8077bb0375 devid 1 transid 5 /dev/sda6 [ 105.955468] BTRFS: device fsid 46774a73-16eb-4b74-8395-bf5b2c51e3b0 devid 1 transid 5 /dev/sda7 [ 106.177401] BTRFS: device fsid 89291dba-ecd5-414c-8699-7fd0cfff4956 devid 1 transid 5 /dev/sda5 [ 106.336609] BTRFS info (device sda5): disk space caching is enabled [ 106.339081] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml&job_state=running -o /dev/null [ 106.343553] BTRFS info (device sda5): has skinny extents [ 106.343554] BTRFS info (device sda5): flagging fs with big metadata feature [ 106.380838] [ 106.385459] microcode current_version: 0x2d, target_version: 0x2d [ 106.385461] [ 106.396869] 2018-04-16 23:53:59 dmsetup remove_all [ 106.396872] [ 106.407288] 2018-04-16 23:53:59 wipefs -a --force /dev/sda5 [ 106.407289] [ 106.417701] /dev/sda5: 8 bytes were erased at offset 0x00010040 (btrfs): 5f 42 48 52 66 53 5f 4d [ 106.417702] [ 106.430540] 2018-04-16 23:54:00 wipefs -a --force /dev/sda6 [ 106.430541] [ 106.440972] /dev/sda6: 8 bytes were erased at offset 0x00010040 (btrfs): 5f 42 48 52 66 53 5f 4d [ 106.440973] [ 106.453902] 2018-04-16 23:54:00 wipefs -a --force /dev/sda7 [ 106.453903] [ 106.468092] 2018-04-16 23:54:00 wipefs -a --force /dev/sda8 [ 106.468095] [ 106.469714] BTRFS info (device sda5): creating UUID tree [ 106.475618] 2018-04-16 23:54:00 mkfs -t btrfs /dev/sda5 [ 106.482985] [ 106.492281] 2018-04-16 23:54:00 mkfs -t btrfs /dev/sda6 [ 106.492282] [ 106.501469] 2018-04-16 23:54:00 mkfs -t btrfs /dev/sda7 [ 106.501470] [ 106.510620] 2018-04-16 23:54:00 mkfs -t btrfs /dev/sda8 [ 106.510621] [ 106.519287] btrfs-progs v4.7.3 [ 106.519288] [ 106.526491] See http://btrfs.wiki.kernel.org for more information. [ 106.526492] [ 106.535622] [ 106.538402] Label: (null) [ 106.538403] [ 106.545198] BTRFS info (device sda6): disk space caching is enabled [ 106.545522] UUID: [ 106.552170] [ 106.552172] BTRFS info (device sda6): has skinny extents [ 106.556653] Node size: 16384 [ 106.558233] BTRFS info (device sda6): flagging fs with big metadata feature [ 106.571928] BTRFS info (device sda6): creating UUID tree [ 106.576203] [ 106.576759] Sector size: 4096 [ 106.584375] [ 106.591438] Filesystem size: 200.00GiB [ 106.591439] [ 106.598626] Block group profiles: [ 106.598627] [ 106.605578] Data: single 8.00MiB [ 106.605579] [ 106.614608] Metadata: DUP 1.00GiB [ 106.614610] [ 106.623589] System: DUP 8.00MiB [ 106.623590] [ 106.631789] BTRFS info (device sda7): disk space caching is enabled [ 106.632158] SSD detected: no [ 106.638609] [ 106.638612] BTRFS info (device sda7): has skinny extents [ 106.643569] Incompat features: extref, skinny-metadata [ 106.644570] BTRFS info (device sda7): flagging fs with big metadata feature [ 106.663661] [ 106.666162] Number of devices: 1 [ 106.666163] [ 106.672173] Devices: [ 106.672174] [ 106.676884] BTRFS info (device sda7): creating UUID tree [ 106.677404] ID SIZE PATH [ 106.768039] Data: single 8.00MiB [ 106.778445] [ 106.787272] Metadata: DUP 1.00GiB [ 106.787273] [ 106.796064] System: DUP 8.00MiB [ 106.796065] [ 106.804285] SSD detected: no [ 106.804286] [ 106.810931] Incompat features: extref, skinny-metadata [ 106.810933] [ 106.818999] Number of devices: 1 [ 106.819000] [ 106.824833] Devices: [ 106.824834] [ 106.829950] ID SIZE PATH [ 106.829952] [ 106.836488] 1 200.00GiB /dev/sda6 [ 106.836490] [ 106.844082] [ 106.846414] btrfs-progs v4.7.3 [ 106.846415] [ 106.853126] See http://btrfs.wiki.kernel.org for more information. [ 106.853127] [ 106.862783] [ 106.865519] Label: (null) [ 106.865520] [ 106.865658] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.870156] UUID: [ 106.871581] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.884166] [ 106.884732] Node size: 16384 [ 106.903394] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.904299] [ 106.908439] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.921714] Sector size: 4096 [ 106.937737] [ 106.938703] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.942630] Filesystem size: 200.00GiB [ 106.944001] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.959515] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.963313] [ 106.976207] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 106.989664] Block group profiles: [ 107.006090] [ 107.006768] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.011140] Data: single 8.00MiB [ 107.012277] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.025361] [ 107.047009] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.049718] Metadata: DUP 1.00GiB [ 107.061925] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.077519] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.084446] [ 107.097832] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.101182] System: DUP 8.00MiB [ 107.113658] [ 107.123820] SSD detected: no [ 107.123821] [ 107.128725] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.131950] Incompat features: extref, skinny-metadata [ 107.144543] [ 107.144547] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.151159] Number of devices: 1 [ 107.168317] [ 107.168373] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.172712] Devices: [ 107.174902] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.188431] [ 107.206857] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.209699] ID SIZE PATH [ 107.222738] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.238388] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.242599] [ 107.256167] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.259197] 1 200.00GiB /dev/sda7 [ 107.272106] [ 107.280117] [ 107.283199] btrfs-progs v4.7.3 [ 107.283200] [ 107.287098] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.288790] See http://btrfs.wiki.kernel.org for more information. [ 107.289880] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.303349] [ 107.325728] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.341441] [ 107.341443] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.344379] Label: (null) [ 107.357215] [ 107.365344] UUID: [ 107.365345] [ 107.372197] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.372909] Node size: 16384 [ 107.385757] [ 107.385760] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.390821] Sector size: 4096 [ 107.407692] [ 107.407977] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.412767] Filesystem size: 200.00GiB [ 107.414448] x86/PAT: bmc-watchdog:880 map pfn expected mapping type uncached-minus for [mem 0xbac69000-0xbac69fff], got write-back [ 107.427777] [ 107.448893] Block group profiles: [ 107.448895] [ 107.456338] Data: single 8.00MiB [ 107.456339] [ 107.465851] Metadata: DUP 1.00GiB [ 107.465852] [ 107.475358] System: DUP 8.00MiB [ 107.475359] [ 107.484490] SSD detected: no [ 107.484493] [ 107.492580] Incompat features: extref, skinny-metadata [ 107.492582] [ 107.501258] Number of devices: 1 [ 107.501259] [ 107.507600] Devices: [ 107.507602] [ 107.513122] ID SIZE PATH [ 107.513124] [ 107.520036] 1 200.00GiB /dev/sda5 [ 107.520037] [ 107.526712] [ 107.529633] 2018-04-16 23:54:01 mkdir -p /fs/sda5 [ 107.529634] [ 107.538231] 2018-04-16 23:54:01 mount -t btrfs /dev/sda5 /fs/sda5 [ 107.538232] [ 107.547828] 2018-04-16 23:54:01 mkdir -p /fs/sda6 [ 107.547829] [ 107.556415] 2018-04-16 23:54:01 mount -t btrfs /dev/sda6 /fs/sda6 [ 107.556416] [ 107.565900] 2018-04-16 23:54:02 mkdir -p /fs/sda7 [ 107.565901] [ 107.574385] 2018-04-16 23:54:02 mount -t btrfs /dev/sda7 /fs/sda7 [ 107.574386] [ 107.583983] 2018-04-16 23:54:02 mkdir -p /fs/sda8 [ 107.583984] [ 107.592447] 2018-04-16 23:54:02 mount -t btrfs /dev/sda8 /fs/sda8 [ 107.592448] [ 108.176606] 2018-04-16 23:54:03 export TEST_DIR=/fs/sda5 [ 108.176609] [ 108.185735] 2018-04-16 23:54:03 export TEST_DEV=/dev/sda5 [ 108.185737] [ 108.194826] 2018-04-16 23:54:03 export FSTYP=btrfs [ 108.194828] [ 108.203480] 2018-04-16 23:54:03 export SCRATCH_MNT=/fs/scratch [ 108.203482] [ 108.212980] 2018-04-16 23:54:03 mkdir /fs/scratch -p [ 108.212982] [ 108.222421] 2018-04-16 23:54:03 export SCRATCH_DEV_POOL="/dev/sda6 /dev/sda7 /dev/sda8" [ 108.222430] [ 108.236307] 2018-04-16 23:54:03 sed "s:^:generic/:" /lkp/lkp/src/pack/xfstests-addon/tests/generic-group5 | grep -F -f merged_ignored_files [ 108.236310] [ 108.252470] generic/458 [ 108.252471] [ 108.257814] generic/476 [ 108.257815] [ 108.263575] ignored by lkp: generic/458 [ 108.263577] [ 108.270697] ignored by lkp: generic/476 [ 108.270699] [ 108.280483] 2018-04-16 23:54:03 sed "s:^:generic/:" /lkp/lkp/src/pack/xfstests-addon/tests/generic-group5 | grep -v -F -f merged_ignored_files [ 108.280486] [ 108.297030] generic/445 [ 108.297031] [ 108.302468] generic/446 [ 108.302470] [ 108.307936] generic/447 [ 108.307938] [ 108.313378] generic/448 [ 108.313379] [ 108.318792] generic/449 [ 108.318793] [ 108.324150] generic/450 [ 108.324151] [ 108.329533] generic/451 [ 108.329535] [ 108.334853] generic/452 [ 108.334854] [ 108.340145] generic/453 [ 108.340147] [ 108.345381] generic/454 [ 108.345383] [ 108.350617] generic/456 [ 108.350618] [ 108.355792] generic/459 [ 108.355793] [ 108.360902] generic/460 [ 108.360903] [ 108.366009] generic/461 [ 108.366010] [ 108.371070] generic/463 [ 108.371072] [ 108.376094] generic/464 [ 108.376095] [ 108.381063] generic/465 [ 108.381064] [ 108.385944] generic/466 [ 108.385946] [ 108.390780] generic/467 [ 108.390782] [ 108.395670] generic/468 [ 108.395672] [ 108.400556] generic/469 [ 108.400557] [ 108.405416] generic/471 [ 108.405417] [ 108.410279] generic/472 [ 108.410280] [ 108.415035] generic/473 [ 108.415036] [ 108.419794] generic/474 [ 108.419795] [ 108.424538] generic/475 [ 108.424540] [ 108.429285] generic/477 [ 108.429286] [ 108.434070] generic/478 [ 108.434071] [ 108.438833] generic/479 [ 108.438834] [ 108.443630] generic/480 [ 108.443631] [ 108.448418] generic/481 [ 108.448419] [ 109.077787] 512+0 records in [ 109.077790] [ 109.136519] 512+0 records out [ 109.136521] [ 110.344475] BTRFS info (device sda5): disk space caching is enabled [ 110.351183] BTRFS info (device sda5): has skinny extents [ 111.415912] 262144 bytes (262 kB, 256 KiB) copied, 0.00874669 s, 30.0 MB/s [ 111.415915] [ 111.425451] 512+0 records in [ 111.425453] [ 111.430934] 512+0 records out [ 111.430936] [ 111.437598] 262144 bytes (262 kB, 256 KiB) copied, 0.0136695 s, 19.2 MB/s [ 111.437599] [ 111.447081] 512+0 records in [ 111.447082] [ 111.452625] 512+0 records out [ 111.452627] [ 111.459301] 262144 bytes (262 kB, 256 KiB) copied, 0.0222052 s, 11.8 MB/s [ 111.459303] [ 112.146743] 2018-04-16 23:54:03 ./check generic/445 generic/446 generic/447 generic/448 generic/449 generic/450 generic/451 generic/452 generic/453 generic/454 generic/456 generic/459 generic/460 generic/461 generic/463 generic/464 generic/465 generic/466 generic/467 generic/468 generic/469 generic/471 generic/472 generic/473 generic/474 generic/475 generic/477 generic/478 generic/479 generic/480 generic/481 [ 112.146746] [ 112.261968] FSTYP -- btrfs [ 112.261971] [ 112.274577] PLATFORM -- Linux/x86_64 snb-drag 4.17.0-rc1 [ 112.274579] [ 112.283527] MKFS_OPTIONS -- /dev/sda6 [ 112.283528] [ 112.290647] MOUNT_OPTIONS -- /dev/sda6 /fs/scratch [ 112.290648] [ 112.298110] [ 112.471669] BTRFS: device fsid bae269a6-e6af-40d1-b80f-5a3cf968aa64 devid 1 transid 5 /dev/sda6 [ 112.489403] BTRFS info (device sda6): disk space caching is enabled [ 112.496285] BTRFS info (device sda6): has skinny extents [ 112.502182] BTRFS info (device sda6): flagging fs with big metadata feature [ 112.511328] BTRFS info (device sda6): creating UUID tree [ 112.755531] BTRFS info (device sda5): disk space caching is enabled [ 112.762464] BTRFS info (device sda5): has skinny extents [ 112.791214] run fstests generic/445 at 2018-04-16 23:54:08 [ 113.342680] generic/445 0s [ 113.342684] [ 113.478658] BTRFS info (device sda5): disk space caching is enabled [ 113.485629] BTRFS info (device sda5): has skinny extents [ 113.527272] run fstests generic/446 at 2018-04-16 23:54:08 [ 114.085440] BTRFS: device fsid 9141b5da-f5a8-4a16-ade0-63b16eff71df devid 1 transid 5 /dev/sda6 [ 114.102858] BTRFS info (device sda6): disk space caching is enabled [ 114.109835] BTRFS info (device sda6): has skinny extents [ 114.115807] BTRFS info (device sda6): flagging fs with big metadata feature [ 114.125058] BTRFS info (device sda6): creating UUID tree [ 116.720366] generic/446 4s [ 116.720369] [ 116.841017] run fstests generic/447 at 2018-04-16 23:54:12 [ 117.136952] BTRFS: device fsid 1cef7385-84e3-4c2a-90c7-a52d7ef49f8c devid 1 transid 5 /dev/sda6 [ 117.154031] BTRFS info (device sda6): disk space caching is enabled [ 117.161037] BTRFS info (device sda6): has skinny extents [ 117.167080] BTRFS info (device sda6): flagging fs with big metadata feature [ 117.176391] BTRFS info (device sda6): creating UUID tree [ 117.482190] BTRFS: device fsid 34739b11-5d73-438f-9071-0d1b7042dc89 devid 1 transid 5 /dev/sda6 [ 117.499745] BTRFS info (device sda6): disk space caching is enabled [ 117.506803] BTRFS info (device sda6): has skinny extents [ 117.512795] BTRFS info (device sda6): flagging fs with big metadata feature [ 117.522062] BTRFS info (device sda6): creating UUID tree [ 167.438091] generic/447 IPMI BMC is not supported on this machine, skip bmc-watchdog setup! [ 167.438094] [ 197.639162] BTRFS info (device sda6): disk space caching is enabled [ 197.646110] BTRFS info (device sda6): has skinny extents [ 197.763843] 81s [ 197.763847] [ 225.771216] BTRFS info (device sda6): disk space caching is enabled [ 225.778568] BTRFS info (device sda6): has skinny extents [ 225.841313] run fstests generic/448 at 2018-04-16 23:56:01 [ 226.031246] generic/448 0s [ 226.031249] [ 226.272285] BTRFS info (device sda5): disk space caching is enabled [ 226.279197] BTRFS info (device sda5): has skinny extents [ 226.311468] run fstests generic/449 at 2018-04-16 23:56:01 [ 226.767114] BTRFS: device fsid 7521a5d2-a1f3-4190-ac60-6f5e23d3702a devid 1 transid 5 /dev/sda6 [ 226.797609] BTRFS info (device sda6): disk space caching is enabled [ 226.804542] BTRFS info (device sda6): has skinny extents [ 226.811922] BTRFS info (device sda6): creating UUID tree [ 228.749660] generic/449 3s [ 228.749664] [ 228.868766] BTRFS info (device sda5): disk space caching is enabled [ 228.875672] BTRFS info (device sda5): has skinny extents [ 229.023890] BTRFS info (device sda6): disk space caching is enabled [ 229.030769] BTRFS info (device sda6): has skinny extents [ 229.114976] run fstests generic/450 at 2018-04-16 23:56:04 [ 229.357530] generic/450 0s [ 229.357535] [ 229.502759] BTRFS info (device sda5): disk space caching is enabled [ 229.509665] BTRFS info (device sda5): has skinny extents [ 229.558691] run fstests generic/451 at 2018-04-16 23:56:04 [ 229.742226] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.754132] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 8738 Comm: aio-dio-cycle-w [ 229.788629] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.800898] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 229.814963] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.826632] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 229.859063] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.870691] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 229.893340] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.905026] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 229.920415] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.932162] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 229.950688] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.962412] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 229.979958] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 229.991748] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 230.008609] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 230.020389] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 230.065668] Page cache invalidation failure on direct I/O. Possible data corruption due to collision with buffered I/O! [ 230.077443] File: /fs/sda5/tst-aio-dio-cycle-write.451 PID: 4 Comm: kworker/0:0 [ 259.762874] generic/451 31s [ 259.762877] [ 259.998790] BTRFS info (device sda5): disk space caching is enabled [ 260.005922] BTRFS info (device sda5): has skinny extents [ 260.060012] run fstests generic/452 at 2018-04-16 23:56:35 [ 260.427035] BTRFS: device fsid 71380e0d-31bc-4e4d-a98e-3103b393ac02 devid 1 transid 5 /dev/sda6 [ 260.447382] BTRFS info (device sda6): disk space caching is enabled [ 260.454684] BTRFS info (device sda6): has skinny extents [ 260.460985] BTRFS info (device sda6): flagging fs with big metadata feature [ 260.470927] BTRFS info (device sda6): creating UUID tree [ 260.566231] BTRFS info (device sda6): disk space caching is enabled [ 260.647242] generic/452 1s [ 260.647245] [ 260.725329] BTRFS info (device sda6): disk space caching is enabled [ 260.732479] BTRFS info (device sda6): has skinny extents [ 260.786931] run fstests generic/453 at 2018-04-16 23:56:36 [ 261.096723] BTRFS: device fsid 60e621a3-21e8-4c9c-9815-a53d87b52620 devid 1 transid 5 /dev/sda6 [ 261.115589] BTRFS info (device sda6): disk space caching is enabled [ 261.122746] BTRFS info (device sda6): has skinny extents [ 261.128921] BTRFS info (device sda6): flagging fs with big metadata feature [ 261.138190] BTRFS info (device sda6): creating UUID tree [ 261.302450] generic/453 0s [ 261.302454] [ 261.416061] BTRFS info (device sda6): disk space caching is enabled [ 261.423474] BTRFS info (device sda6): has skinny extents [ 261.511374] run fstests generic/454 at 2018-04-16 23:56:36 [ 261.840301] BTRFS: device fsid 2aed2d5a-72b3-4e86-8464-01e568e0ed41 devid 1 transid 5 /dev/sda6 [ 261.861219] BTRFS info (device sda6): disk space caching is enabled [ 261.868357] BTRFS info (device sda6): has skinny extents [ 261.874520] BTRFS info (device sda6): flagging fs with big metadata feature [ 261.884063] BTRFS info (device sda6): creating UUID tree [ 262.068519] generic/454 1s [ 262.068522] [ 262.181097] BTRFS info (device sda6): disk space caching is enabled [ 262.188131] BTRFS info (device sda6): has skinny extents [ 262.278572] run fstests generic/456 at 2018-04-16 23:56:37 [ 262.693602] BTRFS: device fsid 8cce1375-71e5-4155-a7bf-8171c4f34a8e devid 1 transid 5 /dev/sda6 [ 262.728094] BTRFS info (device dm-0): disk space caching is enabled [ 262.735778] BTRFS info (device dm-0): has skinny extents [ 262.742455] BTRFS info (device dm-0): flagging fs with big metadata feature [ 262.752060] BTRFS info (device dm-0): creating UUID tree [ 262.902433] BTRFS info (device dm-0): disk space caching is enabled [ 262.909501] BTRFS info (device dm-0): has skinny extents [ 262.976158] BTRFS info (device dm-0): checking UUID tree [ 263.115546] generic/456 1s [ 263.115550] [ 263.171080] run fstests generic/459 at 2018-04-16 23:56:38 [ 263.590048] BTRFS: device fsid 1d1bbe87-356f-4b9e-a37f-b7c2b2703b77 devid 1 transid 5 /dev/sda6 [ 264.226373] device-mapper: thin: Data device (dm-1) discard unsupported: Disabling discard passdown. [ 264.450302] device-mapper: thin: Data device (dm-1) discard unsupported: Disabling discard passdown. [ 264.660442] BTRFS: device fsid 6b978baa-45c9-4f47-b0d7-55c88bfabd69 devid 1 transid 5 /dev/mapper/vg_459-lv_459 [ 264.816718] BTRFS info (device dm-5): disk space caching is enabled [ 264.823810] BTRFS info (device dm-5): has skinny extents [ 264.829864] BTRFS info (device dm-5): flagging fs with big metadata feature [ 264.839356] BTRFS info (device dm-5): creating UUID tree [ 265.791280] device-mapper: thin: 252:2: reached low water mark for data device: sending event. [ 265.848294] device-mapper: thin: 252:2: switching pool to out-of-data-space (error IO) mode [ 265.878332] BTRFS: error (device dm-5) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 265.891414] BTRFS info (device dm-5): forced readonly [ 265.897198] BTRFS warning (device dm-5): Skipping commit of aborted transaction. [ 265.905274] BTRFS: error (device dm-5) in cleanup_transaction:1854: errno=-5 IO failure [ 265.913964] BTRFS info (device dm-5): delayed_refs has NO entry [ 265.926013] VFS:Filesystem freeze failed [ 275.935392] device-mapper: thin: Data device (dm-1) discard unsupported: Disabling discard passdown. [ 275.945435] device-mapper: thin: 252:2: switching pool to write mode [ 275.952618] device-mapper: thin: 252:2: growing the data device from 1600 to 3200 blocks [ 276.005251] BTRFS error (device dm-5): cleaner transaction attach returned -30 [ 276.366172] generic/459 13s [ 276.366176] [ 276.401392] run fstests generic/460 at 2018-04-16 23:56:51 [ 276.726924] BTRFS: device fsid e3dfb28f-2f93-4e19-862c-f08a1c5dd7f7 devid 1 transid 5 /dev/sda6 [ 276.748116] BTRFS info (device sda6): disk space caching is enabled [ 276.755115] BTRFS info (device sda6): has skinny extents [ 276.761140] BTRFS info (device sda6): flagging fs with big metadata feature [ 276.794364] BTRFS info (device sda6): creating UUID tree [ 277.118292] generic/460 1s [ 277.118296] [ 285.782215] BTRFS info (device sda6): disk space caching is enabled [ 285.789202] BTRFS info (device sda6): has skinny extents [ 285.866438] run fstests generic/461 at 2018-04-16 23:57:01 [ 286.185554] BTRFS: device fsid e3f6337f-bf36-407f-88e7-0bc881c56498 devid 1 transid 5 /dev/sda6 [ 286.204591] BTRFS info (device sda6): disk space caching is enabled [ 286.211592] BTRFS info (device sda6): has skinny extents [ 286.217609] BTRFS info (device sda6): flagging fs with big metadata feature [ 286.226992] BTRFS info (device sda6): creating UUID tree [ 286.329480] generic/461 [not run] btrfs does not support shutdown [ 286.329485] [ 286.350913] run fstests generic/463 at 2018-04-16 23:57:01 [ 286.592926] generic/463 0s [ 286.592929] [ 286.799078] BTRFS info (device sda5): disk space caching is enabled [ 286.806082] BTRFS info (device sda5): has skinny extents [ 286.889058] run fstests generic/464 at 2018-04-16 23:57:02 [ 287.105754] BTRFS: device fsid d791f210-8187-4d8a-9c76-5e01a01d44f0 devid 1 transid 5 /dev/sda6 [ 287.222957] BTRFS info (device sda6): disk space caching is enabled [ 287.230029] BTRFS info (device sda6): has skinny extents [ 287.236090] BTRFS info (device sda6): flagging fs with big metadata feature [ 287.245385] BTRFS info (device sda6): creating UUID tree [ 293.703817] BTRFS info (device sda6): disk space caching is enabled [ 293.710769] BTRFS info (device sda6): has skinny extents [ 300.794755] BTRFS info (device sda6): disk space caching is enabled [ 300.801789] BTRFS info (device sda6): has skinny extents [ 308.456926] BTRFS info (device sda6): disk space caching is enabled [ 308.463874] BTRFS info (device sda6): has skinny extents [ 315.814643] BTRFS info (device sda6): disk space caching is enabled [ 315.821614] BTRFS info (device sda6): has skinny extents [ 323.849187] BTRFS info (device sda6): disk space caching is enabled [ 323.856129] BTRFS info (device sda6): has skinny extents [ 332.226620] BTRFS info (device sda6): disk space caching is enabled [ 332.233522] BTRFS info (device sda6): has skinny extents [ 339.521815] BTRFS info (device sda6): disk space caching is enabled [ 339.528829] BTRFS info (device sda6): has skinny extents [ 346.722261] BTRFS info (device sda6): disk space caching is enabled [ 346.729208] BTRFS info (device sda6): has skinny extents [ 355.167033] BTRFS info (device sda6): disk space caching is enabled [ 355.173952] BTRFS info (device sda6): has skinny extents [ 361.834284] BTRFS info (device sda6): disk space caching is enabled [ 361.841240] BTRFS info (device sda6): has skinny extents [ 361.940586] generic/464 75s [ 361.940591] [ 361.984297] run fstests generic/465 at 2018-04-16 23:58:17 [ 373.104752] generic/465 11s [ 373.104755] [ 373.136873] run fstests generic/466 at 2018-04-16 23:58:28 [ 373.544531] BTRFS: device fsid d1c16bb7-563e-4db6-a043-22434c286c4e devid 1 transid 5 /dev/sda6 [ 373.564829] BTRFS info (device sda6): disk space caching is enabled [ 373.571675] BTRFS info (device sda6): has skinny extents [ 373.577561] BTRFS info (device sda6): flagging fs with big metadata feature [ 373.587070] BTRFS info (device sda6): creating UUID tree [ 373.817953] BTRFS: device fsid 6bb5b12b-ea5c-4a2f-a717-d40ca0a3557f devid 1 transid 5 /dev/sda6 [ 373.833222] BTRFS info (device sda6): disk space caching is enabled [ 373.840104] BTRFS info (device sda6): has skinny extents [ 373.846030] BTRFS info (device sda6): flagging fs with big metadata feature [ 373.855108] BTRFS info (device sda6): creating UUID tree [ 374.051272] BTRFS: device fsid c49a9ea6-8c5f-4a9e-a771-73b4542cd396 devid 1 transid 5 /dev/sda6 [ 374.066537] BTRFS info (device sda6): disk space caching is enabled [ 374.073704] BTRFS info (device sda6): has skinny extents [ 374.079878] BTRFS info (device sda6): flagging fs with big metadata feature [ 374.089211] BTRFS info (device sda6): creating UUID tree [ 374.295550] BTRFS: device fsid 69bde0df-b8ec-4f9b-9324-71496fbbf659 devid 1 transid 5 /dev/sda6 [ 374.310295] BTRFS info (device sda6): disk space caching is enabled [ 374.317182] BTRFS info (device sda6): has skinny extents [ 374.323071] BTRFS info (device sda6): flagging fs with big metadata feature [ 374.332218] BTRFS info (device sda6): creating UUID tree [ 374.463877] BTRFS info (device sda6): disk space caching is enabled [ 374.470818] BTRFS info (device sda6): has skinny extents [ 374.712384] BTRFS info (device sda6): disk space caching is enabled [ 374.719247] BTRFS info (device sda6): has skinny extents [ 374.884726] BTRFS: device fsid 7c4f6177-afc6-4e6f-9f4b-415081c41868 devid 1 transid 5 /dev/sda6 [ 374.900068] BTRFS info (device sda6): disk space caching is enabled [ 374.907004] BTRFS info (device sda6): has skinny extents [ 374.912943] BTRFS info (device sda6): flagging fs with big metadata feature [ 374.922224] BTRFS info (device sda6): creating UUID tree [ 375.150999] BTRFS: device fsid 886c0d42-b84d-4749-8e91-2ab9cc8d50df devid 1 transid 5 /dev/sda6 [ 375.166878] BTRFS info (device sda6): disk space caching is enabled [ 375.173834] BTRFS info (device sda6): has skinny extents [ 375.179790] BTRFS info (device sda6): flagging fs with big metadata feature [ 375.189024] BTRFS info (device sda6): creating UUID tree [ 375.395670] BTRFS: device fsid aab0d8c9-7db8-411b-8e6c-c53c3d6eb290 devid 1 transid 5 /dev/sda6 [ 375.411484] BTRFS info (device sda6): disk space caching is enabled [ 375.418402] BTRFS info (device sda6): has skinny extents [ 375.424343] BTRFS info (device sda6): flagging fs with big metadata feature [ 375.433994] BTRFS info (device sda6): creating UUID tree [ 375.651063] BTRFS: device fsid 6dc44357-0735-47df-9ac6-894d785705e7 devid 1 transid 5 /dev/sda6 [ 375.666718] BTRFS info (device sda6): disk space caching is enabled [ 375.673647] BTRFS info (device sda6): has skinny extents [ 375.679600] BTRFS info (device sda6): flagging fs with big metadata feature [ 375.688985] BTRFS info (device sda6): creating UUID tree [ 375.731757] generic/466 3s [ 375.731761] [ 375.765820] run fstests generic/467 at 2018-04-16 23:58:31 [ 376.180312] sh (8475): drop_caches: 3 [ 376.287612] sh (8482): drop_caches: 3 [ 376.477527] sh (8489): drop_caches: 3 [ 376.624520] sh (8496): drop_caches: 3 [ 376.740200] sh (8501): drop_caches: 3 [ 376.913506] sh (8508): drop_caches: 3 [ 377.028126] sh (8517): drop_caches: 3 [ 377.158940] sh (8527): drop_caches: 3 [ 377.178549] generic/467 1s [ 377.178554] [ 377.333958] BTRFS info (device sda5): disk space caching is enabled [ 377.340889] BTRFS info (device sda5): has skinny extents [ 377.397996] run fstests generic/468 at 2018-04-16 23:58:32 [ 377.907736] BTRFS: device fsid 199c5d20-cba8-4755-82f6-7219707ab45a devid 1 transid 5 /dev/sda6 [ 377.953461] BTRFS info (device sda6): disk space caching is enabled [ 377.960803] BTRFS info (device sda6): has skinny extents [ 377.967176] BTRFS info (device sda6): flagging fs with big metadata feature [ 378.004726] BTRFS info (device sda6): creating UUID tree [ 378.071986] generic/468 [not run] btrfs does not support shutdown [ 378.071989] [ 378.101314] run fstests generic/469 at 2018-04-16 23:58:33 [ 378.473559] generic/469 0s [ 378.473564] [ 378.632235] BTRFS info (device sda5): disk space caching is enabled [ 378.639107] BTRFS info (device sda5): has skinny extents [ 378.695634] run fstests generic/471 at 2018-04-16 23:58:34 [ 378.871532] generic/471 [not run] xfs_io pwrite failed (old kernel/wrong fs?) [ 378.871539] [ 378.892649] run fstests generic/472 at 2018-04-16 23:58:34 [ 379.241332] BTRFS: device fsid dc3a7bd3-aba2-4a04-ba75-de37008d8c1e devid 1 transid 5 /dev/sda6 [ 379.256759] BTRFS info (device sda6): disk space caching is enabled [ 379.263670] BTRFS info (device sda6): has skinny extents [ 379.269588] BTRFS info (device sda6): flagging fs with big metadata feature [ 379.279049] BTRFS info (device sda6): creating UUID tree [ 380.691097] generic/472 - output mismatch (see /lkp/benchmarks/xfstests/results//generic/472.out.bad) [ 380.691103] [ 380.704834] --- tests/generic/472.out 2018-03-28 13:46:17.000000000 +0800 [ 380.704836] [ 380.716976] +++ /lkp/benchmarks/xfstests/results//generic/472.out.bad 2018-04-16 23:58:36.094904632 +0800 [ 380.716977] [ 380.730045] @@ -2,8 +2,9 @@ [ 380.730046] [ 380.736480] Reformat with 320M size [ 380.736482] [ 380.743631] Fill the filesystem [ 380.743632] [ 380.750738] pwrite: No space left on device [ 380.750740] [ 380.758830] -pwrite wrote more than zero bytes. [ 380.758831] [ 380.768468] -00000000: bb bb bb bb bb bb bb bb bb bb bb bb bb bb bb bb ................ [ 380.768470] [ 380.780545] +pwrite: No space left on device [ 380.780546] [ 380.788510] +pwrite wrote zero bytes [ 380.788511] [ 380.795342] ... [ 380.795343] [ 380.803049] (Run 'diff -u tests/generic/472.out /lkp/benchmarks/xfstests/results//generic/472.out.bad' to see the entire diff) [ 380.803051] [ 380.855590] BTRFS info (device sda6): disk space caching is enabled [ 380.862595] BTRFS info (device sda6): has skinny extents [ 380.918032] run fstests generic/473 at 2018-04-16 23:58:36 [ 381.243330] generic/473 0s [ 381.243335] [ 381.386871] BTRFS info (device sda5): disk space caching is enabled [ 381.393792] BTRFS info (device sda5): has skinny extents [ 381.451968] run fstests generic/474 at 2018-04-16 23:58:36 [ 381.848899] BTRFS: device fsid b2ac8d46-7820-4805-8734-ab1e28a75161 devid 1 transid 5 /dev/sda6 [ 381.879812] BTRFS info (device sda6): disk space caching is enabled [ 381.886723] BTRFS info (device sda6): has skinny extents [ 381.892661] BTRFS info (device sda6): flagging fs with big metadata feature [ 381.957806] BTRFS info (device sda6): creating UUID tree [ 382.014621] generic/474 [not run] btrfs does not support shutdown [ 382.014625] [ 382.043865] run fstests generic/475 at 2018-04-16 23:58:37 [ 382.437822] BTRFS: device fsid 5e8181ff-6aa5-4feb-8e3a-39befa434dcc devid 1 transid 5 /dev/sda6 [ 382.607809] BTRFS info (device dm-0): disk space caching is enabled [ 382.614767] BTRFS info (device dm-0): has skinny extents [ 382.620717] BTRFS info (device dm-0): flagging fs with big metadata feature [ 382.679994] BTRFS info (device dm-0): creating UUID tree [ 383.812328] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 383.818617] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 383.822426] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 383.822435] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 1, flush 0, corrupt 0, gen 0 [ 383.822437] BTRFS error (device dm-0): error writing primary super block to device 1 [ 383.822440] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 383.822442] BTRFS info (device dm-0): forced readonly [ 383.822444] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 383.823018] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 2, flush 0, corrupt 0, gen 0 [ 383.823025] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 3, flush 0, corrupt 0, gen 0 [ 383.823057] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 3, flush 0, corrupt 0, gen 0 [ 383.823062] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 3, flush 0, corrupt 0, gen 0 [ 383.823064] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 3, flush 0, corrupt 0, gen 0 [ 383.823071] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 3, flush 0, corrupt 0, gen 0 [ 383.823077] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 3, flush 0, corrupt 0, gen 0 [ 383.823083] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 3, flush 0, corrupt 0, gen 0 [ 383.823664] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 383.823666] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 383.987702] BTRFS info (device dm-0): delayed_refs has NO entry [ 384.020763] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 384.064921] BTRFS info (device dm-0): disk space caching is enabled [ 384.071829] BTRFS info (device dm-0): has skinny extents [ 384.134538] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 384.144028] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 384.213913] BTRFS info (device dm-0): checking UUID tree [ 385.237233] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 385.243115] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 385.247360] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 385.265203] BTRFS error (device dm-0): error writing primary super block to device 1 [ 385.273699] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 385.285355] BTRFS info (device dm-0): forced readonly [ 385.291154] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 385.299308] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 385.308071] BTRFS info (device dm-0): delayed_refs has NO entry [ 385.337516] BTRFS error (device dm-0): pending csums is 8192 [ 385.344154] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 385.389036] BTRFS info (device dm-0): disk space caching is enabled [ 385.396110] BTRFS info (device dm-0): has skinny extents [ 385.410842] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 387.437765] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 387.438778] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 387.459340] BTRFS info (device dm-0): forced readonly [ 387.465167] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 387.473335] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 387.482117] BTRFS info (device dm-0): delayed_refs has NO entry [ 387.488853] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 387.497055] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 387.505970] BTRFS info (device dm-0): delayed_refs has NO entry [ 387.542232] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 387.584320] BTRFS info (device dm-0): disk space caching is enabled [ 387.591516] BTRFS info (device dm-0): has skinny extents [ 387.643493] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 387.653269] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 387.731501] BTRFS info (device dm-0): checking UUID tree [ 388.780759] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 388.788289] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 388.791191] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 388.809195] BTRFS error (device dm-0): error writing primary super block to device 1 [ 388.817997] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 388.829550] BTRFS info (device dm-0): forced readonly [ 388.835470] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 388.843759] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 388.852630] BTRFS info (device dm-0): delayed_refs has NO entry [ 388.859542] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 388.867829] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 388.876819] BTRFS info (device dm-0): delayed_refs has NO entry [ 388.900874] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 388.961298] BTRFS info (device dm-0): disk space caching is enabled [ 388.968510] BTRFS info (device dm-0): has skinny extents [ 389.010728] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 389.020687] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 389.085642] BTRFS info (device dm-0): checking UUID tree [ 389.109972] btrfs_dev_stat_print_on_error: 143 callbacks suppressed [ 389.117123] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 389.118175] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 389.127991] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 1, flush 0, corrupt 0, gen 0 [ 389.128145] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 2, flush 0, corrupt 0, gen 0 [ 389.128192] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 3, flush 0, corrupt 0, gen 0 [ 389.128218] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 4, flush 0, corrupt 0, gen 0 [ 389.128264] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 5, flush 0, corrupt 0, gen 0 [ 389.128280] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 6, flush 0, corrupt 0, gen 0 [ 389.128288] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 7, flush 0, corrupt 0, gen 0 [ 389.128301] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 8, flush 0, corrupt 0, gen 0 [ 389.128308] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 9, flush 0, corrupt 0, gen 0 [ 389.129825] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 389.129826] BTRFS info (device dm-0): forced readonly [ 389.129827] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 389.129828] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 389.129829] BTRFS info (device dm-0): delayed_refs has NO entry [ 389.312855] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 389.372059] BTRFS info (device dm-0): disk space caching is enabled [ 389.379931] BTRFS info (device dm-0): has skinny extents [ 389.413720] BTRFS info (device dm-0): checking UUID tree [ 391.491576] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 391.498094] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 391.502025] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 391.520004] BTRFS error (device dm-0): error writing primary super block to device 1 [ 391.528826] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 391.540167] BTRFS info (device dm-0): forced readonly [ 391.546008] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 391.554211] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 391.562996] BTRFS info (device dm-0): delayed_refs has NO entry [ 391.569740] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 391.577913] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 391.586757] BTRFS info (device dm-0): delayed_refs has NO entry [ 391.619876] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 391.663300] BTRFS info (device dm-0): disk space caching is enabled [ 391.671034] BTRFS info (device dm-0): has skinny extents [ 391.721170] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 391.731071] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 391.786952] BTRFS info (device dm-0): checking UUID tree [ 391.836130] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 391.836428] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 391.857709] BTRFS info (device dm-0): forced readonly [ 391.863558] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 391.871726] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 391.880530] BTRFS info (device dm-0): delayed_refs has NO entry [ 391.887269] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 391.895482] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 391.904360] BTRFS info (device dm-0): delayed_refs has NO entry [ 391.932544] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 391.973388] BTRFS info (device dm-0): disk space caching is enabled [ 391.980510] BTRFS info (device dm-0): has skinny extents [ 392.036306] BTRFS info (device dm-0): checking UUID tree [ 393.101633] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 393.103163] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 393.123409] BTRFS info (device dm-0): forced readonly [ 393.129307] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 393.137575] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 393.146430] BTRFS info (device dm-0): delayed_refs has NO entry [ 393.153235] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 393.161495] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 393.170426] BTRFS info (device dm-0): delayed_refs has NO entry [ 393.195933] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 393.261084] BTRFS info (device dm-0): disk space caching is enabled [ 393.268258] BTRFS info (device dm-0): has skinny extents [ 393.315596] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 393.324886] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 394.372542] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 394.377524] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 394.383302] btrfs_dev_stat_print_on_error: 97 callbacks suppressed [ 394.383305] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 394.409656] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 394.419486] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 394.430372] BTRFS error (device dm-0): error writing primary super block to device 1 [ 394.430374] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 1, flush 0, corrupt 0, gen 0 [ 394.430379] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 1, flush 0, corrupt 0, gen 0 [ 394.438980] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 394.455905] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 1, flush 0, corrupt 0, gen 0 [ 394.460700] BTRFS info (device dm-0): forced readonly [ 394.460702] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 394.460704] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 394.460705] BTRFS info (device dm-0): delayed_refs has NO entry [ 394.513114] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 1, flush 0, corrupt 0, gen 0 [ 394.524940] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 1, flush 0, corrupt 0, gen 0 [ 394.536189] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 1, flush 0, corrupt 0, gen 0 [ 394.547428] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 1, flush 0, corrupt 0, gen 0 [ 394.558422] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 1, flush 0, corrupt 0, gen 0 [ 394.569448] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 394.582788] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 394.591042] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 394.599890] BTRFS info (device dm-0): delayed_refs has NO entry [ 394.633345] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 394.678491] BTRFS info (device dm-0): disk space caching is enabled [ 394.685880] BTRFS info (device dm-0): has skinny extents [ 395.724434] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 395.727189] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 395.734390] BTRFS error (device dm-0): error writing primary super block to device 1 [ 395.751300] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 395.762658] BTRFS info (device dm-0): forced readonly [ 395.768552] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 395.777010] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 395.785271] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 395.818231] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 395.858664] BTRFS info (device dm-0): disk space caching is enabled [ 395.866032] BTRFS info (device dm-0): has skinny extents [ 395.922894] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 395.974640] BTRFS info (device dm-0): checking UUID tree [ 396.016927] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 396.017334] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 396.038714] BTRFS info (device dm-0): forced readonly [ 396.044617] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 396.052868] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 396.061786] BTRFS info (device dm-0): delayed_refs has NO entry [ 396.068577] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 396.076824] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 396.085758] BTRFS info (device dm-0): delayed_refs has NO entry [ 396.115044] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 396.160015] BTRFS info (device dm-0): disk space caching is enabled [ 396.167086] BTRFS info (device dm-0): has skinny extents [ 396.175964] BTRFS info (device dm-0): checking UUID tree [ 396.218750] BTRFS error (device dm-0): error reading free space cache [ 396.221121] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 396.226016] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 396.248525] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 396.261885] BTRFS info (device dm-0): forced readonly [ 396.267757] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 396.275985] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 396.284798] BTRFS info (device dm-0): delayed_refs has NO entry [ 396.326328] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 396.382480] BTRFS info (device dm-0): disk space caching is enabled [ 396.389594] BTRFS info (device dm-0): has skinny extents [ 396.402601] BTRFS info (device dm-0): checking UUID tree [ 397.445555] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 397.450574] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 397.456174] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 397.474243] BTRFS error (device dm-0): error writing primary super block to device 1 [ 397.482903] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 397.494452] BTRFS info (device dm-0): forced readonly [ 397.500359] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 397.508614] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 397.517474] BTRFS info (device dm-0): delayed_refs has NO entry [ 397.524298] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 397.532575] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 397.541484] BTRFS info (device dm-0): delayed_refs has NO entry [ 397.579682] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 397.628532] BTRFS info (device dm-0): disk space caching is enabled [ 397.635742] BTRFS info (device dm-0): has skinny extents [ 397.719081] BTRFS info (device dm-0): checking UUID tree [ 398.770621] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 398.778196] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 398.781309] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 398.799440] BTRFS error (device dm-0): error writing primary super block to device 1 [ 398.808055] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 398.819414] BTRFS info (device dm-0): forced readonly [ 398.825287] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 398.833507] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 398.842356] BTRFS info (device dm-0): delayed_refs has NO entry [ 398.849151] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 398.857429] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 398.866352] BTRFS info (device dm-0): delayed_refs has NO entry [ 398.893580] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 398.949763] BTRFS info (device dm-0): disk space caching is enabled [ 398.956915] BTRFS info (device dm-0): has skinny extents [ 399.001325] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 399.011133] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 399.141315] BTRFS info (device dm-0): checking UUID tree [ 400.169391] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 400.180135] btrfs_dev_stat_print_on_error: 162 callbacks suppressed [ 400.180138] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 400.181920] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 400.187245] BTRFS error (device dm-0): error writing primary super block to device 1 [ 400.215053] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 400.226463] BTRFS info (device dm-0): forced readonly [ 400.232374] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 400.240834] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 1, flush 0, corrupt 0, gen 0 [ 400.241970] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 400.251717] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 1, flush 0, corrupt 0, gen 0 [ 400.260006] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 400.279876] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 1, flush 0, corrupt 0, gen 0 [ 400.324904] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 400.366105] BTRFS info (device dm-0): disk space caching is enabled [ 400.373304] BTRFS info (device dm-0): has skinny extents [ 402.447198] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 402.451890] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 402.459152] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 402.478754] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 402.489698] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0 [ 402.500720] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0 [ 402.511692] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0 [ 402.522731] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 402.536200] BTRFS info (device dm-0): forced readonly [ 402.542169] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 402.550446] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 402.559344] BTRFS info (device dm-0): delayed_refs has NO entry [ 402.566216] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 402.574529] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 402.583492] BTRFS info (device dm-0): delayed_refs has NO entry [ 402.613501] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 402.657401] BTRFS info (device dm-0): disk space caching is enabled [ 402.664674] BTRFS info (device dm-0): has skinny extents [ 402.729091] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 404.729552] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 404.730497] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 404.751403] BTRFS info (device dm-0): forced readonly [ 404.757333] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 404.765609] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 404.774524] BTRFS info (device dm-0): delayed_refs has NO entry [ 404.781366] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 404.789790] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 404.798867] BTRFS info (device dm-0): delayed_refs has NO entry [ 404.833570] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 404.879918] BTRFS info (device dm-0): disk space caching is enabled [ 404.887116] BTRFS info (device dm-0): has skinny extents [ 404.943305] BTRFS error (device dm-0): error reading free space cache [ 404.944578] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 404.950584] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 404.952785] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 404.983857] BTRFS info (device dm-0): forced readonly [ 404.989764] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 404.998016] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 405.006844] BTRFS info (device dm-0): delayed_refs has NO entry [ 405.032304] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 405.081441] BTRFS info (device dm-0): disk space caching is enabled [ 405.088679] BTRFS info (device dm-0): has skinny extents [ 407.230073] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 407.237319] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 407.240628] btrfs_dev_stat_print_on_error: 140 callbacks suppressed [ 407.240631] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 407.266780] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 407.276566] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 407.287401] BTRFS error (device dm-0): error writing primary super block to device 1 [ 407.287403] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 407.307080] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 407.318483] BTRFS info (device dm-0): forced readonly [ 407.324402] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 407.324597] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0 [ 407.332672] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 407.343836] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0 [ 407.352424] BTRFS info (device dm-0): delayed_refs has NO entry [ 407.370240] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0 [ 407.381169] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0 [ 407.393486] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0 [ 407.404746] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0 [ 407.416032] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0 [ 407.427440] BTRFS info (device dm-0): delayed_refs has NO entry [ 407.457152] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 407.501072] BTRFS info (device dm-0): disk space caching is enabled [ 407.509056] BTRFS info (device dm-0): has skinny extents [ 407.564582] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 407.620318] BTRFS info (device dm-0): checking UUID tree [ 407.694982] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 407.696758] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 407.716735] BTRFS info (device dm-0): forced readonly [ 407.722656] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 407.730905] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 407.739778] BTRFS info (device dm-0): delayed_refs has NO entry [ 407.746657] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 407.755210] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 407.764341] BTRFS info (device dm-0): delayed_refs has NO entry [ 407.793571] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 407.835909] BTRFS info (device dm-0): disk space caching is enabled [ 407.843274] BTRFS info (device dm-0): has skinny extents [ 407.852272] BTRFS info (device dm-0): checking UUID tree [ 407.873227] BTRFS error (device dm-0): error reading free space cache [ 407.874917] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 407.880510] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 408.421000] BTRFS: error (device dm-0) in __btrfs_free_extent:6962: errno=-5 IO failure [ 408.429871] BTRFS info (device dm-0): forced readonly [ 408.435765] BTRFS: error (device dm-0) in btrfs_run_delayed_refs:3070: errno=-5 IO failure [ 408.444905] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 408.453178] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 408.462111] BTRFS error (device dm-0): pending csums is 45056 [ 408.493213] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 408.536566] BTRFS info (device dm-0): disk space caching is enabled [ 408.543744] BTRFS info (device dm-0): has skinny extents [ 408.553483] BTRFS info (device dm-0): checking UUID tree [ 408.578561] BTRFS error (device dm-0): error reading free space cache [ 408.579586] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 408.585795] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 408.607782] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 408.621097] BTRFS info (device dm-0): forced readonly [ 408.626941] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 408.635179] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 408.643996] BTRFS info (device dm-0): delayed_refs has NO entry [ 408.677222] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 408.726995] BTRFS info (device dm-0): disk space caching is enabled [ 408.734093] BTRFS info (device dm-0): has skinny extents [ 408.791428] BTRFS info (device dm-0): checking UUID tree [ 410.966837] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 410.975871] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 410.977861] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 410.984907] BTRFS error (device dm-0): error writing primary super block to device 1 [ 411.000204] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 411.010797] BTRFS info (device dm-0): forced readonly [ 411.015879] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 411.023324] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 411.031367] BTRFS info (device dm-0): delayed_refs has NO entry [ 411.037406] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 411.044853] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 411.077347] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 411.126722] BTRFS info (device dm-0): disk space caching is enabled [ 411.133048] BTRFS info (device dm-0): has skinny extents [ 412.210090] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 412.217351] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 412.219118] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 412.235624] BTRFS error (device dm-0): error writing primary super block to device 1 [ 412.243409] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 412.253954] BTRFS info (device dm-0): forced readonly [ 412.259078] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 412.266529] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 412.274574] BTRFS info (device dm-0): delayed_refs has NO entry [ 412.280575] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 412.288034] BTRFS warning (device dm-0): cleanup_transaction:1854: Aborting unused transaction(IO failure). [ 412.297899] BTRFS info (device dm-0): delayed_refs has NO entry [ 412.327380] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 412.382991] BTRFS info (device dm-0): disk space caching is enabled [ 412.389324] BTRFS info (device dm-0): has skinny extents [ 412.438406] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 412.497039] BTRFS info (device dm-0): checking UUID tree [ 414.519437] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 414.524721] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 414.528464] btrfs_dev_stat_print_on_error: 194 callbacks suppressed [ 414.528467] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 414.552332] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 414.561332] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 414.571411] BTRFS error (device dm-0): error writing primary super block to device 1 [ 414.571413] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 414.571421] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0 [ 414.579190] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 414.609846] BTRFS info (device dm-0): forced readonly [ 414.614924] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 414.615161] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0 [ 414.622356] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 414.632415] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0 [ 414.641309] BTRFS info (device dm-0): delayed_refs has NO entry [ 414.659157] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0 [ 414.670092] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0 [ 414.681587] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0 [ 414.692912] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0 [ 414.705427] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 414.718932] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 414.727228] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 414.736144] BTRFS info (device dm-0): delayed_refs has NO entry [ 414.764986] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 414.816795] BTRFS info (device dm-0): disk space caching is enabled [ 414.898481] BTRFS info (device dm-0): checking UUID tree [ 415.928181] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 415.941467] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 415.954964] BTRFS info (device dm-0): forced readonly [ 415.960904] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 415.969195] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 415.978061] BTRFS info (device dm-0): delayed_refs has NO entry [ 416.004629] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 416.161340] BTRFS info (device dm-0): disk space caching is enabled [ 416.168774] BTRFS info (device dm-0): has skinny extents [ 418.411031] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 418.418334] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 418.420857] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 418.439103] BTRFS error (device dm-0): error writing primary super block to device 1 [ 418.447874] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 418.459404] BTRFS info (device dm-0): forced readonly [ 418.465364] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 418.473654] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 418.482545] BTRFS info (device dm-0): delayed_refs has NO entry [ 418.489373] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 418.497692] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 418.506662] BTRFS info (device dm-0): delayed_refs has NO entry [ 418.538070] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 418.592589] BTRFS info (device dm-0): disk space caching is enabled [ 418.600527] BTRFS info (device dm-0): has skinny extents [ 418.649445] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 418.719878] BTRFS info (device dm-0): checking UUID tree [ 419.756089] btrfs_dev_stat_print_on_error: 39 callbacks suppressed [ 419.756094] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 419.761225] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 419.764006] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 419.795341] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 419.806368] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0 [ 419.817380] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0 [ 419.828312] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0 [ 419.839132] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0 [ 419.850130] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0 [ 419.860924] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0 [ 419.871777] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0 [ 419.882847] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 419.896100] BTRFS info (device dm-0): forced readonly [ 419.901902] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 419.910088] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 419.918855] BTRFS info (device dm-0): delayed_refs has NO entry [ 419.947510] BTRFS error (device dm-0): pending csums is 1253376 [ 419.954420] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 420.017030] BTRFS info (device dm-0): disk space caching is enabled [ 420.024142] BTRFS info (device dm-0): has skinny extents [ 420.131486] BTRFS info (device dm-0): checking UUID tree [ 421.219274] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 421.222565] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 421.229677] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 421.247571] BTRFS error (device dm-0): error writing primary super block to device 1 [ 421.256069] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 421.267350] BTRFS info (device dm-0): forced readonly [ 421.273165] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 421.281325] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 421.290088] BTRFS info (device dm-0): delayed_refs has NO entry [ 421.296804] BTRFS error (device dm-0): pending csums is 1187840 [ 421.327028] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 421.383483] BTRFS info (device dm-0): disk space caching is enabled [ 421.390556] BTRFS info (device dm-0): has skinny extents [ 421.437198] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 422.500570] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 422.505280] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 422.510758] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 422.528641] BTRFS error (device dm-0): error writing primary super block to device 1 [ 422.537140] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 422.548429] BTRFS info (device dm-0): forced readonly [ 422.554255] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 422.562398] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 422.571174] BTRFS info (device dm-0): delayed_refs has NO entry [ 422.577880] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 422.586056] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 422.594901] BTRFS info (device dm-0): delayed_refs has NO entry [ 422.625182] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 422.671926] BTRFS info (device dm-0): disk space caching is enabled [ 422.679739] BTRFS info (device dm-0): has skinny extents [ 422.731459] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 422.741114] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 423.730925] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 423.733394] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 423.744390] BTRFS info (device dm-0): forced readonly [ 423.758612] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 423.766863] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 423.775720] BTRFS info (device dm-0): delayed_refs has NO entry [ 423.782538] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 423.790801] BTRFS warning (device dm-0): cleanup_transaction:1854: Aborting unused transaction(IO failure). [ 423.801411] BTRFS info (device dm-0): delayed_refs has NO entry [ 423.836587] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 423.882813] BTRFS info (device dm-0): disk space caching is enabled [ 423.889865] BTRFS info (device dm-0): has skinny extents [ 424.141763] BTRFS info (device dm-0): checking UUID tree [ 424.171261] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 424.173046] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 424.181103] BTRFS error (device dm-0): error writing primary super block to device 1 [ 424.198040] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 424.209414] BTRFS info (device dm-0): forced readonly [ 424.215314] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 424.223796] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 424.232058] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 424.241104] BTRFS error (device dm-0): pending csums is 1282048 [ 424.269290] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 424.326272] BTRFS info (device dm-0): disk space caching is enabled [ 424.334288] BTRFS info (device dm-0): has skinny extents [ 424.449634] BTRFS info (device dm-0): checking UUID tree [ 425.487113] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 425.697283] btrfs_dev_stat_print_on_error: 91 callbacks suppressed [ 425.697285] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 425.715172] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 425.726069] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 425.736953] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0 [ 425.747818] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0 [ 425.758640] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0 [ 425.769519] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0 [ 425.780297] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0 [ 425.791145] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0 [ 425.801883] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0 [ 425.814963] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 425.828191] BTRFS info (device dm-0): forced readonly [ 425.833950] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 425.842084] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 425.850815] BTRFS info (device dm-0): delayed_refs has NO entry [ 425.881808] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 425.927072] BTRFS info (device dm-0): disk space caching is enabled [ 427.536804] BTRFS error (device dm-0): error reading free space cache [ 427.543945] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 427.557717] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 427.570874] BTRFS info (device dm-0): forced readonly [ 427.576652] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 427.584789] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 427.593525] BTRFS info (device dm-0): delayed_refs has NO entry [ 427.617207] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 427.659886] BTRFS info (device dm-0): disk space caching is enabled [ 427.666922] BTRFS info (device dm-0): has skinny extents [ 427.697015] BTRFS error (device dm-0): error reading free space cache [ 427.698741] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 427.704184] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 427.726013] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 427.739236] BTRFS info (device dm-0): forced readonly [ 427.745011] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 427.753147] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 427.761869] BTRFS info (device dm-0): delayed_refs has NO entry [ 427.799240] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 427.842066] BTRFS info (device dm-0): disk space caching is enabled [ 427.849072] BTRFS info (device dm-0): has skinny extents [ 429.945090] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 429.949185] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 429.955255] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 429.973186] BTRFS error (device dm-0): error writing primary super block to device 1 [ 429.981736] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 429.993040] BTRFS info (device dm-0): forced readonly [ 429.998846] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 430.007031] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 430.015822] BTRFS info (device dm-0): delayed_refs has NO entry [ 430.022617] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 430.030867] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 430.039851] BTRFS info (device dm-0): delayed_refs has NO entry [ 430.071946] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 430.128095] BTRFS info (device dm-0): disk space caching is enabled [ 430.135306] BTRFS info (device dm-0): has skinny extents [ 430.254433] BTRFS info (device dm-0): checking UUID tree [ 432.336356] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 432.347067] btrfs_dev_stat_print_on_error: 283 callbacks suppressed [ 432.347070] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 432.348887] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 432.354176] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 432.383299] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 432.394209] BTRFS error (device dm-0): error writing primary super block to device 1 [ 432.402860] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 432.414285] BTRFS info (device dm-0): forced readonly [ 432.420236] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 432.420900] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 432.428513] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 432.428515] BTRFS info (device dm-0): delayed_refs has NO entry [ 432.428522] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 4, rd 0, flush 0, corrupt 0, gen 0 [ 432.440046] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 0, flush 0, corrupt 0, gen 0 [ 432.448300] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 0, flush 0, corrupt 0, gen 0 [ 432.455663] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 0, flush 0, corrupt 0, gen 0 [ 432.498791] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 0, flush 0, corrupt 0, gen 0 [ 432.509676] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 0, flush 0, corrupt 0, gen 0 [ 432.520690] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 10, rd 0, flush 0, corrupt 0, gen 0 [ 432.531949] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 432.545360] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 432.553604] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 432.562459] BTRFS info (device dm-0): delayed_refs has NO entry [ 432.593102] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 432.649447] BTRFS info (device dm-0): disk space caching is enabled [ 432.656602] BTRFS info (device dm-0): has skinny extents [ 432.688079] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 432.697413] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 433.702149] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 433.750121] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 433.763591] BTRFS info (device dm-0): forced readonly [ 433.769492] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 433.777755] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 433.786645] BTRFS info (device dm-0): delayed_refs has NO entry [ 433.811584] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 433.860604] BTRFS info (device dm-0): disk space caching is enabled [ 433.867775] BTRFS info (device dm-0): has skinny extents [ 433.918658] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 433.928813] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 434.037350] BTRFS info (device dm-0): checking UUID tree [ 435.232434] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 435.242791] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 435.244494] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 435.252811] BTRFS error (device dm-0): error writing primary super block to device 1 [ 435.269610] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 435.280928] BTRFS info (device dm-0): forced readonly [ 435.286832] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 435.295119] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 435.303949] BTRFS info (device dm-0): delayed_refs has NO entry [ 435.310760] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 435.319007] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 435.355186] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 435.405507] BTRFS info (device dm-0): disk space caching is enabled [ 435.412557] BTRFS info (device dm-0): has skinny extents [ 435.593008] BTRFS info (device dm-0): checking UUID tree [ 435.640574] BTRFS: error (device dm-0) in __btrfs_free_extent:6962: errno=-5 IO failure [ 435.641940] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 435.649371] BTRFS info (device dm-0): forced readonly [ 435.663456] BTRFS: error (device dm-0) in btrfs_run_delayed_refs:3070: errno=-5 IO failure [ 435.672661] BTRFS error (device dm-0): pending csums is 2891776 [ 435.703655] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 435.760253] BTRFS info (device dm-0): disk space caching is enabled [ 435.768149] BTRFS info (device dm-0): has skinny extents [ 435.777312] BTRFS info (device dm-0): checking UUID tree [ 435.802580] BTRFS: error (device dm-0) in __btrfs_free_extent:6962: errno=-5 IO failure [ 435.803004] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 435.811435] BTRFS info (device dm-0): forced readonly [ 435.825594] BTRFS: error (device dm-0) in btrfs_run_delayed_refs:3070: errno=-5 IO failure [ 435.834699] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 435.842920] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 435.852420] BTRFS error (device dm-0): pending csums is 262144 [ 435.883842] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 435.923082] BTRFS info (device dm-0): disk space caching is enabled [ 435.930163] BTRFS info (device dm-0): has skinny extents [ 435.940069] BTRFS info (device dm-0): checking UUID tree [ 435.962701] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 435.964586] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 435.972538] BTRFS error (device dm-0): error writing primary super block to device 1 [ 435.989332] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 436.000657] BTRFS info (device dm-0): forced readonly [ 436.006505] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 436.569237] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 436.612556] BTRFS info (device dm-0): disk space caching is enabled [ 436.619660] BTRFS info (device dm-0): has skinny extents [ 436.629188] BTRFS info (device dm-0): checking UUID tree [ 437.786332] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 437.792887] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 437.796675] btrfs_dev_stat_print_on_error: 398 callbacks suppressed [ 437.796678] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 437.822792] BTRFS error (device dm-0): error writing primary super block to device 1 [ 437.822793] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 437.822819] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 3, rd 0, flush 0, corrupt 0, gen 0 [ 437.831351] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 437.864251] BTRFS info (device dm-0): forced readonly [ 437.870079] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 437.878527] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 437.886734] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 437.895669] BTRFS info (device dm-0): delayed_refs has NO entry [ 437.921430] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 437.965629] BTRFS info (device dm-0): disk space caching is enabled [ 437.972961] BTRFS info (device dm-0): has skinny extents [ 438.151735] BTRFS info (device dm-0): checking UUID tree [ 438.199479] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 438.204097] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 438.209244] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 438.228322] BTRFS error (device dm-0): error writing primary super block to device 1 [ 438.228323] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 1, flush 0, corrupt 0, gen 0 [ 438.228328] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 2, flush 0, corrupt 0, gen 0 [ 438.228599] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 2, flush 0, corrupt 0, gen 0 [ 438.236853] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 438.236855] BTRFS info (device dm-0): forced readonly [ 438.247706] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 3, flush 0, corrupt 0, gen 0 [ 438.258459] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 438.305705] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 4, flush 0, corrupt 0, gen 0 [ 438.316619] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 5, flush 0, corrupt 0, gen 0 [ 438.327552] BTRFS: error (device dm-0) in __btrfs_free_extent:6962: errno=-5 IO failure [ 438.336415] BTRFS: error (device dm-0) in btrfs_run_delayed_refs:3070: errno=-5 IO failure [ 438.345590] BTRFS error (device dm-0): pending csums is 1028096 [ 438.369254] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 438.377674] BTRFS info (device dm-0): at unmount delalloc count 98304 [ 438.385166] WARNING: CPU: 1 PID: 14674 at fs/btrfs/disk-io.c:3675 free_fs_root+0xc2/0xd0 [btrfs] [ 438.396562] Modules linked in: dm_snapshot dm_thin_pool dm_persistent_data dm_bio_prison dm_bufio dm_flakey dm_mod netconsole btrfs xor zstd_decompress zstd_compress xxhash raid6_pq sd_mod sg snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ata_generic pata_acpi intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp snd_hda_intel kvm_intel snd_hda_codec kvm irqbypass crct10dif_pclmul eeepc_wmi crc32_pclmul crc32c_intel ghash_clmulni_intel pata_via asus_wmi sparse_keymap snd_hda_core ata_piix snd_hwdep ppdev rfkill wmi_bmof i915 pcbc snd_pcm snd_timer drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops snd aesni_intel parport_pc crypto_simd pcspkr libata soundcore cryptd glue_helper drm wmi parport video shpchp ip_tables [ 438.467607] CPU: 1 PID: 14674 Comm: umount Not tainted 4.17.0-rc1 #1 [ 438.474798] Hardware name: System manufacturer System Product Name/P8H67-M PRO, BIOS 1002 04/01/2011 [ 438.484804] RIP: 0010:free_fs_root+0xc2/0xd0 [btrfs] [ 438.490590] RSP: 0018:ffffc90008b0fda8 EFLAGS: 00010282 [ 438.496641] RAX: ffff88017c5954b0 RBX: ffff880137f6d800 RCX: 0000000180100003 [ 438.504652] RDX: 0000000000000001 RSI: ffffea0006e93600 RDI: 0000000000000000 [ 438.512679] RBP: ffff88017b360000 R08: ffff8801ba4dd000 R09: 0000000180100003 [ 438.520644] R10: ffffc90008b0fc70 R11: 0000000000000000 R12: ffffc90008b0fdd0 [ 438.528657] R13: ffff88017b360080 R14: ffffc90008b0fdc8 R15: 0000000000000000 [ 438.536662] FS: 00007f06c1a80fc0(0000) GS:ffff8801bfa80000(0000) knlGS:0000000000000000 [ 438.545582] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 438.552157] CR2: 00007f06c12b0260 CR3: 000000017d3de004 CR4: 00000000000606e0 [ 438.642653] RAX: 0000000000000000 RBX: 000000000234a2d0 RCX: 00007f06c1359cf7 [ 438.793559] CPU: 1 PID: 14674 Comm: umount Tainted: G W 4.17.0-rc1 #1 [ 438.802152] Hardware name: System manufacturer System Product Name/P8H67-M PRO, BIOS 1002 04/01/2011 [ 438.812108] RIP: 0010:btrfs_put_block_group+0x41/0x60 [btrfs] [ 438.819364] RSP: 0018:ffffc90008b0fde0 EFLAGS: 00010206 [ 438.825378] RAX: 0000000000000000 RBX: ffff8801abf63000 RCX: e38e38e38e38e38f [ 438.833307] RDX: 0000000000000001 RSI: 00000000000009f6 RDI: ffff8801abf63000 [ 438.841230] RBP: ffff88017b360000 R08: ffff88017d3b7750 R09: 0000000180380010 [ 438.849133] R10: ffffc90008b0fca0 R11: 0000000000000000 R12: ffff8801abf63000 [ 438.857047] R13: ffff88017b3600a0 R14: ffff8801abf630e0 R15: dead000000000100 [ 438.864943] FS: 00007f06c1a80fc0(0000) GS:ffff8801bfa80000(0000) knlGS:0000000000000000 [ 438.873793] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 438.880320] CR2: 00007f06c12b0260 CR3: 000000017d3de004 CR4: 00000000000606e0 [ 438.888226] Call Trace: [ 438.891454] btrfs_free_block_groups+0x138/0x3d0 [btrfs] [ 438.897569] close_ctree+0x13b/0x2f0 [btrfs] [ 438.902618] generic_shutdown_super+0x6c/0x120 [ 438.907801] kill_anon_super+0xe/0x20 [ 438.912223] btrfs_kill_super+0x13/0x100 [btrfs] [ 438.917598] deactivate_locked_super+0x3f/0x70 [ 438.922757] cleanup_mnt+0x3b/0x70 [ 438.926879] task_work_run+0xa3/0xe0 [ 438.931205] exit_to_usermode_loop+0x9e/0xa0 [ 438.936226] do_syscall_64+0x16c/0x180 [ 438.940717] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 438.946507] RIP: 0033:0x7f06c1359cf7 [ 438.950798] RSP: 002b:00007ffc6a59c608 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6 [ 438.959137] RAX: 0000000000000000 RBX: 000000000234a2d0 RCX: 00007f06c1359cf7 [ 438.967056] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 000000000234a4b0 [ 438.974937] RBP: 000000000234a4b0 R08: 0000000000000005 R09: 000000000234b510 [ 438.982801] R10: 00000000000006f4 R11: 0000000000000246 R12: 00007f06c1865e44 [ 438.990695] R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffc6a59c890 [ 438.998600] Code: 2a 48 8b 83 e8 01 00 00 48 85 c0 75 2c 48 8b bb d8 00 00 00 e8 c1 1e b8 e0 48 89 df 5b e9 b8 1e b8 e0 0f 0b 48 83 7b 50 00 74 d6 <0f> 0b 48 8b 83 e8 01 00 00 48 85 c0 74 d4 0f 0b eb d0 0f 1f 00 [ 439.019082] ---[ end trace 9263ab2c46fd437a ]--- [ 439.030057] WARNING: CPU: 2 PID: 14674 at fs/btrfs/extent-tree.c:9898 btrfs_free_block_groups+0x2a2/0x3d0 [btrfs] [ 439.044178] Modules linked in: dm_snapshot dm_thin_pool dm_persistent_data dm_bio_prison dm_bufio dm_flakey dm_mod netconsole btrfs xor zstd_decompress zstd_compress xxhash raid6_pq sd_mod sg snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ata_generic pata_acpi intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp snd_hda_intel kvm_intel snd_hda_codec kvm irqbypass crct10dif_pclmul eeepc_wmi crc32_pclmul crc32c_intel ghash_clmulni_intel pata_via asus_wmi sparse_keymap snd_hda_core ata_piix snd_hwdep ppdev rfkill wmi_bmof i915 pcbc snd_pcm snd_timer drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops snd aesni_intel parport_pc crypto_simd pcspkr libata soundcore cryptd glue_helper drm wmi parport video shpchp ip_tables [ 439.116243] CPU: 2 PID: 14674 Comm: umount Tainted: G W 4.17.0-rc1 #1 [ 439.124990] Hardware name: System manufacturer System Product Name/P8H67-M PRO, BIOS 1002 04/01/2011 [ 439.135133] RIP: 0010:btrfs_free_block_groups+0x2a2/0x3d0 [btrfs] [ 439.142218] RSP: 0018:ffffc90008b0fdf0 EFLAGS: 00010206 [ 439.148415] RAX: ffff88017a675a88 RBX: ffff88017a675a88 RCX: 0000000000000001 [ 439.156513] RDX: 0000000000000001 RSI: ffff88017b36cb10 RDI: ffff88017a675e00 [ 439.164603] RBP: ffff88017b360000 R08: 0000000000000000 R09: ffff88017a675ea8 [ 439.172675] R10: 0000000000000002 R11: ffff88017a675eb8 R12: ffff8801abf61c00 [ 439.180737] R13: ffff88017b3600a0 R14: 0000000000000000 R15: dead000000000100 [ 439.188805] FS: 00007f06c1a80fc0(0000) GS:ffff8801bfb00000(0000) knlGS:0000000000000000 [ 439.197825] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 439.204507] CR2: 00007fb6b66729f4 CR3: 000000017d3de005 CR4: 00000000000606e0 [ 439.212582] Call Trace: [ 439.215985] close_ctree+0x13b/0x2f0 [btrfs] [ 439.221199] generic_shutdown_super+0x6c/0x120 [ 439.226573] kill_anon_super+0xe/0x20 [ 439.231160] btrfs_kill_super+0x13/0x100 [btrfs] [ 439.237730] deactivate_locked_super+0x3f/0x70 [ 439.243079] cleanup_mnt+0x3b/0x70 [ 439.247382] task_work_run+0xa3/0xe0 [ 439.251849] exit_to_usermode_loop+0x9e/0xa0 [ 439.257015] do_syscall_64+0x16c/0x180 [ 439.261659] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 439.267606] RIP: 0033:0x7f06c1359cf7 [ 439.272077] RSP: 002b:00007ffc6a59c608 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6 [ 439.280555] RAX: 0000000000000000 RBX: 000000000234a2d0 RCX: 00007f06c1359cf7 [ 439.288606] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 000000000234a4b0 [ 439.296660] RBP: 000000000234a4b0 R08: 0000000000000005 R09: 000000000234b510 [ 439.304708] R10: 00000000000006f4 R11: 0000000000000246 R12: 00007f06c1865e44 [ 439.312761] R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffc6a59c890 [ 439.320807] Code: 8b 85 10 cb 00 00 48 39 c6 0f 84 bc 00 00 00 49 bf 00 01 00 00 00 00 ad de 48 8b 9d 10 cb 00 00 48 83 7b 90 00 0f 84 b1 00 00 00 <0f> 0b 48 8d b3 78 ff ff ff 31 c9 31 d2 48 89 ef e8 19 65 ff ff [ 439.341579] ---[ end trace 9263ab2c46fd437b ]--- [ 439.347181] BTRFS info (device dm-0): space_info 1 has 930922496 free, is not full [ 439.355583] BTRFS info (device dm-0): space_info total=1082130432, used=151076864, pinned=0, reserved=32768, may_use=98304, readonly=0 [ 439.369408] WARNING: CPU: 1 PID: 14674 at fs/btrfs/extent-tree.c:9898 btrfs_free_block_groups+0x2a2/0x3d0 [btrfs] [ 439.384450] Modules linked in: dm_snapshot dm_thin_pool dm_persistent_data dm_bio_prison dm_bufio dm_flakey dm_mod netconsole btrfs xor zstd_decompress zstd_compress xxhash raid6_pq sd_mod sg snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic ata_generic pata_acpi intel_rapl x86_pkg_temp_thermal intel_powerclamp coretemp snd_hda_intel kvm_intel snd_hda_codec kvm irqbypass crct10dif_pclmul eeepc_wmi crc32_pclmul crc32c_intel ghash_clmulni_intel pata_via asus_wmi sparse_keymap snd_hda_core ata_piix snd_hwdep ppdev rfkill wmi_bmof i915 pcbc snd_pcm snd_timer drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops snd aesni_intel parport_pc crypto_simd pcspkr libata soundcore cryptd glue_helper drm wmi parport video shpchp ip_tables [ 439.459196] CPU: 1 PID: 14674 Comm: umount Tainted: G W 4.17.0-rc1 #1 [ 439.478964] RIP: 0010:btrfs_free_block_groups+0x2a2/0x3d0 [btrfs] [ 439.486479] RSP: 0018:ffffc90008b0fdf0 EFLAGS: 00010206 [ 439.493108] RAX: ffff88017a675e88 RBX: ffff88017a675e88 RCX: 00000001820001ae [ 439.501633] RDX: 00000001820001af RSI: ffffea0005117cc0 RDI: 0000000040000000 [ 439.510149] RBP: ffff88017b360000 R08: ffff8801445f3cb8 R09: 00000001820001ae [ 439.518668] R10: ffffc90008b0fd30 R11: 0000000000000000 R12: ffff88017a675bf8 [ 439.527179] R13: ffff88017a675bf8 R14: 0000000000000000 R15: dead000000000100 [ 439.535690] FS: 00007f06c1a80fc0(0000) GS:ffff8801bfa80000(0000) knlGS:0000000000000000 [ 439.545167] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 439.552296] CR2: 00007f06c12b0260 CR3: 000000017d3de004 CR4: 00000000000606e0 [ 439.560762] Call Trace: [ 439.564490] close_ctree+0x13b/0x2f0 [btrfs] [ 439.569590] generic_shutdown_super+0x6c/0x120 [ 439.574827] kill_anon_super+0xe/0x20 [ 439.579310] btrfs_kill_super+0x13/0x100 [btrfs] [ 439.584739] deactivate_locked_super+0x3f/0x70 [ 439.589997] cleanup_mnt+0x3b/0x70 [ 439.594216] task_work_run+0xa3/0xe0 [ 439.598601] exit_to_usermode_loop+0x9e/0xa0 [ 439.603691] do_syscall_64+0x16c/0x180 [ 439.608255] entry_SYSCALL_64_after_hwframe+0x44/0xa9 [ 439.614118] RIP: 0033:0x7f06c1359cf7 [ 439.618495] RSP: 002b:00007ffc6a59c608 EFLAGS: 00000246 ORIG_RAX: 00000000000000a6 [ 439.626877] RAX: 0000000000000000 RBX: 000000000234a2d0 RCX: 00007f06c1359cf7 [ 439.634828] RDX: 0000000000000001 RSI: 0000000000000000 RDI: 000000000234a4b0 [ 439.642790] RBP: 000000000234a4b0 R08: 0000000000000005 R09: 000000000234b510 [ 439.650753] R10: 00000000000006f4 R11: 0000000000000246 R12: 00007f06c1865e44 [ 439.658698] R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffc6a59c890 [ 439.667189] Code: 8b 85 10 cb 00 00 48 39 c6 0f 84 bc 00 00 00 49 bf 00 01 00 00 00 00 ad de 48 8b 9d 10 cb 00 00 48 83 7b 90 00 0f 84 b1 00 00 00 <0f> 0b 48 8d b3 78 ff ff ff 31 c9 31 d2 48 89 ef e8 19 65 ff ff [ 439.688915] ---[ end trace 9263ab2c46fd437c ]--- [ 439.695001] BTRFS info (device dm-0): space_info 4 has 1070989312 free, is not full [ 439.703516] BTRFS info (device dm-0): space_info total=1073741824, used=1638400, pinned=0, reserved=0, may_use=1048576, readonly=65536 [ 439.717402] VFS: Busy inodes after unmount of dm-0. Self-destruct in 5 seconds. Have a nice day... [ 439.752378] BTRFS info (device dm-0): disk space caching is enabled [ 439.759520] BTRFS info (device dm-0): has skinny extents [ 439.791414] BTRFS info (device dm-0): checking UUID tree [ 439.829566] BTRFS error (device dm-0): error reading free space cache [ 439.830480] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 439.836826] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 442.850467] btrfs_dev_stat_print_on_error: 46 callbacks suppressed [ 442.850470] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 5, rd 41, flush 0, corrupt 0, gen 0 [ 442.868563] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 6, rd 41, flush 0, corrupt 0, gen 0 [ 442.879538] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 7, rd 41, flush 0, corrupt 0, gen 0 [ 442.890406] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 8, rd 41, flush 0, corrupt 0, gen 0 [ 442.901330] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 9, rd 41, flush 0, corrupt 0, gen 0 [ 442.912146] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 10, rd 41, flush 0, corrupt 0, gen 0 [ 442.923128] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 11, rd 41, flush 0, corrupt 0, gen 0 [ 442.934054] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 12, rd 41, flush 0, corrupt 0, gen 0 [ 442.944991] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 13, rd 41, flush 0, corrupt 0, gen 0 [ 442.955864] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 14, rd 41, flush 0, corrupt 0, gen 0 [ 442.969341] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 442.982480] BTRFS info (device dm-0): forced readonly [ 442.988223] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 442.996330] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 443.005038] BTRFS info (device dm-0): delayed_refs has NO entry [ 443.036216] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 443.076868] BTRFS info (device dm-0): disk space caching is enabled [ 445.131816] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 445.137794] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 445.142288] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 445.160289] BTRFS error (device dm-0): error writing primary super block to device 1 [ 445.168790] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 445.180067] BTRFS info (device dm-0): forced readonly [ 445.185900] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 445.194080] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 445.202861] BTRFS info (device dm-0): delayed_refs has NO entry [ 445.209671] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 445.217856] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 445.226848] BTRFS info (device dm-0): delayed_refs has NO entry [ 445.259488] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 445.316269] BTRFS info (device dm-0): disk space caching is enabled [ 445.323308] BTRFS info (device dm-0): has skinny extents [ 445.404329] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 447.370720] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 447.373183] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 447.392254] BTRFS info (device dm-0): forced readonly [ 447.398087] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 447.406243] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 447.415029] BTRFS info (device dm-0): delayed_refs has NO entry [ 447.421767] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 447.429921] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 447.438784] BTRFS info (device dm-0): delayed_refs has NO entry [ 447.469296] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 447.538536] BTRFS info (device dm-0): disk space caching is enabled [ 447.545604] BTRFS info (device dm-0): has skinny extents [ 447.597558] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 447.607240] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 449.608945] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 449.613314] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 449.619548] btrfs_dev_stat_print_on_error: 130 callbacks suppressed [ 449.619551] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 449.645727] BTRFS error (device dm-0): error writing primary super block to device 1 [ 449.645728] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 1, flush 0, corrupt 0, gen 0 [ 449.665185] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 449.676568] BTRFS info (device dm-0): forced readonly [ 449.682486] BTRFS: error (device dm-0) in btrfs_sync_log:3101: errno=-5 IO failure [ 449.690976] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 449.699252] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 449.729291] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 449.848244] BTRFS info (device dm-0): disk space caching is enabled [ 449.855436] BTRFS info (device dm-0): has skinny extents [ 449.901231] BTRFS warning (device dm-0): block group 29360128 has wrong amount of free space [ 449.911366] BTRFS warning (device dm-0): failed to load free space cache for block group 29360128, rebuilding it now [ 449.950130] BTRFS warning (device dm-0): block group 1103101952 has wrong amount of free space [ 449.960139] BTRFS warning (device dm-0): failed to load free space cache for block group 1103101952, rebuilding it now [ 450.049104] BTRFS info (device dm-0): checking UUID tree [ 451.070707] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 451.076237] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 451.081535] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 1, rd 0, flush 0, corrupt 0, gen 0 [ 451.100770] BTRFS warning (device dm-0): lost page write due to IO error on /dev/mapper/error-test [ 451.110629] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 2, rd 0, flush 0, corrupt 0, gen 0 [ 451.121541] BTRFS error (device dm-0): error writing primary super block to device 1 [ 451.130208] BTRFS: error (device dm-0) in write_all_supers:3637: errno=-5 IO failure (1 errors while writing supers) [ 451.141633] BTRFS info (device dm-0): forced readonly [ 451.147561] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 451.155858] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 451.164808] BTRFS info (device dm-0): delayed_refs has NO entry [ 451.195462] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 451.249709] BTRFS info (device dm-0): disk space caching is enabled [ 451.256957] BTRFS info (device dm-0): has skinny extents [ 451.296124] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 0, rd 1, flush 0, corrupt 0, gen 0 [ 451.302251] Buffer I/O error on dev dm-0, logical block 52428784, async page read [ 451.307072] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 0, rd 2, flush 0, corrupt 0, gen 0 [ 451.326371] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 0, rd 3, flush 0, corrupt 0, gen 0 [ 451.337342] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 0, rd 4, flush 0, corrupt 0, gen 0 [ 451.348363] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 0, rd 5, flush 0, corrupt 0, gen 0 [ 451.359385] BTRFS error (device dm-0): bdev /dev/mapper/error-test errs: wr 0, rd 6, flush 0, corrupt 0, gen 0 [ 451.392491] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 451.404474] BTRFS: error (device dm-0) in btrfs_commit_transaction:2244: errno=-5 IO failure (Error while writing out transaction) [ 451.417840] BTRFS info (device dm-0): forced readonly [ 451.423726] BTRFS warning (device dm-0): Skipping commit of aborted transaction. [ 451.431938] BTRFS: error (device dm-0) in cleanup_transaction:1854: errno=-5 IO failure [ 451.440774] BTRFS info (device dm-0): delayed_refs has NO entry [ 451.447637] BTRFS error (device dm-0): cleaner transaction attach returned -30 [ 451.490262] BTRFS info (device dm-0): disk space caching is enabled [ 451.497484] BTRFS info (device dm-0): has skinny extents [ 451.533219] BTRFS info (device dm-0): the free space cache file (1103101952) is invalid, skip it [ 451.632923] generic/475 70s [ 451.632926] [ 451.881516] _check_dmesg: something found in dmesg (see /lkp/benchmarks/xfstests/results//generic/475.dmesg) [ 451.881520] [ 451.901186] run fstests generic/477 at 2018-04-16 23:59:47 [ 452.585533] BTRFS info (device sda5): disk space caching is enabled [ 452.592728] BTRFS info (device sda5): has skinny extents [ 452.669166] sh (16189): drop_caches: 3 [ 452.890951] BTRFS info (device sda5): disk space caching is enabled [ 452.898128] BTRFS info (device sda5): has skinny extents [ 452.974342] sh (16225): drop_caches: 3 [ 453.131022] BTRFS info (device sda5): disk space caching is enabled [ 453.138110] BTRFS info (device sda5): has skinny extents [ 453.203295] sh (16261): drop_caches: 3 [ 453.396525] BTRFS info (device sda5): disk space caching is enabled [ 453.403689] BTRFS info (device sda5): has skinny extents [ 453.476538] sh (16298): drop_caches: 3 [ 453.496777] generic/477 1s [ 453.496782] [ 453.683757] BTRFS info (device sda5): disk space caching is enabled [ 453.690745] BTRFS info (device sda5): has skinny extents [ 453.758703] run fstests generic/478 at 2018-04-16 23:59:49 [ 455.045995] generic/478 1s [ 455.045999] [ 455.231358] BTRFS info (device sda5): disk space caching is enabled [ 455.238317] BTRFS info (device sda5): has skinny extents [ 455.309615] run fstests generic/479 at 2018-04-16 23:59:50 [ 455.707468] BTRFS: device fsid 203ef42b-7349-4350-897d-9011c75c57e9 devid 1 transid 5 /dev/sda6 [ 455.771089] BTRFS info (device dm-0): disk space caching is enabled [ 455.778143] BTRFS info (device dm-0): has skinny extents [ 455.784141] BTRFS info (device dm-0): flagging fs with big metadata feature [ 455.793311] BTRFS info (device dm-0): creating UUID tree [ 456.236678] BTRFS info (device dm-0): disk space caching is enabled [ 456.243608] BTRFS info (device dm-0): has skinny extents [ 456.291582] BTRFS info (device dm-0): checking UUID tree [ 456.593182] BTRFS: device fsid f13de3f9-2efe-4603-909f-588dd32b7979 devid 1 transid 5 /dev/sda6 [ 456.627319] BTRFS info (device dm-0): disk space caching is enabled [ 456.634378] BTRFS info (device dm-0): has skinny extents [ 456.640413] BTRFS info (device dm-0): flagging fs with big metadata feature [ 456.649895] BTRFS info (device dm-0): creating UUID tree [ 456.895246] BTRFS info (device dm-0): disk space caching is enabled [ 456.902362] BTRFS info (device dm-0): has skinny extents [ 456.947183] BTRFS info (device dm-0): checking UUID tree [ 457.207646] BTRFS: device fsid 27543091-6f9d-4cd8-bfd4-a3362030d135 devid 1 transid 5 /dev/sda6 [ 457.238704] BTRFS info (device dm-0): disk space caching is enabled [ 457.245626] BTRFS info (device dm-0): has skinny extents [ 457.251583] BTRFS info (device dm-0): flagging fs with big metadata feature [ 457.261051] BTRFS info (device dm-0): creating UUID tree [ 457.512530] BTRFS info (device dm-0): disk space caching is enabled [ 457.519511] BTRFS info (device dm-0): has skinny extents [ 457.558312] BTRFS info (device dm-0): checking UUID tree [ 457.689120] generic/479 3s [ 457.689124] [ 457.774062] run fstests generic/480 at 2018-04-16 23:59:53 [ 458.104311] BTRFS: device fsid 84d31d62-1fe8-4ae0-82a6-5c21cc047cd2 devid 1 transid 5 /dev/sda6 [ 458.137170] BTRFS info (device dm-0): disk space caching is enabled [ 458.144067] BTRFS info (device dm-0): has skinny extents [ 458.149951] BTRFS info (device dm-0): flagging fs with big metadata feature [ 458.159206] BTRFS info (device dm-0): creating UUID tree [ 458.402986] BTRFS info (device dm-0): disk space caching is enabled [ 458.410453] BTRFS info (device dm-0): has skinny extents [ 458.458254] BTRFS info (device dm-0): checking UUID tree [ 458.581430] generic/480 0s [ 458.581434] [ 458.675191] run fstests generic/481 at 2018-04-16 23:59:54 [ 459.015439] BTRFS: device fsid 7692dc27-9b30-4819-a628-a5f819bb314d devid 1 transid 5 /dev/sda6 [ 459.051330] BTRFS info (device dm-0): disk space caching is enabled [ 459.058258] BTRFS info (device dm-0): has skinny extents [ 459.064170] BTRFS info (device dm-0): flagging fs with big metadata feature [ 459.073215] BTRFS info (device dm-0): creating UUID tree [ 459.247181] BTRFS info (device dm-0): disk space caching is enabled [ 459.254047] BTRFS info (device dm-0): has skinny extents [ 459.320163] BTRFS info (device dm-0): checking UUID tree [ 459.462134] generic/481 0s [ 459.462139] [ 459.523352] Ran: generic/445 generic/446 generic/447 generic/448 generic/449 generic/450 generic/451 generic/452 generic/453 generic/454 generic/456 generic/459 generic/460 generic/463 generic/464 generic/465 generic/466 generic/467 generic/469 generic/472 generic/473 generic/475 generic/477 generic/478 generic/479 generic/480 generic/481 [ 459.523356] [ 459.558606] Not run: generic/461 generic/468 generic/471 generic/474 [ 459.558607] [ 459.568660] Failures: generic/472 generic/475 [ 459.568661] [ 459.576140] Failed 2 of 27 tests [ 459.576141] [ 459.581985] [ 459.996266] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml&job_state=post_run -o /dev/null [ 459.996270] [ 461.214792] kill 851 vmstat --timestamp -n 10 [ 461.214797] [ 461.223734] kill 849 dmesg --follow --decode [ 461.223736] [ 461.231774] wait for background monitors: 854 oom-killer [ 461.231776] [ 466.337853] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml&loadavg=6.79%207.37%203.36%201/153%2018057&start_time=1523894043&end_time=1523894395&version=/lkp/lkp/.src-20180416-220206& -o /dev/null [ 466.337856] [ 466.582518] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml&job_state=failed -o /dev/null [ 466.582522] [ 466.858089] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-post-run?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_BASE-20180407-59436-1nzfgnb-0.yaml -o /dev/null [ 466.858094] [ 467.234235] getting new job... [ 467.234239] [ 467.248042] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/gpxelinux.cgi?hostname=snb-drag&mac=20:cf:30:f4:b6:f7&last_kernel=/pkg/linux/x86_64-rhel-7.2/gcc-7/60cc43fc888428bb2f18f08997432d426a243338/vmlinuz-4.17.0-rc1&lkp_wtmp -o /tmp/next-job-lkp [ 467.248045] [ 469.546513] /usr/bin/curl -sSf http://inn:80/~lkp//lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.cgz -o /tmp/next-job.cgz [ 469.546516] [ 469.552561] 22 blocks [ 469.568262] [ 469.577948] downloading kernel image ... [ 469.577952] [ 469.591899] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.yaml&job_state=wget_kernel -o /dev/null [ 469.591901] [ 470.745441] /usr/bin/curl -sSf http://inn:80/~lkp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/vmlinuz-4.17.0-rc1-wt-ath-04728-g6bab68d -o /opt/rootfs/tmp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/vmlinuz-4.17.0-rc1-wt-ath-04728-g6bab68d -z /opt/rootfs/tmp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/vmlinuz-4.17.0-rc1-wt-ath-04728-g6bab68d [ 470.745444] [ 470.789747] downloading initrds ... [ 470.789749] [ 470.802947] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.yaml&job_state=wget_initrd -o /dev/null [ 470.802949] [ 470.836263] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/debian/debian-x86_64-2016-08-31.cgz -o /opt/rootfs/tmp/osimage/debian/debian-x86_64-2016-08-31.cgz -z /opt/rootfs/tmp/osimage/debian/debian-x86_64-2016-08-31.cgz [ 470.836266] [ 473.811965] 868655 blocks [ 473.811972] [ 473.830342] /usr/bin/curl -sSf http://inn:80/~lkp/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.cgz -o /opt/rootfs/tmp/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.cgz [ 473.830345] [ 475.080241] 22 blocks [ 475.080246] [ 475.091141] /usr/bin/curl -sSf http://inn:80/~lkp/lkp/lkp/lkp-x86_64.cgz -o /opt/rootfs/tmp/lkp/lkp/lkp-x86_64.cgz -z /opt/rootfs/tmp/lkp/lkp/lkp-x86_64.cgz [ 475.091144] [ 475.170915] 15449 blocks [ 475.170918] [ 475.182576] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz -o /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz -z /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz [ 475.182579] [ 475.209919] 1077 blocks [ 475.213881] [ 475.219723] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-12-14.cgz -o /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-12-14.cgz -z /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2017-12-14.cgz [ 475.219725] [ 475.252470] 4350 blocks [ 475.252473] [ 475.264088] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz -o /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz -z /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz [ 475.264091] [ 475.307031] 8266 blocks [ 475.307034] [ 475.324758] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/deps/debian-x86_64-2016-08-31.cgz/fs_2016-11-15.cgz -o /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/fs_2016-11-15.cgz -z /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/fs_2016-11-15.cgz [ 475.324761] [ 475.448963] 18404 blocks [ 475.448967] [ 475.473863] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/deps/debian-x86_64-2016-08-31.cgz/xfstests_2018-03-14.cgz -o /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/xfstests_2018-03-14.cgz -z /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/xfstests_2018-03-14.cgz [ 475.473866] [ 476.492360] 294194 blocks [ 476.492363] [ 476.520821] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/pkg/debian-x86_64-2016-08-31.cgz/xfstests-x86_64-545e7ca6_2018-03-28.cgz -o /opt/rootfs/tmp/osimage/pkg/debian-x86_64-2016-08-31.cgz/xfstests-x86_64-545e7ca6_2018-03-28.cgz -z /opt/rootfs/tmp/osimage/pkg/debian-x86_64-2016-08-31.cgz/xfstests-x86_64-545e7ca6_2018-03-28.cgz [ 476.520824] [ 476.762739] 66401 blocks [ 476.762742] [ 476.773835] /usr/bin/curl -sSf http://inn:80/~lkp/osimage/deps/debian-x86_64-2016-08-31.cgz/hw_2016-11-15.cgz -o /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/hw_2016-11-15.cgz -z /opt/rootfs/tmp/osimage/deps/debian-x86_64-2016-08-31.cgz/hw_2016-11-15.cgz [ 476.773839] [ 476.949369] 31441 blocks [ 476.949372] [ 476.963188] /usr/bin/curl -sSf http://inn:80/~lkp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/modules.cgz -o /opt/rootfs/tmp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/modules.cgz -z /opt/rootfs/tmp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/modules.cgz [ 476.963192] [ 480.776657] 1142346 blocks [ 480.776660] [ 481.544310] /usr/bin/curl -sSf http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.yaml&job_state=booting -o /dev/null [ 481.544313] [ 481.778800] LKP: kexec loading... [ 481.778804] [ 481.794975] kexec --noefi -l /opt/rootfs/tmp/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/vmlinuz-4.17.0-rc1-wt-ath-04728-g6bab68d --initrd=/opt/rootfs/tmp/initrd-concatenated [ 481.794979] [ 485.810321] --append=ip=::::snb-drag::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/snb-drag/xfstests-4HDD-btrfs-generic-group5-debian-x86_64-2016-08-31.cgz-CYCLIC_HEAD-20180407-59436-1f9r2pv-0.yaml ARCH=x86_64 kconfig=x86_64-rhel-7.2 branch=linux-devel/devel-hourly-2018041614 commit=6bab68d02ac0da487fdb4551ff192755306df5f1 BOOT_IMAGE=/pkg/linux/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/vmlinuz-4.17.0-rc1-wt-ath-04728-g6bab68d max_uptime=7200 RESULT_ROOT=/result/xfstests/4HDD-btrfs-generic-group5/snb-drag/debian-x86_64-2016-08-31.cgz/x86_64-rhel-7.2/gcc-7/6bab68d02ac0da487fdb4551ff192755306df5f1/0 LKP_SERVER=inn debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earl [ 485.896624] [ 486.881806] Unknown type (Reserved) while parsing /sys/firmware/memmap/21/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.881810] [ 486.900723] Unknown type (Reserved) while parsing /sys/firmware/memmap/11/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.900726] [ 486.919707] Unknown type (Reserved) while parsing /sys/firmware/memmap/1/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.919710] [ 486.938682] Unknown type (Reserved) while parsing /sys/firmware/memmap/16/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.938685] [ 486.957666] Unknown type (Reserved) while parsing /sys/firmware/memmap/6/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.957669] [ 486.976597] Unknown type (Reserved) while parsing /sys/firmware/memmap/14/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.976599] [ 486.995583] Unknown type (Reserved) while parsing /sys/firmware/memmap/4/type. Please report this as bug. Using RANGE_RESERVED now. [ 486.995585] [ 487.014509] Unknown type (Reserved) while parsing /sys/firmware/memmap/2/type. Please report this as bug. Using RANGE_RESERVED now. [ 487.014511] [ 487.033542] Unknown type (Reserved) while parsing /sys/firmware/memmap/20/type. Please report this as bug. Using RANGE_RESERVED now. [ 487.033545] [ 487.052694] Unknown type (Reserved) while parsing /sys/firmware/memmap/19/type. Please report this as bug. Using RANGE_RESERVED now. [ 487.052696] [ 487.071849] Unknown type (Reserved) while parsing /sys/firmware/memmap/9/type. Please report this as bug. Using RANGE_RESERVED now. [ 487.071851] [ 488.105330] umount: /tmp: target is busy [ 488.105332] [ 488.114052] (In some cases useful info about processes that [ 488.114054] [ 488.125026] use the device is found by lsof(8) or fuser(1).) [ 488.125027] [ 488.237590] umount: /sys/fs/cgroup/systemd: target is busy [ 488.237594] [ 488.250048] (In some cases useful info about processes that [ 488.250049] [ 488.260842] use the device is found by lsof(8) or fuser(1).) [ 488.260843] [ 488.271379] umount: /sys/fs/cgroup: target is busy [ 488.271380] [ 488.280606] (In some cases useful info about processes that [ 488.280607] [ 488.291316] use the device is found by lsof(8) or fuser(1).) [ 488.291317] [ 488.301542] umount: /run: target is busy [ 488.301543]