Bug 67761 - Kernel fails to boot under virtualization, kernel panic in intel_pstate_timer_func
Summary: Kernel fails to boot under virtualization, kernel panic in intel_pstate_timer...
Status: RESOLVED CODE_FIX
Alias: None
Product: Virtualization
Classification: Unclassified
Component: kvm (show other bugs)
Hardware: All Linux
: P1 normal
Assignee: virtualization_kvm
URL:
Keywords:
Depends on:
Blocks:
 
Reported: 2013-12-26 10:00 UTC by Kashyap Chamarthy
Modified: 2014-01-27 09:12 UTC (History)
1 user (show)

See Also:
Kernel Version: 3.13-rc4
Tree: Fedora
Regression: No


Attachments
Full dmesg output captured from the VM (52.24 KB, text/plain)
2013-12-27 14:07 UTC, Kashyap Chamarthy
Details
dmesg output from physical host (117.92 KB, text/plain)
2013-12-27 17:11 UTC, Kashyap Chamarthy
Details
stdout of libguestfs-test-tool run, which shows a successful boot-up of a nested KVM guest. (45.63 KB, text/plain)
2013-12-31 10:02 UTC, Kashyap Chamarthy
Details

Description Kashyap Chamarthy 2013-12-26 10:00:49 UTC
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
Comment 1 Alan 2013-12-27 12:25:07 UTC
See also linux-kernel thread: "intel_pstate divide error with v3.13-rc4-256-gb7000ad"
Comment 2 Kashyap Chamarthy 2013-12-27 14:07:39 UTC
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.
Comment 3 Kashyap Chamarthy 2013-12-27 17:11:36 UTC
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
Comment 4 Kashyap Chamarthy 2013-12-31 10:00:43 UTC
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.
Comment 5 Kashyap Chamarthy 2013-12-31 10:02:59 UTC
Created attachment 120401 [details]
stdout of libguestfs-test-tool run, which shows a successful boot-up of a nested KVM guest.

Note You need to log in before you can comment on or make changes to this bug.