boot: next Please wait, loading kernel... Elf64 kernel loaded... Loading ramdisk... ramdisk loaded at 03500000, size: 2379 Kbytes OF stdout device is: /vdevice/vty@30000000 Preparing to boot Linux version 2.6.29-next-20090325 (root@mjs22lp5) (gcc versio n 4.1.2 20080704 (Red Hat 4.1.2-44)) #1 SMP Wed Mar 25 14:36:40 IST 2009 Hypertas detected, assuming LPAR ! command line: ro selinux=0 console=hvc0 root=LABEL=/ memory layout at init: alloc_bottom : 0000000003760000 alloc_top : 0000000008000000 alloc_top_hi : 0000000008000000 rmo_top : 0000000008000000 ram_top : 0000000008000000 instantiating rtas at 0x0000000007630000 ... done boot cpu hw idx 0000000000000000 starting cpu hw idx 0000000000000002... done copying OF device tree ... Building dt strings... Building dt structure... Device tree strings 0x0000000003970000 -> 0x000000000397155a Device tree struct 0x0000000003980000 -> 0x0000000003990000 Calling quiesce ... returning from prom_init Using pSeries machine description Using 1TB segments Found initrd at 0xc000000003500000:0xc000000003752c00 console [udbg0] enabled Partition configured for 8 cpus. CPU maps initialized for 2 threads per core Starting Linux PPC64 #1 SMP Wed Mar 25 14:36:40 IST 2009 ----------------------------------------------------- ppc64_pft_size = 0x19 physicalMemorySize = 0x80000000 htab_hash_mask = 0x3ffff ----------------------------------------------------- Linux version 2.6.29-next-20090325 (root@mjs22lp5) (gcc version 4.1.2 20080704 ( Red Hat 4.1.2-44)) #1 SMP Wed Mar 25 14:36:40 IST 2009 [boot]0012 Setup Arch EEH: No capable adapters found PPC64 nvram contains 15360 bytes Zone PFN ranges: DMA 0x00000000 -> 0x00008000 Normal 0x00008000 -> 0x00008000 Movable zone start PFN for each node early_node_map[3] active PFN ranges 1: 0x00000000 -> 0x00000800 0: 0x00000800 -> 0x00004600 1: 0x00004600 -> 0x00008000 [boot]0015 Setup Done Built 2 zonelists in Node order, mobility grouping on. Total pages: 32702 Policy zone: DMA Kernel command line: ro selinux=0 console=hvc0 root=LABEL=/ Experimental hierarchical RCU init done. NR_IRQS:512 [boot]0020 XICS Init [boot]0021 XICS Done PID hash table entries: 4096 (order: 12, 32768 bytes) clocksource: timebase mult[7d0000] shift[22] registered Console: colour dummy device 80x25 console handover: boot [udbg0] -> real [hvc0] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar ... MAX_LOCKDEP_SUBCLASSES: 8 ... MAX_LOCK_DEPTH: 48 ... MAX_LOCKDEP_KEYS: 8191 ... CLASSHASH_SIZE: 4096 ... MAX_LOCKDEP_ENTRIES: 8192 ... MAX_LOCKDEP_CHAINS: 16384 ... CHAINHASH_SIZE: 8192 memory used by lock dependency info: 4607 kB per task-struct memory footprint: 1920 bytes Dentry cache hash table entries: 262144 (order: 5, 2097152 bytes) Inode-cache hash table entries: 131072 (order: 4, 1048576 bytes) freeing bootmem node 0 freeing bootmem node 1 Memory: 2044096k/2097152k available (8320k kernel code, 54272k reserved, 1152k d ata, 8981k bss, 448k init) SLUB: Genslabs=18, HWalign=128, Order=0-3, MinObjects=0, CPUs=8, Nodes=16 ODEBUG: selftest passed Calibrating delay loop... 1021.95 BogoMIPS (lpj=510976) Security Framework initialized SELinux: Disabled at boot. Mount-cache hash table entries: 4096 Processor 1 found. Processor 2 found. Processor 3 found. Brought up 4 CPUs khelper used greatest stack depth: 10464 bytes left net_namespace: 2008 bytes NET: Registered protocol family 16 IBM eBus Device Driver PCI: Probing PCI hardware bio: create slab at 0 SCSI subsystem initialized usbcore: registered new interface driver usbfs usbcore: registered new interface driver hub usbcore: registered new device driver usb NetLabel: Initializing NetLabel: domain hash size = 128 NetLabel: protocols = UNLABELED CIPSOv4 NetLabel: unlabeled traffic allowed by default NET: Registered protocol family 2 IP route cache hash table entries: 16384 (order: 1, 131072 bytes) TCP established hash table entries: 65536 (order: 4, 1048576 bytes) TCP bind hash table entries: 65536 (order: 5, 3670016 bytes) TCP: Hash tables configured (established 65536 bind 65536) TCP reno registered NET: Registered protocol family 1 checking if image is initramfs... it is Freeing initrd memory: 2379k freed IOMMU table initialized, virtual merging enabled ====[ backtrace testing ]=========== Testing a backtrace from process context. The following trace is a kernel self test and not a bug! Call Trace: [c000000044103c60] [c0000000000115f4] .show_stack+0x70/0x184 (unreliable) [c000000044103d10] [c0000000000a4ae8] .backtrace_regression_test+0x54/0x148 [c000000044103e10] [c0000000000092e4] .do_one_initcall+0x90/0x1b0 [c000000044103f00] [c0000000007b0c0c] .kernel_init+0x19c/0x218 [c000000044103f90] [c00000000002bd9c] .kernel_thread+0x54/0x70 The following trace is a kernel self test and not a bug! Call Trace: [c00000000ffefd10] [c0000000000115f4] .show_stack+0x70/0x184 (unreliable) [c00000000ffefdc0] [c0000000000a4a70] .backtrace_test_irq_callback+0x18/0x3c [c00000000ffefe40] [c00000000006fd50] .tasklet_action+0xf4/0x1d4 [c00000000ffefee0] [c00000000006f824] .__do_softirq+0x120/0x298 [c00000000ffeff90] [c00000000002ba94] .call_do_softirq+0x14/0x24 [c000000044303dc0] [c00000000000d674] .do_softirq+0x94/0x114 [c000000044303e60] [c00000000006fb60] .ksoftirqd+0x98/0x194 [c000000044303f00] [c0000000000853cc] .kthread+0x80/0xcc [c000000044303f90] [c00000000002bd9c] .kernel_thread+0x54/0x70 Testing a saved backtrace. The following trace is a kernel self test and not a bug! [] .backtrace_regression_test+0x100/0x148 [] .do_one_initcall+0x90/0x1b0 [] .kernel_init+0x19c/0x218 [] .kernel_thread+0x54/0x70 ====[ end of backtrace testing ]==== audit: initializing netlink socket (disabled) type=2000 audit(1237983125.312:1): initialized Kprobe smoke test started Kprobe smoke test passed successfully rcu-torture:--- Start of test: nreaders=8 nfakewriters=4 stat_interval=0 verbose =0 test_no_idle_hz=0 shuffle_interval=3 stutter=5 irqreader=1 HugeTLB registered 16 GB page size, pre-allocated 0 pages VFS: Disk quotas dquot_6.5.2 Dquot-cache hash table entries: 8192 (order 0, 65536 bytes) Btrfs loaded msgmni has been set to 3996 alg: No test for stdrng (krng) io scheduler noop registered io scheduler anticipatory registered io scheduler deadline registered io scheduler cfq registered (default) pci_hotplug: PCI Hot Plug PCI Core version: 0.5 Linux agpgart interface v0.103 Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled brd: module loaded Uniform Multi-Platform E-IDE driver ibmvscsi 30000002: SRP_VERSION: 16.a scsi0 : IBM POWER Virtual SCSI Adapter 1.5.8 ibmvscsi 30000002: partner initialization complete ibmvscsi 30000002: sent SRP login ibmvscsi 30000002: SRP_LOGIN succeeded ibmvscsi 30000002: host srp version: 16.a, host partition 06-1C12A (1), OS 3, ma x io 262144 ?: timing out command, waited 22s scsi0 : IBM POWER Virtual SCSI Adapter 1.5.8 ibmvscsi 30000002: partner initialization complete ibmvscsi 30000002: sent SRP login ibmvscsi 30000002: SRP_LOGIN succeeded ibmvscsi 30000002: host srp version: 16.a, host partition 06-1C12A (1), OS 3, ma x io 262144 ?: timing out command, waited 22s ============================================= [ INFO: possible recursive locking detected ] 2.6.29-next-20090325 #1 --------------------------------------------- khelper/1202 is trying to acquire lock: (&q->__queue_lock){..-...}, at: [] .blk_end_io+0x88/0xd8 but task is already holding lock: (&q->__queue_lock){..-...}, at: [] .__scsi_queue_insert+0xc4/ 0x128 other info that might help us debug this: 1 lock held by khelper/1202: #0: (&q->__queue_lock){..-...}, at: [] .__scsi_queue_insert+ 0xc4/0x128 stack backtrace: Call Trace: [c00000000ffff940] [c0000000000115f4] .show_stack+0x70/0x184 (unreliable) [c00000000ffff9f0] [c0000000000964c0] .validate_chain+0x6a8/0xe64 [c00000000ffffab0] [c000000000097494] .__lock_acquire+0x818/0x8e0 [c00000000ffffba0] [c000000000097664] .lock_acquire+0x108/0x154 [c00000000ffffc60] [c00000000058d2e4] ._spin_lock_irqsave+0x54/0x84 [c00000000ffffd00] [c0000000002f44d0] .blk_end_io+0x88/0xd8 [c00000000ffffda0] [c000000000407888] .__scsi_queue_insert+0xd8/0x128 [c00000000ffffe50] [c0000000002fa248] .blk_done_softirq+0xb0/0xe0 [c00000000ffffee0] [c00000000006f824] .__do_softirq+0x120/0x298 [c00000000fffff90] [c00000000002ba94] .call_do_softirq+0x14/0x24 [c000000044453330] [c00000000000d674] .do_softirq+0x94/0x114 [c0000000444533d0] [c00000000006fab0] .irq_exit+0x70/0x88 [c000000044453450] [c00000000000db2c] .do_IRQ+0x1c8/0x210 [c000000044453500] [c000000000004814] hardware_interrupt_entry+0x1c/0x20 --- Exception: 501 at .raw_local_irq_restore+0x3c/0x40 LR = .kmem_cache_alloc+0xf4/0x14c [c0000000444537f0] [c000000000112038] .kmem_cache_alloc+0xe8/0x14c (unreliable) [c0000000444538a0] [c00000000001154c] .alloc_thread_info+0x28/0x60 [c000000044453920] [c0000000000663b0] .copy_process+0xe4/0x1168 [c000000044453a10] [c000000000067804] .do_fork+0x194/0x438 [c000000044453b30] [c000000000011a18] .sys_clone+0x5c/0x74 [c000000044453ba0] [c000000000008788] .ppc_clone+0x8/0xc --- Exception: c00 at .kernel_thread+0x28/0x70 LR = .wait_for_helper+0x38/0xb0 [c000000044453e90] [0000000000000078] 0x78 (unreliable) [c000000044453f00] [c00000000007f200] .wait_for_helper+0x24/0xb0 [c000000044453f90] [c00000000002bd9c] .kernel_thread+0x54/0x70 BUG: spinlock lockup on CPU#0, khelper/1202, c0000000449b0368 Call Trace: [c00000000ffffb10] [c0000000000115f4] .show_stack+0x70/0x184 (unreliable) [c00000000ffffbc0] [c000000000316710] ._raw_spin_lock+0x140/0x17c [c00000000ffffc60] [c00000000058d2f0] ._spin_lock_irqsave+0x60/0x84 [c00000000ffffd00] [c0000000002f44d0] .blk_end_io+0x88/0xd8 [c00000000ffffda0] [c000000000407888] .__scsi_queue_insert+0xd8/0x128 [c00000000ffffe50] [c0000000002fa248] .blk_done_softirq+0xb0/0xe0 [c00000000ffffee0] [c00000000006f824] .__do_softirq+0x120/0x298 [c00000000fffff90] [c00000000002ba94] .call_do_softirq+0x14/0x24 [c000000044453330] [c00000000000d674] .do_softirq+0x94/0x114 [c0000000444533d0] [c00000000006fab0] .irq_exit+0x70/0x88 [c000000044453450] [c00000000000db2c] .do_IRQ+0x1c8/0x210 [c000000044453500] [c000000000004814] hardware_interrupt_entry+0x1c/0x20 --- Exception: 501 at .raw_local_irq_restore+0x3c/0x40 LR = .kmem_cache_alloc+0xf4/0x14c [c0000000444537f0] [c000000000112038] .kmem_cache_alloc+0xe8/0x14c (unreliable) [c0000000444538a0] [c00000000001154c] .alloc_thread_info+0x28/0x60 [c000000044453920] [c0000000000663b0] .copy_process+0xe4/0x1168 [c000000044453a10] [c000000000067804] .do_fork+0x194/0x438 [c000000044453b30] [c000000000011a18] .sys_clone+0x5c/0x74 [c000000044453ba0] [c000000000008788] .ppc_clone+0x8/0xc --- Exception: c00 at .kernel_thread+0x28/0x70 LR = .wait_for_helper+0x38/0xb0 [c000000044453e90] [0000000000000078] 0x78 (unreliable) [c000000044453f00] [c00000000007f200] .wait_for_helper+0x24/0xb0 [c000000044453f90] [c00000000002bd9c] .kernel_thread+0x54/0x70