Bug 1223969

Summary: `supermin` fails to find the root device, resulting in a Kernel panic
Product: [Fedora] Fedora Reporter: Kashyap Chamarthy <kchamart>
Component: superminAssignee: Richard W.M. Jones <rjones>
Status: CLOSED RAWHIDE QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: rawhideCC: ptoscano, rjones
Target Milestone: ---   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: supermin-5.1.12-6.fc23.x86_64 Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2015-05-22 08:25:44 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:

Description Kashyap Chamarthy 2015-05-21 20:16:00 UTC
Description of problem
----------------------

Running `libguestfs-test-tool` with 'direct' backend results in
(complete, unedited output in the next comment):

[. . .]
supermin: waiting another 32768000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 65536000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 131072000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 262144000000 ns for /sys/block/sdb/dev to appear
supermin: no ext2 root device found
[. . .]
Please include FULL verbose output in your bug report.
[  524.581179] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100
[  524.581179] 
[  524.581869] CPU: 0 PID: 1 Comm: init Not tainted 4.0.0-1.fc22.x86_64 #1
[  524.582011] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.8.1-20150318_183358- 04/01/2014
[  524.582011]  0000000000000000 00000000653c231d ffff88001ddb7e38 ffffffff81783088
[  524.582011]  0000000000000000 ffffffff81a4c3d0 ffff88001ddb7eb8 ffffffff81781ebe
[  524.582011]  ffffffff00000010 ffff88001ddb7ec8 ffff88001ddb7e68 00000000653c231d
[  524.582011] Call Trace:
[  524.582011]  [<ffffffff81783088>] dump_stack+0x45/0x57
[  524.582011]  [<ffffffff81781ebe>] panic+0xd0/0x203
[  524.582011]  [<ffffffff8109f63d>] do_exit+0xb3d/0xb40
[  524.582011]  [<ffffffff8121dcf4>] ? vfs_write+0x184/0x210
[  524.582011]  [<ffffffff8109f6d7>] do_group_exit+0x47/0xb0
[  524.582011]  [<ffffffff8109f754>] SyS_exit_group+0x14/0x20
[  524.582011]  [<ffffffff81789509>] system_call_fastpath+0x12/0x17
[  524.582011] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)
[  524.582011] Rebooting in 1 seconds..libguestfs: error: appliance closed the connection unexpectedly, see earlier error messages


Version
-------

    $ uname -r; rpm -q libguestfs-tools-c supermin
    4.0.0-1.fc22.x86_64
    libguestfs-tools-c-1.29.43-1.fc23.x86_64
    supermin-5.1.12-6.fc23.x86_64


How reproducible: Consistently.


Steps to Reproduce
------------------

(1) Use the direct backend:

    $ export LIBGUESTFS_BACKEND=direct

(2) Run the `libguestfs-test-tool`:

    $ libguestfs-test-tool


Actual results
--------------

`supermin` appliance fails to build as it can't find the root device,
the Kernel panics and throws a call trace.


Expected results
----------------

`supermin` appliance should build successfully.


Additional info
---------------

`qemu-sanity-check --accel=kvm` works just fine.

Comment 1 Kashyap Chamarthy 2015-05-21 20:19:31 UTC
Complete output of `libguestfs-test-tool`:

$ libguestfs-test-tool
     ************************************************************
     *                    IMPORTANT NOTICE
     *
     * When reporting bugs, include the COMPLETE, UNEDITED
     * output below in your bug report.
     *
     ************************************************************
LIBGUESTFS_BACKEND=direct
PATH=/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/home/kashyapc/.local/bin:/home/kashyapc/bin
SELinux: Permissive
guestfs_get_append: (null)
guestfs_get_autosync: 1
guestfs_get_backend: direct
guestfs_get_backend_settings: []
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.29.43fedora=23,release=1.fc23,libvirt
libguestfs: launch: backend registered: unix
libguestfs: launch: backend registered: uml
libguestfs: launch: backend registered: libvirt
libguestfs: launch: backend registered: direct
libguestfs: launch: backend=direct
libguestfs: launch: tmpdir=/tmp/libguestfsEsUlSz
libguestfs: launch: umask=0002
libguestfs: launch: euid=1001
libguestfs: [00000ms] begin building supermin appliance
libguestfs: [00000ms] run supermin
libguestfs: command: run: /usr/bin/supermin
libguestfs: command: run: \ --build
libguestfs: command: run: \ --verbose
libguestfs: command: run: \ --if-newer
libguestfs: command: run: \ --lock /var/tmp/.guestfs-1001/lock
libguestfs: command: run: \ --copy-kernel
libguestfs: command: run: \ -f ext2
libguestfs: command: run: \ --host-cpu x86_64
libguestfs: command: run: \ /usr/lib64/guestfs/supermin.d
libguestfs: command: run: \ -o /var/tmp/.guestfs-1001/appliance.d
supermin: version: 5.1.12
supermin: rpm: detected RPM version 4.12
supermin: package handler: fedora/rpm
supermin: acquiring lock on /var/tmp/.guestfs-1001/lock
supermin: build: /usr/lib64/guestfs/supermin.d
supermin: reading the supermin appliance
supermin: build: visiting /usr/lib64/guestfs/supermin.d/base.tar.gz type gzip base image (tar)
supermin: build: visiting /usr/lib64/guestfs/supermin.d/daemon.tar.gz type gzip base image (tar)
supermin: build: visiting /usr/lib64/guestfs/supermin.d/excludefiles type uncompressed excludefiles
supermin: build: visiting /usr/lib64/guestfs/supermin.d/hostfiles type uncompressed hostfiles
supermin: build: visiting /usr/lib64/guestfs/supermin.d/init.tar.gz type gzip base image (tar)
supermin: build: visiting /usr/lib64/guestfs/supermin.d/packages type uncompressed packages
supermin: build: visiting /usr/lib64/guestfs/supermin.d/udev-rules.tar.gz type gzip base image (tar)
supermin: mapping package names to installed packages
supermin: resolving full list of package dependencies
supermin: build: 200 packages, including dependencies
supermin: build: 32064 files
supermin: build: 17830 files, after removing unreadable files
supermin: build: 8300 files, after matching excludefiles
supermin: build: 8311 files, after adding hostfiles
supermin: build: 8337 files, after munging
supermin: kernel: picked kernel vmlinuz-4.0.0-1.fc22.x86_64
supermin: kernel: picked modules path /lib/modules/4.0.0-1.fc22.x86_64
supermin: kernel: kernel_version 4.0.0-1.fc22.x86_64
supermin: kernel: modules /lib/modules/4.0.0-1.fc22.x86_64
supermin: ext2: creating empty ext2 filesystem '/var/tmp/.guestfs-1001/appliance.d.65kyuq4j/root'
supermin: ext2: populating from base image
supermin: ext2: copying files from host filesystem
supermin: open: /usr/bin/chfn: Permission denied
supermin: open: /usr/bin/chsh: Permission denied
supermin: open: /usr/libexec/dbus-1/dbus-daemon-launch-helper: Permission denied
supermin: open: /usr/libexec/utempter/utempter: Permission denied
supermin: open: /usr/sbin/build-locale-archive: Permission denied
supermin: open: /usr/sbin/glibc_post_upgrade.x86_64: Permission denied
supermin: open: /usr/sbin/groupadd: Permission denied
supermin: open: /usr/sbin/groupdel: Permission denied
supermin: open: /usr/sbin/groupmems: Permission denied
supermin: open: /usr/sbin/groupmod: Permission denied
supermin: open: /usr/sbin/unix_update: Permission denied
supermin: open: /usr/sbin/useradd: Permission denied
supermin: open: /usr/sbin/userdel: Permission denied
supermin: open: /usr/sbin/usermod: Permission denied
supermin: open: /var/lib/systemd/random-seed: Permission denied
supermin: ext2: copying kernel modules
supermin: ext2: creating minimal initrd '/var/tmp/.guestfs-1001/appliance.d.65kyuq4j/initrd'
supermin: ext2: wrote 28 modules to minimal initrd
supermin: renaming /var/tmp/.guestfs-1001/appliance.d.65kyuq4j to /var/tmp/.guestfs-1001/appliance.d
libguestfs: [03274ms] finished building supermin appliance
libguestfs: [03274ms] begin testing qemu features
libguestfs: command: run: /usr/bin/qemu-kvm
libguestfs: command: run: \ -display none
libguestfs: command: run: \ -help
libguestfs: command: run: /usr/bin/qemu-kvm
libguestfs: command: run: \ -display none
libguestfs: command: run: \ -version
libguestfs: qemu version 2.2
libguestfs: command: run: /usr/bin/qemu-kvm
libguestfs: command: run: \ -display none
libguestfs: command: run: \ -machine accel=kvm:tcg
libguestfs: command: run: \ -device ?
libguestfs: [03375ms] finished testing qemu features
[03376ms] /usr/bin/qemu-kvm \
    -global virtio-blk-pci.scsi=off \
    -nodefconfig \
    -enable-fips \
    -nodefaults \
    -display none \
    -cpu host \
    -machine accel=kvm:tcg \
    -m 500 \
    -no-reboot \
    -rtc driftfix=slew \
    -no-hpet \
    -global kvm-pit.lost_tick_policy=discard \
    -kernel /var/tmp/.guestfs-1001/appliance.d/kernel \
    -initrd /var/tmp/.guestfs-1001/appliance.d/initrd \
    -device virtio-scsi-pci,id=scsi \
    -drive file=/tmp/libguestfsEsUlSz/scratch.1,cache=unsafe,format=raw,id=hd0,if=none \
    -device scsi-hd,drive=hd0 \
    -drive file=/var/tmp/.guestfs-1001/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \
    -device scsi-hd,drive=appliance \
    -device virtio-serial-pci \
    -serial stdio \
    -device sga \
    -chardev socket,path=/tmp/libguestfsEsUlSz/guestfsd.sock,id=channel0 \
    -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \
    -append 'panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=screen'
\x1b[1;256r\x1b[256;256H\x1b[6n
Google, Inc.
Serial Graphics Adapter 06/08/14
SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (mockbuild@) Sun Jun  8 11:52:13 UTC 2014
Term: 80x24
4 0
\x1b[2J
SeaBIOS (version 1.8.1-20150318_183358-)


Booting from ROM...


Probing EDD (edd=off to disable)... ok

\x1b[2J[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.0.0-1.fc22.x86_64 (mockbuild.fedoraproject.org) (gcc version 5.0.0 20150319 (Red Hat 5.0.0-0.21) (GCC) ) #1 SMP Mon Apr 13 10:03:33 UTC 2015
[    0.000000] Command line: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 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-0x000000000009f7ff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3dffff] usable
[    0.000000] BIOS-e820: [mem 0x000000001f3e0000-0x000000001f3fffff] reserved
[    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.8 present.
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: last_pfn = 0x1f3e0 max_arch_pfn = 0x400000000
[    0.000000] PAT configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- UC  
[    0.000000] found SMP MP-table at [mem 0x000f65a0-0x000f65af] mapped at [ffff8800000f65a0]
[    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 0x00100000-0x1effffff]
[    0.000000] init_memory_mapping: [mem 0x1f200000-0x1f3dffff]
[    0.000000] RAMDISK: [mem 0x1f2d0000-0x1f3dffff]
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3dffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x1f2bc000-0x1f2cffff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:1f2ac001, primary cpu clock
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x000000001f3dffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000001f3dffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3dffff]
[    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 0x00000000-0x00000fff]
[    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:1024 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] PERCPU: Embedded 32 pages/cpu @ffff88001f000000 s91608 r8192 d31272 u2097152
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 1f00e100
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 125849
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 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] xsave: enabled xstate_bv 0x7, cntxt size 0x340 using standard form
[    0.000000] Memory: 485076K/511480K available (7737K kernel code, 1149K rwdata, 3340K rodata, 1500K init, 1524K bss, 26404K reserved, 0K cma-reserved)
[    0.000000] SLUB: 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] \tRCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=1.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.000000] NR_IRQS:65792 nr_irqs:256 16
[    0.000000] \tOffload RCU callbacks from all CPUs
[    0.000000] \tOffload RCU callbacks from CPUs: 0.
[    0.000000] Console: colour *CGA 80x25
[    0.000000] console [ttyS0] enabled
[    0.000000] tsc: Detected 2294.686 MHz processor
[    0.001000] Calibrating delay loop (skipped) preset value.. 4589.37 BogoMIPS (lpj=2294686)
[    0.001000] pid_max: default: 32768 minimum: 301
[    0.001022] Security Framework initialized
[    0.001267] SELinux:  Disabled at boot.
[    0.001491] Yama: becoming mindful.
[    0.001821] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.002075] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.002530] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.003003] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.003524] Initializing cgroup subsys blkio
[    0.004004] Initializing cgroup subsys memory
[    0.004260] Initializing cgroup subsys devices
[    0.004520] Initializing cgroup subsys freezer
[    0.004785] Initializing cgroup subsys net_cls
[    0.005003] Initializing cgroup subsys perf_event
[    0.005280] Initializing cgroup subsys net_prio
[    0.005558] Initializing cgroup subsys hugetlb
[    0.006862] mce: CPU supports 10 MCE banks
[    0.007037] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.007389] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.015617] Freeing SMP alternatives memory: 28K (ffffffff81e98000 - ffffffff81e9f000)
[    0.018243] ftrace: allocating 27819 entries in 109 pages
[    0.024207] x2apic enabled
[    0.025003] Switched APIC routing to physical x2apic.
[    0.026171] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.026536] smpboot: CPU0: Intel(R) Core(TM) i5-4670T CPU @ 2.30GHz (fam: 06, model: 3c, stepping: 03)
[    0.027000] Performance Events: 16-deep LBR, Haswell events, Intel PMU driver.
[    0.031137] ... version:                2
[    0.031356] ... bit width:              48
[    0.031578] ... generic registers:      8
[    0.031797] ... value mask:             0000ffffffffffff
[    0.032002] ... max period:             000000007fffffff
[    0.032365] ... fixed-purpose events:   3
[    0.032583] ... event mask:             00000007000000ff
[    0.033405] x86: Booted up 1 node, 1 CPUs
[    0.033674] smpboot: Total of 1 processors activated (4589.37 BogoMIPS)
[    0.034171] NMI watchdog: disabled (cpu0): hardware events not enabled
[    0.034621] devtmpfs: initialized
[    0.036168] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[    0.036573] pinctrl core: initialized pinctrl subsystem
[    0.036903] RTC time: 19:18:24, date: 05/21/15
[    0.037078] NET: Registered protocol family 16
[    0.037514] cpuidle: using governor menu
[    0.037865] PCI: Using configuration type 1 for base access
[    0.038869] ACPI: Interpreter disabled.
[    0.039056] vgaarb: loaded
[    0.039250] SCSI subsystem initialized
[    0.039503] usbcore: registered new interface driver usbfs
[    0.039806] usbcore: registered new interface driver hub
[    0.040006] usbcore: registered new device driver usb
[    0.040351] PCI: Probing PCI hardware
[    0.040573] PCI host bridge to bus 0000:00
[    0.040808] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    0.041003] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]
[    0.041382] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.045542] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.045961] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.046002] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.046418] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.047083] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.047528] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.060253] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]
[    0.061189] NetLabel: Initializing
[    0.061467] NetLabel:  domain hash size = 128
[    0.061800] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.062010] NetLabel:  unlabeled traffic allowed by default
[    0.062438] Switched to clocksource kvm-clock
[    0.065506] pnp: PnP ACPI: disabled
[    0.066504] NET: Registered protocol family 2
[    0.066958] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[    0.067372] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
[    0.067738] TCP: Hash tables configured (established 4096 bind 4096)
[    0.068297] TCP: reno registered
[    0.068479] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.068796] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.069254] NET: Registered protocol family 1
[    0.069518] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.069859] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.070259] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.070673] Unpacking initramfs...
[    0.071351] Freeing initrd memory: 1088K (ffff88001f2d0000 - ffff88001f3e0000)
[    0.071851] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.078197] microcode: CPU0 sig=0x306c3, pf=0x1, revision=0x1
[    0.078607] microcode: Microcode Update Driver: v2.00 <tigran.co.uk>, Peter Oruba
[    0.079229] AVX2 version of gcm_enc/dec engaged.
[    0.079576] AES CTR mode by8 optimization enabled
[    0.080030] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni)
[    0.080594] futex hash table entries: 256 (order: 2, 16384 bytes)
[    0.080991] Initialise system trusted keyring
[    0.081260] audit: initializing netlink subsys (disabled)
[    0.081562] audit: type=2000 audit(1432235905.746:1): initialized
[    0.082195] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.083586] zpool: loaded
[    0.083761] zbud: loaded
[    0.084043] VFS: Disk quotas dquot_6.5.2
[    0.084339] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.085101] Key type big_key registered
[    0.085651] alg: No test for stdrng (krng)
[    0.085951] NET: Registered protocol family 38
[    0.086255] Key type asymmetric registered
[    0.086492] Asymmetric key parser 'x509' registered
[    0.086796] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.087325] io scheduler noop registered
[    0.087554] io scheduler deadline registered
[    0.087832] io scheduler cfq registered (default)
[    0.088364] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.088730] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.089242] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.112260] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    0.112999] Non-volatile memory driver v1.3
[    0.113291] Linux agpgart interface v0.103
[    0.114325] scsi host0: ata_piix
[    0.114622] scsi host1: ata_piix
[    0.114840] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14
[    0.115258] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15
[    0.115886] libphy: Fixed MDIO Bus: probed
[    0.116173] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.116545] ehci-pci: EHCI PCI platform driver
[    0.116872] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.117253] ohci-pci: OHCI PCI platform driver
[    0.117516] uhci_hcd: USB Universal Host Controller Interface driver
[    0.117949] usbcore: registered new interface driver usbserial
[    0.118304] usbcore: registered new interface driver usbserial_generic
[    0.118684] usbserial: USB Serial support registered for generic
[    0.119099] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    0.119900] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.120288] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.120749] mousedev: PS/2 mouse device common for all mice
[    0.121257] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.122816] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    0.123227] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram
[    0.123626] device-mapper: uevent: version 1.0.3
[    0.123923] device-mapper: ioctl: 4.30.0-ioctl (2014-12-22) initialised: dm-devel
[    0.124560] hidraw: raw HID events driver (C) Jiri Kosina
[    0.124929] usbcore: registered new interface driver usbhid
[    0.125310] usbhid: USB HID core driver
[    0.125554] drop_monitor: Initializing network drop monitor service
[    0.125953] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.126337] TCP: cubic registered
[    0.126549] Initializing XFRM netlink socket
[    0.126854] NET: Registered protocol family 10
[    0.127253] mip6: Mobile IPv6
[    0.127501] NET: Registered protocol family 17
[    0.127918] Loading compiled-in X.509 certificates
[    0.128850] Loaded X.509 cert 'Fedora kernel signing key: d0cbee4318c9d255a27f7577c0e6d2f9e5322f4f'
[    0.129375] registered taskstats version 1
[    0.129727]   Magic number: 15:857:344
[    0.129949] tty tty61: hash matches
[    0.130292] rtc_cmos rtc_cmos: setting system clock to 2015-05-21 19:18:25 UTC (1432235905)
[    0.271491] Freeing unused kernel memory: 1500K (ffffffff81d21000 - ffffffff81e98000)
[    0.272150] Write protecting the kernel read-only data: 12288k
[    0.272750] Freeing unused kernel memory: 444K (ffff880001791000 - ffff880001800000)
[    0.273339] Freeing unused kernel memory: 756K (ffff880001b43000 - ffff880001c00000)
supermin: mounting /proc
supermin: uptime: 0.27 0.16
supermin: ext2 mini initrd starting up: 5.1.12
supermin: cmdline: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=screen
supermin: mounting /sys
supermin: internal insmod crc32-pclmul.ko.xz
insmod: init_module: crc32-pclmul.ko.xz: Invalid module format
supermin: internal insmod crc32c-intel.ko.xz
insmod: init_module: crc32c-intel.ko.xz: Invalid module format
supermin: internal insmod crct10dif-pclmul.ko.xz
insmod: init_module: crct10dif-pclmul.ko.xz: Invalid module format
supermin: internal insmod crc32.ko.xz
insmod: init_module: crc32.ko.xz: Invalid module format
supermin: internal insmod virtio.ko.xz
insmod: init_module: virtio.ko.xz: Invalid module format
supermin: internal insmod virtio_ring.ko.xz
insmod: init_module: virtio_ring.ko.xz: Invalid module format
supermin: internal insmod virtio_blk.ko.xz
insmod: init_module: virtio_blk.ko.xz: Invalid module format
supermin: internal insmod virtio-rng.ko.xz
insmod: init_module: virtio-rng.ko.xz: Invalid module format
supermin: internal insmod virtio_console.ko.xz
insmod: init_module: virtio_console.ko.xz: Invalid module format
supermin: internal insmod ideapad_slidebar.ko.xz
insmod: init_module: ideapad_slidebar.ko.xz: Invalid module format
supermin: internal insmod virtio_net.ko.xz
insmod: init_module: virtio_net.ko.xz: Invalid module format
supermin: internal insmod sparse-keymap.ko.xz
insmod: init_module: sparse-keymap.ko.xz: Invalid module format
supermin: internal insmod rfkill.ko.xz
insmod: init_module: rfkill.ko.xz: Invalid module format
supermin: internal insmod ideapad-laptop.ko.xz
insmod: init_module: ideapad-laptop.ko.xz: Invalid module format
supermin: internal insmod megaraid.ko.xz
insmod: init_module: megaraid.ko.xz: Invalid module format
supermin: internal insmod megaraid_mm.ko.xz
insmod: init_module: megaraid_mm.ko.xz: Invalid module format
supermin: internal insmod megaraid_mbox.ko.xz
insmod: init_module: megaraid_mbox.ko.xz: Invalid module format
supermin: internal insmod megaraid_sas.ko.xz
insmod: init_module: megaraid_sas.ko.xz: Invalid module format
supermin: internal insmod scsi_transport_spi.ko.xz
insmod: init_module: scsi_transport_spi.ko.xz: Invalid module format
supermin: internal insmod sym53c8xx.ko.xz
insmod: init_module: sym53c8xx.ko.xz: Invalid module format
supermin: internal insmod virtio_scsi.ko.xz
insmod: init_module: virtio_scsi.ko.xz: Invalid module format
supermin: internal insmod virtio_balloon.ko.xz
insmod: init_module: virtio_balloon.ko.xz: Invalid module format
supermin: internal insmod virtio_mmio.ko.xz
insmod: init_module: virtio_mmio.ko.xz: Invalid module format
supermin: internal insmod virtio_pci.ko.xz
insmod: init_module: virtio_pci.ko.xz: Invalid module format
supermin: internal insmod crc-ccitt.ko.xz
insmod: init_module: crc-ccitt.ko.xz: Invalid module format
supermin: internal insmod crc-itu-t.ko.xz
insmod: init_module: crc-itu-t.ko.xz: Invalid module format
supermin: internal insmod crc8.ko.xz
insmod: init_module: crc8.ko.xz: Invalid module format
supermin: internal insmod libcrc32c.ko.xz
insmod: init_module: libcrc32c.ko.xz: Invalid module format
[    0.953895] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
supermin: waiting another 1024000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 2048000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 4096000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 8192000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 16384000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 32768000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 65536000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 131072000000 ns for /sys/block/sdb/dev to appear
supermin: waiting another 262144000000 ns for /sys/block/sdb/dev to appear
supermin: no ext2 root device found
Please include FULL verbose output in your bug report.
[  524.581179] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00000100
[  524.581179] 
[  524.581869] CPU: 0 PID: 1 Comm: init Not tainted 4.0.0-1.fc22.x86_64 #1
[  524.582011] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.8.1-20150318_183358- 04/01/2014
[  524.582011]  0000000000000000 00000000653c231d ffff88001ddb7e38 ffffffff81783088
[  524.582011]  0000000000000000 ffffffff81a4c3d0 ffff88001ddb7eb8 ffffffff81781ebe
[  524.582011]  ffffffff00000010 ffff88001ddb7ec8 ffff88001ddb7e68 00000000653c231d
[  524.582011] Call Trace:
[  524.582011]  [<ffffffff81783088>] dump_stack+0x45/0x57
[  524.582011]  [<ffffffff81781ebe>] panic+0xd0/0x203
[  524.582011]  [<ffffffff8109f63d>] do_exit+0xb3d/0xb40
[  524.582011]  [<ffffffff8121dcf4>] ? vfs_write+0x184/0x210
[  524.582011]  [<ffffffff8109f6d7>] do_group_exit+0x47/0xb0
[  524.582011]  [<ffffffff8109f754>] SyS_exit_group+0x14/0x20
[  524.582011]  [<ffffffff81789509>] system_call_fastpath+0x12/0x17
[  524.582011] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)
[  524.582011] Rebooting in 1 seconds..libguestfs: error: appliance closed the connection unexpectedly, see earlier error messages
libguestfs: child_cleanup: 0x7f8ba3c51360: child process died
libguestfs: sending SIGTERM to process 4891
libguestfs: error: guestfs_launch failed, see earlier error messages
libguestfs-test-tool: failed to launch appliance
libguestfs: closing guestfs handle 0x7f8ba3c51360 (state 0)
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfsEsUlSz

Comment 2 Kashyap Chamarthy 2015-05-22 08:25:44 UTC
Fixed in: supermin-5.1.12-6.fc23.x86_64

Root cause:

`supermin` was unable to load the virtio modules because they were xz compressed. (Thanks, Rich Jones.)