early console in setup code Probing EDD (edd=off to disable)... ok [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 4.0.0-rc4-01920-g5d82bf5 (kbuild@snb) (gcc version 4.9.1 (Debian 4.9.1-19) ) #278 SMP PREEMPT Wed Mar 18 03:09:32 CST 2015 [ 0.000000] Command line: user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-9/rand_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-s0-03172218-5d82bf5acb0121c54bed6b202654b626a20949f4-0-20150318-15538-w5kixz.yaml ARCH=x86_64 BOOT_IMAGE=/kernel/x86_64-randconfig-s0-03172218/5d82bf5acb0121c54bed6b202654b626a20949f4/vmlinuz-4.0.0-rc4-01920-g5d82bf5 kconfig=x86_64-randconfig-s0-03172218 commit=5d82bf5acb0121c54bed6b202654b626a20949f4 branch=peterz-queue/master root=/dev/ram0 max_uptime=3600 RESULT_ROOT=/result/vm-vp-quantal-x86_64/boot/1/quantal-core-x86_64.cgz/x86_64-randconfig-s0-03172218/5d82bf5acb0121c54bed6b202654b626a20949f4/0 ip=::::vm-vp-quantal-x86_64-9::dhcp earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw drbd.minor_cou [ 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-0x00000000167dffff] usable [ 0.000000] BIOS-e820: [mem 0x00000000167e0000-0x00000000167fffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 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 = 0x167e0 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] 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 0x000f0eb0-0x000f0ebf] mapped at [ffff8800000f0eb0] [ 0.000000] mpc: f0ec0-f0fa4 [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] [mem 0x00000000-0x000fffff] page 4k [ 0.000000] BRK [0x036bf000, 0x036bffff] PGTABLE [ 0.000000] BRK [0x036c0000, 0x036c0fff] PGTABLE [ 0.000000] BRK [0x036c1000, 0x036c1fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x14e00000-0x14ffffff] [ 0.000000] [mem 0x14e00000-0x14ffffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x00100000-0x14dfffff] [ 0.000000] [mem 0x00100000-0x001fffff] page 4k [ 0.000000] [mem 0x00200000-0x14dfffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x15000000-0x167dffff] [ 0.000000] [mem 0x15000000-0x165fffff] page 2M [ 0.000000] [mem 0x16600000-0x167dffff] page 4k [ 0.000000] BRK [0x036c2000, 0x036c2fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x15035000-0x167d7fff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F0C90 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000167E18BD 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000167E0B37 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000167E0040 000AF7 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000167E0000 000040 [ 0.000000] ACPI: SSDT 0x00000000167E0BAB 000C5A (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x00000000167E1805 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000167E1885 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff57d000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x00000000167dffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1500e000-0x15034fff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:14f8e001, primary cpu clock [ 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-0x00000000167dffff] [ 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-0x00000000167dffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x00000000167dffff] [ 0.000000] On node 0 totalpages: 92030 [ 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: 1376 pages used for memmap [ 0.000000] DMA32 zone: 88032 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff57d000 ( fee00000) [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) [ 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 ffffffffff57c000 (fec00000) [ 0.000000] e820: [mem 0x16800000-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: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff880014c00000 s79624 r0 d30968 u1048576 [ 0.000000] pcpu-alloc: s79624 r0 d30968 u1048576 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 14c0d2c0 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 90569 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-9/rand_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-s0-03172218-5d82bf5acb0121c54bed6b202654b626a20949f4-0-20150318-15538-w5kixz.yaml ARCH=x86_64 BOOT_IMAGE=/kernel/x86_64-randconfig-s0-03172218/5d82bf5acb0121c54bed6b202654b626a20949f4/vmlinuz-4.0.0-rc4-01920-g5d82bf5 kconfig=x86_64-randconfig-s0-03172218 commit=5d82bf5acb0121c54bed6b202654b626a20949f4 branch=peterz-queue/master root=/dev/ram0 max_uptime=3600 RESULT_ROOT=/result/vm-vp-quantal-x86_64/boot/1/quantal-core-x86_64.cgz/x86_64-randconfig-s0-03172218/5d82bf5acb0121c54bed6b202654b626a20949f4/0 ip=::::vm-vp-quantal-x86_64-9::dhcp earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal rw drbd.mi [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Memory: 296488K/368120K available (14041K kernel code, 2213K rwdata, 4544K rodata, 1116K init, 15820K bss, 71632K reserved, 0K cma-reserved) [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 [ 0.000000] Running RCU self tests [ 0.000000] NR_IRQS:524544 nr_irqs:440 16 [ 0.000000] console [ttyS0] enabled [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.000000] ... CHAINHASH_SIZE: 32768 [ 0.000000] memory used by lock dependency info: 8159 kB [ 0.000000] per task-struct memory footprint: 1920 bytes [ 0.000000] ------------------------ [ 0.000000] | Locking API testsuite: [ 0.000000] ---------------------------------------------------------------------------- [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] A-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] double unlock: ok | ok | ok | ok | ok | ok | [ 0.000000] initialize held: ok | ok | ok | ok | ok | ok | [ 0.000000] bad unlock order: ok | ok | ok | ok | ok | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] recursive read-lock: | ok | | ok | [ 0.000000] recursive read-lock #2: | ok | | ok | [ 0.000000] mixed read-write-lock: | ok | | ok | [ 0.000000] mixed write-read-lock: | ok | | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.000000] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.000000] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.000000] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.000000] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.000000] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.000000] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.000000] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.000000] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.000000] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/123: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/123: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/132: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/132: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/213: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/213: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/231: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/231: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/312: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/312: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/321: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/321: ok | ok | ok | [ 0.000000] hard-irq read-recursion/123: ok | [ 0.000000] soft-irq read-recursion/123: ok | [ 0.000000] hard-irq read-recursion/132: ok | [ 0.000000] soft-irq read-recursion/132: ok | [ 0.000000] hard-irq read-recursion/213: ok | [ 0.000000] soft-irq read-recursion/213: ok | [ 0.000000] hard-irq read-recursion/231: ok | [ 0.000000] soft-irq read-recursion/231: ok | [ 0.000000] hard-irq read-recursion/312: ok | [ 0.000000] soft-irq read-recursion/312: ok | [ 0.000000] hard-irq read-recursion/321: ok | [ 0.000000] soft-irq read-recursion/321: ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] | Wound/wait tests | [ 0.000000] --------------------- [ 0.000000] ww api failures: ok | ok | ok | [ 0.000000] ww contexts mixing: ok | ok | [ 0.000000] finishing ww context: ok | ok | ok | ok | [ 0.000000] locking mismatches: ok | ok | ok | [ 0.000000] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.000000] spinlock nest unlocked: ok | [ 0.000000] ----------------------------------------------------- [ 0.000000] |block | try |context| [ 0.000000] ----------------------------------------------------- [ 0.000000] context: ok | ok | ok | [ 0.000000] try: ok | ok | ok | [ 0.000000] block: ok | ok | ok | [ 0.000000] spinlock: ok | ok | ok | [ 0.000000] ------------------------------------------------------- [ 0.000000] Good, all 253 testcases passed! | [ 0.000000] --------------------------------- [ 0.000000] ODEBUG: selftest passed [ 0.000000] clocksource hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2693.508 MHz processor [ 0.002000] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS (lpj=2693508) [ 0.002253] pid_max: default: 32768 minimum: 301 [ 0.002631] ACPI: Core revision 20150204 [ 0.006761] ACPI: All ACPI Tables successfully acquired [ 0.007166] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.008100] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.008708] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.009007] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.010277] Initializing cgroup subsys memory [ 0.010681] Initializing cgroup subsys freezer [ 0.011017] Initializing cgroup subsys net_prio [ 0.011423] Initializing cgroup subsys debug [ 0.011857] mce: CPU supports 10 MCE banks [ 0.012042] numa_add_cpu cpu 0 node 0: mask now 0 [ 0.012457] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.013004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.013708] Freeing SMP alternatives memory: 40K (ffffffff82742000 - ffffffff8274c000) [ 0.017421] Getting VERSION: 1050014 [ 0.017745] Getting VERSION: 1050014 [ 0.018008] Getting ID: 0 [ 0.018251] Getting ID: ff000000 [ 0.018548] Getting LVT0: 8700 [ 0.018819] Getting LVT1: 8400 [ 0.019045] enabled ExtINT on CPU#0 [ 0.020194] ENABLING IO-APIC IRQs [ 0.020503] init IO_APIC IRQs [ 0.020766] apic 0 pin 0 not connected [ 0.021029] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.021745] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.022021] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.023020] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.024008] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.024709] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.025020] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.026020] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.026719] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.027020] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.028020] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.029021] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.029740] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.030020] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.031020] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.031740] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.032017] apic 0 pin 16 not connected [ 0.033003] apic 0 pin 17 not connected [ 0.033349] apic 0 pin 18 not connected [ 0.033687] apic 0 pin 19 not connected [ 0.034003] apic 0 pin 20 not connected [ 0.034352] apic 0 pin 21 not connected [ 0.034692] apic 0 pin 22 not connected [ 0.035003] apic 0 pin 23 not connected [ 0.035497] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.036004] Using local APIC timer interrupts. [ 0.036004] calibrating APIC timer ... [ 0.038000] ... lapic delta = 6250109 [ 0.038000] ... PM-Timer delta = 357939 [ 0.038000] ... PM-Timer result ok [ 0.038000] ..... delta 6250109 [ 0.038000] ..... mult: 268440137 [ 0.038000] ..... calibration result: 1000017 [ 0.038000] ..... CPU clock speed is 2693.0398 MHz. [ 0.038000] ..... host bus clock speed is 1000.0017 MHz. [ 0.038037] smpboot: CPU0: Intel QEMU Virtual CPU version 2.1.2 (fam: 06, model: 06, stepping: 03) [ 0.039021] Performance Events: Broken PMU hardware detected, using software events only. [ 0.041003] Failed to access perfctr msr (MSR c1 is 0) [ 0.043211] ------------[ cut here ]------------ [ 0.043778] WARNING: CPU: 0 PID: 1 at kernel/locking/lockdep.c:3538 check_flags+0x22d/0x240() [ 0.044000] DEBUG_LOCKS_WARN_ON(current->hardirqs_enabled) [ 0.044000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.0.0-rc4-01920-g5d82bf5 #278 [ 0.044000] ffffffff82136e10 ffff88001495bb88 ffffffff81da3073 000000000000002e [ 0.044000] ffff88001495bbd8 ffff88001495bbc8 ffffffff810d1e0b 0000000000000286 [ 0.044000] ffffffff82441420 0000000000000046 ffffffff827c53f1 ffff88001496e850 [ 0.044000] Call Trace: [ 0.044000] [] dump_stack+0x4f/0x7b [ 0.044000] [] warn_slowpath_common+0x7b/0xc0 [ 0.044000] [] warn_slowpath_fmt+0x41/0x50 [ 0.044000] [] ? get_parent_ip+0x11/0x50 [ 0.044000] [] check_flags+0x22d/0x240 [ 0.044000] [] lock_is_held+0x36/0x90 [ 0.044000] [] rcu_read_lock_held+0x65/0x70 [ 0.044000] [] lockdep_init_map+0x336/0x6f0 [ 0.044000] [] alloc_unbound_pwq+0x26f/0x4d0 [ 0.044000] [] apply_workqueue_attrs+0x154/0x490 [ 0.044000] [] __alloc_workqueue_key+0x1c4/0x610 [ 0.044000] [] ? alloc_cpumask_var_node+0x48/0x80 [ 0.044000] [] init_workqueues+0x414/0x528 [ 0.044000] [] ? wq_sysfs_init+0x14/0x14 [ 0.044000] [] ? wq_sysfs_init+0x14/0x14 [ 0.044000] [] do_one_initcall+0xe8/0x17f [ 0.044000] [] ? native_smp_prepare_cpus+0x2c0/0x2cf [ 0.044000] [] kernel_init_freeable+0x74/0x1b0 [ 0.044000] [] ? rest_init+0x140/0x140 [ 0.044000] [] kernel_init+0x9/0xf0 [ 0.044000] [] ret_from_fork+0x53/0x90 [ 0.044000] [] ? rest_init+0x140/0x140 [ 0.044000] ---[ end trace c79ceaf7cc379335 ]--- [ 0.044000] possible reason: unannotated irqs-off. [ 0.044000] irq event stamp: 1485 [ 0.044000] hardirqs last enabled at (1485): [] _raw_spin_unlock_irqrestore+0x5d/0x80 [ 0.044000] hardirqs last disabled at (1484): [] _raw_spin_lock_irqsave+0x24/0x70 [ 0.044000] softirqs last enabled at (908): [] __do_softirq+0x24c/0x300 [ 0.044000] softirqs last disabled at (903): [] irq_exit+0xd5/0x110 [ 0.051196] x86: Booting SMP configuration: [ 0.051691] .... node #0, CPUs: #1 [ 0.049060] kvm-clock: cpu 1, msr 0:14f8e041, secondary cpu clock [ 0.049060] masked ExtINT on CPU#1 [ 0.049060] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 0.065029] x86: Booted up 1 node, 2 CPUs [ 0.065017] KVM setup async PF for cpu 1 [ 0.065019] kvm-stealtime: cpu 1, msr 14d0d2c0 [ 0.066703] ---------------- [ 0.067002] | NMI testsuite: [ 0.067247] -------------------- [ 0.067521] remote IPI: ok | [ 0.070120] local IPI: ok | [ 0.076015] -------------------- [ 0.076305] Good, all 2 testcases passed! | [ 0.076679] --------------------------------- [ 0.077003] smpboot: Total of 2 processors activated (10774.03 BogoMIPS) [ 0.078079] devtmpfs: initialized [ 0.080854] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.081081] prandom: seed boundary self test passed [ 0.082442] prandom: 100 self tests passed [ 0.083006] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 0.083682] RTC time: 3:13:32, date: 03/18/15 [ 0.084141] NET: Registered protocol family 16 [ 0.088016] cpuidle: using governor ladder [ 0.091016] cpuidle: using governor menu [ 0.091629] ACPI: bus type PCI registered [ 0.092041] PCI: Using configuration type 1 for base access [ 0.099115] ACPI: Added _OSI(Module Device) [ 0.099449] ACPI: Added _OSI(Processor Device) [ 0.100003] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.100406] ACPI: Added _OSI(Processor Aggregator Device) [ 0.101705] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:3) [ 0.104113] ACPI: Interpreter enabled [ 0.104429] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20150204/hwxface-580) [ 0.105195] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150204/hwxface-580) [ 0.106011] ACPI: (supports S0 S3 S5) [ 0.106322] ACPI: Using IOAPIC for interrupt routing [ 0.106764] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.112844] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.113006] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.113470] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.114156] PCI host bridge to bus 0000:00 [ 0.114508] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.115004] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.115568] pci_bus 0000:00: root bus resource [io 0x0d00-0xadff window] [ 0.116003] pci_bus 0000:00: root bus resource [io 0xae0f-0xaeff window] [ 0.117003] pci_bus 0000:00: root bus resource [io 0xaf20-0xafdf window] [ 0.117568] pci_bus 0000:00: root bus resource [io 0xafe4-0xffff window] [ 0.118003] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.118626] pci_bus 0000:00: root bus resource [mem 0x16800000-0xfebfffff window] [ 0.119030] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.120356] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.121334] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.123961] pci 0000:00:01.1: reg 0x20: [io 0xc040-0xc04f] [ 0.125218] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.125815] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.126003] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.126602] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.127221] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.128271] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.129010] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.129864] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 0.131009] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref] [ 0.132403] pci 0000:00:02.0: reg 0x14: [mem 0xfebf0000-0xfebf0fff] [ 0.137007] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.137821] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.139004] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.140356] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.144360] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.145241] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000 [ 0.146004] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f] [ 0.149760] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.150280] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.151094] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.151745] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.152208] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.153293] ACPI: Enabled 16 GPEs in block 00 to 0F [ 0.153897] vgaarb: setting as boot device: PCI:0000:00:02.0 [ 0.153897] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.154005] vgaarb: loaded [ 0.154238] vgaarb: bridge control possible 0000:00:02.0 [ 0.155180] media: Linux media interface: v0.10 [ 0.155591] Linux video capture interface: v2.00 [ 0.156009] pps_core: LinuxPPS API ver. 1 registered [ 0.156423] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.157104] Advanced Linux Sound Architecture Driver Initialized. [ 0.157620] PCI: Using ACPI for IRQ routing [ 0.158003] PCI: pci_cache_line_size set to 64 bytes [ 0.158493] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.159003] e820: reserve RAM buffer [mem 0x167e0000-0x17ffffff] [ 0.159767] Bluetooth: Core ver 2.20 [ 0.160017] NET: Registered protocol family 31 [ 0.160397] Bluetooth: HCI device and connection manager initialized [ 0.161003] Bluetooth: HCI socket layer initialized [ 0.161415] Bluetooth: L2CAP socket layer initialized [ 0.161843] Bluetooth: SCO socket layer initialized [ 0.162176] cfg80211: Calling CRDA to update world regulatory domain [ 0.163053] nfc: nfc_init: NFC Core ver 0.1 [ 0.163435] NET: Registered protocol family 39 [ 0.164250] Switched to clocksource kvm-clock [ 0.164696] FS-Cache: Loaded [ 0.164978] pnp: PnP ACPI init [ 0.165074] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3) [ 0.165782] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.166371] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3) [ 0.167113] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.167705] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3) [ 0.168478] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.169088] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3) [ 0.169764] pnp 00:03: [dma 2] [ 0.170069] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.170660] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3) [ 0.171396] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.171995] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3) [ 0.172729] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.173648] pnp: PnP ACPI: found 6 devices [ 0.179687] clocksource acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.180485] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.181000] pci_bus 0000:00: resource 5 [io 0x0d00-0xadff window] [ 0.181528] pci_bus 0000:00: resource 6 [io 0xae0f-0xaeff window] [ 0.182051] pci_bus 0000:00: resource 7 [io 0xaf20-0xafdf window] [ 0.182567] pci_bus 0000:00: resource 8 [io 0xafe4-0xffff window] [ 0.183087] pci_bus 0000:00: resource 9 [mem 0x000a0000-0x000bffff window] [ 0.183660] pci_bus 0000:00: resource 10 [mem 0x16800000-0xfebfffff window] [ 0.184257] NET: Registered protocol family 2 [ 0.184737] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 0.185372] TCP bind hash table entries: 4096 (order: 6, 262144 bytes) [ 0.185997] TCP: Hash tables configured (established 4096 bind 4096) [ 0.186956] TCP: reno registered [ 0.187254] UDP hash table entries: 256 (order: 3, 40960 bytes) [ 0.187757] UDP-Lite hash table entries: 256 (order: 3, 40960 bytes) [ 0.188358] NET: Registered protocol family 1 [ 0.188862] RPC: Registered named UNIX socket transport module. [ 0.189377] RPC: Registered udp transport module. [ 0.189771] RPC: Registered tcp transport module. [ 0.190170] RPC: Registered tcp NFSv4.1 backchannel transport module