early console in setup code [ 0.000000] Linux version 4.17.0-rc2 (kbuild@lkp-hsx03) (gcc version 7.3.0 (Debian 7.3.0-16)) #145 SMP Wed Apr 25 10:08:32 CST 2018 [ 0.000000] Command line: ip=::::vm-ivb41-yocto-ia32-18::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-ivb41-yocto-ia32-18/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-6d08b06e67cd117f6992c46611dfb4ce267cd71e-20180425-6417-i7ogw5-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-04251005 branch=linus/master commit=6d08b06e67cd117f6992c46611dfb4ce267cd71e BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/vmlinuz-4.17.0-rc2 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/2 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 drbd. [ 0.000000] KERNEL supported cpus: [ 0.000000] Centaur CentaurHauls [ 0.000000] CPU: vendor_id 'GenuineIntel' unknown, using generic init. [ 0.000000] CPU: Your system may be unstable. [ 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 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001a3ddfff] usable [ 0.000000] BIOS-e820: [mem 0x000000001a3de000-0x000000001a3fffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x1a3de max_arch_pfn = 0x400000000 [ 0.000000] MTRR default type: write-back [ 0.000000] MTRR fixed ranges enabled: [ 0.000000] 00000-9FFFF write-back [ 0.000000] A0000-BFFFF uncachable [ 0.000000] C0000-FFFFF write-protect [ 0.000000] MTRR variable ranges enabled: [ 0.000000] 0 base 0080000000 mask FF80000000 uncachable [ 0.000000] 1 disabled [ 0.000000] 2 disabled [ 0.000000] 3 disabled [ 0.000000] 4 disabled [ 0.000000] 5 disabled [ 0.000000] 6 disabled [ 0.000000] 7 disabled [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] Scan for SMP in [mem 0x00000000-0x000003ff] [ 0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff] [ 0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff] [ 0.000000] found SMP MP-table at [mem 0x000f6a90-0x000f6a9f] mapped at [ (ptrval)] [ 0.000000] mpc: f6aa0-f6b78 [ 0.000000] Base memory trampoline at [ (ptrval)] 99000 size 24576 [ 0.000000] BRK [0x03e00000, 0x03e00fff] PGTABLE [ 0.000000] BRK [0x03e01000, 0x03e01fff] PGTABLE [ 0.000000] BRK [0x03e02000, 0x03e02fff] PGTABLE [ 0.000000] BRK [0x03e03000, 0x03e03fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x19ff9000-0x1a3cffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000001A3E15CE 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000001A3E142A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000001A3E0040 0013EA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000001A3E0000 000040 [ 0.000000] ACPI: APIC 0x000000001A3E151E 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000001A3E1596 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fd000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001a3ddfff] [ 0.000000] NODE_DATA(0) allocated [mem 0x19fd7000-0x19ff8fff] [ 0.000000] kvm-clock: cpu 0, msr 0:19f57001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 425782853 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001a3ddfff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x000000001a3ddfff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001a3ddfff] [ 0.000000] On node 0 totalpages: 107388 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 21 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1616 pages used for memmap [ 0.000000] DMA32 zone: 103390 pages, LIFO batch:31 [ 0.000000] Reserved but unavailable: 98 pages [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, 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 5 global_irq 5 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05 [ 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: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b [ 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] ACPI: IRQ5 used by override. [ 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] ACPI: IRQ10 used by override. [ 0.000000] ACPI: IRQ11 used by override. [ 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: 0x8086a201 base: 0xfed00000 [ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff5fc000 (fec00000) [ 0.000000] e820: [mem 0x1a400000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns [ 0.000000] random: get_random_bytes called from start_kernel+0xc5/0x596 with crng_init=0 [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] percpu: Embedded 484 pages/cpu @ (ptrval) s1950936 r0 d31528 u2097152 [ 0.000000] pcpu-alloc: s1950936 r0 d31528 u2097152 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 19c108c0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 105687 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: ip=::::vm-ivb41-yocto-ia32-18::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-ivb41-yocto-ia32-18/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-6d08b06e67cd117f6992c46611dfb4ce267cd71e-20180425-6417-i7ogw5-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s4-04251005 branch=linus/master commit=6d08b06e67cd117f6992c46611dfb4ce267cd71e BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/vmlinuz-4.17.0-rc2 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/2 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 r [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] Memory: 214540K/429552K available (16396K kernel code, 3328K rwdata, 5164K rodata, 4180K init, 14860K bss, 61996K reserved, 0K cma-reserved) [ 0.004000] Running RCU self tests [ 0.004000] Hierarchical RCU implementation. [ 0.004000] RCU event tracing is enabled. [ 0.004000] RCU lockdep checking is enabled. [ 0.004000] RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1. [ 0.004000] RCU debug extended QS entry/exit. [ 0.004000] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.004000] Tasks RCU enabled. [ 0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.004000] NR_IRQS: 4352, nr_irqs: 48, preallocated irqs: 16 [ 0.004000] Offload RCU callbacks from CPUs: (none). [ 0.004000] console [ttyS0] enabled [ 0.004000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.004000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.004000] ... MAX_LOCK_DEPTH: 48 [ 0.004000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.004000] ... CLASSHASH_SIZE: 4096 [ 0.004000] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.004000] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.004000] ... CHAINHASH_SIZE: 32768 [ 0.004000] memory used by lock dependency info: 7903 kB [ 0.004000] per task-struct memory footprint: 2688 bytes [ 0.004000] ------------------------ [ 0.004000] | Locking API testsuite: [ 0.004000] ---------------------------------------------------------------------------- [ 0.004000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.004000] -------------------------------------------------------------------------- [ 0.004000] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.004000] -------------------------------------------------------------------------- [ 0.004000] recursive read-lock: | ok | | ok | [ 0.004000] recursive read-lock #2: | ok | | ok | [ 0.004000] mixed read-write-lock: | ok | | ok | [ 0.004000] mixed write-read-lock: | ok | | ok | [ 0.004000] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.004000] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.004000] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.004000] -------------------------------------------------------------------------- [ 0.004000] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.004000] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.004000] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.004000] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.004000] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.004000] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.004000] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.004000] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.004000] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.004000] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.004000] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.004000] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.004000] hard-irq lock-inversion/123: ok | ok | ok | [ 0.004000] soft-irq lock-inversion/123: ok | ok | ok | [ 0.004000] hard-irq lock-inversion/132: ok | ok | ok | [ 0.004000] soft-irq lock-inversion/132: ok | ok | ok | [ 0.004000] hard-irq lock-inversion/213: ok | ok | ok | [ 0.004000] soft-irq lock-inversion/213: ok | ok | ok | [ 0.004000] hard-irq lock-inversion/231: ok | ok | ok | [ 0.004000] soft-irq lock-inversion/231: ok | ok | ok | [ 0.004000] hard-irq lock-inversion/312: ok | ok | ok | [ 0.004000] soft-irq lock-inversion/312: ok | ok | ok | [ 0.004000] hard-irq lock-inversion/321: ok | ok | ok | [ 0.004000] soft-irq lock-inversion/321: ok | ok | ok | [ 0.004000] hard-irq read-recursion/123: ok | [ 0.004000] soft-irq read-recursion/123: ok | [ 0.004000] hard-irq read-recursion/132: ok | [ 0.004000] soft-irq read-recursion/132: ok | [ 0.004000] hard-irq read-recursion/213: ok | [ 0.004000] soft-irq read-recursion/213: ok | [ 0.004000] hard-irq read-recursion/231: ok | [ 0.004000] soft-irq read-recursion/231: ok | [ 0.004000] hard-irq read-recursion/312: ok | [ 0.004000] soft-irq read-recursion/312: ok | [ 0.004000] hard-irq read-recursion/321: ok | [ 0.004000] soft-irq read-recursion/321: ok | [ 0.004000] -------------------------------------------------------------------------- [ 0.004000] | Wound/wait tests | [ 0.004000] --------------------- [ 0.004000] ww api failures: ok | ok | ok | [ 0.004000] ww contexts mixing: ok | ok | [ 0.004000] finishing ww context: ok | ok | ok | ok | [ 0.004000] locking mismatches: ok | ok | ok | [ 0.004000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.004000] spinlock nest unlocked: ok | [ 0.004000] ----------------------------------------------------- [ 0.004000] |block | try |context| [ 0.004000] ----------------------------------------------------- [ 0.004000] context: ok | ok | ok | [ 0.004000] try: ok | ok | ok | [ 0.004000] block: ok | ok | ok | [ 0.004000] spinlock: ok | ok | ok | [ 0.004000] ------------------------------------------------------- [ 0.004000] Good, all 261 testcases passed! | [ 0.004000] --------------------------------- [ 0.004000] ACPI: Core revision 20180313 [ 0.004000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.004000] hpet clockevent registered [ 0.004027] APIC: Switch to symmetric I/O mode setup [ 0.004917] enabled ExtINT on CPU#0 [ 0.006450] ENABLING IO-APIC IRQs [ 0.007044] init IO_APIC IRQs [ 0.008012] apic 0 pin 0 not connected [ 0.008609] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.009910] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.011259] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.012000] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.012000] apic 0 pin 16 not connected [ 0.012000] apic 0 pin 17 not connected [ 0.012000] apic 0 pin 18 not connected [ 0.012000] apic 0 pin 19 not connected [ 0.012000] apic 0 pin 20 not connected [ 0.012000] apic 0 pin 21 not connected [ 0.012000] apic 0 pin 22 not connected [ 0.012000] apic 0 pin 23 not connected [ 0.012000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.012000] tsc: Detected 2693.508 MHz processor [ 0.012000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns [ 0.012034] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=10774032) [ 0.013444] pid_max: default: 4096 minimum: 301 [ 0.014373] Security Framework initialized [ 0.015113] Smack: Initializing. [ 0.016009] Smack: IPv6 port labeling enabled. [ 0.016687] AppArmor: AppArmor disabled by boot time parameter [ 0.017731] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.018919] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.020053] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.021126] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.023340] mce: CPU supports 10 MCE banks [ 0.024013] mce: unknown CPU type - not enabling MCE support [ 0.025070] numa_add_cpu cpu 0 node 0: mask now 0 [ 0.026007] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.026935] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.028016] Spectre V2 : Spectre mitigation: kernel not compiled with retpoline; no mitigation available! [ 0.039774] Freeing SMP alternatives memory: 40K [ 0.045485] TSC deadline timer enabled [ 0.046138] smpboot: CPU0: GenuineIntel Intel Xeon E3-12xx v2 (Ivy Bridge) (family: 0x6, model: 0x3a, stepping: 0x9) [ 0.048000] Performance Events: no PMU driver, software events only. [ 0.048000] Hierarchical SRCU implementation. [ 0.048474] NMI watchdog: Perf event create on CPU 0 failed with -2 [ 0.049572] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.050939] smp: Bringing up secondary CPUs ... [ 0.051823] smp: Brought up 1 node, 1 CPU [ 0.052014] smpboot: Max logical packages: 1 [ 0.052772] smpboot: Total of 1 processors activated (5387.01 BogoMIPS) [ 0.056015] node 0 initialised, 38254 pages in 4ms [ 0.057678] devtmpfs: initialized [ 0.060869] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.062603] futex hash table entries: 16 (order: -1, 2048 bytes) [ 0.063999] prandom: seed boundary self test passed [ 0.064854] prandom: 100 self tests passed [ 0.065583] pinctrl core: initialized pinctrl subsystem [ 0.067147] regulator-dummy: no parameters [ 0.069336] NET: Registered protocol family 16 [ 0.071229] audit: initializing netlink subsys (disabled) [ 0.072996] cpuidle: using governor ladder [ 0.074561] ACPI: bus type PCI registered [ 0.075412] PCI: Using configuration type 1 for base access [ 0.076541] audit: type=2000 audit(1524625361.051:1): state=initialized audit_enabled=0 res=1 [ 0.101625] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.103630] cryptd: max_cpu_qlen set to 1000 [ 0.104605] ACPI: Added _OSI(Module Device) [ 0.105337] ACPI: Added _OSI(Processor Device) [ 0.106122] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.107018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.107901] ACPI: Added _OSI(Linux-Dell-Video) [ 0.113448] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.120801] ACPI: Interpreter enabled [ 0.121520] ACPI: (supports S0 S3 S5) [ 0.122140] ACPI: Using IOAPIC for interrupt routing [ 0.123090] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.124654] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.145971] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.147017] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.147946] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.148081] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.150212] PCI host bridge to bus 0000:00 [ 0.150960] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.152017] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.153107] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.154353] pci_bus 0000:00: root bus resource [mem 0x1a400000-0xfebfffff window] [ 0.155548] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.156122] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.158585] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.161111] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.167901] pci 0000:00:01.1: reg 0x20: [io 0xc080-0xc08f] [ 0.170870] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.172016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.173162] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.174387] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.176405] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.178063] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.179240] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.184310] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.188039] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 0.196035] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.209321] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.212947] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.216955] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.221975] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.233781] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.236013] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.240018] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 0.244020] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 0.253446] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit pref] [ 0.259005] pci 0000:00:05.0: [8086:25ab] type 00 class 0x088000 [ 0.261833] pci 0000:00:05.0: reg 0x10: [mem 0xfebf2000-0xfebf200f] [ 0.272394] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.273864] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.275438] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.276455] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.277696] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.280352] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.281532] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.283001] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.284015] vgaarb: loaded [ 0.285550] ACPI: bus type USB registered [ 0.286373] usbcore: registered new interface driver usbfs [ 0.287496] usbcore: registered new interface driver hub [ 0.288101] usbcore: registered new device driver usb [ 0.289266] Linux video capture interface: v2.00 [ 0.290215] pps_core: LinuxPPS API ver. 1 registered [ 0.291079] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.292670] PCI: Using ACPI for IRQ routing [ 0.293426] PCI: pci_cache_line_size set to 64 bytes [ 0.294507] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.295549] e820: reserve RAM buffer [mem 0x1a3de000-0x1bffffff] [ 0.296868] NetLabel: Initializing [ 0.302649] NetLabel: domain hash size = 128 [ 0.303558] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.306231] NetLabel: unlabeled traffic allowed by default [ 0.309103] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 0.311126] clocksource: Switched to clocksource kvm-clock [ 0.312000] FS-Cache: Loaded [ 0.312000] pnp: PnP ACPI init [ 0.312000] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.312718] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.314027] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.315276] pnp 00:03: [dma 2] [ 0.315931] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.317456] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.318963] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.320386] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.322657] pnp: PnP ACPI: found 7 devices [ 0.329473] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.331115] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.332254] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.333274] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.334399] pci_bus 0000:00: resource 7 [mem 0x1a400000-0xfebfffff window] [ 0.335971] NET: Registered protocol family 2 [ 0.339809] tcp_listen_portaddr_hash hash table entries: 256 (order: 2, 22528 bytes) [ 0.341261] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 0.342574] TCP bind hash table entries: 4096 (order: 6, 327680 bytes) [ 0.343990] TCP: Hash tables configured (established 4096 bind 4096) [ 0.345423] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 0.346531] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 0.347891] NET: Registered protocol family 1 [ 0.349458] RPC: Registered named UNIX socket transport module. [ 0.350509] RPC: Registered udp transport module. [ 0.351334] RPC: Registered tcp transport module. [ 0.352303] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.353432] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.354481] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.355632] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.356988] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.358693] PCI: CLS 0 bytes, default 64 [ 0.359960] Unpacking initramfs... [ 1.712096] Freeing initrd memory: 3932K [ 1.720179] Machine check injector initialized [ 1.730721] AVX2 or AES-NI instructions are not detected. [ 1.731645] AVX2 instructions are not detected. [ 1.733369] spin_lock-torture:--- Start of test [debug]: nwriters_stress=2 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 1.736896] spin_lock-torture: Creating torture_shuffle task [ 1.737966] spin_lock-torture: Creating torture_stutter task [ 1.738966] spin_lock-torture: torture_shuffle task started [ 1.739908] spin_lock-torture: Creating lock_torture_writer task [ 1.741008] spin_lock-torture: torture_stutter task started [ 1.741956] spin_lock-torture: Creating lock_torture_writer task [ 1.743004] spin_lock-torture: lock_torture_writer task started [ 1.744108] spin_lock-torture: Creating lock_torture_stats task [ 1.745171] spin_lock-torture: lock_torture_writer task started [ 1.746329] spin_lock-torture: lock_torture_stats task started [ 2.720196] tsc: Refined TSC clocksource calibration: 2693.508 MHz [ 2.721299] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d349e8249, max_idle_ns: 440795288087 ns [ 8.297489] torture_init_begin: Refusing rcu init: spin_lock running. [ 8.298959] torture_init_begin: One torture test at a time! [ 8.300996] Initialise system trusted keyrings [ 8.301759] Key type blacklist registered [ 8.304123] workingset: timestamp_bits=36 max_order=17 bucket_order=0 [ 8.305214] zbud: loaded [ 8.306509] DLM installed [ 8.307959] FS-Cache: Netfs 'nfs' registered for caching [ 8.309227] NFS: Registering the id_resolver key type [ 8.310058] Key type id_resolver registered [ 8.310719] Key type id_legacy registered [ 8.311362] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 8.312406] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 8.313558] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 8.315290] FS-Cache: Netfs 'cifs' registered for caching [ 8.317607] fuse init (API version 7.26) [ 8.318681] orangefs_debugfs_init: called with debug mask: :none: :0: [ 8.319863] orangefs_init: module version upstream loaded [ 8.320731] 9p: Installing v9fs 9p2000 file system support [ 8.321574] FS-Cache: Netfs '9p' registered for caching [ 8.322513] ceph: loaded (mds proto 32) [ 8.323115] pstore: using lz4 compression [ 8.340256] NET: Registered protocol family 38 [ 8.341133] Key type asymmetric registered [ 8.341783] Asymmetric key parser 'x509' registered [ 8.704240] String selftests succeeded [ 8.705218] crc32: CRC_LE_BITS = 64, CRC_BE BITS = 64 [ 8.705970] crc32: self tests passed, processed 225944 bytes in 164471 nsec [ 8.707153] crc32c: CRC_LE_BITS = 64 [ 8.707693] crc32c: self tests passed, processed 225944 bytes in 82457 nsec [ 8.725055] crc32_combine: 8373 self tests passed [ 8.742094] crc32c_combine: 8373 self tests passed [ 8.743231] xz_dec_test: module loaded [ 8.743813] xz_dec_test: Create a device node with 'mknod xz_dec_test c 249 0' and write .xz files to it. [ 8.746225] gpio_it87: no device [ 8.747199] gpio_winbond: chip ID at 2e is ffff [ 8.747903] gpio_winbond: not an our chip [ 8.748604] gpio_winbond: chip ID at 4e is ffff [ 8.749298] gpio_winbond: not an our chip [ 8.750959] no IO addresses supplied [ 8.751846] hgafb: HGA card not detected. [ 8.752543] hgafb: probe of hgafb.0 failed with error -22 [ 8.753522] usbcore: registered new interface driver udlfb [ 8.754423] usbcore: registered new interface driver smscufx [ 8.756666] uvesafb: failed to execute /sbin/v86d [ 8.757400] uvesafb: make sure that the v86d helper is installed and executable [ 8.758543] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2) [ 8.759558] uvesafb: vbe_init() failed with -22 [ 8.760316] uvesafb: probe of uvesafb.0 failed with error -22 [ 8.761284] ipmi message handler version 39.2 [ 8.761963] IPMI System Interface driver. [ 8.762785] ipmi_si: Unable to find any System Interface(s) [ 8.763649] IPMI SSIF Interface driver [ 8.764346] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot. [ 8.765927] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 8.767235] ACPI: Power Button [PWRF] [ 8.831894] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 8.859699] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 8.890312] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 8.893084] lp: driver loaded but no devices found [ 8.893900] telclk_interrupt = 0xf non-mcpbl0010 hw. [ 8.895850] parport_pc 00:04: reported by Plug and Play ACPI [ 8.897125] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 9.009198] lp0: using parport0 (interrupt-driven). [ 9.011908] dummy-irq: no IRQ given. Use irq=N [ 9.012941] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti [ 9.014374] c2port c2port0: C2 port uc added [ 9.015119] c2port c2port0: uc flash has 30 blocks x 512 bytes (15360 bytes total) [ 9.024135] usbcore: registered new interface driver rtsx_usb [ 9.025822] usbcore: registered new interface driver viperboard [ 9.027010] usbcore: registered new interface driver dln2 [ 9.028341] mtdoops: mtd device (mtddev=name/number) must be supplied [ 9.031779] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 9.033398] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 9.034988] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 9.036579] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 9.038166] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 9.039752] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 9.041339] nand: device found, Manufacturer ID: 0x98, Chip ID: 0x39 [ 9.042424] nand: Toshiba NAND 128MiB 1,8V 8-bit [ 9.043220] nand: 128 MiB, SLC, erase size: 16 KiB, page size: 512, OOB size: 16 [ 9.044651] flash size: 128 MiB [ 9.045202] page size: 512 bytes [ 9.045771] OOB area size: 16 bytes [ 9.046369] sector size: 16 KiB [ 9.046919] pages number: 262144 [ 9.047476] pages per sector: 32 [ 9.048072] bus width: 8 [ 9.048522] bits in sector size: 14 [ 9.049129] bits in page size: 9 [ 9.049694] bits in OOB size: 4 [ 9.050235] flash size with OOB: 135168 KiB [ 9.050954] page address bytes: 4 [ 9.051524] sector address bytes: 3 [ 9.052146] options: 0x42 [ 9.053547] Scanning device for bad blocks [ 9.085915] Creating 1 MTD partitions on "NAND 128MiB 1,8V 8-bit": [ 9.087074] 0x000000000000-0x000008000000 : "NAND simulator partition 0" [ 9.098734] [nandsim] warning: CONFIG_MTD_PARTITIONED_MASTER must be enabled to expose debugfs stuff [ 9.100420] Ethernet Channel Bonding Driver: v3.7.1 (April 27, 2011) [ 9.106356] libphy: Fixed MDIO Bus: probed [ 9.109695] tun: Universal TUN/TAP device driver, 1.6 [ 9.110850] vcan: Virtual CAN interface driver [ 9.111622] vxcan: Virtual CAN Tunnel driver [ 9.112572] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 9.113786] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 9.449861] PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 9.900712] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56 [ 9.901936] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection [ 9.903399] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k [ 9.904459] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 9.905591] igb: Intel(R) Gigabit Ethernet Network Driver - version 5.4.0-k [ 9.906842] igb: Copyright (c) 2007-2014 Intel Corporation. [ 9.907886] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k [ 9.909348] ixgbe: Copyright (c) 1999-2016 Intel Corporation. [ 9.916336] YAM driver version 0.8 by F1OAT/F6FBB [ 9.919093] AX.25: bpqether driver version 004 [ 9.919909] baycom_ser_hdx: (C) 1996-2000 Thomas Sailer, HB9JNX/AE4WA [ 9.919909] baycom_ser_hdx: version 0.10 [ 9.923602] hdlcdrv: (C) 1996-2000 Thomas Sailer HB9JNX/AE4WA [ 9.924669] hdlcdrv: version 0.8 [ 9.925263] baycom_par: (C) 1996-2000 Thomas Sailer, HB9JNX/AE4WA [ 9.925263] baycom_par: version 0.9 [ 9.930109] NET3 PLIP version 2.4-parport gniibe@mri.co.jp [ 9.931110] plip0: Parallel port at 0x378, using IRQ 7. [ 9.932078] PPP generic driver version 2.4.2 [ 9.933193] PPP Deflate Compression module registered [ 9.934134] PPP MPPE Compression module registered [ 9.934998] NET: Registered protocol family 24 [ 9.937773] ieee802154fakelb ieee802154fakelb: added 2 fake ieee802154 hardware devices [ 9.939352] usbcore: registered new interface driver atusb [ 9.940635] parport0: cannot grant exclusive access for device ks0108 [ 9.941784] ks0108: ERROR: parport didn't register new device [ 9.942930] cfag12864b: ERROR: ks0108 is not initialized [ 9.943872] cfag12864bfb: ERROR: cfag12864b is not initialized [ 10.214804] panel: panel driver registered on parport0 (io=0x378). [ 10.216447] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 10.217831] ehci-pci: EHCI PCI platform driver [ 10.218650] ehci-platform: EHCI generic platform driver [ 10.219679] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 10.220843] ohci-pci: OHCI PCI platform driver [ 10.221686] ohci-platform: OHCI generic platform driver [ 10.224320] driver u132_hcd [ 10.225093] fotg210_hcd: FOTG210 Host Controller (EHCI) Driver [ 10.226098] Warning! fotg210_hcd should always be loaded before uhci_hcd and ohci_hcd, not after [ 10.236674] usbcore: registered new interface driver usblp [ 10.237808] usbcore: registered new interface driver cdc_wdm [ 10.238795] usbcore: registered new interface driver usbtmc [ 10.239607] usbcore: registered new interface driver mdc800 [ 10.250923] mdc800: v0.7.5 (30/10/2000):USB Driver for Mustek MDC800 Digital Camera [ 10.252450] usbcore: registered new interface driver adutux [ 10.253571] usbcore: registered new interface driver appledisplay [ 10.254796] usbcore: registered new interface driver cytherm [ 10.255954] usbcore: registered new interface driver emi26 - firmware loader [ 10.257271] usbcore: registered new interface driver emi62 - firmware loader [ 10.258421] ftdi_elan: driver ftdi-elan [ 10.259112] usbcore: registered new interface driver ftdi-elan [ 10.273179] usbcore: registered new interface driver usblcd [ 10.274162] usbcore: registered new interface driver legousbtower [ 10.275285] usbcore: registered new interface driver rio500 [ 10.276272] usbcore: registered new interface driver usbtest [ 10.277267] usbcore: registered new interface driver usb_ehset_test [ 10.278367] usbcore: registered new interface driver trancevibrator [ 10.279452] usbcore: registered new interface driver yurex [ 10.280599] usbcore: registered new interface driver lvs [ 10.282764] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller [ 10.284180] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 1 [ 10.285454] vhci_hcd: created sysfs vhci_hcd.0 [ 10.299701] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.17 [ 10.301110] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 10.302421] usb usb1: Product: USB/IP Virtual Host Controller [ 10.303487] usb usb1: Manufacturer: Linux 4.17.0-rc2 vhci_hcd [ 10.304482] usb usb1: SerialNumber: vhci_hcd.0 [ 10.308954] hub 1-0:1.0: USB hub found [ 10.309924] hub 1-0:1.0: 8 ports detected [ 10.312522] vhci_hcd vhci_hcd.0: USB/IP Virtual Host Controller [ 10.314128] vhci_hcd vhci_hcd.0: new USB bus registered, assigned bus number 2 [ 10.315868] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 10.317922] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.17 [ 10.319784] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 10.321470] usb usb2: Product: USB/IP Virtual Host Controller [ 10.322537] usb usb2: Manufacturer: Linux 4.17.0-rc2 vhci_hcd [ 10.323481] usb usb2: SerialNumber: vhci_hcd.0 [ 10.324891] hub 2-0:1.0: USB hub found [ 10.325567] hub 2-0:1.0: 8 ports detected [ 10.327892] usbcore: registered new device driver usbip-host [ 10.329893] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 10.332579] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 10.333455] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 10.334888] parport0: cannot grant exclusive access for device parkbd [ 10.443807] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 10.446495] i2c-parport: adapter type unspecified [ 10.447288] i2c-parport-light: adapter type unspecified [ 10.448344] usbcore: registered new interface driver RobotFuzz Open Source InterFace, OSIF [ 10.449758] usbcore: registered new interface driver i2c-tiny-usb [ 10.469447] usbcore: registered new interface driver dvb_usb_af9015 [ 10.474372] usbcore: registered new interface driver dvb_usb_af9035 [ 10.475155] usbcore: registered new interface driver dvb_usb_dvbsky [ 10.475956] usbcore: registered new interface driver zd1301 [ 10.476979] usbcore: registered new interface driver smsusb [ 10.477977] usbcore: registered new interface driver usbvision [ 10.478947] USBVision USB Video Device Driver for Linux : 0.9.11 [ 10.480229] pps pps0: new PPS source ktimer [ 10.480944] pps pps0: ktimer PPS source registered [ 10.481749] Driver for 1-wire Dallas network protocol. [ 10.482757] usbcore: registered new interface driver DS9490R [ 10.483843] w1_f0d_init() [ 10.486346] f71882fg: Not a Fintek device [ 10.487048] f71882fg: Not a Fintek device [ 10.489110] pc87360: PC8736x not detected, module not inserted [ 10.491455] CAPI 2.0 started up with major 68 (no middleware) [ 10.492519] sdhci: Secure Digital Host Controller Interface driver [ 10.493526] sdhci: Copyright(c) Pierre Ossman [ 10.494370] VUB300 Driver rom wait states = 1C irqpoll timeout = 0400 [ 10.494820] usbcore: registered new interface driver vub300 [ 10.496761] usbcore: registered new interface driver ushc [ 10.497650] sdhci-pltfm: SDHCI platform and OF driver helper [ 10.501147] usbcore: registered new interface driver usbhid [ 10.502060] usbhid: USB HID core driver [ 10.502680] dell_smbios: Unable to run on non-Dell system [ 10.512327] pktgen: Packet Generator for packet performance testing. Version: 2.75 [ 10.514714] IPVS: Registered protocols (SCTP, AH, ESP) [ 10.515585] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 10.516802] IPVS: Each connection entry needs 400 bytes at least [ 10.517982] IPVS: ipvs loaded. [ 10.518379] IPVS: [rr] scheduler registered. [ 10.518993] IPVS: [wrr] scheduler registered. [ 10.519682] IPVS: [lc] scheduler registered. [ 10.520387] IPVS: [ovf] scheduler registered. [ 10.521038] IPVS: [lblc] scheduler registered. [ 10.521690] IPVS: [sh] scheduler registered. [ 10.522343] IPVS: [sed] scheduler registered. [ 10.523115] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 10.524639] gre: GRE over IPv4 demultiplexor driver [ 10.525447] ip_gre: GRE over IPv4 tunneling driver [ 10.528208] NET: Registered protocol family 10 [ 10.531417] Segment Routing with IPv6 [ 10.532195] mip6: Mobile IPv6 [ 10.533046] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver [ 10.535200] ip6_gre: GRE over IPv6 tunneling driver [ 10.538142] NET: Registered protocol family 17 [ 10.538917] NET: Registered protocol family 15 [ 10.539859] NET: Registered protocol family 5 [ 10.553890] NET: Registered protocol family 6 [ 10.554715] NET: Registered protocol family 3 [ 10.555445] can: controller area network core (rev 20170425 abi 9) [ 10.564200] NET: Registered protocol family 29 [ 10.564995] can: raw protocol (rev 20170425) [ 10.566321] NET: Registered protocol family 33 [ 10.567051] Key type rxrpc registered [ 10.567572] Key type rxrpc_s registered [ 10.568263] NET: Registered protocol family 35 [ 10.569350] DCCP: Activated CCID 2 (TCP-like) [ 10.570447] sctp: Hash tables configured (bind 32/51) [ 10.571483] tipc: Activated (version 2.0.0) [ 10.572363] NET: Registered protocol family 30 [ 10.573536] tipc: Started in single node mode [ 10.574297] 9pnet: Installing 9P2000 support [ 10.575256] Key type dns_resolver registered [ 10.576096] Key type ceph registered [ 10.577560] libceph: loaded (mon/osd proto 15/24) [ 10.578821] batman_adv: B.A.T.M.A.N. advanced 2018.1 (compatibility version 15) loaded [ 10.580417] openvswitch: Open vSwitch switching datapath [ 10.581673] mpls_gso: MPLS GSO support [ 10.582608] mce: Unable to init MCE device (rc: -5) [ 10.584096] ... APIC ID: 00000000 (0) [ 10.584811] ... APIC VERSION: 01050014 [ 10.585459] 0000000000000000000000000000000000000000000000000000000000000000 [ 10.586677] 0000000000000000000000000000000000000000000000000000000000000000 [ 10.587879] 0000000000000000000000000000000000000000000000000000000000000000 [ 10.589105] number of MP IRQ sources: 15. [ 10.589874] number of IO-APIC #0 registers: 24. [ 10.590642] testing the IO APIC....................... [ 10.591521] IO APIC #0...... [ 10.592043] .... register #00: 00000000 [ 10.592718] ....... : physical APIC id: 00 [ 10.593463] ....... : Delivery Type: 0 [ 10.594139] ....... : LTS : 0 [ 10.594820] .... register #01: 00170011 [ 10.595498] ....... : max redirection entries: 17 [ 10.596423] ....... : PRQ implemented: 0 [ 10.597130] ....... : IO APIC version: 11 [ 10.597854] .... register #02: 00000000 [ 10.598494] ....... : arbitration: 00 [ 10.599152] .... IRQ redirection table: [ 10.599945] IOAPIC 0: [ 10.600368] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.601591] pin01, enabled , edge , high, V(24), IRR(0), S(0), logical , D(01), M(0) [ 10.602749] pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(0) [ 10.604058] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.605381] pin04, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.606497] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.607602] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.608993] pin07, enabled , edge , high, V(22), IRR(0), S(0), logical , D(01), M(0) [ 10.610448] pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.611613] pin09, enabled , level, high, V(21), IRR(0), S(0), logical , D(01), M(0) [ 10.612751] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.613897] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.615289] pin0c, enabled , edge , high, V(23), IRR(0), S(0), logical , D(01), M(0) [ 10.616673] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.618030] pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.619382] pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.625417] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.626778] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.628143] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.629488] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.635874] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.637250] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.638600] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.640893] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 10.642239] IRQ to pin mappings: [ 10.642814] IRQ0 -> 0:2 [ 10.643274] IRQ1 -> 0:1 [ 10.643756] IRQ3 -> 0:3 [ 10.644251] IRQ4 -> 0:4 [ 10.644738] IRQ5 -> 0:5 [ 10.645216] IRQ6 -> 0:6 [ 10.645844] IRQ7 -> 0:7 [ 10.646306] IRQ8 -> 0:8 [ 10.646773] IRQ9 -> 0:9 [ 10.647218] IRQ10 -> 0:10 [ 10.647699] IRQ11 -> 0:11 [ 10.648198] IRQ12 -> 0:12 [ 10.648679] IRQ13 -> 0:13 [ 10.649150] IRQ14 -> 0:14 [ 10.649623] IRQ15 -> 0:15 [ 10.650179] .................................... done. [ 10.651192] AVX version of gcm_enc/dec engaged. [ 10.651989] AES CTR mode by8 optimization enabled [ 10.655547] sched_clock: Marking stable (10652813941, 0)->(11241770164, -588956223) [ 10.658218] registered taskstats version 1 [ 10.658915] Loading compiled-in X.509 certificates [ 10.660468] Key type encrypted registered [ 10.661933] console [netcon0] enabled [ 10.662642] netconsole: network logging started [ 10.664683] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 10.668032] ### dt-test ### start of unittest - you will see error messages [ 10.669661] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 10.671812] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 10.673907] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 10.675160] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 10.676425] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 10.677817] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 10.680218] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 10.682003] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 10.683261] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 10.694798] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 10.696724] OF: overlay: overlay #6 is not topmost [ 10.703799] i2c i2c-0: Added multiplexed i2c bus 1 [ 10.707648] i2c i2c-0: Added multiplexed i2c bus 2 [ 10.712881] ### dt-test ### FAIL of_unittest_overlay_high_level():2304 overlay_base_root not initialized [ 10.714501] ### dt-test ### end of unittest - 207 passed, 1 failed [ 10.716915] IPv6: ADDRCONF(NETDEV_UP): bond0: link is not ready [ 10.723110] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 10.724769] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX [ 10.726722] IP-Config: Failed to open plip0 [ 10.727470] DHCP/BOOTP: Ignoring device wpan0, MTU 123 too small [ 10.728552] DHCP/BOOTP: Ignoring device wpan1, MTU 123 too small [ 10.729580] IP-Config: Failed to open gretap0 [ 10.730389] IP-Config: Failed to open erspan0 [ 10.731161] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 10.748078] Sending DHCP requests ., OK [ 10.756751] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15 [ 10.757947] IP-Config: Complete: [ 10.758503] device=eth0, hwaddr=52:54:00:12:34:56, ipaddr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2 [ 10.760121] host=vm-ivb41-yocto-ia32-18, domain=, nis-domain=(none) [ 10.761243] bootserver=10.0.2.2, rootserver=10.0.2.2, rootpath= nameserver0=10.0.2.3 [ 10.774348] Freeing unused kernel memory: 4180K [ 10.775224] Write protecting the kernel read-only data: 24576k [ 10.778454] Freeing unused kernel memory: 2000K [ 10.783817] Freeing unused kernel memory: 980K [ 10.784906] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 10.785966] rodata_test: all tests were successful / # PATH=/sbin:/usr/sbin:/bin:/usr/bin:/lkp/lkp/src/bin LKP: HOSTNAME vm-ivb41-yocto-ia32-18, MAC 12:4d:1c:c2:ba:e8, kernel 4.17.0-rc2 145, serial console /dev/ttyS0 [ 11.091342] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 11.283615] random: fast init done [ 11.831486] /lkp/lkp/src/monitors/heartbeat: line 9: vmstat: not found [ 11.831486] /lkp/lkp/src/monitors/heartbeat: exec: line 23: vmstat: not found [ 11.833991] Kernel tests: Boot OK! [ 11.833991] /lkp/lkp/src/bin/run-lkp [ 11.833991] RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/2 [ 11.833991] job=/lkp/scheduled/vm-ivb41-yocto-ia32-18/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-6d08b06e67cd117f6992c46611dfb4ce267cd71e-20180425-6417-i7ogw5-0.yaml [ 11.833991] run-job /lkp/scheduled/vm-ivb41-yocto-ia32-18/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-6d08b06e67cd117f6992c46611dfb4ce267cd71e-20180425-6417-i7ogw5-0.yaml [ 11.833991] /bin/busybox wget -q http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/scheduled/vm-ivb41-yocto-ia32-18/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-6d08b06e67cd117f6992c46611dfb4ce267cd71e-20180425-6417-i7ogw5-0.yaml&job_state=running -O /dev/null [ 11.833991] skip microcode check for virtual machine [ 12.848089] BusyBox v1.24.1 (2016-04-18 23:03:30 GMT) multi-call binary. [ 12.848089] [ 12.848089] Usage: time [-v] PROG ARGS [ 12.864300] Seeding trinity based on x86_64-randconfig-s4-04251005 [ 12.864300] 2018-04-25 03:02:53 trinity -q -q -l off -s 897068 -x get_robust_list -x remap_file_pages -N 999999999 -X [ 12.864300] Trinity 1.8 Dave Jones [ 12.864300] shm:0xf764d000-0x3df9e14 (4 pages) [ 12.864300] [main] Marking syscall get_robust_list (312) as to be disabled. [ 12.864300] [main] Marking syscall remap_file_pages (257) as to be disabled. [ 12.864300] Error getting nobody pwent (No such file or directory) [ 62.432136] Writes: Total: 20944057 Max/Min: 0/0 Fail: 0 [ 123.885728] Writes: Total: 41708535 Max/Min: 0/0 Fail: 0 [ 185.312613] Writes: Total: 61148802 Max/Min: 0/0 Fail: 0 [ 246.752095] Writes: Total: 86067258 Max/Min: 0/0 Fail: 0 [ 294.560671] [ 294.560924] ================================ [ 294.561539] WARNING: inconsistent lock state [ 294.562165] 4.17.0-rc2 #145 Not tainted [ 294.562724] -------------------------------- [ 294.563338] inconsistent {HARDIRQ-ON-W} -> {IN-HARDIRQ-W} usage. [ 294.564201] kworker/0:2/28 [HC1[1]:SC0[0]:HE0:SE1] takes: [ 294.564976] (ptrval) (fs_reclaim){?.+.}, at: fs_reclaim_acquire+0x0/0x30 [ 294.566157] {HARDIRQ-ON-W} state was registered at: [ 294.566863] lock_acquire+0x79/0xd0 [ 294.567379] fs_reclaim_acquire+0x24/0x30 [ 294.568069] fs_reclaim_acquire+0x14/0x20 [ 294.568660] kmem_cache_alloc_node+0x32/0x240 [ 294.569294] __kmalloc_node+0x24/0x30 [ 294.569841] alloc_cpumask_var_node+0x1f/0x50 [ 294.570477] zalloc_cpumask_var+0x14/0x20 [ 294.571071] native_smp_prepare_cpus+0xf4/0x364 [ 294.571738] kvm_smp_prepare_cpus+0x21/0xff [ 294.572349] kernel_init_freeable+0x2b5/0x4db [ 294.572995] kernel_init+0x9/0x100 [ 294.573499] ret_from_fork+0x24/0x30 [ 294.574169] irq event stamp: 141042 [ 294.574681] hardirqs last enabled at (141041): [] _raw_spin_unlock_irqrestore+0x31/0x60 [ 294.576050] hardirqs last disabled at (141042): [] interrupt_entry+0xd4/0x100 [ 294.577278] softirqs last enabled at (140644): [] update_defense_level+0x122/0x450 [ 294.578577] softirqs last disabled at (140642): [] update_defense_level+0x0/0x450 [ 294.579855] [ 294.579855] other info that might help us debug this: [ 294.580867] Possible unsafe locking scenario: [ 294.580867] [ 294.581714] CPU0 [ 294.582073] ---- [ 294.582431] lock(fs_reclaim); [ 294.582892] [ 294.583273] lock(fs_reclaim); [ 294.583757] [ 294.583757] *** DEADLOCK *** [ 294.583757] [ 294.584592] 2 locks held by kworker/0:2/28: [ 294.585196] #0: (ptrval) ((wq_completion)"events"){+.+.}, at: process_one_work+0x1d6/0x460 [ 294.586497] #1: (ptrval) ((work_completion)(&(&adapter->watchdog_task)->work)){+.+.}, at: process_one_work+0x1d6/0x460 [ 294.588125] [ 294.588125] stack backtrace: [ 294.588753] CPU: 0 PID: 28 Comm: kworker/0:2 Not tainted 4.17.0-rc2 #145 [ 294.589803] Workqueue: events e1000_watchdog [ 294.590410] Call Trace: [ 294.590776] [ 294.591078] dump_stack+0x8e/0xd5 [ 294.591557] print_usage_bug+0x247/0x262 [ 294.592221] mark_lock+0x5c5/0x660 [ 294.592719] ? check_usage_backwards+0x160/0x160 [ 294.593436] __lock_acquire+0xdcf/0x1b70 [ 294.594006] ? __lock_acquire+0x3f1/0x1b70 [ 294.594595] ? trace_hardirqs_off+0xd/0x10 [ 294.595190] lock_acquire+0x79/0xd0 [ 294.595706] ? lock_acquire+0x79/0xd0 [ 294.596259] ? find_suitable_fallback+0x80/0x80 [ 294.609933] ? crng_reseed+0x13e/0x2e0 [ 294.610479] fs_reclaim_acquire+0x24/0x30 [ 294.611163] ? find_suitable_fallback+0x80/0x80 [ 294.611915] fs_reclaim_acquire+0x14/0x20 [ 294.612490] __kmalloc+0x35/0x240 [ 294.612982] crng_reseed+0x13e/0x2e0 [ 294.613499] credit_entropy_bits+0x21c/0x230 [ 294.625342] add_interrupt_randomness+0x293/0x300 [ 294.626072] handle_irq_event_percpu+0x3b/0x70 [ 294.626718] handle_irq_event+0x34/0x60 [ 294.627272] handle_fasteoi_irq+0x70/0x120 [ 294.627867] handle_irq+0x15/0x20 [ 294.628349] do_IRQ+0x53/0x100 [ 294.628803] common_interrupt+0xf/0xf [ 294.629331] [ 294.629653] RIP: 0010:e1000_watchdog+0x126/0x530 [ 294.630335] RSP: 0000:ffff880016823e00 EFLAGS: 00000297 ORIG_RAX: ffffffffffffffda [ 294.631690] RAX: 0000000000000010 RBX: ffff880014f992c0 RCX: 0000000000000006 [ 294.632971] RDX: ffffc900008e0000 RSI: ffff88001681d3b8 RDI: ffff880014f98e70 [ 294.634237] RBP: ffff880016823e38 R08: 0000000097c673cd R09: 0000000000000000 [ 294.635549] R10: 0000000000000000 R11: 0000000000000000 R12: ffff880014f98a80 [ 294.636838] R13: ffff880014f98000 R14: ffff880018994480 R15: ffff880014f98e70 [ 294.638276] process_one_work+0x236/0x460 [ 294.638993] ? process_one_work+0x1d6/0x460 [ 294.639652] worker_thread+0x35/0x3f0 [ 294.640175] kthread+0x122/0x140 [ 294.640646] ? process_one_work+0x460/0x460 [ 294.641242] ? __kthread_create_on_node+0x190/0x190 [ 294.641941] ret_from_fork+0x24/0x30 [ 294.642506] BUG: sleeping function called from invalid context at mm/slab.h:421 [ 294.644135] in_atomic(): 1, irqs_disabled(): 1, pid: 28, name: kworker/0:2 [ 294.646373] INFO: lockdep is turned off. [ 294.647940] irq event stamp: 141042 [ 294.648638] hardirqs last enabled at (141041): [] _raw_spin_unlock_irqrestore+0x31/0x60 [ 294.655362] hardirqs last disabled at (141042): [] interrupt_entry+0xd4/0x100 [ 294.676115] softirqs last enabled at (140644): [] update_defense_level+0x122/0x450 [ 294.677737] softirqs last disabled at (140642): [] update_defense_level+0x0/0x450 [ 294.682738] CPU: 0 PID: 28 Comm: kworker/0:2 Not tainted 4.17.0-rc2 #145 [ 294.683763] Workqueue: events e1000_watchdog [ 294.684414] Call Trace: [ 294.697850] [ 294.698174] dump_stack+0x8e/0xd5 [ 294.698697] ___might_sleep+0x15f/0x250 [ 294.699288] __might_sleep+0x45/0x80 [ 294.699853] __kmalloc+0x1a3/0x240 [ 294.700387] crng_reseed+0x13e/0x2e0 [ 294.700957] credit_entropy_bits+0x21c/0x230 [ 294.701642] add_interrupt_randomness+0x293/0x300 [ 294.702382] handle_irq_event_percpu+0x3b/0x70 [ 294.703080] handle_irq_event+0x34/0x60 [ 294.703689] handle_fasteoi_irq+0x70/0x120 [ 294.704325] handle_irq+0x15/0x20 [ 294.704863] do_IRQ+0x53/0x100 [ 294.705347] common_interrupt+0xf/0xf [ 294.705927] [ 294.706267] RIP: 0010:e1000_watchdog+0x126/0x530 [ 294.706983] RSP: 0000:ffff880016823e00 EFLAGS: 00000297 ORIG_RAX: ffffffffffffffda [ 294.708142] RAX: 0000000000000010 RBX: ffff880014f992c0 RCX: 0000000000000006 [ 294.709228] RDX: ffffc900008e0000 RSI: ffff88001681d3b8 RDI: ffff880014f98e70 [ 294.710314] RBP: ffff880016823e38 R08: 0000000097c673cd R09: 0000000000000000 [ 294.715815] R10: 0000000000000000 R11: 0000000000000000 R12: ffff880014f98a80 [ 294.716907] R13: ffff880014f98000 R14: ffff880018994480 R15: ffff880014f98e70 [ 294.718019] process_one_work+0x236/0x460 [ 294.718643] ? process_one_work+0x1d6/0x460 [ 294.719281] worker_thread+0x35/0x3f0 [ 294.719859] kthread+0x122/0x140 [ 294.720361] ? process_one_work+0x460/0x460 [ 294.721008] ? __kthread_create_on_node+0x190/0x190 [ 294.721766] ret_from_fork+0x24/0x30 [ 294.722324] random: crng init done Elapsed time: 300 #!/bin/bash # To reproduce, # 1) save job-script and this script (both are attached in 0day report email) # 2) run this script with your compiled kernel and optional env $INSTALL_MOD_PATH kernel=$1 initrds=( /osimage/yocto/yocto-tiny-i386-2016-04-22.cgz /lkp/lkp/lkp-i386.cgz /osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz /osimage/pkg/debian-x86_64-2016-08-31.cgz/trinity-static-i386-x86_64-6ddabfd2_2017-11-10.cgz ) HTTP_PREFIX=https://github.com/0day-ci/lkp-qemu/raw/master wget --timestamping "${initrds[@]/#/$HTTP_PREFIX}" { cat "${initrds[@]//*\//}" [[ $INSTALL_MOD_PATH ]] && ( cd "$INSTALL_MOD_PATH" find lib | cpio -o -H newc --quiet | gzip ) echo job-script | cpio -o -H newc --quiet | gzip } > initrd.img qemu-img create -f qcow2 disk-vm-ivb41-yocto-ia32-18-0 256G kvm=( qemu-system-x86_64 -enable-kvm -cpu IvyBridge -kernel $kernel -initrd initrd.img -m 420 -smp 1 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -drive file=disk-vm-ivb41-yocto-ia32-18-0,media=disk,if=virtio -serial stdio -display none -monitor null ) append=( ip=::::vm-ivb41-yocto-ia32-18::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=x86_64 kconfig=x86_64-randconfig-s4-04251005 branch=linus/master commit=6d08b06e67cd117f6992c46611dfb4ce267cd71e BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/vmlinuz-4.17.0-rc2 max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/x86_64-randconfig-s4-04251005/gcc-7/6d08b06e67cd117f6992c46611dfb4ce267cd71e/2 result_service=tmpfs 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 drbd.minor_count=8 rcuperf.shutdown=0 ) "${kvm[@]}" -append "${append[*]}"