early console in setup code Probing EDD (edd=off to disable)... ok early console in extract_kernel input_data: 0x03176080 input_len: 0x009a1fb4 output: 0x01000000 output_len: 0x020c6568 kernel_total_size: 0x02b30000 Physical KASLR using RDTSC... Decompressing Linux... Parsing ELF... Performing relocations... done. Booting the kernel. [ 0.000000] Linux version 4.14.0-06692-g5bbcc0f (kbuild@cairo) (gcc version 4.9.4 (Debian 4.9.4-2)) #1 SMP PREEMPT Thu Nov 16 07:10:32 CST 2017 [ 0.000000] KERNEL supported cpus: [ 0.000000] AMD AuthenticAMD [ 0.000000] NSC Geode by NSC [ 0.000000] Cyrix CyrixInstead [ 0.000000] Centaur CentaurHauls [ 0.000000] Transmeta GenuineTMx86 [ 0.000000] Transmeta TransmetaCPU [ 0.000000] CPU: vendor_id 'GenuineIntel' unknown, using generic init. [ 0.000000] CPU: Your system may be unstable. [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 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-0x0000000013fdffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000013fe0000-0x0000000013ffffff] 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] bootconsole [earlyser0] enabled [ 0.000000] Notice: NX (Execute Disable) protection missing in CPU! [ 0.000000] random: fast init done [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 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 = 0x13fe0 max_arch_pfn = 0x100000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 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 0x000f6a80-0x000f6a8f] mapped at [ffd0fa80] [ 0.000000] mpc: f6a90-f6b74 [ 0.000000] initial memory mapped: [mem 0x00000000-0x073fffff] [ 0.000000] Base memory trampoline at [4009b000] 9b000 size 16384 [ 0.000000] BRK [0x06c28000, 0x06c28fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x13c65000-0x13fdffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F6870 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000013FE1628 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000013FE147C 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000013FE0040 00143C (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x0000000013FE0000 000040 [ 0.000000] ACPI: APIC 0x0000000013FE1570 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000013FE15F0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffc000 ( fee00000) [ 0.000000] 0MB HIGHMEM available. [ 0.000000] 319MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 13fe0000 [ 0.000000] low ram: 0 - 13fe0000 [ 0.000000] kvm-clock: cpu 0, msr 0:13c63001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 1973740931 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] BRK [0x06c29000, 0x06c29fff] PGTABLE [ 0.000000] Zone ranges: [ 0.000000] Normal [mem 0x0000000000001000-0x0000000013fdffff] [ 0.000000] HighMem 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-0x0000000013fdffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fdffff] [ 0.000000] On node 0 totalpages: 81790 [ 0.000000] Normal zone: 640 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 81790 pages, LIFO batch:15 [ 0.000000] Using APIC driver default [ 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 2 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffb000 (fec00000) [ 0.000000] e820: [mem 0x14000000-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: 6370452778343963 ns [ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] percpu: Embedded 20 pages/cpu @539b8000 s58688 r0 d23232 u81920 [ 0.000000] pcpu-alloc: s58688 r0 d23232 u81920 alloc=20*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 139bac00 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 81150 [ 0.000000] Kernel command line: ip=::::vm-lkp-hsw01-yocto-i386-16::dhcp root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-lkp-hsw01-yocto-i386-16/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-5bbcc0f595fadb4cac0eddc4401035ec0bd95b09-20171116-677-1hpvvcb-0.yaml ARCH=i386 kconfig=i386-randconfig-c0-10271823 branch=linus/master commit=5bbcc0f595fadb4cac0eddc4401035ec0bd95b09 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-10271823/gcc-4.9/5bbcc0f595fadb4cac0eddc4401035ec0bd95b09/vmlinuz-4.14.0-06692-g5bbcc0f max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-10271823/gcc-4.9/5bbcc0f595fadb4cac0eddc4401035ec0bd95b09/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, [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Initializing CPU#0 [ 0.000000] Initializing HighMem for node 0 (00000000:00000000) [ 0.000000] Memory: 279244K/327160K available (16082K kernel code, 3164K rwdata, 6656K rodata, 1356K init, 13624K bss, 47916K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfff0e000 - 0xfffff000 ( 964 kB) [ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB) [ 0.000000] vmalloc : 0x547e0000 - 0xff7fe000 (2736 MB) [ 0.000000] lowmem : 0x40000000 - 0x53fe0000 ( 319 MB) [ 0.000000] .init : 0x45d64000 - 0x45eb7000 (1356 kB) [ 0.000000] .data : 0x453b4878 - 0x45d4d100 (9826 kB) [ 0.000000] .text : 0x44400000 - 0x453b4878 (16082 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] [ 0.000000] ********************************************************** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ** ** [ 0.000000] ** trace_printk() being used. Allocating extra memory. ** [ 0.000000] ** ** [ 0.000000] ** This means that this is a DEBUG kernel and it is ** [ 0.000000] ** unsafe for production use. ** [ 0.000000] ** ** [ 0.000000] ** If you see this message and you are not debugging ** [ 0.000000] ** the kernel, report this immediately to your vendor! ** [ 0.000000] ** ** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ********************************************************** [ 0.003333] Running RCU self tests [ 0.003333] Preemptible hierarchical RCU implementation. [ 0.003333] RCU lockdep checking is enabled. [ 0.003333] RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2. [ 0.003333] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.003333] Tasks RCU enabled. [ 0.003333] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.003333] NR_IRQS: 2304, nr_irqs: 440, preallocated irqs: 16 [ 0.003333] CPU 0 irqstacks, hard=40214000 soft=40216000 [ 0.003333] console [ttyS0] enabled [ 0.003333] bootconsole [earlyser0] disabled [ 0.003333] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.003333] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.003333] ... MAX_LOCK_DEPTH: 48 [ 0.003333] ... MAX_LOCKDEP_KEYS: 8191 [ 0.003333] ... CLASSHASH_SIZE: 4096 [ 0.003333] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.003333] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.003333] ... CHAINHASH_SIZE: 32768 [ 0.003333] memory used by lock dependency info: 4399 kB [ 0.003333] per task-struct memory footprint: 1536 bytes [ 0.003333] ------------------------ [ 0.003333] | Locking API testsuite: [ 0.003333] ---------------------------------------------------------------------------- [ 0.003333] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] recursive read-lock: | ok | | ok | [ 0.003333] recursive read-lock #2: | ok | | ok | [ 0.003333] mixed read-write-lock: | ok | | ok | [ 0.003333] mixed write-read-lock: | ok | | ok | [ 0.003333] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.003333] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.003333] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.003333] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.003333] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.003333] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.003333] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.003333] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.003333] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.003333] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.003333] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.003333] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/123: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/123: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/132: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/132: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/213: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/213: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/231: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/231: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/312: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/312: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/321: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/321: ok | ok | ok | [ 0.003333] hard-irq read-recursion/123: ok | [ 0.003333] soft-irq read-recursion/123: ok | [ 0.003333] hard-irq read-recursion/132: ok | [ 0.003333] soft-irq read-recursion/132: ok | [ 0.003333] hard-irq read-recursion/213: ok | [ 0.003333] soft-irq read-recursion/213: ok | [ 0.003333] hard-irq read-recursion/231: ok | [ 0.003333] soft-irq read-recursion/231: ok | [ 0.003333] hard-irq read-recursion/312: ok | [ 0.003333] soft-irq read-recursion/312: ok | [ 0.003333] hard-irq read-recursion/321: ok | [ 0.003333] soft-irq read-recursion/321: ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] | Wound/wait tests | [ 0.003333] --------------------- [ 0.003333] ww api failures: ok | ok | ok | [ 0.003333] ww contexts mixing: ok | ok | [ 0.003333] finishing ww context: ok | ok | ok | ok | [ 0.003333] locking mismatches: ok | ok | ok | [ 0.003333] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.003333] spinlock nest unlocked: ok | [ 0.003333] ----------------------------------------------------- [ 0.003333] |block | try |context| [ 0.003333] ----------------------------------------------------- [ 0.003333] context: ok | ok | ok | [ 0.003333] try: ok | ok | ok | [ 0.003333] block: ok | ok | ok | [ 0.003333] spinlock: ok | ok | ok | [ 0.003333] ------------------------------------------------------- [ 0.003333] Good, all 261 testcases passed! | [ 0.003333] --------------------------------- [ 0.003333] ACPI: Core revision 20170831 [ 0.003333] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.003333] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.003333] hpet clockevent registered [ 0.003361] APIC: Switch to symmetric I/O mode setup [ 0.003894] Enabling APIC mode: Flat. Using 1 I/O APICs [ 0.004500] enabled ExtINT on CPU#0 [ 0.005723] ENABLING IO-APIC IRQs [ 0.006077] init IO_APIC IRQs [ 0.006681] apic 0 pin 0 not connected [ 0.007130] IOAPIC[0]: Set routing entry (0-1 -> 0xef -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.007964] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.008777] IOAPIC[0]: Set routing entry (0-3 -> 0xef -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.009628] IOAPIC[0]: Set routing entry (0-4 -> 0xef -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-5 -> 0xef -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-6 -> 0xef -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-7 -> 0xef -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-8 -> 0xef -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-9 -> 0xef -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-10 -> 0xef -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-11 -> 0xef -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-12 -> 0xef -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-13 -> 0xef -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-14 -> 0xef -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.009999] IOAPIC[0]: Set routing entry (0-15 -> 0xef -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.009999] apic 0 pin 16 not connected [ 0.009999] apic 0 pin 17 not connected [ 0.009999] apic 0 pin 18 not connected [ 0.009999] apic 0 pin 19 not connected [ 0.009999] apic 0 pin 20 not connected [ 0.009999] apic 0 pin 21 not connected [ 0.009999] apic 0 pin 22 not connected [ 0.009999] apic 0 pin 23 not connected [ 0.009999] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009999] tsc: Detected 2593.992 MHz processor [ 0.009999] tsc: Marking TSC unstable due to TSCs unsynchronized [ 0.009999] Calibrating delay loop (skipped) preset value.. 5190.15 BogoMIPS (lpj=8646640) [ 0.010018] pid_max: default: 4096 minimum: 301 [ 0.011157] Security Framework initialized [ 0.011863] Yama: becoming mindful. [ 0.013534] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.014687] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.016939] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.017647] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.018715] Freeing SMP alternatives memory: 20K [ 0.021066] smpboot: Max logical packages: 2 [ 0.021602] Using local APIC timer interrupts. [ 0.021602] calibrating APIC timer ... [ 0.023333] ... lapic delta = 6249447 [ 0.023333] ... PM-Timer delta = 357928 [ 0.023333] ... PM-Timer result ok [ 0.023333] ..... delta 6249447 [ 0.023333] ..... mult: 268411731 [ 0.023333] ..... calibration result: 3333038 [ 0.023333] ..... CPU clock speed is 2594.0178 MHz. [ 0.023333] ..... host bus clock speed is 1000.0038 MHz. [ 0.023383] smpboot: CPU0: GenuineIntel QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) [ 0.036718] Performance Events: no PMU driver, software events only. [ 0.043371] Hierarchical SRCU implementation. [ 0.063355] smp: Bringing up secondary CPUs ... [ 0.091618] CPU 1 irqstacks, hard=5302a000 soft=5302c000 [ 0.092225] x86: Booting SMP configuration: [ 0.092816] .... node #0, CPUs: #1 [ 0.000000] Initializing CPU#1 [ 0.003333] kvm-clock: cpu 1, msr 0:13c63041, secondary cpu clock [ 0.003333] masked ExtINT on CPU#1 [ 0.107015] KVM setup async PF for cpu 1 [ 0.107428] kvm-stealtime: cpu 1, msr 139cec00 [ 0.108022] smp: Brought up 1 node, 2 CPUs [ 0.108022] smpboot: Total of 2 processors activated (10380.30 BogoMIPS) [ 0.110919] devtmpfs: initialized [ 0.113761] gcov: version magic: 0x3430392a [ 0.123768] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.124773] futex hash table entries: 16 (order: -2, 1024 bytes) [ 0.127128] prandom: seed boundary self test passed [ 0.130766] prandom: 100 self tests passed [ 0.131790] regulator-dummy: no parameters [ 0.132607] regulator-dummy: no parameters [ 0.133234] regulator-dummy: Failed to create debugfs directory [ 0.134556] NET: Registered protocol family 16 [ 0.138265] cpuidle: using governor ladder [ 0.138804] cpuidle: using governor menu [ 0.140602] ACPI: bus type PCI registered [ 0.142632] PCI: PCI BIOS revision 2.10 entry at 0xfd501, last bus=0 [ 0.143297] PCI: Using configuration type 1 for base access [ 0.187356] ACPI: Added _OSI(Module Device) [ 0.187829] ACPI: Added _OSI(Processor Device) [ 0.188307] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.188816] ACPI: Added _OSI(Processor Aggregator Device) [ 0.205040] ACPI: Interpreter enabled [ 0.205537] ACPI: (supports S0 S3 S5) [ 0.205931] ACPI: Using IOAPIC for interrupt routing [ 0.206570] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.207790] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.244752] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.245439] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.246064] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.246715] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.248257] PCI host bridge to bus 0000:00 [ 0.248714] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.249482] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.250023] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.250776] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window] [ 0.251523] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.252172] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.254557] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.256729] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.260803] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 0.263366] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.264091] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.264746] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.265477] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.267232] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.268656] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.269402] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.271161] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.273358] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref] [ 0.276688] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.284377] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.286724] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.289034] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.291147] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.297488] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.300157] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 0.301432] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 0.306536] pci_bus 0000:00: on NUMA node 0 [ 0.310720] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.312044] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.313348] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.314599] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.315508] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.319936] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 0.319999] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 0.320021] pci 0000:00:02.0: vgaarb: bridge control possible [ 0.320616] vgaarb: loaded [ 0.324086] media: Linux media interface: v0.10 [ 0.324642] Linux video capture interface: v2.00 [ 0.325270] pps_core: LinuxPPS API ver. 1 registered [ 0.325790] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.326768] EDAC MC: Ver: 3.0.0 [ 0.330792] Advanced Linux Sound Architecture Driver Initialized. [ 0.331555] PCI: Using ACPI for IRQ routing [ 0.331985] PCI: pci_cache_line_size set to 32 bytes [ 0.333481] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.334127] e820: reserve RAM buffer [mem 0x13fe0000-0x13ffffff] [ 0.341509] clocksource: Switched to clocksource kvm-clock [ 0.351404] Warning: could not register annotated branches stats [ 0.474556] FS-Cache: Loaded [ 0.475094] pnp: PnP ACPI init [ 0.475835] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.476777] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.477638] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.478304] pnp 00:03: [dma 2] [ 0.478768] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.479768] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.480789] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.481717] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.483820] pnp: PnP ACPI: found 7 devices [ 0.522862] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.524187] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.524822] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.525458] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.526148] pci_bus 0000:00: resource 7 [mem 0x14000000-0xfebfffff window] [ 0.527357] NET: Registered protocol family 1 [ 0.527869] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.528509] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.529131] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.529818] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 0.530727] PCI: CLS 0 bytes, default 32 [ 0.531886] Unpacking initramfs... [ 1.982243] Freeing initrd memory: 3564K [ 1.985939] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x25641074d3b, max_idle_ns: 440795244898 ns [ 1.987691] Machine check injector initialized [ 2.000854] PCLMULQDQ-NI instructions are not detected. [ 2.001434] The force parameter has not been set to 1. The Iris poweroff handler will not be installed. [ 2.002349] NatSemi SCx200 Driver [ 2.002919] spin_lock-torture:--- Start of test [debug]: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 2.004911] spin_lock-torture: Creating torture_shuffle task [ 2.005848] spin_lock-torture: Creating torture_stutter task [ 2.005860] spin_lock-torture: torture_shuffle task started [ 2.020262] spin_lock-torture: Creating lock_torture_writer task [ 2.021154] spin_lock-torture: torture_stutter task started [ 2.021832] spin_lock-torture: Creating lock_torture_writer task [ 2.022646] spin_lock-torture: lock_torture_writer task started [ 2.023486] spin_lock-torture: Creating lock_torture_writer task [ 2.024352] spin_lock-torture: lock_torture_writer task started [ 2.025042] spin_lock-torture: Creating lock_torture_writer task [ 2.025815] spin_lock-torture: lock_torture_writer task started [ 2.026528] spin_lock-torture: Creating lock_torture_stats task [ 2.027461] spin_lock-torture: lock_torture_writer task started [ 2.028686] spin_lock-torture: lock_torture_stats task started [ 2.531239] [ 2.531439] ====================================================== [ 2.532005] WARNING: possible circular locking dependency detected [ 2.532570] 4.14.0-06692-g5bbcc0f #1 Not tainted [ 2.532979] ------------------------------------------------------ [ 2.533515] kworker/u4:1/54 is trying to acquire lock: [ 2.533967] (ww_class_mutex){+.+.}, at: [<44567940>] test_cycle_work+0xf0/0x460 [ 2.534473] [ 2.534473] but now in release context of a crosslock acquired at the following: [ 2.534473] ((completion)&cycle->b_signal){+.+.}, at: [<4539ccd5>] wait_for_completion+0x25/0x40 [ 2.534473] [ 2.534473] which lock already depends on the new lock. [ 2.534473] [ 2.536776] [ 2.536776] the existing dependency chain (in reverse order) is: [ 2.536776] [ 2.536776] -> #1 ((completion)&cycle->b_signal){+.+.}: [ 2.536776] [ 2.536776] -> #0 (ww_class_mutex){+.+.}: [ 2.536776] test_cycle_work+0xf0/0x460 [ 2.536776] [ 2.536776] other info that might help us debug this: [ 2.536776] [ 2.536776] Possible unsafe locking scenario by crosslock: [ 2.536776] [ 2.536776] CPU0 CPU1 [ 2.536776] ---- ---- [ 2.536776] lock(ww_class_mutex); [ 2.536776] lock((completion)&cycle->b_signal); [ 2.536776] lock(ww_class_mutex); [ 2.536776] unlock((completion)&cycle->b_signal); [ 2.536776] [ 2.536776] *** DEADLOCK *** [ 2.536776] [ 2.536776] 5 locks held by kworker/u4:1/54: [ 2.536776] #0: ((wq_completion)"test-ww_mutex"){+.+.}, at: [<444d5dc4>] process_one_work+0x294/0x1210 [ 2.536776] #1: ((work_completion)(&cycle->work)){+.+.}, at: [<444d5dc4>] process_one_work+0x294/0x1210 [ 2.536776] #2: (ww_class_acquire){+.+.}, at: [<444d5f2d>] process_one_work+0x3fd/0x1210 [ 2.536776] #3: (ww_class_mutex){+.+.}, at: [<44567940>] test_cycle_work+0xf0/0x460 [ 2.536776] #4: (&x->wait#7){....}, at: [<4453c625>] complete+0x25/0xa0 [ 2.536776] [ 2.536776] stack backtrace: [ 2.536776] CPU: 1 PID: 54 Comm: kworker/u4:1 Not tainted 4.14.0-06692-g5bbcc0f #1 [ 2.536776] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014 [ 2.536776] Workqueue: test-ww_mutex test_cycle_work [ 2.550112] Call Trace: [ 2.550112] ? dump_stack+0x153/0x1fa [ 2.550112] ? print_circular_bug+0x1d3/0x1f0 [ 2.550112] ? check_prev_add+0xca/0xc90 [ 2.550112] ? __lock_acquire+0x697/0x1370 [ 2.550112] ? commit_xhlock+0x330/0x5d0 [ 2.550112] ? __print_lock_name+0x130/0x130 [ 2.550112] ? lock_commit_crosslock+0x1c4/0x3c0 [ 2.550112] ? complete+0x3d/0xa0 [ 2.550112] ? test_cycle_work+0x106/0x460 [ 2.550112] ? process_one_work+0x3fd/0x1210 [ 2.550112] ? worker_thread+0x3e3/0xae0 [ 2.550112] ? kthread+0x1ac/0x220 [ 2.550112] ? rescuer_thread+0x640/0x640 [ 2.550112] ? __kthread_bind+0x50/0x50 [ 2.550112] ? ret_from_fork+0x19/0x30 [ 8.624965] torture_init_begin: Refusing rcu init: spin_lock running. [ 8.626961] torture_init_begin: One torture test at a time! [ 9.419528] Initialise system trusted keyrings [ 9.420475] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [ 9.421452] zbud: loaded [ 9.423018] fuse init (API version 7.26) [ 9.424598] orangefs_debugfs_init: called with debug mask: :none: :0: [ 9.426270] orangefs_init: module version upstream loaded [ 9.683560] Key type asymmetric registered [ 9.684050] Asymmetric key parser 'x509' registered [ 9.684534] test_string_helpers: Running tests... [ 9.691592] test_hexdump: all 1184 tests passed [ 9.692492] test_firmware: interface ready [ 9.709317] test_hash: __hash_32() has no arch implementation to test. [ 9.710168] test_hash: hash_32() has no arch implementation to test. [ 9.710885] test_hash: hash_64() has no arch implementation to test. [ 9.711448] test_hash: 33152 tests passed. [ 9.711832] test_siphash: self-tests: pass [ 9.712505] test passed [ 9.713085] test_printf: all 260 tests passed [ 9.873282] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 2370 [ 9.887781] test_bitmap: all 460506 tests passed [ 9.888366] test_uuid: all 18 tests passed [ 9.890299] crc32: CRC_LE_BITS = 8, CRC_BE BITS = 8 [ 9.890902] crc32: self tests passed, processed 225944 bytes in 671662 nsec [ 9.892303] crc32c: CRC_LE_BITS = 8 [ 9.892678] crc32c: self tests passed, processed 225944 bytes in 343976 nsec [ 9.981725] crc32_combine: 8373 self tests passed [ 10.031385] crc32c_combine: 8373 self tests passed [ 10.033537] atomic64_test: passed for i386+ platform with CX8 and with SSE [ 10.034284] glob: 64 self-tests passed, 0 failed [ 10.034896] interval tree insert/remove [ 13.065780] -> 78620 cycles [ 13.067866] interval tree search [ 14.229152] -> 301230 cycles (2692 results) [ 14.231970] gpio_it87: no device [ 14.238353] no IO addresses supplied [ 14.239605] hgafb: HGA card not detected. [ 14.240316] hgafb: probe of hgafb.0 failed with error -22 [ 14.243038] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 14.246750] ACPI: Power Button [PWRF] [ 14.248387] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 [ 14.249245] ACPI: Power Button [PWRF] [ 14.265009] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 14.293438] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 14.295863] console [ttyS0] disabled [ 14.323576] console [ttyS0] enabled [ 14.350382] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 14.380085] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 14.390376] lp: driver loaded but no devices found [ 14.391376] Non-volatile memory driver v1.3 [ 14.391912] toshiba: not a supported Toshiba laptop [ 14.394037] ppdev: user-space parallel port driver [ 14.395108] platform pc8736x_gpio.0: NatSemi pc8736x GPIO Driver Initializing [ 14.395855] platform pc8736x_gpio.0: no device found [ 14.396650] nsc_gpio initializing [ 14.397128] smapi::smapi_init, ERROR invalid usSmapiID [ 14.397697] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine [ 14.398620] mwave: mwavedd::mwave_init: Error: Failed to initialize board data [ 14.399304] mwave: mwavedd::mwave_init: Error: Failed to initialize [ 14.400311] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 14.403600] drm_mm: Testing DRM range manger (struct drm_mm), with random_seed=0x933c043a max_iterations=8192 max_prime=128 [ 14.404713] drm_mm: igt_sanitycheck - ok! [ 61.131639] drm_mm: lookup failed for node 0 + 1 [ 61.148327] [drm] Initialized vgem 1.0.0 20120112 for virtual device on minor 0 [ 61.149723] parport_pc 00:04: reported by Plug and Play ACPI [ 61.150486] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 61.230472] lp0: using parport0 (interrupt-driven). [ 61.230937] lp0: console ready [ 61.231427] console [lp0] disabled [ 61.232150] parport_pc 00:04: reported by Plug and Play ACPI [ 61.232845] parport1: PC-style at 0x378, irq 7 [PCSPP,TRISTATE,EPP] [ 61.313803] lp1: using parport1 (interrupt-driven). [ 61.316820] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti [ 61.317659] c2port c2port0: C2 port uc added [ 61.318049] c2port c2port0: uc flash has 30 blocks x 512 bytes (15360 bytes total) [ 61.320357] mtdoops: mtd device (mtddev=name/number) must be supplied [ 61.321282] L440GX flash mapping: failed to find PIIX4 ISA bridge, cannot continue [ 61.322060] scx200_docflash: NatSemi SCx200 DOCCS Flash Driver [ 61.322774] slram: not enough parameters. [ 61.386993] No valid DiskOnChip devices found [ 61.387679] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 61.388506] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 61.389329] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 61.390183] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 61.393668] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 61.394507] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 61.395409] nand: device found, Manufacturer ID: 0x98, Chip ID: 0x39 [ 61.396083] nand: Toshiba NAND 128MiB 1,8V 8-bit [ 61.396567] nand: 128 MiB, SLC, erase size: 16 KiB, page size: 512, OOB size: 16 [ 61.397545] flash size: 128 MiB [ 61.397885] page size: 512 bytes [ 61.398237] OOB area size: 16 bytes [ 61.398605] sector size: 16 KiB [ 61.398938] pages number: 262144 [ 61.399276] pages per sector: 32 [ 61.399612] bus width: 8 [ 61.399883] bits in sector size: 14 [ 61.400293] bits in page size: 9 [ 61.403542] bits in OOB size: 4 [ 61.403858] flash size with OOB: 135168 KiB [ 61.404279] page address bytes: 4 [ 61.404681] sector address bytes: 3 [ 61.405039] options: 0x42 [ 61.419205] Scanning device for bad blocks [ 61.488990] Creating 1 MTD partitions on "NAND 128MiB 1,8V 8-bit": [ 61.489678] 0x000000000000-0x000008000000 : "NAND simulator partition 0" [ 61.528666] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 61.530651] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 61.531362] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 61.543514] mousedev: PS/2 mouse device common for all mice [ 61.544340] evbug: Connected device: input1 (Power Button at LNXPWRBN/button/input0) [ 61.545691] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 [ 61.548482] mk712: device not present [ 61.551701] i2c /dev entries driver [ 61.552268] evbug: Connected device: input2 (AT Translated Set 2 keyboard at isa0060/serio0/input0) [ 61.552565] evbug: Disconnected device: input2 [ 61.553278] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 [ 61.555939] i2c-parport: adapter type unspecified [ 61.556404] i2c-parport-light: adapter type unspecified [ 61.557494] Registered IR keymap rc-empty [ 61.558031] rc rc0: rc-core loopback device as /devices/virtual/rc/rc0 [ 61.558808] input: rc-core loopback device as /devices/virtual/rc/rc0/input4 [ 61.559691] evbug: Connected device: input3 (AT Translated Set 2 keyboard at isa0060/serio0/input0) [ 61.561166] evbug: Connected device: input4 (rc-core loopback device at rc-core/virtual) [ 61.562661] sir_ir: I/O port 0x03e8, IRQ 4. [ 61.563153] rc_core: IR keymap rc-rc6-mce not found [ 61.563681] Registered IR keymap rc-empty [ 61.564199] rc rc1: SIR IrDA port as /devices/platform/sir_ir.0/rc/rc1 [ 61.565088] input: SIR IrDA port as /devices/platform/sir_ir.0/rc/rc1/input6 [ 61.566018] evbug: Connected device: input6 (SIR IrDA port at sir_ir/input0) [ 61.573802] evbug: Disconnected device: input6 [ 61.574709] sir_ir: I/O port 0x03e8, IRQ 4. [ 61.575177] rc_core: IR keymap rc-rc6-mce not found [ 61.575625] Registered IR keymap rc-empty [ 61.576125] rc rc1: SIR IrDA port as /devices/platform/sir_ir.0/rc/rc1 [ 61.576934] input: SIR IrDA port as /devices/platform/sir_ir.0/rc/rc1/input7 [ 61.577827] evbug: Connected device: input7 (SIR IrDA port at sir_ir/input0) [ 61.579840] pps pps0: new PPS source ktimer [ 61.580300] pps pps0: ktimer PPS source registered [ 61.580767] pps_ldisc: PPS line discipline registered [ 61.581265] pps_parport: parallel port PPS client [ 61.581724] parport1: cannot grant exclusive access for device pps_parport [ 61.582403] pps_parport: couldn't register with parport1 [ 61.583030] Driver for 1-wire Dallas network protocol. [ 61.583794] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko [ 61.584520] w1_f0d_init() [ 61.586499] applesmc: supported laptop not found! [ 61.587033] applesmc: driver init failed (ret=-19)! [ 61.590048] pc87360: PC8736x not detected, module not inserted [ 61.590823] sch56xx_common: Unsupported device id: 0xff [ 61.591371] sch56xx_common: Unsupported device id: 0xff [ 61.618126] advantechwdt: WDT driver for Advantech single board computer initialising [ 61.619383] advantechwdt: initialized. timeout=60 sec (nowayout=0) [ 61.620137] ib700wdt: WDT driver for IB700 single board computer initialising [ 61.621082] ib700wdt: START method I/O 443 is not available [ 61.621650] ib700wdt: probe of ib700wdt failed with error -5 [ 61.622349] wafer5823wdt: WDT driver for Wafer 5823 single board computer initialising [ 61.623137] wafer5823wdt: I/O address 0x0443 already in use [ 61.623787] it87_wdt: no device [ 61.624118] sc1200wdt: build 20020303 [ 61.624541] sc1200wdt: io parameter must be specified [ 61.625108] sbc8360: failed to register misc device [ 61.625612] sbc7240_wdt: I/O address 0x0443 already in use [ 61.626212] smsc37b787_wdt: SMsC 37B787 watchdog component driver 1.1 initialising... [ 61.628090] smsc37b787_wdt: Unable to register miscdev on minor 130 [ 61.628772] w83877f_wdt: I/O address 0x0443 already in use [ 61.629335] w83977f_wdt: driver v1.00 [ 61.629709] w83977f_wdt: cannot register miscdev on minor=130 (err=-16) [ 61.638791] machzwd: MachZ ZF-Logic Watchdog driver initializing [ 61.639465] machzwd: no ZF-Logic found [ 61.639869] sbc_epx_c3: cannot register miscdev on minor=130 (err=-16) [ 61.640970] cpufreq_nforce2: No nForce2 chipset [ 61.641564] sdhci: Secure Digital Host Controller Interface driver [ 61.642182] sdhci: Copyright(c) Pierre Ossman [ 61.661401] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 61.662206] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 61.664202] fw_cfg: probe of QEMU0002:00 failed with error -16 [ 61.692523] fake-fmc-carrier: mezzanine 0 [ 61.692983] Manufacturer: fake-vendor [ 61.693424] Product name: fake-design-for-testing [ 61.694097] fmc fake-design-for-testing-f001: Driver has no ID: matches all [ 61.694835] fmc_trivial: probe of fake-design-for-testing-f001 failed with error -95 [ 61.695642] fmc fake-design-for-testing-f001: Driver has no ID: matches all [ 61.696352] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: no busid passed, refusing all cards [ 61.751056] oprofile: using NMI timer interrupt. [ 61.751654] mce: Unable to init MCE device (rc: -5) [ 61.753498] ... APIC ID: 00000000 (0) [ 61.753839] ... APIC VERSION: 01050014 [ 61.754149] 0000000000000000000000000000000000000000000000000000000000000000 [ 61.754710] 0000000000000000000000000000000000000000000000000000000000000000 [ 61.755275] 0000000000000000000000000000000000000000000000000000000000000000 [ 61.755841] number of MP IRQ sources: 15. [ 61.756248] number of IO-APIC #0 registers: 24. [ 61.756725] testing the IO APIC....................... [ 61.757259] IO APIC #0...... [ 61.757553] .... register #00: 00000000 [ 61.757939] ....... : physical APIC id: 00 [ 61.758362] ....... : Delivery Type: 0 [ 61.758753] ....... : LTS : 0 [ 61.759158] .... register #01: 00170011 [ 61.759537] ....... : max redirection entries: 17 [ 61.760086] ....... : PRQ implemented: 0 [ 61.760511] ....... : IO APIC version: 11 [ 61.760917] .... register #02: 00000000 [ 61.761286] ....... : arbitration: 00 [ 61.761678] .... IRQ redirection table: [ 61.762065] IOAPIC 0: [ 61.762313] pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.763089] pin01, enabled , edge , high, V(24), IRR(0), S(0), logical , D(01), M(1) [ 61.763874] pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , D(01), M(1) [ 61.777848] pin03, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.778645] pin04, enabled , edge , high, V(25), IRR(0), S(0), logical , D(01), M(1) [ 61.779431] pin05, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.780248] pin06, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.781040] pin07, enabled , edge , high, V(22), IRR(0), S(0), logical , D(01), M(1) [ 61.781829] pin08, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.782644] pin09, enabled , level, high, V(21), IRR(0), S(0), logical , D(01), M(1) [ 61.783448] pin0a, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.784241] pin0b, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.785025] pin0c, enabled , edge , high, V(23), IRR(0), S(0), logical , D(01), M(1) [ 61.785788] pin0d, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.786564] pin0e, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.787367] pin0f, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.788146] pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.788916] pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.789695] pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.790480] pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.791248] pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.792018] pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.792780] pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.793561] pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, D(00), M(0) [ 61.794321] IRQ to pin mappings: [ 61.794642] IRQ0 -> 0:2 [ 61.808003] IRQ1 -> 0:1 [ 61.808298] IRQ3 -> 0:3 [ 61.808550] IRQ4 -> 0:4 [ 61.808803] IRQ5 -> 0:5 [ 61.809065] IRQ6 -> 0:6 [ 61.809318] IRQ7 -> 0:7 [ 61.809571] IRQ8 -> 0:8 [ 61.809824] IRQ9 -> 0:9 [ 61.810109] IRQ10 -> 0:10 [ 61.810367] IRQ11 -> 0:11 [ 61.810635] IRQ12 -> 0:12 [ 61.810911] IRQ13 -> 0:13 [ 61.811178] IRQ14 -> 0:14 [ 61.811449] IRQ15 -> 0:15 [ 61.811721] .................................... done. [ 61.812237] Using IPI No-Shortcut mode [ 61.812643] sched_clock: Marking stable (61810094502, 0)->(62373837392, -563742890) [ 61.814183] Loading compiled-in X.509 certificates [ 61.815268] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 61.815861] EDD information not available. [ 61.816281] ALSA device list: [ 61.816580] No soundcards found. [ 61.830383] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 61.838238] sir_ir sir_ir.0: Trapped in interrupt [ 61.839502] Freeing unused kernel memory: 1356K [ 61.840552] Write protecting the kernel text: 16084k [ 61.841424] Write protecting the kernel read-only data: 6660k [ 61.920343] sir_ir sir_ir.0: Trapped in interrupt [ 61.920883] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 61.935943] sir_ir sir_ir.0: Trapped in interrupt [ 61.936448] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 61.982800] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 61.984206] sir_ir sir_ir.0: Trapped in interrupt [ 61.986308] sir_ir sir_ir.0: Trapped in interrupt [ 62.773473] Writes: Total: 4 Max/Min: 0/0 Fail: 0 [ 62.936793] Kernel tests: Boot OK! [ 62.936793] LKP: waiting for network... [ 62.953257] sir_ir sir_ir.0: Trapped in interrupt [ 62.953802] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 62.956210] sir_ir sir_ir.0: Trapped in interrupt [ 62.956797] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 63.972187] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 63.973799] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 63.976015] sir_ir sir_ir.0: Trapped in interrupt [ 64.982275] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 64.983882] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 64.986133] sir_ir sir_ir.0: Trapped in interrupt [ 65.993237] sir_ir sir_ir.0: Trapped in interrupt [ 65.993834] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 65.996444] sir_ir sir_ir.0: Trapped in interrupt [ 65.997081] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 67.008963] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 67.010566] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 67.012731] sir_ir sir_ir.0: Trapped in interrupt [ 68.015599] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 68.017269] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 68.019402] sir_ir sir_ir.0: Trapped in interrupt [ 69.026527] sir_ir sir_ir.0: Trapped in interrupt [ 69.027083] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 69.029440] sir_ir sir_ir.0: Trapped in interrupt [ 69.033333] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 70.038915] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 70.040462] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 70.043145] sir_ir sir_ir.0: Trapped in interrupt [ 71.074874] sir_ir sir_ir.0: Trapped in interrupt [ 71.075386] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 71.078074] sir_ir sir_ir.0: Trapped in interrupt [ 71.078556] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 72.091412] sir_ir sir_ir.0: Trapped in interrupt [ 72.091910] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 72.094555] sir_ir sir_ir.0: Trapped in interrupt [ 72.095059] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 73.117980] sir_ir sir_ir.0: Trapped in interrupt [ 73.118475] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 73.133915] sir_ir sir_ir.0: Trapped in interrupt [ 73.134429] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 74.183600] sir_ir sir_ir.0: Trapped in interrupt [ 74.184147] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 74.198840] sir_ir sir_ir.0: Trapped in interrupt [ 74.199358] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 75.212634] sir_ir sir_ir.0: Trapped in interrupt [ 75.214204] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 75.218397] sir_ir sir_ir.0: Trapped in interrupt [ 75.218922] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 76.237046] sir_ir sir_ir.0: Trapped in interrupt [ 76.237553] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 76.239741] sir_ir sir_ir.0: Trapped in interrupt [ 76.240192] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 77.309753] sir_ir sir_ir.0: Trapped in interrupt [ 77.310225] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 77.312513] sir_ir sir_ir.0: Trapped in interrupt [ 77.312946] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 78.323537] sir_ir sir_ir.0: Trapped in interrupt [ 78.324012] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 78.326150] sir_ir sir_ir.0: Trapped in interrupt [ 78.326577] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 79.380002] sir_ir sir_ir.0: Trapped in interrupt [ 79.380452] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 79.382736] sir_ir sir_ir.0: Trapped in interrupt [ 79.383181] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 79.430220] serial8250: too much work for irq3 [ 80.435040] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 80.436127] sir_ir sir_ir.0: Trapped in interrupt [ 80.436904] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 80.437792] sir_ir sir_ir.0: Trapped in interrupt [ 80.439004] sir_ir sir_ir.0: Trapped in interrupt [ 81.445454] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 81.446955] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 81.448931] sir_ir sir_ir.0: Trapped in interrupt [ 82.455455] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 82.456988] genirq: Flags mismatch irq 4. 00000000 (ttyS0) vs. 00000000 (sir_ir) [ 82.459026] sir_ir sir_ir.0: Trapped in interrupt [ 82.829025] sysrq: SysRq : Emergency Sync [ 82.829536] sysrq: SysRq : Resetting Elapsed time: 80 #!/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 kvm=( qemu-system-i386 -enable-kvm -kernel $kernel -initrd initrd.img -m 320 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -serial stdio -display none -monitor null ) append=( ip=::::vm-lkp-hsw01-yocto-i386-16::dhcp root=/dev/ram0 user=lkp job=/job-script ARCH=i386 kconfig=i386-randconfig-c0-10271823 branch=linus/master commit=5bbcc0f595fadb4cac0eddc4401035ec0bd95b09 BOOT_IMAGE=/pkg/linux/i386-randconfig-c0-10271823/gcc-4.9/5bbcc0f595fadb4cac0eddc4401035ec0bd95b09/vmlinuz-4.14.0-06692-g5bbcc0f max_uptime=1500 RESULT_ROOT=/result/trinity/300s/vm-lkp-hsw01-yocto-i386/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-c0-10271823/gcc-4.9/5bbcc0f595fadb4cac0eddc4401035ec0bd95b09/0 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 ) "${kvm[@]}" -append "${append[*]}"