[Qemu-devel] qemu.git hangs booting Linux after insmod virtio_blk.ko

["Richard W.M. Jones" <rjones@redhat.com>]

[-- Attachment #1: Type: text/plain, Size: 658 bytes --]


I've not looked into this at all, it's just a report that something
seems to be "up".  I will try to git bisect this later if no one spots
anything obvious.

The next operation after insmod virtio_blk would be insmod_virtio_net.

Guest kernel is a Fedora kernel, version 3.1.0-0.rc6.git0.3.fc16.x86_64.

The ImExPS/2 message may or may not be coincidental.

Rich.

-- 
Richard Jones, Virtualization Group, Red Hat http://people.redhat.com/~rjones
New in Fedora 11: Fedora Windows cross-compiler. Compile Windows
programs, test, and build Windows installers. Over 70 libraries supprt'd
http://fedoraproject.org/wiki/MinGW http://www.annexia.org/fedora_mingw

[-- Attachment #2: log --]
[-- Type: text/plain, Size: 15371 bytes --]

libguestfs: [00000ms] febootstrap-supermin-helper --verbose -f checksum '/home/rjones/d/libguestfs/appliance/supermin.d' x86_64
supermin helper [00000ms] whitelist = (not specified), host_cpu = x86_64, kernel = (null), initrd = (null), appliance = (null)
supermin helper [00000ms] inputs[0] = /home/rjones/d/libguestfs/appliance/supermin.d
checking modpath /lib/modules/3.1.0-0.rc6.git0.0.fc16.x86_64 is a directory
picked vmlinuz-3.1.0-0.rc6.git0.0.fc16.x86_64 because modpath /lib/modules/3.1.0-0.rc6.git0.0.fc16.x86_64 exists
checking modpath /lib/modules/3.1.0-0.rc3.git0.0.fc16.x86_64 is a directory
picked vmlinuz-3.1.0-0.rc3.git0.0.fc16.x86_64 because modpath /lib/modules/3.1.0-0.rc3.git0.0.fc16.x86_64 exists
checking modpath /lib/modules/3.1.0-0.rc6.git0.3.fc16.x86_64 is a directory
picked vmlinuz-3.1.0-0.rc6.git0.3.fc16.x86_64 because modpath /lib/modules/3.1.0-0.rc6.git0.3.fc16.x86_64 exists
supermin helper [00000ms] finished creating kernel
supermin helper [00000ms] visiting /home/rjones/d/libguestfs/appliance/supermin.d
supermin helper [00000ms] visiting /home/rjones/d/libguestfs/appliance/supermin.d/base.img
supermin helper [00000ms] visiting /home/rjones/d/libguestfs/appliance/supermin.d/daemon.img
supermin helper [00000ms] visiting /home/rjones/d/libguestfs/appliance/supermin.d/hostfiles
supermin helper [00028ms] visiting /home/rjones/d/libguestfs/appliance/supermin.d/init.img
supermin helper [00112ms] finished creating appliance
libguestfs: [00118ms] begin testing qemu features
libguestfs: [00150ms] finished testing qemu features
libguestfs: accept_from_daemon: 0x1b6ad20 g->state = 1
[00151ms] /home/rjones/d/qemu/qemu.wrapper \
    -drive file=/dev/null,if=virtio \
    -nodefconfig \
    -machine accel=kvm:tcg \
    -nodefaults \
    -nographic \
    -m 500 \
    -no-reboot \
    -no-hpet \
    -device virtio-serial \
    -serial stdio \
    -chardev socket,path=/home/rjones/d/libguestfs/libguestfsunnq4X/guestfsd.sock,id=channel0 \
    -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \
    -kernel /home/rjones/d/libguestfs/.guestfs-500/kernel.26648 \
    -initrd /home/rjones/d/libguestfs/.guestfs-500/initrd.26648 \
    -append 'panic=1 console=ttyS0 udevtimeout=300 no_timer_check acpi=off printk.time=1 cgroup_disable=memory selinux=0 guestfs_verbose=1 TERM=xterm ' \
    -drive file=/home/rjones/d/libguestfs/.guestfs-500/root.26648,snapshot=on,if=virtio,cache=unsafeCould not open option rom 'sgabios.bin': No such file or directory
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.1.0-0.rc6.git0.3.fc16.x86_64 (mockbuild@x86-02.phx2.fedoraproject.org) (gcc version 4.6.1 20110824 (Red Hat 4.6.1-8) (GCC) ) #1 SMP Fri Sep 16 12:26:22 UTC 2011
[    0.000000] Command line: panic=1 console=ttyS0 udevtimeout=300 no_timer_check acpi=off printk.time=1 cgroup_disable=memory selinux=0 guestfs_verbose=1 TERM=xterm 
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009bc00 (usable)
[    0.000000]  BIOS-e820: 000000000009bc00 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000001f3fd000 (usable)
[    0.000000]  BIOS-e820: 000000001f3fd000 - 000000001f400000 (reserved)
[    0.000000]  BIOS-e820: 00000000feffc000 - 00000000ff000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserved)
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] DMI 2.4 present.
[    0.000000] No AGP bridge found
[    0.000000] last_pfn = 0x1f3fd max_arch_pfn = 0x400000000
[    0.000000] PAT not supported by CPU.
[    0.000000] found SMP MP-table at [ffff8800000fda70] fda70
[    0.000000] init_memory_mapping: 0000000000000000-000000001f3fd000
[    0.000000] RAMDISK: 1f2ed000 - 1f3f0000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at 0000000000000000-000000001f3fd000
[    0.000000] Initmem setup node 0 0000000000000000-000000001f3fd000
[    0.000000]   NODE_DATA [000000001f2d9000 - 000000001f2ecfff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:1b74f01, boot clock
[    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 -> 0x0000009b
[    0.000000]     0: 0x00000100 -> 0x0001f3fd
[    0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org
[    0.000000] Intel MultiProcessor Specification v1.4
[    0.000000] MPTABLE: OEM ID: BOCHSCPU
[    0.000000] MPTABLE: Product ID: 0.1         
[    0.000000] MPTABLE: APIC at: 0xFEE00000
[    0.000000] Processor #0 (Bootup-CPU)
[    0.000000] IOAPIC[0]: apic_id 1, version 17, address 0xfec00000, GSI 0-23
[    0.000000] Processors: 1
[    0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: 000000000009b000 - 000000000009c000
[    0.000000] PM: Registered nosave memory: 000000000009c000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000
[    0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at 1f400000 (gap: 1f400000:dfbfc000)
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] setup_percpu: NR_CPUS:256 nr_cpumask_bits:256 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] PERCPU: Embedded 27 pages/cpu @ffff88001f000000 s81024 r8192 d21376 u2097152
[    0.000000] kvm-clock: cpu 0, msr 0:1f012f01, primary cpu clock
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 1f00d700
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 125875
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=300 no_timer_check acpi=off printk.time=1 cgroup_disable=memory selinux=0 guestfs_verbose=1 TERM=xterm 
[    0.000000] Disabling memory control group subsystem
[    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000000] Checking aperture...
[    0.000000] No AGP bridge found
[    0.000000] Memory: 487824k/511988k available (4866k kernel code, 468k absent, 23696k reserved, 6783k data, 940k init)
[    0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] \tRCU dyntick-idle grace-period acceleration is enabled.
[    0.000000] NR_IRQS:16640 nr_irqs:256 16
[    0.000000] Console: colour *CGA 80x25
[    0.000000] console [ttyS0] enabled
[    0.000000] Detected 2659.998 MHz processor.
[    0.000999] Calibrating delay loop (skipped) preset value.. 5319.99 BogoMIPS (lpj=2659998)
[    0.001023] pid_max: default: 32768 minimum: 301
[    0.001564] Security Framework initialized
[    0.002031] SELinux:  Disabled at boot.
[    0.002565] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.003118] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.004051] Mount-cache hash table entries: 256
[    0.004500] Initializing cgroup subsys cpuacct
[    0.004812] Initializing cgroup subsys memory
[    0.005016] Initializing cgroup subsys devices
[    0.005296] Initializing cgroup subsys freezer
[    0.005612] Initializing cgroup subsys net_cls
[    0.006006] Initializing cgroup subsys blkio
[    0.006279] Initializing cgroup subsys perf_event
[    0.006649] mce: CPU supports 10 MCE banks
[    0.007139] SMP alternatives: switching to UP code
[    0.013494] Freeing SMP alternatives: 12k freed
[    0.013847] ftrace: allocating 25203 entries in 99 pages
[    0.016806] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.017002] CPU0: Intel QEMU Virtual CPU version 0.15.50 stepping 03
[    0.119301] Performance Events: unsupported p6 CPU model 2 no PMU driver, software events only.
[    0.120132] NMI watchdog disabled (cpu0): hardware events not enabled
[    0.120619] Brought up 1 CPUs
[    0.120828] Total of 1 processors activated (5319.99 BogoMIPS).
[    0.121469] devtmpfs: initialized
[    0.125600] atomic64 test passed for x86-64 platform with CX8 and with SSE
[    0.126036] RTC time: 12:25:30, date: 09/30/11
[    0.126393] NET: Registered protocol family 16
[    0.127146] PCI: Using configuration type 1 for base access
[    0.128325] bio: create slab <bio-0> at 0
[    0.128667] ACPI: Interpreter disabled.
[    0.129006] vgaarb: loaded
[    0.129246] SCSI subsystem initialized
[    0.129580] usbcore: registered new interface driver usbfs
[    0.130003] usbcore: registered new interface driver hub
[    0.130421] usbcore: registered new device driver usb
[    0.130778] PCI: Probing PCI hardware
[    0.132369] pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
[    0.132838] pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB
[    0.140668] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]
[    0.141198] NetLabel: Initializing
[    0.141434] NetLabel:  domain hash size = 128
[    0.141715] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.142003] NetLabel:  unlabeled traffic allowed by default
[    0.142373] Switching to clocksource kvm-clock
[    0.143983] Switched to NOHz mode on CPU #0
[    0.149215] pnp: PnP ACPI: disabled
[    0.150955] NET: Registered protocol family 2
[    0.151449] IP route cache hash table entries: 4096 (order: 3, 32768 bytes)
[    0.151970] TCP established hash table entries: 16384 (order: 6, 262144 bytes)
[    0.152961] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[    0.153799] TCP: Hash tables configured (established 16384 bind 16384)
[    0.154506] TCP reno registered
[    0.154710] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.155250] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.155960] NET: Registered protocol family 1
[    0.156409] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.156785] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.157390] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.158081] Unpacking initramfs...
[    0.159554] Freeing initrd memory: 1036k freed
[    0.160195] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.161020] Intel AES-NI instructions are not detected.
[    0.161704] audit: initializing netlink socket (disabled)
[    0.162180] type=2000 audit(1317385531.161:1): initialized
[    0.181823] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.183870] VFS: Disk quotas dquot_6.5.2
[    0.184253] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.185201] msgmni has been set to 954
[    0.185770] alg: No test for stdrng (krng)
[    0.186091] NET: Registered protocol family 38
[    0.186454] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.187199] io scheduler noop registered
[    0.187488] io scheduler deadline registered
[    0.187784] io scheduler cfq registered (default)
[    0.188355] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.188884] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.189412] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.190203] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 34
[    0.190963] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 35
[    0.191869] virtio-pci 0000:00:04.0: PCI->APIC IRQ transform: INT A -> IRQ 35
[    0.192789] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.214514] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[    0.220269] Non-volatile memory driver v1.3
[    0.220660] Linux agpgart interface v0.103
[    0.221586] loop: module loaded
[    0.222646] scsi0 : ata_piix
[    0.222929] scsi1 : ata_piix
[    0.223282] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14
[    0.223910] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15
[    0.224703] Fixed MDIO Bus: probed
[    0.224979] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.225876] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.226506] uhci_hcd: USB Universal Host Controller Interface driver
[    0.227139] usbcore: registered new interface driver usbserial
[    0.227846] USB Serial support registered for generic
[    0.228350] usbcore: registered new interface driver usbserial_generic
[    0.228993] usbserial: USB Serial Driver core
[    0.229515] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    0.230885] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.231460] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.231858] mousedev: PS/2 mouse device common for all mice
[    0.232758] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.236537] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    0.237132] rtc0: alarms up to one day, 114 bytes nvram
[    0.237616] device-mapper: uevent: version 1.0.3
[    0.238322] device-mapper: ioctl: 4.21.0-ioctl (2011-07-06) initialised: dm-devel@redhat.com
[    0.239153] cpuidle: using governor ladder
[    0.239651] cpuidle: using governor menu
[    0.240181] EFI Variables Facility v0.08 2004-May-17
[    0.240672] usbcore: registered new interface driver usbhid
[    0.241133] usbhid: USB HID core driver
[    0.241759] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.242255] TCP cubic registered
[    0.242667] Initializing XFRM netlink socket
[    0.243254] NET: Registered protocol family 10
[    0.243805] Mobile IPv6
[    0.243981] NET: Registered protocol family 17
[    0.244998] Registering the dns_resolver key type
[    0.245591] registered taskstats version 1
[    0.245998] IMA: No TPM chip found, activating TPM-bypass!
[    0.246787]   Magic number: 11:918:431
[    0.247225] rtc_cmos rtc_cmos: setting system clock to 2011-09-30 12:25:30 UTC (1317385530)
[    0.248188] Initializing network drop monitor service
[    0.402221] Freeing unused kernel memory: 940k freed
[    0.403955] Write protecting the kernel read-only data: 10240k
[    0.415286] Freeing unused kernel memory: 1260k freed
[    0.421039] Freeing unused kernel memory: 1584k freed
febootstrap: mounting /proc
febootstrap: uptime: 0.42 0.16
febootstrap: ext2 mini initrd starting up
febootstrap: mounting /sys
febootstrap: internal insmod libcrc32c.ko
febootstrap: internal insmod crc32c-intel.ko
insmod: init_module: crc32c-intel.ko: No such device
febootstrap: internal insmod crc-itu-t.ko
febootstrap: internal insmod crc-ccitt.ko
febootstrap: internal insmod crc7.ko
febootstrap: internal insmod crc8.ko
febootstrap: internal insmod scsi_transport_spi.ko
febootstrap: internal insmod sym53c8xx.ko
febootstrap: internal insmod rfkill.ko
febootstrap: internal insmod sparse-keymap.ko
febootstrap: internal insmod ideapad-laptop.ko
insmod: init_module: ideapad-laptop.ko: No such device
febootstrap: internal insmod virtio_balloon.ko
febootstrap: internal insmod virtio-rng.ko
febootstrap: internal insmod virtio_blk.ko
[    0.669479] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input1