[ 0.000000] Linux version 3.12.0-00784-gf0e07fb (kbuild@cairo) (gcc version 4.8.1 (Debian 4.8.1-8) ) #314 PREEMPT Thu Nov 7 05:52:48 CST 2013 [ 0.000000] Command line: hung_task_panic=1 rcutree.rcu_cpu_stall_timeout=100 log_buf_len=8M ignore_loglevel debug sched_debug apic=debug dynamic_printk sysrq_always_enabled panic=10 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kernel-tests/run-queue/kvm/x86_64-randconfig-c9-1107/devel-cairo-x86_64-201311070512/.vmlinuz-f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c-20131107055743-8-bens branch=linux-devel/devel-cairo-x86_64-201311070512 BOOT_IMAGE=/kernel/x86_64-randconfig-c9-1107/f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c/vmlinuz-3.12.0-00784-gf0e07fb [ 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-0x000000000fffdfff] usable [ 0.000000] BIOS-e820: [mem 0x000000000fffe000-0x000000000fffffff] 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] SMBIOS 2.4 present. [ 0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2011 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] No AGP bridge found [ 0.000000] e820: last_pfn = 0xfffe 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 enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] Scanning 1 areas for low memory corruption [ 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 [0x0334e000, 0x0334efff] PGTABLE [ 0.000000] BRK [0x0334f000, 0x0334ffff] PGTABLE [ 0.000000] BRK [0x03350000, 0x03350fff] PGTABLE [ 0.000000] init_memory_mapping: [mem 0x0e600000-0x0e7fffff] [ 0.000000] [mem 0x0e600000-0x0e7fffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x0c000000-0x0e5fffff] [ 0.000000] [mem 0x0c000000-0x0e5fffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x00100000-0x0bffffff] [ 0.000000] [mem 0x00100000-0x001fffff] page 4k [ 0.000000] [mem 0x00200000-0x0bffffff] page 2M [ 0.000000] init_memory_mapping: [mem 0x0e800000-0x0fffdfff] [ 0.000000] [mem 0x0e800000-0x0fdfffff] page 2M [ 0.000000] [mem 0x0fe00000-0x0fffdfff] page 4k [ 0.000000] BRK [0x03351000, 0x03351fff] PGTABLE [ 0.000000] log_buf_len: 8388608 [ 0.000000] early log buf free: 127596(97%) [ 0.000000] RAMDISK: [mem 0x0e8d6000-0x0ffeffff] [ 0.000000] ACPI: RSDP 00000000000fd930 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000000fffe450 00034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000000fffff80 00074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000000fffe490 011A9 (v01 BXPC BXDSDT 00000001 INTL 20100528) [ 0.000000] ACPI: FACS 000000000fffff40 00040 [ 0.000000] ACPI: SSDT 000000000ffff7a0 00796 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 000000000ffff680 00080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 000000000ffff640 00038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5f9000 ( fee00000) [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:fffd001, boot clock [ 0.000000] Zone ranges: [ 0.000000] DMA32 [mem 0x00001000-0xffffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x00001000-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0x0fffdfff] [ 0.000000] On node 0 totalpages: 65436 [ 0.000000] DMA32 zone: 1024 pages used for memmap [ 0.000000] DMA32 zone: 21 pages reserved [ 0.000000] DMA32 zone: 65436 pages, LIFO batch:15 [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5f9000 ( 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: NR_CPUS/possible_cpus limit of 1 reached. Processor 1/0x1 ignored. [ 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] ACPI: IRQ2 used by override. [ 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] mapped IOAPIC to ffffffffff5f8000 (fec00000) [ 0.000000] nr_irqs_gsi: 40 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 2433e00 [ 0.000000] e820: [mem 0x10000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64391 [ 0.000000] Kernel command line: hung_task_panic=1 rcutree.rcu_cpu_stall_timeout=100 log_buf_len=8M ignore_loglevel debug sched_debug apic=debug dynamic_printk sysrq_always_enabled panic=10 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kernel-tests/run-queue/kvm/x86_64-randconfig-c9-1107/devel-cairo-x86_64-201311070512/.vmlinuz-f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c-20131107055743-8-bens branch=linux-devel/devel-cairo-x86_64-201311070512 BOOT_IMAGE=/kernel/x86_64-randconfig-c9-1107/f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c/vmlinuz-3.12.0-00784-gf0e07fb [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 1024 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 16384 (order: 5, 131072 bytes) [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 188740K/261744K available (12518K kernel code, 3593K rwdata, 5128K rodata, 1056K init, 11012K bss, 73004K reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] NR_IRQS:4352 nr_irqs:256 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: 16384 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768 [ 0.000000] ... CHAINHASH_SIZE: 16384 [ 0.000000] memory used by lock dependency info: 6367 kB [ 0.000000] per task-struct memory footprint: 2688 bytes [ 0.000000] ODEBUG: 9 of 9 active objects replaced [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2666.772 MHz processor [ 0.020000] Calibrating delay loop (skipped) preset value.. 5333.54 BogoMIPS (lpj=26667720) [ 0.020000] pid_max: default: 32768 minimum: 301 [ 0.020157] Security Framework initialized [ 0.020984] Mount-cache hash table entries: 256 [ 0.022182] [ 0.022487] ============================================= [ 0.026992] [ INFO: possible recursive locking detected ] [ 0.027977] 3.12.0-00784-gf0e07fb #314 Not tainted [ 0.028845] --------------------------------------------- [ 0.030000] swapper/0 is trying to acquire lock: [ 0.030000] (&sb->s_type->i_lock_key){+.+...}, at: [] d_flags_for_inode+0x3c/0xd0 [ 0.030000] [ 0.030000] but task is already holding lock: [ 0.030000] (&sb->s_type->i_lock_key){+.+...}, at: [] d_instantiate+0x34/0x70 [ 0.030000] [ 0.030000] other info that might help us debug this: [ 0.030000] Possible unsafe locking scenario: [ 0.030000] [ 0.030000] CPU0 [ 0.030000] ---- [ 0.030000] lock(&sb->s_type->i_lock_key); [ 0.030000] lock(&sb->s_type->i_lock_key); [ 0.030000] [ 0.030000] *** DEADLOCK *** [ 0.030000] [ 0.030000] May be due to missing lock nesting notation [ 0.030000] [ 0.030000] 2 locks held by swapper/0: [ 0.030000] #0: (&type->s_umount_key/1){+.+...}, at: [] sget+0x266/0x440 [ 0.030000] #1: (&sb->s_type->i_lock_key){+.+...}, at: [] d_instantiate+0x34/0x70 [ 0.030000] [ 0.030000] stack backtrace: [ 0.030000] CPU: 0 PID: 0 Comm: swapper Not tainted 3.12.0-00784-gf0e07fb #314 [ 0.030000] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 0.030000] ffffffff82c998d0 ffffffff82401c18 ffffffff81c21540 ffffffff82401ce0 [ 0.030000] ffffffff81111fb7 ffffffff824134c0 0000000000000000 ffffffff82401c50 [ 0.030000] ffffffff82413af8 ffffffff00000000 00000000c406e037 ffffffff824134c0 [ 0.030000] Call Trace: [ 0.030000] [] dump_stack+0x19/0x1b [ 0.030000] [] __lock_acquire+0x12d7/0x1d60 [ 0.030000] [] ? __lock_acquire+0x1cec/0x1d60 [ 0.030000] [] ? __slab_alloc.constprop.61+0x3dc/0x3fa [ 0.030000] [] lock_acquire+0x19d/0x220 [ 0.030000] [] ? d_flags_for_inode+0x3c/0xd0 [ 0.030000] [] _raw_spin_lock+0x3b/0x70 [ 0.030000] [] ? d_flags_for_inode+0x3c/0xd0 [ 0.030000] [] d_flags_for_inode+0x3c/0xd0 [ 0.030000] [] __d_instantiate+0x1d/0xf0 [ 0.030000] [] d_instantiate+0x3f/0x70 [ 0.030000] [] d_make_root+0x32/0x60 [ 0.030000] [] sysfs_mount+0x178/0x220 [ 0.030000] [] mount_fs+0x73/0x190 [ 0.030000] [] vfs_kern_mount+0x65/0xf0 [ 0.030000] [] kern_mount_data+0x14/0x30 [ 0.030000] [] sysfs_init+0x54/0xb3 [ 0.030000] [] mnt_init+0xcd/0x1d6 [ 0.030000] [] vfs_caches_init+0x90/0x10e [ 0.030000] [] ? integrity_iintcache_init+0x23/0x38 [ 0.030000] [] ? security_init+0x35/0x47 [ 0.030000] [] start_kernel+0x456/0x49e [ 0.030000] [] ? repair_env_string+0x5e/0x5e [ 0.030000] [] ? early_idt_handlers+0x120/0x120 [ 0.030000] [] x86_64_start_reservations+0x2a/0x2c [ 0.030000] [] x86_64_start_kernel+0xf3/0x102 [ 298.715031] BUG: spinlock lockup suspected on CPU#0, swapper/0 [ 298.716133] lock: 0xffff88000dc02078, .magic: dead4ead, .owner: swapper/0, .owner_cpu: 0 [ 298.717583] CPU: 0 PID: 0 Comm: swapper Not tainted 3.12.0-00784-gf0e07fb #314 [ 298.718850] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 298.719900] ffff88000dc02078 ffffffff82401d00 ffffffff81c21540 ffffffff82401d20 [ 298.720912] ffffffff81c215c7 ffff88000dc02078 000000009ef3b620 ffffffff82401d48 [ 298.722347] ffffffff812bf668 ffff88000dc02078 ffff88000dc02090 ffff88000dc04058 [ 298.723770] Call Trace: [ 298.724225] [] dump_stack+0x19/0x1b [ 298.725130] [] spin_dump+0x85/0x8a [ 298.726090] [] do_raw_spin_lock+0x88/0xf0 [ 298.727080] [] _raw_spin_lock+0x5b/0x70 [ 298.728081] [] ? d_flags_for_inode+0x3c/0xd0 [ 298.729158] [] d_flags_for_inode+0x3c/0xd0 [ 298.730008] [] __d_instantiate+0x1d/0xf0 [ 298.730986] [] d_instantiate+0x3f/0x70 [ 298.732013] [] d_make_root+0x32/0x60 [ 298.732933] [] sysfs_mount+0x178/0x220 [ 298.733886] [] mount_fs+0x73/0x190 [ 298.734776] [] vfs_kern_mount+0x65/0xf0 [ 298.735818] [] kern_mount_data+0x14/0x30 [ 298.736792] [] sysfs_init+0x54/0xb3 [ 298.737697] [] mnt_init+0xcd/0x1d6 [ 298.738633] [] vfs_caches_init+0x90/0x10e [ 298.740010] [] ? integrity_iintcache_init+0x23/0x38 [ 298.741160] [] ? security_init+0x35/0x47 [ 298.742181] [] start_kernel+0x456/0x49e [ 298.743149] [] ? repair_env_string+0x5e/0x5e [ 298.744223] [] ? early_idt_handlers+0x120/0x120 [ 298.745345] [] x86_64_start_reservations+0x2a/0x2c [ 298.746471] [] x86_64_start_kernel+0xf3/0x102 Elapsed time: 305 qemu-system-x86_64 -cpu kvm64 -enable-kvm -kernel /kernel/x86_64-randconfig-c9-1107/f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c/vmlinuz-3.12.0-00784-gf0e07fb -append 'hung_task_panic=1 rcutree.rcu_cpu_stall_timeout=100 log_buf_len=8M ignore_loglevel debug sched_debug apic=debug dynamic_printk sysrq_always_enabled panic=10 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=/kernel-tests/run-queue/kvm/x86_64-randconfig-c9-1107/devel-cairo-x86_64-201311070512/.vmlinuz-f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c-20131107055743-8-bens branch=linux-devel/devel-cairo-x86_64-201311070512 BOOT_IMAGE=/kernel/x86_64-randconfig-c9-1107/f0e07fb6dd3d533b6ea2d91e721b68d696d4e64c/vmlinuz-3.12.0-00784-gf0e07fb' -initrd /kernel-tests/initrd/quantal-core-x86_64.cgz -m 256M -smp 2 -net nic,vlan=1,model=e1000 -net user,vlan=1 -boot order=nc -no-reboot -watchdog i6300esb -drive file=/fs/sda7/disk0-quantal-bens-2,media=disk,if=virtio -drive file=/fs/sda7/disk1-quantal-bens-2,media=disk,if=virtio -drive file=/fs/sda7/disk2-quantal-bens-2,media=disk,if=virtio -drive file=/fs/sda7/disk3-quantal-bens-2,media=disk,if=virtio -drive file=/fs/sda7/disk4-quantal-bens-2,media=disk,if=virtio -drive file=/fs/sda7/disk5-quantal-bens-2,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-quantal-bens-2 -serial file:/dev/shm/kboot/serial-quantal-bens-2 -daemonize -display none -monitor null