Description of problem ----------------------- Libguestfs fails to create an appliance with (either through 'direct' of 'libvirt' backends with a Kernel panic. libguestfs-test-tool result below. [I encountered this when I was running libguestfs-test-tool -- http://libguestfs.org/libguestfs-test-tool.1.html, which boots into a Kernel under QEMU] Version ------- $ uname -r; rpm -q libvirt libguestfs qemu-system-x86 3.13.0-0.rc4.git5.1.fc21.x86_64 libvirt-1.1.3.1-2.fc20.x86_64 libguestfs-1.25.18-1.fc21.x86_64 qemu-system-x86-1.6.1-2.fc20.x86_64 How reproducible: Consistently. Steps to Reproduce ------------------ Run libguestfs diagnostics test tool: $ export LIBGUESTFS_BACKEND=libvirt $ guestfish get-backend libvirt # Run the tool: $ libguestfs-test-tool Repeat the above with 'direct' backend: $ export LIBGUESTFS_BACKEND=direct $ guestfish get-backend direct $ libguestfs-test-tool (NOTE: SELinux was Permissive in both cases) Actual results -------------- Libguestfs fails to create appliance With direct backend: ====== $ libguestfs-test-tool ************************************************************ * IMPORTANT NOTICE * * When reporting bugs, include the COMPLETE, UNEDITED * output below in your bug report. * ************************************************************ PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/root/bin SELinux: Permissive guestfs_get_append: (null) guestfs_get_backend: libvirt guestfs_get_autosync: 1 guestfs_get_cachedir: /var/tmp guestfs_get_direct: 0 guestfs_get_hv: /usr/bin/qemu-kvm guestfs_get_memsize: 500 guestfs_get_network: 0 guestfs_get_path: /usr/lib64/guestfs guestfs_get_pgroup: 0 guestfs_get_program: libguestfs-test-tool guestfs_get_recovery_proc: 1 guestfs_get_selinux: 0 guestfs_get_smp: 1 guestfs_get_tmpdir: /tmp guestfs_get_trace: 0 guestfs_get_verbose: 1 host_cpu: x86_64 Launching appliance, timeout set to 600 seconds. libguestfs: launch: program=libguestfs-test-tool libguestfs: launch: version=1.25.18fedora=21,release=1.fc21,libvirt libguestfs: launch: backend registered: unix libguestfs: launch: backend registered: uml libguestfs: launch: backend registered: libvirt libguestfs: launch: backend registered: direct libguestfs: launch: backend=libvirt libguestfs: launch: tmpdir=/tmp/libguestfs61jk02 libguestfs: launch: umask=0022 libguestfs: launch: euid=0 libguestfs: libvirt version = 1001003 (1.1.3) libguestfs: guest random name = guestfs-a89x7io0ks9bnvw2 libguestfs: [00000ms] connect to libvirt libguestfs: opening libvirt handle: URI = NULL, auth = virConnectAuthPtrDefault, flags = 0 libguestfs: successfully opened libvirt handle: conn = 0x7fcba3406810 libguestfs: [00025ms] get libvirt capabilities libguestfs: [00109ms] parsing capabilities XML libguestfs: [00110ms] build appliance libguestfs: command: run: /usr/bin/supermin-helper libguestfs: command: run: \ --verbose libguestfs: command: run: \ -f checksum libguestfs: command: run: \ --host-cpu x86_64 libguestfs: command: run: \ /usr/lib64/guestfs/supermin.d supermin helper [00000ms] whitelist = (not specified) supermin helper [00000ms] host_cpu = x86_64 supermin helper [00000ms] dtb_wildcard = (not specified) supermin helper [00000ms] inputs: supermin helper [00000ms] inputs[0] = /usr/lib64/guestfs/supermin.d supermin helper [00000ms] outputs: supermin helper [00000ms] kernel = (none) supermin helper [00000ms] dtb = (none) supermin helper [00000ms] initrd = (none) supermin helper [00000ms] appliance = (none) checking modpath /lib/modules/3.13.0-0.rc4.git5.1.fc21.x86_64 is a directory checking modpath /lib/modules/3.11.9-300.fc20.x86_64 is a directory checking modpath /lib/modules/3.11.10-301.fc20.x86_64 is a directory picked kernel vmlinuz-3.13.0-0.rc4.git5.1.fc21.x86_64 supermin helper [00001ms] finished creating kernel supermin helper [00003ms] visiting /usr/lib64/guestfs/supermin.d supermin helper [00004ms] visiting /usr/lib64/guestfs/supermin.d/base.img.gz supermin helper [00004ms] visiting /usr/lib64/guestfs/supermin.d/daemon.img.gz supermin helper [00004ms] visiting /usr/lib64/guestfs/supermin.d/hostfiles supermin helper [00171ms] visiting /usr/lib64/guestfs/supermin.d/init.img supermin helper [00171ms] visiting /usr/lib64/guestfs/supermin.d/udev-rules.img supermin helper [00171ms] adding kernel modules supermin helper [00481ms] finished creating appliance libguestfs: checksum of existing appliance: f57789a6f4cb6b6252712b3f88eba1a60423b533f24c7580ac2700e4b058dbbe libguestfs: command: run: qemu-img libguestfs: command: run: \ create libguestfs: command: run: \ -f qcow2 libguestfs: command: run: \ -b /var/tmp/.guestfs-0/root.31997 libguestfs: command: run: \ -o backing_fmt=raw libguestfs: command: run: \ /tmp/libguestfs61jk02/snapshot2 Formatting '/tmp/libguestfs61jk02/snapshot2', fmt=qcow2 size=4294967296 backing_file='/var/tmp/.guestfs-0/root.31997' backing_fmt='raw' encryption=off cluster_size=65536 lazy_refcounts=off libguestfs: [00716ms] create libvirt XML libguestfs: libvirt XML:\n<?xml version="1.0"?>\n<domain type="kvm" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0">\n <name>guestfs-a89x7io0ks9bnvw2</name>\n <memory unit="MiB">500</memory>\n <currentMemory unit="MiB">500</currentMemory>\n <cpu mode="host-passthrough">\n <model fallback="allow"/>\n </cpu>\n <vcpu>1</vcpu>\n <clock offset="utc">\n <timer name="kvmclock" present="yes"/>\n </clock>\n <os>\n <type>hvm</type>\n <kernel>/var/tmp/.guestfs-0/kernel.31997</kernel>\n <initrd>/var/tmp/.guestfs-0/initrd.31997</initrd>\n <cmdline>panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=screen</cmdline>\n </os>\n <on_reboot>destroy</on_reboot>\n <devices>\n <controller type="scsi" index="0" model="virtio-scsi"/>\n <disk device="disk" type="file">\n <source file="/tmp/libguestfs61jk02/scratch.1"/>\n <target dev="sda" bus="scsi"/>\n <driver name="qemu" type="raw" cache="unsafe"/>\n <address type="drive" controller="0" bus="0" target="0" unit="0"/>\n </disk>\n <disk type="file" device="disk">\n <source file="/tmp/libguestfs61jk02/snapshot2"/>\n <target dev="sdb" bus="scsi"/>\n <driver name="qemu" type="qcow2" cache="unsafe"/>\n <address type="drive" controller="0" bus="0" target="1" unit="0"/>\n <shareable/>\n </disk>\n <serial type="unix">\n <source mode="connect" path="/tmp/libguestfs61jk02/console.sock"/>\n <target port="0"/>\n </serial>\n <channel type="unix">\n <source mode="connect" path="/tmp/libguestfs61jk02/guestfsd.sock"/>\n <target type="virtio" name="org.libguestfs.channel.0"/>\n </channel>\n </devices>\n <qemu:commandline>\n <qemu:env name="TMPDIR" value="/var/tmp"/>\n </qemu:commandline>\n</domain>\n libguestfs: command: run: ls libguestfs: command: run: \ -a libguestfs: command: run: \ -l libguestfs: command: run: \ -Z /var/tmp/.guestfs-0 libguestfs: drwxr-xr-x. root root unconfined_u:object_r:user_tmp_t:s0 . libguestfs: drwxrwxrwt. root root system_u:object_r:tmp_t:s0 .. libguestfs: -rwxr-xr-x. root root unconfined_u:object_r:user_tmp_t:s0 checksum libguestfs: -rw-r--r--. root root system_u:object_r:virt_content_t:s0 initrd libguestfs: -rw-r--r--. root root system_u:object_r:virt_content_t:s0 initrd.31997 libguestfs: -rw-r--r--. root root system_u:object_r:virt_content_t:s0 kernel libguestfs: -rw-r--r--. root root system_u:object_r:virt_content_t:s0 kernel.31997 libguestfs: -rw-r--r--. qemu qemu system_u:object_r:virt_content_t:s0 root libguestfs: -rw-r--r--. qemu qemu system_u:object_r:virt_content_t:s0 root.31997 libguestfs: command: run: ls libguestfs: command: run: \ -a libguestfs: command: run: \ -l libguestfs: command: run: \ -Z /tmp/libguestfs61jk02 libguestfs: drwxr-xr-x. root root unconfined_u:object_r:user_tmp_t:s0 . libguestfs: drwxrwxrwt. root root system_u:object_r:tmp_t:s0 .. libguestfs: srw-rw----. root qemu unconfined_u:object_r:user_tmp_t:s0 console.sock libguestfs: srw-rw----. root qemu unconfined_u:object_r:user_tmp_t:s0 guestfsd.sock libguestfs: -rw-------. root root unconfined_u:object_r:user_tmp_t:s0 scratch.1 libguestfs: -rw-r--r--. root root unconfined_u:object_r:user_tmp_t:s0 snapshot2 libguestfs: -rwxr-xr-x. root root unconfined_u:object_r:user_tmp_t:s0 umask-check libguestfs: [00787ms] launch libvirt guest [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 3.13.0-0.rc4.git5.1.fc21.x86_64 (mockbuild@bkernel02) (gcc version 4.8.2 20131212 (Red Hat 4.8.2-7) (GCC) ) #1 SMP Sat Dec 21 13:25:39 UTC 2013 [ 0.000000] Command line: panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=screen [ 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-0x000000001f3fffff] usable [ 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] SMBIOS 2.4 present. [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: last_pfn = 0x1f400 max_arch_pfn = 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] found SMP MP-table at [mem 0x000f17f0-0x000f17ff] mapped at [ffff8800000f17f0] [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] Using GB pages for direct mapping [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] init_memory_mapping: [mem 0x1f000000-0x1f1fffff] [ 0.000000] init_memory_mapping: [mem 0x1c000000-0x1effffff] [ 0.000000] init_memory_mapping: [mem 0x00100000-0x1bffffff] [ 0.000000] init_memory_mapping: [mem 0x1f200000-0x1f3fffff] [ 0.000000] RAMDISK: [mem 0x1f255000-0x1f3effff] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3fffff] [ 0.000000] Initmem setup node 0 [mem 0x00000000-0x1f3fffff] [ 0.000000] NODE_DATA [mem 0x1f22e000-0x1f254fff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:1f1ae001, boot clock [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-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-0x1f3fffff] [ 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 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] Processors: 1 [ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] e820: [mem 0x1f400000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 477 pages/cpu @ffff88001ee00000 s1925056 r8192 d20544 u2097152 [ 0.000000] kvm-clock: cpu 0, msr 0:1f1ae001, primary cpu clock [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1ee0e140 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 125881 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=600 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=screen [ 0.000000] Disabling memory control group subsystem [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Memory: 468064K/511608K available (7626K kernel code, 1583K rwdata, 3212K rodata, 3656K init, 12716K bss, 43544K reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] \tRCU lockdep checking is enabled. [ 0.000000] \tRCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1. [ 0.000000] NR_IRQS:524544 nr_irqs:256 16 [ 0.000000] Console: colour *CGA 80x25 [ 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] kmemleak: Kernel memory leak detector disabled [ 0.000000] tsc: Detected 1864.644 MHz processor [ 0.004000] Calibrating delay loop (skipped) preset value.. 3729.28 BogoMIPS (lpj=1864644) [ 0.008042] pid_max: default: 32768 minimum: 301 [ 0.011288] Security Framework initialized [ 0.014123] SELinux: Disabled at boot. [ 0.018483] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.024075] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.028160] Mount-cache hash table entries: 256 [ 0.038760] Initializing cgroup subsys memory [ 0.040398] Initializing cgroup subsys devices [ 0.042080] Initializing cgroup subsys freezer [ 0.044078] Initializing cgroup subsys net_cls [ 0.046056] Initializing cgroup subsys blkio [ 0.048045] Initializing cgroup subsys perf_event [ 0.050055] Initializing cgroup subsys hugetlb [ 0.055599] mce: CPU supports 10 MCE banks [ 0.059537] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.059537] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.059537] tlb_flushall_shift: 5 [ 2.557741] Freeing SMP alternatives memory: 24K (ffffffff8211f000 - ffffffff82125000) [ 2.713445] ftrace: allocating 25927 entries in 102 pages [ 2.744552] Enabling x2apic [ 2.745000] Enabled x2apic [ 2.745042] Switched APIC routing to physical x2apic. [ 2.780000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 2.780008] smpboot: CPU0: Intel Xeon E312xx (Sandy Bridge) (fam: 06, model: 2a, stepping: 01) [ 2.866642] APIC timer disabled due to verification failure [ 2.879379] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only. [ 2.907514] watchdog: disabled (inside virtual machine) [ 2.908045] x86: Booted up 1 node, 1 CPUs [ 2.909010] smpboot: Total of 1 processors activated (3729.28 BogoMIPS) [ 2.953422] devtmpfs: initialized [ 2.986303] atomic64 test passed for x86-64 platform with CX8 and with SSE [ 2.987008] pinctrl core: initialized pinctrl subsystem [ 3.014010] RTC time: 4:59:11, date: 11/27/12 [ 3.025263] NET: Registered protocol family 16 [ 3.036517] cpuidle: using governor menu [ 3.051112] PCI: Using configuration type 1 for base access [ 3.096303] bio: create slab <bio-0> at 0 [ 3.106070] ACPI: Interpreter disabled. [ 3.115506] vgaarb: loaded [ 3.124262] SCSI subsystem initialized [ 3.133518] usbcore: registered new interface driver usbfs [ 3.145083] usbcore: registered new interface driver hub [ 3.156220] usbcore: registered new device driver usb [ 3.167902] PCI: Probing PCI hardware [ 3.176276] PCI host bridge to bus 0000:00 [ 3.178010] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 3.180009] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff] [ 3.182009] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff] [ 3.349544] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000] [ 3.365309] NetLabel: Initializing [ 3.366008] NetLabel: domain hash size = 128 [ 3.367007] NetLabel: protocols = UNLABELED CIPSOv4 [ 3.383010] NetLabel: unlabeled traffic allowed by default [ 3.401127] Switched to clocksource kvm-clock [ 3.612704] pnp: PnP ACPI: disabled [ 3.657420] NET: Registered protocol family 2 [ 3.667886] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 3.680939] TCP bind hash table entries: 4096 (order: 6, 327680 bytes) [ 3.693109] TCP: Hash tables configured (established 4096 bind 4096) [ 3.704434] TCP: reno registered [ 3.710208] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 3.720675] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 3.734930] NET: Registered protocol family 1 [ 3.742806] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 3.753557] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 3.763836] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 3.775550] pci 0000:00:01.2: PCI->APIC IRQ transform: INT D -> IRQ 35 [ 3.790473] Unpacking initramfs... [ 3.828960] Freeing initrd memory: 1644K (ffff88001f255000 - ffff88001f3f0000) [ 4.470673] DMA-API: preallocated 65536 debug entries [ 4.479649] DMA-API: debugging enabled by kernel config [ 4.489623] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 4.506048] Scanning for low memory corruption every 60 seconds [ 4.520588] cryptomgr_test (24) used greatest stack depth: 6648 bytes left [ 4.533862] cryptomgr_test (23) used greatest stack depth: 6304 bytes left [ 4.545966] cryptomgr_test (22) used greatest stack depth: 6128 bytes left [ 4.558542] cryptomgr_test (21) used greatest stack depth: 6016 bytes left [ 4.570750] kworker/u2:0 (29) used greatest stack depth: 5632 bytes left [ 4.583578] kworker/u2:1 (31) used greatest stack depth: 5240 bytes left [ 4.596866] cryptomgr_test (26) used greatest stack depth: 5104 bytes left [ 4.609678] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni) [ 4.627548] Initialise system trusted keyring [ 4.635580] audit: initializing netlink socket (disabled) [ 4.644827] type=2000 audit(1353992352.223:1): initialized [ 4.697855] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 4.770476] zbud: loaded [ 4.781555] VFS: Disk quotas dquot_6.5.2 [ 4.788907] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 4.812627] msgmni has been set to 917 [ 4.821022] Key type big_key registered [ 4.836758] alg: No test for stdrng (krng) [ 4.843963] NET: Registered protocol family 38 [ 4.851782] Key type asymmetric registered [ 4.859117] Asymmetric key parser 'x509' registered [ 4.868053] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 4.881871] io scheduler noop registered [ 4.889177] io scheduler deadline registered [ 4.898921] io scheduler cfq registered (default) [ 4.924739] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 4.934604] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 4.947818] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.017587] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.063809] Non-volatile memory driver v1.3 [ 5.075619] Linux agpgart interface v0.103 [ 5.130943] scsi0 : ata_piix [ 5.149706] scsi1 : ata_piix [ 5.162043] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14 [ 5.181561] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15 [ 5.210916] libphy: Fixed MDIO Bus: probed [ 5.226556] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 5.248443] ehci-pci: EHCI PCI platform driver [ 5.256494] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 5.267228] ohci-pci: OHCI PCI platform driver [ 5.274971] uhci_hcd: USB Universal Host Controller Interface driver [ 5.287408] uhci_hcd 0000:00:01.2: PCI->APIC IRQ transform: INT D -> IRQ 35 [ 5.301353] uhci_hcd 0000:00:01.2: UHCI Host Controller [ 5.311995] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 [ 5.326351] uhci_hcd 0000:00:01.2: irq 35, io base 0x0000c040 [ 5.338469] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 [ 5.350786] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 5.364365] usb usb1: Product: UHCI Host Controller [ 5.374246] usb usb1: Manufacturer: Linux 3.13.0-0.rc4.git5.1.fc21.x86_64 uhci_hcd [ 5.397085] usb usb1: SerialNumber: 0000:00:01.2 [ 5.413924] hub 1-0:1.0: USB hub found [ 5.421573] hub 1-0:1.0: 2 ports detected [ 5.433626] usbcore: registered new interface driver usbserial [ 5.444719] usbcore: registered new interface driver usbserial_generic [ 5.457612] usbserial: USB Serial support registered for generic [ 5.468939] i8042: PNP: No PS/2 controller found. Probing ports directly. [ 5.492474] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.502707] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.514913] mousedev: PS/2 mouse device common for all mice [ 5.531992] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 5.555586] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0 [ 5.569826] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram [ 5.583892] device-mapper: uevent: version 1.0.3 [ 5.594932] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30) initialised: dm-devel@redhat.com [ 5.698699] Intel P-state driver initializing. [ 5.719048] Intel pstate controlling: cpu 0 [ 5.726438] cpufreq: __cpufreq_add_dev: ->get() failed [ 5.735501] divide error: 0000 [#1] SMP [ 5.736319] Modules linked in: [ 5.736319] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.13.0-0.rc4.git5.1.fc21.x86_64 #1 [ 5.736319] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 5.736319] task: ffff88001ea20000 ti: ffff88001e9bc000 task.ti: ffff88001e9bc000 [ 5.736319] RIP: 0010:[<ffffffff815c551d>] [<ffffffff815c551d>] intel_pstate_timer_func+0x11d/0x2b0 [ 5.736319] RSP: 0000:ffff88001ee03e00 EFLAGS: 00010246 [ 5.736319] RAX: 0000000000000000 RBX: ffff88001a4afb10 RCX: 0000000000006100 [ 5.736319] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000 [ 5.736319] RBP: ffff88001ee03e20 R08: 0000000000000000 R09: 0000000000000000 [ 5.736319] R10: ffff88001ea20000 R11: 0000000000000000 R12: 00000da01ea20000 [ 5.736319] R13: 1ea200001ea20000 R14: ffffffff815c5400 R15: ffff88001a4afb10 [ 5.736319] FS: 0000000000000000(0000) GS:ffff88001ee00000(0000) knlGS:0000000000000000 [ 5.736319] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 5.736319] CR2: 0000000000000000 CR3: 0000000001c0c000 CR4: 00000000000006f0 [ 5.736319] Stack: [ 5.736319] fffffffb1a4afb58 ffffffff821a4500 ffff88001a4afb58 0000000000000100 [ 5.736319] ffff88001ee03e90 ffffffff81083e9a ffffffff81083e15 ffffffff82d5ed40 [ 5.736319] ffffffff82590490 0000000000000000 ffffffff81ac39de 0000000000000000 [ 5.736319] Call Trace: [ 5.736319] <IRQ> [ 5.736319] [<ffffffff81083e9a>] call_timer_fn+0x8a/0x310 [ 5.736319] [<ffffffff81083e15>] ? call_timer_fn+0x5/0x310 [ 5.736319] [<ffffffff815c5400>] ? pid_param_set+0x130/0x130 [ 5.736319] [<ffffffff81084354>] run_timer_softirq+0x234/0x380 [ 5.736319] [<ffffffff8107aee4>] __do_softirq+0x104/0x430 [ 5.736319] [<ffffffff8107b5fd>] irq_exit+0xcd/0xe0 [ 5.736319] [<ffffffff81770596>] do_IRQ+0x56/0xc0 [ 5.736319] [<ffffffff817650b2>] common_interrupt+0x72/0x72 [ 5.736319] <EOI> [ 5.736319] [<ffffffff810e15cd>] ? vprintk_emit+0x1dd/0x5e0 [ 5.736319] [<ffffffff81757719>] printk+0x67/0x69 [ 5.736319] [<ffffffff815c1493>] __cpufreq_add_dev.isra.13+0x883/0x8d0 [ 5.736319] [<ffffffff815c14f0>] cpufreq_add_dev+0x10/0x20 [ 5.736319] [<ffffffff814a14d1>] subsys_interface_register+0xb1/0xf0 [ 5.736319] [<ffffffff815bf5cf>] cpufreq_register_driver+0x9f/0x210 [ 5.736319] [<ffffffff81fb19af>] intel_pstate_init+0x27d/0x3be [ 5.736319] [<ffffffff81761e3e>] ? mutex_unlock+0xe/0x10 [ 5.736319] [<ffffffff81fb1732>] ? cpufreq_gov_dbs_init+0x12/0x12 [ 5.736319] [<ffffffff8100214a>] do_one_initcall+0xfa/0x1b0 [ 5.736319] [<ffffffff8109dbf5>] ? parse_args+0x225/0x3f0 [ 5.736319] [<ffffffff81f64193>] kernel_init_freeable+0x1fc/0x287 [ 5.736319] [<ffffffff81f638d0>] ? do_early_param+0x88/0x88 [ 5.736319] [<ffffffff8174b530>] ? rest_init+0x150/0x150 [ 5.736319] [<ffffffff8174b53e>] kernel_init+0xe/0x130 [ 5.736319] [<ffffffff8176e27c>] ret_from_fork+0x7c/0xb0 [ 5.736319] [<ffffffff8174b530>] ? rest_init+0x150/0x150 [ 5.736319] Code: c1 e0 05 48 63 bc 03 10 01 00 00 48 63 83 d0 00 00 00 48 63 d6 48 c1 e2 08 c1 e1 08 4c 63 c2 48 c1 e0 08 48 98 48 c1 e0 08 48 99 <49> f7 f8 48 98 48 0f af f8 48 c1 ff 08 29 f9 89 ca c1 fa 1f 89 [ 5.736319] RIP [<ffffffff815c551d>] intel_pstate_timer_func+0x11d/0x2b0 [ 5.736319] RSP <ffff88001ee03e00> [ 6.396576] ---[ end trace 7c6c316068159299 ]--- [ 6.404759] Kernel panic - not syncing: Fatal exception in interrupt [ 6.405680] Rebooting in 1 seconds..libguestfs: error: appliance closed the connection unexpectedly, see earlier error messages libguestfs: child_cleanup: 0x7fcba3406190: child process died libguestfs: error: guestfs_launch failed, see earlier error messages libguestfs-test-tool: failed to launch appliance libguestfs: closing guestfs handle 0x7fcba3406190 (state 0) libguestfs: command: run: rm libguestfs: command: run: \ -rf /tmp/libguestfs61jk02 ====== Expected results ---------------- Kernel should not panic and libguestfs should create appliance successfully. More details in the Fedora Kernel bug report -- https://bugzilla.redhat.com/show_bug.cgi?id=1046317
See also linux-kernel thread: "intel_pstate divide error with v3.13-rc4-256-gb7000ad"
Created attachment 119701 [details] Full dmesg output captured from the VM NOTE: dmesg output was captured after invoking libguestfs-test-tool which resulted in the Kernel panic.
Created attachment 119751 [details] dmesg output from physical host I forgot to note this is in a nested environment. Host is running RHEL7 (beta). Here's the versions: $ uname -r; rpm -q libvirt-daemon-kvm qemu-kvm-rhev 3.10.0-52.el7.x86_64 libvirt-daemon-kvm-1.1.1-12.el7.x86_64 qemu-kvm-rhev-1.5.3-19.el7.x86_64
This patch fixes it: https://patchwork.kernel.org/patch/3421271/ I re-built the Kernel on L0 (physical host) and L1 (guest hypervisor) with the above patch, and re-ran the libguestfs-test-tool, which invokes an L2 KVM guest -- it now successfully boots.
Created attachment 120401 [details] stdout of libguestfs-test-tool run, which shows a successful boot-up of a nested KVM guest.