Message-ID: <CAOJsxLHHK57hZ1VJPi+oXL0YUHxNmEixwb0E6GQ5Q9YrPsKtfg@mail.gmail.com>
Date:	Tue, 8 Nov 2011 17:36:18 +0200
From:	Pekka Enberg <penberg@...nel.org>
To:	richard -rw- weinberger <richard.weinberger@...il.com>
Cc:	Linus Torvalds <torvalds@...ux-foundation.org>,
	Avi Kivity <avi@...hat.com>,
	Andrew Morton <akpm@...ux-foundation.org>,
	Ingo Molnar <mingo@...e.hu>, linux-kernel@...r.kernel.org,
	kvm@...r.kernel.org, Sasha Levin <levinsasha928@...il.com>
Subject: Re: [RFC/GIT PULL] Linux KVM tool for v3.2

Hi Richard,

(I'm adding Sasha to the CC.)

On Tue, Nov 8, 2011 at 4:44 PM, richard -rw- weinberger
<richard.weinberger@...il.com> wrote:
> Pekka,
>
> On Fri, Nov 4, 2011 at 9:38 AM, Pekka Enberg <penberg@...helsinki.fi> wrote:
>>    ./kvm run
>>
>> We also support booting both raw images and QCOW2 images in read-only
>> mode:
>>
>>    ./kvm run -d debian_squeeze_amd64_standard.qcow2 -p "root=/dev/vda1"
>>
>
> I'm trying to use the kvm tool, but the virtio_blk userland seems to freeze.
> Any idea what happens here?
>
> ./kvm run -d /scratch/rw/fc14_64_image.qcow2 -p "root=/dev/vda1
> nolapic init=/bin/sh notsc hpet=disable debug"
>  Warning: Forcing read-only support for QCOW
>  # kvm run -k ../../arch/x86/boot/bzImage -m 320 -c 2 --name guest-9815
> eeaarrllyy  ccoonnssoollee  iinn  sseettuupp  ccooddee
>
> early console in decompress_kernel
>
> Decompressing Linux... Parsing ELF... done.
> Booting the kernel.
> [    0.000000] Linux version 3.1.0-00905-ge0cab7f (rw@...elltoy) (gcc
> version 4.5.1 20100924 (Red Hat 4.5.1-4) (GCC) ) #3 Tue Nov 8 14:14:24
> CET 2011
> [    0.000000] Command line: notsc noapic noacpi pci=conf1 reboot=k
> panic=1 i8042.direct=1 i8042.dumbkbd=1 i8042.nopnp=1 console=ttyS0
> earlyprintk=serial i8042.noaux=1 root=/dev/vda1 nolapic init=/bin/sh
> notsc hpet=disable debug
> [    0.000000] BIOS-provided physical RAM map:
> [    0.000000]  BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
> [    0.000000]  BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
> [    0.000000]  BIOS-e820: 00000000000f0000 - 00000000000fffff (reserved)
> [    0.000000]  BIOS-e820: 0000000000100000 - 0000000014000000 (usable)
> [    0.000000] bootconsole [earlyser0] enabled
> [    0.000000] NX (Execute Disable) protection: active
> [    0.000000] DMI not present or invalid.
> [    0.000000] e820 update range: 0000000000000000 - 0000000000010000
> (usable) ==> (reserved)
> [    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
> [    0.000000] No AGP bridge found
> [    0.000000] last_pfn = 0x14000 max_arch_pfn = 0x400000000
> [    0.000000] MTRR default type: uncachable
> [    0.000000] MTRR fixed ranges disabled:
> [    0.000000]   00000-FFFFF uncachable
> [    0.000000] MTRR variable ranges disabled:
> [    0.000000]   0 disabled
> [    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 0x0, new 0x7010600070106
> [    0.000000] CPU MTRRs all blank - virtualized system.
> [    0.000000] found SMP MP-table at [ffff8800000f0370] f0370
> [    0.000000] initial memory mapped : 0 - 20000000
> [    0.000000] Base memory trampoline at [ffff88000009a000] 9a000 size 20480
> [    0.000000] init_memory_mapping: 0000000000000000-0000000014000000
> [    0.000000]  0000000000 - 0014000000 page 2M
> [    0.000000] kernel direct mapping tables up to 14000000 @ 13ffe000-14000000
> [    0.000000] ACPI Error: A valid RSDP was not found (20110623/tbxfroot-219)
> [    0.000000]  [ffffea0000000000-ffffea00005fffff] PMD ->
> [ffff880013000000-ffff8800135fffff] on node 0
> [    0.000000] Zone PFN ranges:
> [    0.000000]   DMA      0x00000010 -> 0x00001000
> [    0.000000]   DMA32    0x00001000 -> 0x00100000
> [    0.000000]   Normal   empty
> [    0.000000] Movable zone start PFN for each node
> [    0.000000] early_node_map[2] active PFN ranges
> [    0.000000]     0: 0x00000010 -> 0x0000009f
> [    0.000000]     0: 0x00000100 -> 0x00014000
> [    0.000000] On node 0 totalpages: 81807
> [    0.000000]   DMA zone: 64 pages used for memmap
> [    0.000000]   DMA zone: 5 pages reserved
> [    0.000000]   DMA zone: 3914 pages, LIFO batch:0
> [    0.000000]   DMA32 zone: 1216 pages used for memmap
> [    0.000000]   DMA32 zone: 76608 pages, LIFO batch:15
> [    0.000000] Intel MultiProcessor Specification v1.4
> [    0.000000] MPTABLE: OEM ID: KVMCPU00
> [    0.000000] MPTABLE: Product ID: 0.1
> [    0.000000] MPTABLE: APIC at: 0xFEE00000
> [    0.000000] Processor #0 (Bootup-CPU)
> [    0.000000] Processor #1
> [    0.000000] ACPI: NR_CPUS/possible_cpus limit of 1 reached.
> Processor 1/0x1 ignored.
> [    0.000000] IOAPIC[0]: apic_id 3, version 17, address 0xfec00000, GSI 0-23
> [    0.000000] Processors: 1
> [    0.000000] nr_irqs_gsi: 40
> [    0.000000] Allocating PCI resources starting at 14000000 (gap:
> 14000000:ec000000)
> [    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: 80522
> [    0.000000] Kernel command line: notsc noapic noacpi pci=conf1
> reboot=k panic=1 i8042.direct=1 i8042.dumbkbd=1 i8042.nopnp=1
> console=ttyS0 earlyprintk=serial i8042.noaux=1 root=/dev/vda1 nolapic
> init=/bin/sh notsc hpet=disable debug
> [    0.000000] notsc: Kernel compiled with CONFIG_X86_TSC, cannot
> disable TSC completely.
> [    0.000000] notsc: Kernel compiled with CONFIG_X86_TSC, cannot
> disable TSC completely.
> [    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
> [    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
> [    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
> [    0.000000] Checking aperture...
> [    0.000000] No AGP bridge found
> [    0.000000] Memory: 304564k/327680k available (3056k kernel code,
> 452k absent, 22664k reserved, 3527k data, 344k init)
> [    0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0,
> CPUs=1, Nodes=1
> [    0.000000] NR_IRQS:288
> [    0.000000] Console: colour *CGA 80x25
> [    0.000000] console [ttyS0] enabled, bootconsole disabled
> [    0.000000] console [ttyS0] enabled, bootconsole disabled
> [    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: 5855 kB
> [    0.000000]  per task-struct memory footprint: 1920 bytes
> [    0.000000] Fast TSC calibration failed
> [    0.000000] TSC: Unable to calibrate against PIT
> [    0.000000] TSC: No reference (HPET/PMTIMER) available
> [    0.000000] Marking TSC unstable due to could not calculate TSC khz
> [    0.020001] Calibrating delay loop... 5656.57 BogoMIPS (lpj=11313152)
> [    0.040002] pid_max: default: 32768 minimum: 301
> [    0.040002] Mount-cache hash table entries: 256
> [    0.044002] CPU: Intel(R) Core(TM)2 CPU         X6800  @ 2.93GHz stepping 05
> [    0.048002] Performance Events: unsupported p6 CPU model 15 no PMU
> driver, software events only.
> [    0.052002] NMI watchdog disabled (cpu0): hardware events not enabled
> [    0.056003] Apic disabled
> [    0.060003] NET: Registered protocol family 16
> [    0.060003] PCI: Using configuration type 1 for base access
> [    0.064003] bio: create slab <bio-0> at 0
> [    0.068003] ACPI: Interpreter disabled.
> [    0.068003] vgaarb: loaded
> [    0.068003] PCI: Probing PCI hardware
> [    0.072003] PCI: Probing PCI hardware (bus 00)
> [    0.076003] pci 0000:00:01.0: [1af4:1001] type 0 class 0x000180
> [    0.076003] pci 0000:00:01.0: reg 10: [io  0x6200-0x62ff]
> [    0.080004] pci 0000:00:01.0: reg 14: [mem 0xd1000000-0xd10000ff 64bit]
> [    0.084004] pci 0000:00:01.0: reg 1c: [mem 0xd1000100-0xd10001ff 64bit]
> [    0.088004] pci 0000:00:02.0: [1af4:1000] type 0 class 0x000200
> [    0.088004] pci 0000:00:02.0: reg 10: [io  0x6600-0x66ff]
> [    0.092004] pci 0000:00:02.0: reg 14: [mem 0xd1000200-0xd10002ff 64bit]
> [    0.096005] pci 0000:00:02.0: reg 1c: [mem 0xd1000300-0xd10003ff 64bit]
> [    0.100005] PCI: pci_cache_line_size set to 64 bytes
> [    0.100005] reserve RAM buffer: 000000000009fc00 - 000000000009ffff
> [    0.104005] pnp: PnP ACPI: disabled
> [    0.108005] PCI: max bus depth: 0 pci_try_num: 1
> [    0.112006] pci_bus 0000:00: resource 0 [io  0x0000-0xffff]
> [    0.112006] pci_bus 0000:00: resource 1 [mem 0x00000000-0xfffffffff]
> [    0.116006] NET: Registered protocol family 2
> [    0.120006] IP route cache hash table entries: 4096 (order: 3, 32768 bytes)
> [    0.124007] TCP established hash table entries: 16384 (order: 6,
> 262144 bytes)
> [    0.128007] TCP bind hash table entries: 16384 (order: 8, 1048576 bytes)
> [    0.136007] TCP: Hash tables configured (established 16384 bind 16384)
> [    0.136007] TCP reno registered
> [    0.140008] UDP hash table entries: 256 (order: 3, 40960 bytes)
> [    0.140008] UDP-Lite hash table entries: 256 (order: 3, 40960 bytes)
> [    0.144008] PCI: CLS 0 bytes, default 64
> [    0.148008] platform rtc_cmos: registered platform RTC device (no
> PNP device found)
> [    0.156009] Installing v9fs 9p2000 file system support
> [    0.160009] Block layer SCSI generic (bsg) driver version 0.4
> loaded (major 254)
> [    0.160009] io scheduler noop registered
> [    0.164009] io scheduler deadline registered
> [    0.164009] io scheduler cfq registered (default)
> [    0.168009] start plist test
> [    0.168009] end plist test
> [    0.168009] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003)
> [    0.172010] virtio-pci 0000:00:01.0: setting latency timer to 64
> [    0.176010] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003)
> [    0.180010] virtio-pci 0000:00:02.0: setting latency timer to 64
> [    0.200011] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
> [    0.208012] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
> [    0.220013] serial8250: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A
> [    0.232013] serial8250: ttyS2 at I/O 0x3e8 (irq = 4) is a 16550A
> [  240.167008] INFO: task swapper:1 blocked for more than 120 seconds.
> [  240.167008] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs"
> disables this message.
> [  240.171009] swapper         D ffffffff81071c70     0     1      0 0x00000000
> [  240.171009]  ffff880012c2d8b0 0000000000000046 ffff880000000000
> ffff880012c2d7d0
> [  240.175009]  0000000000000003 ffff880012c2d8b0 ffff880012c30000
> ffff880012c2dfd8
> [  240.179009]  ffff880012c2c000 ffff880012c2dfd8 ffffffff81614040
> ffff880012c30000
> [  240.179009] Call Trace:
> [  240.179009]  [<ffffffff810a1307>] ? kmem_cache_alloc+0xa7/0xe0
> [  240.183009]  [<ffffffff81057b61>] ? mark_held_locks+0x61/0x150
> [  240.183009]  [<ffffffff812f97db>] ? _raw_spin_unlock_irqrestore+0x3b/0x70
> [  240.187010]  [<ffffffff81071c70>] ? file_read_actor+0x190/0x190
> [  240.187010]  [<ffffffff81057d4d>] ? trace_hardirqs_on_caller+0xfd/0x190
> [  240.191010]  [<ffffffff81071c70>] ? file_read_actor+0x190/0x190
> [  240.191010]  [<ffffffff812f6eb9>] schedule+0x39/0x50
> [  240.191010]  [<ffffffff812f711e>] io_schedule+0x3e/0x60
> [  240.195010]  [<ffffffff812f983b>] ? _raw_spin_unlock_irq+0x2b/0x50
> [  240.195010]  [<ffffffff81071c79>] sleep_on_page+0x9/0x10
> [  240.199010]  [<ffffffff812f76f1>] __wait_on_bit_lock+0x51/0xb0
> [  240.203011]  [<ffffffff810d5520>] ? bioset_create+0x280/0x280
> [  240.203011]  [<ffffffff810726f1>] __lock_page+0x61/0x70
> [  240.203011]  [<ffffffff81045440>] ? autoremove_wake_function+0x40/0x40
> [  240.207011]  [<ffffffff81072ac7>] do_read_cache_page+0x177/0x1a0
> [  240.207011]  [<ffffffff810d5ac0>] ? blkdev_write_begin+0x20/0x20
> [  240.211011]  [<ffffffff81074007>] read_cache_page_async+0x17/0x20
> [  240.211011]  [<ffffffff81074049>] read_cache_page+0x9/0x20
> [  240.215011]  [<ffffffff810f7a8b>] read_dev_sector+0x2b/0x90
> [  240.215011]  [<ffffffff810f7e10>] ? parse_extended+0x2c0/0x2c0
> [  240.219012]  [<ffffffff810f7e8a>] msdos_partition+0x7a/0x5b0
> [  240.219012]  [<ffffffff811855e4>] ? snprintf+0x34/0x40
> [  240.219012]  [<ffffffff810f7e10>] ? parse_extended+0x2c0/0x2c0
> [  240.223012]  [<ffffffff810f7758>] rescan_partitions+0x1a8/0x4b0
> [  240.227012]  [<ffffffff810d6c4e>] __blkdev_get+0x26e/0x3a0
> [  240.227012]  [<ffffffff810d6dce>] blkdev_get+0x4e/0x380
> [  240.227012]  [<ffffffff8117df77>] ? kobject_put+0x27/0x60
> [  240.231012]  [<ffffffff811731c8>] ? disk_get_part+0x18/0xc0
> [  240.231012]  [<ffffffff81173e57>] register_disk+0x157/0x180
> [  240.235013]  [<ffffffff81173f25>] add_disk+0xa5/0x300
> [  240.235013]  [<ffffffff812f4c5a>] virtblk_probe+0x51f/0x5a3
> [  240.235013]  [<ffffffff812f7a9f>] ? mutex_lock_nested+0x22f/0x300
> [  240.239013]  [<ffffffff81209830>] ? do_virtblk_request+0x450/0x450
> [  240.239013]  [<ffffffff811d2720>] ? vp_reset+0x20/0x20
> [  240.243013]  [<ffffffff811d0ee1>] virtio_dev_probe+0xe1/0x140
> [  240.247013]  [<ffffffff812013e8>] driver_probe_device+0x88/0x1a0
> [  240.247013]  [<ffffffff812015a3>] __driver_attach+0xa3/0xb0
> [  240.247013]  [<ffffffff81201500>] ? driver_probe_device+0x1a0/0x1a0
> [  240.251014]  [<ffffffff8120011e>] bus_for_each_dev+0x5e/0x90
> [  240.251014]  [<ffffffff812016d9>] driver_attach+0x19/0x20
> [  240.255014]  [<ffffffff81200888>] bus_add_driver+0x148/0x280
> [  240.255014]  [<ffffffff8168d694>] ? firmware_class_init+0x19/0x19
> [  240.255014]  [<ffffffff81201f11>] driver_register+0x71/0x140
> [  240.259014]  [<ffffffff812f86d9>] ? mutex_unlock+0x9/0x10
> [  240.259014]  [<ffffffff8168d694>] ? firmware_class_init+0x19/0x19
> [  240.263014]  [<ffffffff811d0fcb>] register_virtio_driver+0x1b/0x30
> [  240.263014]  [<ffffffff8168d6ee>] init+0x5a/0x84
> [  240.267015]  [<ffffffff81670b15>] do_one_initcall+0x78/0x12b
> [  240.267015]  [<ffffffff81670c58>] kernel_init+0x90/0x110
> [  240.271015]  [<ffffffff811871ee>] ? trace_hardirqs_on_thunk+0x3a/0x3f
> [  240.271015]  [<ffffffff812fb834>] kernel_thread_helper+0x4/0x10
> [  240.275015]  [<ffffffff812fa259>] ? retint_restore_args+0xe/0xe
> [  240.275015]  [<ffffffff81670bc8>] ? do_one_initcall+0x12b/0x12b
> [  240.275015]  [<ffffffff812fb830>] ? gs_change+0xb/0xb
> [  240.279015] 3 locks held by swapper/1:
> [  240.279015]  #0:  (&__lockdep_no_validate__){......}, at:
> [<ffffffff81201553>] __driver_attach+0x53/0xb0
> [  240.283016]  #1:  (&__lockdep_no_validate__){......}, at:
> [<ffffffff81201561>] __driver_attach+0x61/0xb0
> [  240.287016]  #2:  (&bdev->bd_mutex){+.+.+.}, at:
> [<ffffffff810d6a1b>] __blkdev_get+0x3b/0x3a0

Can you please run "kvm debug -a" on a host terminal when the guest
hangs? Please copy the guest output and debug information from the
host terminal. Also, please share your .config with us.

                        Pekka
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/

Powered by blists - more mailing lists