Prettier report to the user when /dev/kvm is not accessible #1042

janhavlin · 2022-02-09T10:36:41Z

When /dev/kvm is unavailable, the user should get a more reasonable warning.

Sample error output (click to reveal)

$ tmt run
/var/tmp/tmt/run-002

/tests/public/guest-setup/centos-stream-8
    discover
        how: shell
        summary: 1 test selected
    provision
        how: virtual
        image: CentOS-Stream-8
        user: root
        memory: 2048 MB
        disk: 10 GB
        progress: booting...
libguestfs: trace: set_verbose true
libguestfs: trace: set_verbose = 0
libguestfs: trace: set_backend "direct"
libguestfs: trace: set_backend = 0
libguestfs: create: flags = 0, handle = 0x556ebc5471a0, program = virt-make-fs
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: disk_create "/var/tmp/tmt/testcloud/instances/tmt-002-cdPvephN/tmt-002-cdPvephN-seed.img" "raw" 294350
libguestfs: trace: disk_create = 0
libguestfs: trace: add_drive "/var/tmp/tmt/testcloud/instances/tmt-002-cdPvephN/tmt-002-cdPvephN-seed.img" "format:raw" "blocksize:0"
libguestfs: trace: add_drive = 0
libguestfs: trace: launch
libguestfs: trace: max_disks
libguestfs: trace: max_disks = 255
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: version
libguestfs: trace: version = <struct guestfs_version = major: 1, minor: 46, release: 2, extra: fedora=34,release=1.fc34,libvirt, >
libguestfs: trace: get_backend
libguestfs: trace: get_backend = "direct"
libguestfs: launch: program=virt-make-fs
libguestfs: launch: version=1.46.2fedora=34,release=1.fc34,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/libguestfspMKCHP
libguestfs: launch: umask=0002
libguestfs: launch: euid=1000
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: begin building supermin appliance
libguestfs: 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-1000/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-1000/appliance.d
supermin: version: 5.3.1
supermin: rpm: detected RPM version 4.16
supermin: rpm: detected RPM architecture x86_64
supermin: package handler: fedora/rpm
supermin: acquiring lock on /var/tmp/.guestfs-1000/lock
supermin: if-newer: output does not need rebuilding
libguestfs: finished building supermin appliance
libguestfs: begin testing qemu features
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: checking for previously cached test results of /usr/bin/qemu-kvm, in /var/tmp/.guestfs-1000
libguestfs: loading previously cached test results
libguestfs: qemu version: 5.2
libguestfs: qemu mandatory locking: yes
libguestfs: qemu KVM: disabled
libguestfs: trace: get_backend_setting "force_tcg"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "force_kvm"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_sockdir
libguestfs: trace: get_sockdir = "/run/user/1000"
libguestfs: finished testing qemu features
libguestfs: trace: get_backend_setting "gdb"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: command: run: dmesg | grep -Eoh 'lpj=[[:digit:]]+'
libguestfs: read_lpj_from_dmesg: external command exited with error status 1
libguestfs: read_lpj_from_files: no boot messages files are readable
/usr/bin/qemu-kvm \
    -global virtio-blk-pci.scsi=off \
    -no-user-config \
    -nodefaults \
    -display none \
    -machine accel=kvm:tcg,graphics=off \
    -cpu max \
    -m 1280 \
    -no-reboot \
    -rtc driftfix=slew \
    -no-hpet \
    -global kvm-pit.lost_tick_policy=discard \
    -kernel /var/tmp/.guestfs-1000/appliance.d/kernel \
    -initrd /var/tmp/.guestfs-1000/appliance.d/initrd \
    -object rng-random,filename=/dev/urandom,id=rng0 \
    -device virtio-rng-pci,rng=rng0 \
    -device virtio-scsi-pci,id=scsi \
    -drive file=/var/tmp/tmt/testcloud/instances/tmt-002-cdPvephN/tmt-002-cdPvephN-seed.img,cache=writeback,format=raw,id=hd0,if=none \
    -device scsi-hd,drive=hd0 \
    -drive file=/var/tmp/.guestfs-1000/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \
    -device scsi-hd,drive=appliance \
    -device virtio-serial-pci \
    -serial stdio \
    -chardev socket,path=/run/user/1000/libguestfs3WWR7T/guestfsd.sock,id=channel0 \
    -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \
    -append "panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color"
ioctl(KVM_CREATE_VM) failed: 16 Device or resource busy
qemu-kvm: failed to initialize kvm: Device or resource busy
qemu-kvm: falling back to tcg
\x1bc\x1b[?7l\x1b[2J\x1b[0mSeaBIOS (version 1.14.0-4.fc34)
Booting from ROM..\x1bc\x1b[?7l\x1b[2J[    0.000000] Linux version 5.15.16-100.fc34.x86_64 (mockbuild@bkernel01.iad2.fedoraproject.org) (gcc (GCC) 11.2.1 20210728 (Red Hat 11.2.1-1), GNU ld version 2.35.2-6.fc34) #1 SMP Thu Jan 20 16:34:27 UTC 2022
[    0.000000] Command line: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
[    0.000000] x86/fpu: xstate_offset[3]:  960, xstate_sizes[3]:   64
[    0.000000] x86/fpu: xstate_offset[4]: 1024, xstate_sizes[4]:   64
[    0.000000] x86/fpu: xstate_offset[9]: 2688, xstate_sizes[9]:    8
[    0.000000] x86/fpu: Enabled xstate features 0x21b, context size is 2696 bytes, using 'standard' format.
[    0.000000] signal: max sigframe size: 3632
[    0.000000] 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-0x000000004ffdcfff] usable
[    0.000000] BIOS-e820: [mem 0x000000004ffdd000-0x000000004fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.14.0-4.fc34 04/01/2014
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] tsc: Detected 2112.000 MHz processor
[    0.003971] last_pfn = 0x4ffdd max_arch_pfn = 0x10000000000
[    0.004672] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.017519] found SMP MP-table at [mem 0x000f5c90-0x000f5c9f]
[    0.020759] Using GB pages for direct mapping
[    0.025185] RAMDISK: [mem 0x4fc34000-0x4ffcffff]
[    0.025738] ACPI: Early table checksum verification disabled
[    0.026073] ACPI: RSDP 0x00000000000F5AD0 000014 (v00 BOCHS )
[    0.026330] ACPI: RSDT 0x000000004FFE14BD 000030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.026881] ACPI: FACP 0x000000004FFE13A9 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.027345] ACPI: DSDT 0x000000004FFE0040 001369 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.027453] ACPI: FACS 0x000000004FFE0000 000040
[    0.027518] ACPI: APIC 0x000000004FFE141D 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.027558] ACPI: WAET 0x000000004FFE1495 000028 (v01 BOCHS  BXPCWAET 00000001 BXPC 00000001)
[    0.027638] ACPI: Reserving FACP table memory at [mem 0x4ffe13a9-0x4ffe141c]
[    0.027673] ACPI: Reserving DSDT table memory at [mem 0x4ffe0040-0x4ffe13a8]
[    0.027687] ACPI: Reserving FACS table memory at [mem 0x4ffe0000-0x4ffe003f]
[    0.027698] ACPI: Reserving APIC table memory at [mem 0x4ffe141d-0x4ffe1494]
[    0.027709] ACPI: Reserving WAET table memory at [mem 0x4ffe1495-0x4ffe14bc]
[    0.030385] No NUMA configuration found
[    0.030447] Faking a node at [mem 0x0000000000000000-0x000000004ffdcfff]
[    0.031088] NODE_DATA(0) allocated [mem 0x4fc09000-0x4fc33fff]
[    0.057552] Zone ranges:
[    0.057628]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.057731]   DMA32    [mem 0x0000000001000000-0x000000004ffdcfff]
[    0.057756]   Normal   empty
[    0.057783]   Device   empty
[    0.057805] Movable zone start for each node
[    0.057852] Early memory node ranges
[    0.057886]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.058029]   node   0: [mem 0x0000000000100000-0x000000004ffdcfff]
[    0.058289] Initmem setup node 0 [mem 0x0000000000001000-0x000000004ffdcfff]
[    0.059046] On node 0, zone DMA: 1 pages in unavailable ranges
[    0.059326] On node 0, zone DMA: 97 pages in unavailable ranges
[    0.068338] On node 0, zone DMA32: 35 pages in unavailable ranges
[    0.068961] ACPI: PM-Timer IO Port: 0x608
[    0.069447] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.069869] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[    0.070011] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.070360] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.070432] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.070594] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.070616] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.070798] ACPI: Using ACPI (MADT) for SMP configuration information
[    0.071046] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    0.072004] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.072070] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.072105] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.072118] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.072253] [mem 0x50000000-0xfffbffff] available for PCI devices
[    0.072292] Booting paravirtualized kernel on bare hardware
[    0.072602] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.083792] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[    0.092378] percpu: Embedded 61 pages/cpu s212992 r8192 d28672 u2097152
[    0.094573] Built 1 zonelists, mobility grouping on.  Total pages: 322269
[    0.094604] Policy zone: DMA32
[    0.094803] Kernel command line: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color
[    0.096739] cgroup: Disabling memory control group subsystem
[    0.097290] Unknown kernel command line parameters "edd=off udevtimeout=6000 guestfs_verbose=1", will be passed to user space.
[    0.103844] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.106434] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.108153] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.250976] Memory: 1226284K/1310188K available (16394K kernel code, 3561K rwdata, 10200K rodata, 2912K init, 4784K bss, 83700K reserved, 0K cma-reserved)
[    0.253053] random: get_random_u64 called from __kmem_cache_create+0x2a/0x500 with crng_init=0
[    0.261494] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.262984] ftrace: allocating 47371 entries in 186 pages
[    0.341056] ftrace: allocated 186 pages with 5 groups
[    0.349541] rcu: Hierarchical RCU implementation.
[    0.349593] rcu: \tRCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1.
[    0.349678] \tTrampoline variant of Tasks RCU enabled.
[    0.349692] \tRude variant of Tasks RCU enabled.
[    0.349701] \tTracing variant of Tasks RCU enabled.
[    0.349771] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    0.349802] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.376742] NR_IRQS: 524544, nr_irqs: 256, preallocated irqs: 16
[    0.384141] random: crng done (trusting CPU's manufacturer)
[    0.389516] Console: colour *CGA 80x25
[    0.425034] printk: console [ttyS0] enabled
[    0.426261] ACPI: Core revision 20210730
[    0.434108] APIC: Switch to symmetric I/O mode setup
[    0.438248] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.439252] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e71785e5dd, max_idle_ns: 440795244814 ns
[    0.440088] Calibrating delay loop (skipped), value calculated using timer frequency.. 4224.00 BogoMIPS (lpj=2112000)
[    0.441093] pid_max: default: 32768 minimum: 301
[    0.442088] LSM: Security Framework initializing
[    0.442899] Yama: becoming mindful.
[    0.443698] LSM support for eBPF active
[    0.443836] landlock: Up and running.
[    0.444994] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.445148] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.470515] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.473002] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.474668] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.475223] Spectre V2 : Mitigation: Full AMD retpoline
[    0.475346] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.475492] Speculative Store Bypass: Vulnerable
[    0.812006] Freeing SMP alternatives memory: 44K
[    0.932816] smpboot: CPU0: AMD QEMU TCG CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3)
[    0.943327] Performance Events: PMU not available due to virtualization, using software events only.
[    0.945209] rcu: Hierarchical SRCU implementation.
[    0.954861] NMI watchdog: Perf NMI watchdog permanently disabled
[    0.956448] smp: Bringing up secondary CPUs ...
[    0.956618] smp: Brought up 1 node, 1 CPU
[    0.956717] smpboot: Max logical packages: 1
[    0.956881] smpboot: Total of 1 processors activated (4224.00 BogoMIPS)
[    0.974483] devtmpfs: initialized
[    0.978877] x86/mm: Memory block size: 128MB
[    0.988026] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.988845] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
[    0.993001] pinctrl core: initialized pinctrl subsystem
[    1.000059] PM: RTC time: 10:08:32, date: 2022-02-09
[    1.008598] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    1.012036] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
[    1.012441] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    1.012620] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    1.013026] audit: initializing netlink subsys (disabled)
[    1.018139] audit: type=2000 audit(1644401311.581:1): state=initialized audit_enabled=0 res=1
[    1.022814] thermal_sys: Registered thermal governor 'fair_share'
[    1.022875] thermal_sys: Registered thermal governor 'bang_bang'
[    1.023116] thermal_sys: Registered thermal governor 'step_wise'
[    1.023273] thermal_sys: Registered thermal governor 'user_space'
[    1.023843] cpuidle: using governor menu
[    1.026518] ACPI: bus type PCI registered
[    1.026711] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    1.030166] PCI: Using configuration type 1 for base access
[    1.057102] Kprobes globally optimized
[    1.060235] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    1.060416] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    1.065652] alg: self-tests disabled
[    1.080325] cryptd: max_cpu_qlen set to 1000
[    1.100516] raid6: skip pq benchmark and using algorithm sse2x4
[    1.100754] raid6: using ssse3x2 recovery algorithm
[    1.103102] ACPI: Added _OSI(Module Device)
[    1.103239] ACPI: Added _OSI(Processor Device)
[    1.103314] ACPI: Added _OSI(3.0 _SCP Extensions)
[    1.103395] ACPI: Added _OSI(Processor Aggregator Device)
[    1.103552] ACPI: Added _OSI(Linux-Dell-Video)
[    1.103628] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    1.103952] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    1.121457] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    1.136760] ACPI: Interpreter enabled
[    1.137644] ACPI: PM: (supports S0 S3 S4 S5)
[    1.137882] ACPI: Using IOAPIC for interrupt routing
[    1.138593] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    1.138867] PCI: Using E820 reservations for host bridge windows
[    1.140635] ACPI: Enabled 2 GPEs in block 00 to 0F
[    1.174547] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    1.175287] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI EDR HPX-Type3]
[    1.176035] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    1.182266] acpiphp: Slot [2] registered
[    1.182639] acpiphp: Slot [3] registered
[    1.182943] acpiphp: Slot [4] registered
[    1.183136] acpiphp: Slot [5] registered
[    1.183335] acpiphp: Slot [6] registered
[    1.183573] acpiphp: Slot [7] registered
[    1.183933] acpiphp: Slot [8] registered
[    1.184199] acpiphp: Slot [9] registered
[    1.184469] acpiphp: Slot [10] registered
[    1.184718] acpiphp: Slot [11] registered
[    1.185002] acpiphp: Slot [12] registered
[    1.185203] acpiphp: Slot [13] registered
[    1.185409] acpiphp: Slot [14] registered
[    1.185571] acpiphp: Slot [15] registered
[    1.185732] acpiphp: Slot [16] registered
[    1.186032] acpiphp: Slot [17] registered
[    1.186228] acpiphp: Slot [18] registered
[    1.186428] acpiphp: Slot [19] registered
[    1.186589] acpiphp: Slot [20] registered
[    1.186748] acpiphp: Slot [21] registered
[    1.186996] acpiphp: Slot [22] registered
[    1.187155] acpiphp: Slot [23] registered
[    1.187330] acpiphp: Slot [24] registered
[    1.187511] acpiphp: Slot [25] registered
[    1.187692] acpiphp: Slot [26] registered
[    1.187905] acpiphp: Slot [27] registered
[    1.188063] acpiphp: Slot [28] registered
[    1.188223] acpiphp: Slot [29] registered
[    1.188423] acpiphp: Slot [30] registered
[    1.188584] acpiphp: Slot [31] registered
[    1.189093] PCI host bridge to bus 0000:00
[    1.189343] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    1.189651] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    1.189764] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    1.189946] pci_bus 0000:00: root bus resource [mem 0x50000000-0xfebfffff window]
[    1.190082] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[    1.190353] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.193141] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    1.198854] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    1.200102] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    1.202196] pci 0000:00:01.1: reg 0x20: [io  0xc0a0-0xc0af]
[    1.202854] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    1.203027] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    1.203241] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    1.203443] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    1.205287] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    1.205765] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    1.206103] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    1.208117] pci 0000:00:02.0: [1af4:1005] type 00 class 0x00ff00
[    1.208702] pci 0000:00:02.0: reg 0x10: [io  0xc080-0xc09f]
[    1.210179] pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf3fff 64bit pref]
[    1.212777] pci 0000:00:03.0: [1af4:1004] type 00 class 0x010000
[    1.214873] pci 0000:00:03.0: reg 0x10: [io  0xc000-0xc03f]
[    1.216875] pci 0000:00:03.0: reg 0x14: [mem 0xfebfe000-0xfebfefff]
[    1.217816] pci 0000:00:03.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref]
[    1.221441] pci 0000:00:04.0: [1af4:1003] type 00 class 0x078000
[    1.222254] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    1.223952] pci 0000:00:04.0: reg 0x14: [mem 0xfebff000-0xfebfffff]
[    1.228891] pci 0000:00:04.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref]
[    1.234816] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
[    1.236515] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
[    1.237816] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
[    1.237816] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
[    1.237816] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
[    1.240816] iommu: Default domain type: Translated 
[    1.240816] iommu: DMA domain TLB invalidation policy: lazy mode 
[    1.246646] vgaarb: loaded
[    1.249816] SCSI subsystem initialized
[    1.251368] usbcore: USB support disabled
[    1.252460] pps_core: LinuxPPS API ver. 1 registered
[    1.252611] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    1.252963] PTP clock support registered
[    1.254459] EDAC MC: Ver: 3.0.0
[    1.265889] NetLabel: Initializing
[    1.266026] NetLabel:  domain hash size = 128
[    1.266120] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    1.267065] NetLabel:  unlabeled traffic allowed by default
[    1.267250] PCI: Using ACPI for IRQ routing
[    1.272816] clocksource: Switched to clocksource tsc-early
[    1.373555] VFS: Disk quotas dquot_6.6.0
[    1.373820] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.412477] pnp: PnP ACPI init
[    1.416722] pnp: PnP ACPI: found 5 devices
[    1.437290] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.438543] NET: Registered PF_INET protocol family
[    1.440297] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    1.465103] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
[    1.465405] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.465796] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear)
[    1.466272] TCP: Hash tables configured (established 16384 bind 16384)
[    1.469040] MPTCP token hash table entries: 2048 (order: 3, 49152 bytes, linear)
[    1.469481] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    1.469816] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    1.471744] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.472259] NET: Registered PF_XDP protocol family
[    1.591626] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.591807] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    1.591962] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    1.592091] pci_bus 0000:00: resource 7 [mem 0x50000000-0xfebfffff window]
[    1.593096] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[    1.595447] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.595741] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.596182] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    1.596356] PCI: CLS 0 bytes, default 64
[    1.601224] Trying to unpack rootfs image as initramfs...
[    1.641371] Freeing initrd memory: 3696K
[    1.652402] Initialise system trusted keyrings
[    1.654194] Key type blacklist registered
[    1.655541] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[    1.668726] zbud: loaded
[    1.677174] integrity: Platform Keyring initialized
[    1.703676] NET: Registered PF_ALG protocol family
[    1.704003] xor: measuring software checksum speed
[    1.709797]    prefetch64-sse  :  2124 MB/sec
[    1.715275]    generic_sse     :  1944 MB/sec
[    1.715467] xor: using function: prefetch64-sse (2124 MB/sec)
[    1.715626] Key type asymmetric registered
[    1.715761] Asymmetric key parser 'x509' registered
[    1.716389] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    1.717680] io scheduler mq-deadline registered
[    1.717939] io scheduler kyber registered
[    1.718374] io scheduler bfq registered
[    1.733814] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[    1.736334] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    1.739574] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    1.743118] ACPI: button: Power Button [PWRF]
[    1.948784] ACPI: \_SB_.LNKB: Enabled at IRQ 10
[    2.125491] ACPI: \_SB_.LNKC: Enabled at IRQ 11
[    2.307489] ACPI: \_SB_.LNKD: Enabled at IRQ 11
[    2.309371] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    2.311580] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    2.316025] Non-volatile memory driver v1.3
[    2.319904] Linux agpgart interface v0.103
[    2.333846] scsi host0: ata_piix
[    2.338659] scsi host1: ata_piix
[    2.339460] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14
[    2.339636] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15
[    2.345170] libphy: Fixed MDIO Bus: probed
[    2.348734] usbserial: usb_serial_init - registering generic driver failed
[    2.349343] usbserial: usb_serial_init - returning with error -19
[    2.350702] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    2.353637] serio: i8042 KBD port at 0x60,0x64 irq 1
[    2.353991] serio: i8042 AUX port at 0x60,0x64 irq 12
[    2.356119] mousedev: PS/2 mouse device common for all mice
[    2.359266] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    2.363073] rtc_cmos 00:04: RTC can wake from S4
[    2.371218] rtc_cmos 00:04: registered as rtc0
[    2.371996] rtc_cmos 00:04: setting system clock to 2022-02-09T10:08:33 UTC (1644401313)
[    2.372991] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram
[    2.373402] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    2.373822] device-mapper: uevent: version 1.0.3
[    2.375179] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[    2.379319] hid: raw HID events driver (C) Jiri Kosina
[    2.381725] drop_monitor: Initializing network drop monitor service
[    2.383196] Initializing XFRM netlink socket
[    2.384515] NET: Registered PF_INET6 protocol family
[    2.415529] Segment Routing with IPv6
[    2.415753] RPL Segment Routing with IPv6
[    2.416195] In-situ OAM (IOAM) with IPv6
[    2.416708] mip6: Mobile IPv6
[    2.417160] NET: Registered PF_PACKET protocol family
[    2.420450] IPI shorthand broadcast: enabled
[    2.420706] SSE version of gcm_enc/dec engaged.
[    2.428071] sched_clock: Marking stable (2375921738, 51924296)->(2452619752, -24773718)
[    2.431364] registered taskstats version 1
[    2.432762] Loading compiled-in X.509 certificates
[    2.445266] Loaded X.509 cert 'Fedora kernel signing key: 261374f6539d47a88923f40083738a8ed9bf8d55'
[    2.447273] zswap: loaded using pool lzo/zbud
[    2.449466] page_owner is disabled
[    2.450627] Key type ._fscrypt registered
[    2.450752] Key type .fscrypt registered
[    2.450874] Key type fscrypt-provisioning registered
[    2.455647] Btrfs loaded, crc32c=crc32c-generic, zoned=yes, fsverity=yes
[    2.456749] Key type big_key registered
[    2.462284] Key type encrypted registered
[    2.463454] ima: No TPM chip found, activating TPM-bypass!
[    2.463718] Loading compiled-in module X.509 certificates
[    2.467366] Loaded X.509 cert 'Fedora kernel signing key: 261374f6539d47a88923f40083738a8ed9bf8d55'
[    2.467660] ima: Allocated hash algorithm: sha256
[    2.474355] ima: No architecture policies found
[    2.475263] evm: Initialising EVM extended attributes:
[    2.475375] evm: security.selinux
[    2.475478] evm: security.SMACK64 (disabled)
[    2.475556] evm: security.SMACK64EXEC (disabled)
[    2.475630] evm: security.SMACK64TRANSMUTE (disabled)
[    2.475708] evm: security.SMACK64MMAP (disabled)
[    2.475779] evm: security.apparmor (disabled)
[    2.475846] evm: security.ima
[    2.476767] evm: security.capability
[    2.476888] evm: HMAC attrs: 0x1
[    2.479617] PM:   Magic number: 10:773:122
[    2.479992] acpi LNXSYBUS:00: hash matches
[    2.481052] RAS: Correctable Errors collector initialized.
[    2.578541] Freeing unused decrypted memory: 2036K
[    2.625490] Freeing unused kernel image (initmem) memory: 2912K
[    2.625932] Write protecting the kernel read-only data: 28672k
[    2.630036] Freeing unused kernel image (text/rodata gap) memory: 2036K
[    2.630433] Freeing unused kernel image (rodata/data gap) memory: 40K
[    2.758666] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    2.759033] rodata_test: all tests were successful
[    2.759226] tsc: Refined TSC clocksource calibration: 2111.962 MHz
[    2.759523] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e7154648bf, max_idle_ns: 440795214483 ns
[    2.760088] clocksource: Switched to clocksource tsc
[    2.760873] Run /init as init process
supermin: mounting /proc
supermin: ext2 mini initrd starting up: 5.3.1 dietlibc
supermin: cmdline: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color
supermin: uptime: 2.74 0.01
supermin: mounting /sys
supermin: internal insmod crc32-pclmul.ko
supermin: internal insmod crc32c-intel.ko
supermin: internal insmod crct10dif-pclmul.ko
supermin: internal insmod crc32_generic.ko
supermin: internal insmod libnvdimm.ko
supermin: internal insmod nfit.ko
supermin: internal insmod virtio_blk.ko
supermin: internal insmod ecdh_generic.ko
supermin: internal insmod rfkill.ko
supermin: internal insmod bluetooth.ko
[    3.003649] Bluetooth: Core ver 2.22
[    3.004005] NET: Registered PF_BLUETOOTH protocol family
[    3.004098] Bluetooth: HCI device and connection manager initialized
[    3.004292] Bluetooth: HCI socket layer initialized
[    3.004442] Bluetooth: L2CAP socket layer initialized
[    3.005229] Bluetooth: SCO socket layer initialized
supermin: internal insmod virtio_bt.ko
supermin: internal insmod virtio_console.ko
supermin: internal insmod crypto_engine.ko
supermin: internal insmod virtio_crypto.ko
supermin: internal insmod failover.ko
supermin: internal insmod net_failover.ko
supermin: internal insmod virtio_net.ko
supermin: internal insmod nd_btt.ko
supermin: internal insmod nd_pmem.ko
supermin: internal insmod virtio_scsi.ko
[    3.109253] scsi host2: Virtio SCSI HBA
[    3.122566] scsi 2:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    3.125995] scsi 2:0:1:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    3.227732] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    3.282241] sd 2:0:0:0: Power-on or device reset occurred
[    3.285512] sd 2:0:0:0: [sda] 575 512-byte logical blocks: (294 kB/288 KiB)
[    3.286021] sd 2:0:0:0: [sda] Write Protect is off
[    3.286687] sd 2:0:0:0: Attached scsi generic sg0 type 0
[    3.287329] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.289037] sd 2:0:1:0: Power-on or device reset occurred
[    3.291240] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)
[    3.291755] sd 2:0:1:0: [sdb] Write Protect is off
[    3.292148] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    3.297326] sd 2:0:1:0: Attached scsi generic sg1 type 0
[    3.324662] sd 2:0:1:0: [sdb] Attached SCSI disk
[    3.325570] sd 2:0:0:0: [sda] Attached SCSI disk
supermin: internal insmod virtio_balloon.ko
supermin: internal insmod virtio_dma_buf.ko
supermin: internal insmod virtio_input.ko
supermin: internal insmod virtio_mem.ko
supermin: internal insmod virtio_mmio.ko
supermin: internal insmod vdpa.ko
supermin: internal insmod virtio_vdpa.ko
supermin: internal insmod fuse.ko
[    3.399308] fuse: init (API version 7.34)
supermin: internal insmod virtiofs.ko
supermin: internal insmod crc-itu-t.ko
supermin: internal insmod crc4.ko
supermin: internal insmod crc64.ko
supermin: internal insmod crc7.ko
supermin: internal insmod crc8.ko
supermin: internal insmod soundcore.ko
supermin: internal insmod snd.ko
supermin: internal insmod snd-timer.ko
supermin: internal insmod snd-pcm.ko
supermin: internal insmod virtio_snd.ko
supermin: picked 8:16 as root device
supermin: creating /dev/root as block special 8:16
supermin: mounting new root on /root
[    3.521042] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem
[    3.544200] EXT4-fs (sdb): mounted filesystem without journal. Opts: . Quota mode: none.
supermin: deleting initramfs files
supermin: chroot
/bin/bash: error while loading shared libraries: libtinfo.so.6: cannot open shared object file: No such file or directory
[    3.604166] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00007f00
[    3.604516] CPU: 0 PID: 1 Comm: init Not tainted 5.15.16-100.fc34.x86_64 #1
[    3.604697] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.14.0-4.fc34 04/01/2014
[    3.605051] Call Trace:
[    3.605794]  <TASK>
[    3.605992]  dump_stack_lvl+0x46/0x5a
[    3.606448]  panic+0xe3/0x2bb
[    3.606527]  ? asm_sysvec_apic_timer_interrupt+0x12/0x20
[    3.606632]  do_exit.cold+0x9f/0x9f
[    3.606722]  do_group_exit+0x33/0xa0
[    3.606806]  __x64_sys_exit_group+0x14/0x20
[    3.607060]  do_syscall_64+0x3b/0x90
[    3.607142]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[    3.607439] RIP: 0033:0x7f773e1145b1
[    3.607896] Code: 1f 84 00 00 00 00 00 66 90 f3 0f 1e fa be e7 00 00 00 ba 3c 00 00 00 eb 0d 89 d0 0f 05 48 3d 00 f0 ff ff 77 1c f4 89 f0 0f 05 <48> 3d 00 f0 ff ff 76 e7 f7 d8 89 05 e7 1b 01 00 eb dd 0f 1f 44 00
[    3.608475] RSP: 002b:00007ffe864c5448 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
[    3.608717] RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 00007f773e1145b1
[    3.608850] RDX: 000000000000003c RSI: 00000000000000e7 RDI: 000000000000007f
[    3.609573] RBP: 00007ffe864c5ec0 R08: 0000000000000000 R09: 0000000000000000
[    3.609706] R10: 00000000ffffffff R11: 0000000000000246 R12: 00007f773e11d960
[    3.609853] R13: 00007f773e126e1f R14: 00007f773e126e30 R15: 0000000000000000
[    3.610734]  </TASK>
[    3.611252] Kernel Offset: 0x25000000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff)
[    3.611826] Rebooting in 1 seconds..
libguestfs: error: appliance closed the connection unexpectedly, see earlier error messages
libguestfs: child_cleanup: 0x556ebc5471a0: child process died
libguestfs: sending SIGTERM to process 3389907
libguestfs: qemu maxrss 258632K
libguestfs: error: guestfs_launch failed, see earlier error messages
libguestfs: trace: launch = -1 (error)
libguestfs: trace: close
libguestfs: closing guestfs handle 0x556ebc5471a0 (state 0)
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfspMKCHP
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /run/user/1000/libguestfs3WWR7T
Seed image generation failed. Exiting
    finish
libvirt: QEMU Driver error : Domain not found: no domain with matching name 'tmt-002-cdPvephN'

Tagging @frantisekz please, take a look.

The text was updated successfully, but these errors were encountered:

frantisekz · 2022-02-09T11:04:45Z

Mhm, from briefly looking at the logs, it seems the missing /dev/kvm shouldn't cause the issues. The kernel panic might be caused indirectly by missing /dev/kvm , but it generally should work. I'll try to ping Richard W.M. Jones about this.

Did you mean that the warning about missing kvm should be printed even if it doesn't mean that you can't use tmt/testcloud?
Apart from that, I am, on purpose, introducing non-kvm modes for multiarch support in #1040 where I got kvm-less VMs created successfully. But here it seems it crashed on seed image generation in libguestfs/supermin.

(Btw, even if this really turned out to be an issue libguestfs/supermin side and got fixed, it would have crashed later on anyway, I'll fix it as part of #1040 ).

frantisekz · 2022-02-09T11:12:20Z

@janhavlin can you please try to run libguestfs-test-tool and post the output here? I think that should crash the same way as did the tmt run, if my assumption was correct.

janhavlin · 2022-02-09T11:26:18Z

It indeed seems like it crashes in the same way.

output

$ libguestfs-test-tool
     ************************************************************
     *                    IMPORTANT NOTICE
     *
     * When reporting bugs, include the COMPLETE, UNEDITED
     * output below in your bug report.
     *
     ************************************************************
libguestfs: trace: set_verbose true
libguestfs: trace: set_verbose = 0
libguestfs: trace: set_verbose true
libguestfs: trace: set_verbose = 0
LIBGUESTFS_TRACE=1
LIBGUESTFS_DEBUG=1
PATH=/home/jhavlin/work/infrastructure-testing-farm/.direnv/bin:/home/jhavlin/.cache/pypoetry/virtualenvs/infrastructure-7L4d6EY6-py3.9/bin:/home/jhavlin/.poetry/bin:/home/jhavlin/.local/bin:/home/jhavlin/bin:/usr/lib64/qt-3.3/bin:/usr/local/bin:/usr/local/sbin:/usr/bin:/usr/sbin:/var/lib/snapd/snap/bin
XDG_RUNTIME_DIR=/run/user/1000
SELinux: Enforcing
libguestfs: trace: add_drive_scratch 104857600
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: disk_create "/tmp/libguestfswnJqGy/scratch1.img" "raw" 104857600
libguestfs: trace: disk_create = 0
libguestfs: trace: add_drive "/tmp/libguestfswnJqGy/scratch1.img" "format:raw" "cachemode:unsafe"
libguestfs: trace: add_drive = 0
libguestfs: trace: add_drive_scratch = 0
libguestfs: trace: get_append
libguestfs: trace: get_append = "NULL"
guestfs_get_append: (null)
libguestfs: trace: get_autosync
libguestfs: trace: get_autosync = 1
guestfs_get_autosync: 1
libguestfs: trace: get_backend
libguestfs: trace: get_backend = "libvirt"
guestfs_get_backend: libvirt
libguestfs: trace: get_backend_settings
libguestfs: trace: get_backend_settings = []
guestfs_get_backend_settings: []
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
guestfs_get_cachedir: /var/tmp
libguestfs: trace: get_hv
libguestfs: trace: get_hv = "/usr/bin/qemu-kvm"
guestfs_get_hv: /usr/bin/qemu-kvm
libguestfs: trace: get_memsize
libguestfs: trace: get_memsize = 1280
guestfs_get_memsize: 1280
libguestfs: trace: get_network
libguestfs: trace: get_network = 0
guestfs_get_network: 0
libguestfs: trace: get_path
libguestfs: trace: get_path = "/usr/lib64/guestfs"
guestfs_get_path: /usr/lib64/guestfs
libguestfs: trace: get_pgroup
libguestfs: trace: get_pgroup = 0
guestfs_get_pgroup: 0
libguestfs: trace: get_program
libguestfs: trace: get_program = "libguestfs-test-tool"
guestfs_get_program: libguestfs-test-tool
libguestfs: trace: get_recovery_proc
libguestfs: trace: get_recovery_proc = 1
guestfs_get_recovery_proc: 1
libguestfs: trace: get_smp
libguestfs: trace: get_smp = 1
guestfs_get_smp: 1
libguestfs: trace: get_sockdir
libguestfs: trace: get_sockdir = "/run/user/1000"
guestfs_get_sockdir: /run/user/1000
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
guestfs_get_tmpdir: /tmp
libguestfs: trace: get_trace
libguestfs: trace: get_trace = 1
guestfs_get_trace: 1
libguestfs: trace: get_verbose
libguestfs: trace: get_verbose = 1
guestfs_get_verbose: 1
host_cpu: x86_64
Launching appliance, timeout set to 600 seconds.
libguestfs: trace: launch
libguestfs: trace: max_disks
libguestfs: trace: max_disks = 255
libguestfs: trace: version
libguestfs: trace: version = <struct guestfs_version = major: 1, minor: 46, release: 2, extra: fedora=34,release=1.fc34,libvirt, >
libguestfs: trace: get_backend
libguestfs: trace: get_backend = "libvirt"
libguestfs: launch: program=libguestfs-test-tool
libguestfs: launch: version=1.46.2fedora=34,release=1.fc34,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/libguestfswnJqGy
libguestfs: launch: umask=0002
libguestfs: launch: euid=1000
libguestfs: libvirt version = 7000000 (7.0.0)
libguestfs: guest random name = guestfs-2xdym2a4zhh1624n
libguestfs: connect to libvirt
libguestfs: opening libvirt handle: URI = qemu:///session, auth = default+wrapper, flags = 0
libguestfs: successfully opened libvirt handle: conn = 0x55cba8663030
libguestfs: qemu version (reported by libvirt) = 5002000 (5.2.0)
libguestfs: get libvirt capabilities
libguestfs: parsing capabilities XML
libguestfs: trace: get_backend_setting "force_kvm"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "force_tcg"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: parsing domcapabilities XML
libguestfs: trace: get_backend_setting "internal_libvirt_label"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "internal_libvirt_imagelabel"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "internal_libvirt_norelabel_disks"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: build appliance
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: begin building supermin appliance
libguestfs: 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-1000/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-1000/appliance.d
supermin: version: 5.3.1
supermin: rpm: detected RPM version 4.16
supermin: rpm: detected RPM architecture x86_64
supermin: package handler: fedora/rpm
supermin: acquiring lock on /var/tmp/.guestfs-1000/lock
supermin: if-newer: output does not need rebuilding
libguestfs: finished building supermin appliance
libguestfs: trace: disk_create "/tmp/libguestfswnJqGy/overlay2.qcow2" "qcow2" -1 "backingfile:/var/tmp/.guestfs-1000/appliance.d/root"
libguestfs: trace: disk_format "/var/tmp/.guestfs-1000/appliance.d/root"
libguestfs: command: run: qemu-img --help | grep -sqE -- '\binfo\b.*-U\b'
libguestfs: command: run: qemu-img
libguestfs: command: run: \ info
libguestfs: command: run: \ -U
libguestfs: command: run: \ --output json
libguestfs: command: run: \ /var/tmp/.guestfs-1000/appliance.d/root
libguestfs: parse_json: qemu-img info JSON output:\n{\n    "virtual-size": 4294967296,\n    "filename": "/var/tmp/.guestfs-1000/appliance.d/root",\n    "format": "raw",\n    "actual-size": 308338688,\n    "dirty-flag": false\n}\n\n
libguestfs: trace: disk_format = "raw"
libguestfs: command: run: qemu-img
libguestfs: command: run: \ create
libguestfs: command: run: \ -f qcow2
libguestfs: command: run: \ -o backing_file=/var/tmp/.guestfs-1000/appliance.d/root,backing_fmt=raw
libguestfs: command: run: \ /tmp/libguestfswnJqGy/overlay2.qcow2
Formatting '/tmp/libguestfswnJqGy/overlay2.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=4294967296 backing_file=/var/tmp/.guestfs-1000/appliance.d/root backing_fmt=raw lazy_refcounts=off refcount_bits=16
libguestfs: trace: disk_create = 0
libguestfs: trace: get_sockdir
libguestfs: trace: get_sockdir = "/run/user/1000"
libguestfs: create libvirt XML
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
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-2xdym2a4zhh1624n</name>\n  <memory unit="MiB">1280</memory>\n  <currentMemory unit="MiB">1280</currentMemory>\n  <cpu mode="host-passthrough">\n    <model fallback="allow"/>\n  </cpu>\n  <vcpu>1</vcpu>\n  <clock offset="utc">\n    <timer name="rtc" tickpolicy="catchup"/>\n    <timer name="pit" tickpolicy="delay"/>\n    <timer name="hpet" present="no"/>\n  </clock>\n  <os>\n    <type>hvm</type>\n    <kernel>/var/tmp/.guestfs-1000/appliance.d/kernel</kernel>\n    <initrd>/var/tmp/.guestfs-1000/appliance.d/initrd</initrd>\n    <cmdline>panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color</cmdline>\n    <bios useserial="yes"/>\n  </os>\n  <seclabel type="none"/>\n  <on_reboot>destroy</on_reboot>\n  <devices>\n    <emulator>/usr/bin/qemu-kvm</emulator>\n    <rng model="virtio">\n      <backend model="random">/dev/urandom</backend>\n    </rng>\n    <controller type="scsi" index="0" model="virtio-scsi"/>\n    <disk device="disk" type="file">\n      <source file="/tmp/libguestfswnJqGy/scratch1.img"/>\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/libguestfswnJqGy/overlay2.qcow2"/>\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    </disk>\n    <serial type="unix">\n      <source mode="connect" path="/run/user/1000/libguestfsSGtFJR/console.sock"/>\n      <target port="0"/>\n    </serial>\n    <channel type="unix">\n      <source mode="connect" path="/run/user/1000/libguestfsSGtFJR/guestfsd.sock"/>\n      <target type="virtio" name="org.libguestfs.channel.0"/>\n    </channel>\n    <controller type="usb" model="none"/>\n    <memballoon model="none"/>\n  </devices>\n  <qemu:commandline>\n    <qemu:env name="TMPDIR" value="/var/tmp"/>\n  </qemu:commandline>\n</domain>\n
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: command: run: ls
libguestfs: command: run: \ -a
libguestfs: command: run: \ -l
libguestfs: command: run: \ -R
libguestfs: command: run: \ -Z /var/tmp/.guestfs-1000
libguestfs: /var/tmp/.guestfs-1000:
libguestfs: total 216
libguestfs: drwxr-xr-x.  3 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0   4096 Feb  9 12:23 .
libguestfs: drwxrwxrwt. 18 root    root    system_u:object_r:tmp_t:s0            4096 Feb  9 12:23 ..
libguestfs: drwxr-xr-x.  2 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0   4096 Feb  9 10:53 appliance.d
libguestfs: -rw-r--r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0      0 Feb  9 10:49 lock
libguestfs: -rw-rw-r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0  15219 Feb  9 10:56 qemu-13220744-1623698265.devices
libguestfs: -rw-rw-r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0  29258 Feb  9 10:56 qemu-13220744-1623698265.help
libguestfs: -rw-rw-r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0 149315 Feb  9 10:56 qemu-13220744-1623698265.qmp-schema
libguestfs: -rw-rw-r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0     49 Feb  9 10:56 qemu-13220744-1623698265.query-kvm
libguestfs: -rw-rw-r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0     40 Feb  9 10:56 qemu-13220744-1623698265.stat
libguestfs: 
libguestfs: /var/tmp/.guestfs-1000/appliance.d:
libguestfs: total 315664
libguestfs: drwxr-xr-x. 2 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0       4096 Feb  9 10:53 .
libguestfs: drwxr-xr-x. 3 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0       4096 Feb  9 12:23 ..
libguestfs: -rw-r--r--. 1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    3781632 Feb  9 12:23 initrd
libguestfs: -rwxr-xr-x. 1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0   11104464 Feb  9 12:23 kernel
libguestfs: -rw-r--r--. 1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0 4294967296 Feb  9 12:23 root
libguestfs: command: run: ls
libguestfs: command: run: \ -a
libguestfs: command: run: \ -l
libguestfs: command: run: \ -Z /run/user/1000/libguestfsSGtFJR
libguestfs: total 0
libguestfs: drwx------.  2 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0   80 Feb  9 12:23 .
libguestfs: drwx------. 25 jhavlin jhavlin system_u:object_r:user_tmp_t:s0     1080 Feb  9 12:23 ..
libguestfs: srwxrwxr-x.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    0 Feb  9 12:23 console.sock
libguestfs: srwxrwxr-x.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    0 Feb  9 12:23 guestfsd.sock
libguestfs: launch libvirt guest
libguestfs: responding to serial console Device Status Report
\x1b[1;256r\x1b[256;256H\x1b[6n
Google, Inc.
Serial Graphics Adapter 01/29/21
SGABIOS $Id$ (mockbuild@) Fri Jan 29 01:55:59 UTC 2021
Term: 80x24
4 0
SeaBIOS (version 1.14.0-4.fc34)
Machine UUID cb214c2e-f674-4aa5-9afb-4946315b8652
Booting from ROM...
\x1b[2J[    0.000000] Linux version 5.15.16-100.fc34.x86_64 (mockbuild@bkernel01.iad2.fedoraproject.org) (gcc (GCC) 11.2.1 20210728 (Red Hat 11.2.1-1), GNU ld version 2.35.2-6.fc34) #1 SMP Thu Jan 20 16:34:27 UTC 2022
[    0.000000] Command line: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
[    0.000000] x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
[    0.000000] x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
[    0.000000] signal: max sigframe size: 2032
[    0.000000] 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-0x000000004fffcfff] usable
[    0.000000] BIOS-e820: [mem 0x000000004fffd000-0x000000004fffffff] 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] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.14.0-4.fc34 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 39a01001, primary cpu clock
[    0.000003] kvm-clock: using sched offset of 365246346 cycles
[    0.000009] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000020] tsc: Detected 2111.998 MHz processor
[    0.000316] last_pfn = 0x4fffd max_arch_pfn = 0x400000000
[    0.000361] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.008761] found SMP MP-table at [mem 0x000f5c90-0x000f5c9f]
[    0.009127] Using GB pages for direct mapping
[    0.009440] RAMDISK: [mem 0x4fc54000-0x4ffeffff]
[    0.009541] ACPI: Early table checksum verification disabled
[    0.009674] ACPI BIOS Error (bug): A valid RSDP was not found (20210730/tbxfroot-210)
[    0.009918] No NUMA configuration found
[    0.009922] Faking a node at [mem 0x0000000000000000-0x000000004fffcfff]
[    0.009938] NODE_DATA(0) allocated [mem 0x4fc29000-0x4fc53fff]
[    0.109596] Zone ranges:
[    0.109606]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.109610]   DMA32    [mem 0x0000000001000000-0x000000004fffcfff]
[    0.109612]   Normal   empty
[    0.109613]   Device   empty
[    0.109615] Movable zone start for each node
[    0.109618] Early memory node ranges
[    0.109619]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.109621]   node   0: [mem 0x0000000000100000-0x000000004fffcfff]
[    0.109624] Initmem setup node 0 [mem 0x0000000000001000-0x000000004fffcfff]
[    0.109632] On node 0, zone DMA: 1 pages in unavailable ranges
[    0.109656] On node 0, zone DMA: 97 pages in unavailable ranges
[    0.111436] On node 0, zone DMA32: 3 pages in unavailable ranges
[    0.111671] Intel MultiProcessor Specification v1.4
[    0.111675] MPTABLE: OEM ID: BOCHSCPU
[    0.111676] MPTABLE: Product ID: 0.1         
[    0.111677] MPTABLE: APIC at: 0xFEE00000
[    0.111695] Processor #0 (Bootup-CPU)
[    0.111724] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.111728] Processors: 1
[    0.111729] TSC deadline timer available
[    0.111736] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    0.111795] kvm-guest: KVM setup pv remote TLB flush
[    0.111798] kvm-guest: setup PV sched yield
[    0.111820] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.111823] PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.111824] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.111825] PM: hibernation: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.111827] [mem 0x50000000-0xfeffbfff] available for PCI devices
[    0.111831] Booting paravirtualized kernel on KVM
[    0.111845] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[    0.116360] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[    0.117793] percpu: Embedded 61 pages/cpu s212992 r8192 d28672 u2097152
[    0.117836] kvm-guest: setup async PF for cpu 0
[    0.117841] kvm-guest: stealtime: cpu 0, msr 4e633080
[    0.117844] kvm-guest: PV spinlocks disabled, single CPU
[    0.117861] Built 1 zonelists, mobility grouping on.  Total pages: 322301
[    0.117863] Policy zone: DMA32
[    0.117864] Kernel command line: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color
[    0.118142] cgroup: Disabling memory control group subsystem
[    0.118203] Unknown kernel command line parameters "edd=off udevtimeout=6000 guestfs_verbose=1", will be passed to user space.
[    0.119437] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.119467] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.119515] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.128319] Memory: 1228376K/1310316K available (16394K kernel code, 3561K rwdata, 10200K rodata, 2912K init, 4784K bss, 81680K reserved, 0K cma-reserved)
[    0.128337] random: get_random_u64 called from __kmem_cache_create+0x2a/0x500 with crng_init=0
[    0.128483] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.128503] ftrace: allocating 47371 entries in 186 pages
[    0.142787] ftrace: allocated 186 pages with 5 groups
[    0.143505] rcu: Hierarchical RCU implementation.
[    0.143512] rcu: \tRCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1.
[    0.143515] \tTrampoline variant of Tasks RCU enabled.
[    0.143515] \tRude variant of Tasks RCU enabled.
[    0.143516] \tTracing variant of Tasks RCU enabled.
[    0.143516] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[    0.143517] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.147057] NR_IRQS: 524544, nr_irqs: 256, preallocated irqs: 16
[    0.147221] random: crng done (trusting CPU's manufacturer)
[    0.147304] Console: colour *CGA 80x25
[    0.189809] printk: console [ttyS0] enabled
[    0.190125] APIC: Switch to symmetric I/O mode setup
[    0.190578] x2apic enabled
[    0.190892] Switched APIC routing to physical x2apic.
[    0.191225] kvm-guest: setup PV IPIs
[    0.191979] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e71768ef8b, max_idle_ns: 440795218977 ns
[    0.192702] Calibrating delay loop (skipped) preset value.. 4223.99 BogoMIPS (lpj=2111998)
[    0.193353] pid_max: default: 32768 minimum: 301
[    0.193695] LSM: Security Framework initializing
[    0.193697] Yama: becoming mindful.
[    0.193697] LSM support for eBPF active
[    0.193697] landlock: Up and running.
[    0.193697] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.193697] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.193697] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[    0.193697] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.193697] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.193697] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.193697] Spectre V2 : Mitigation: Enhanced IBRS
[    0.193697] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.193697] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[    0.193697] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[    0.193697] TAA: Mitigation: TSX disabled
[    0.193697] SRBDS: Mitigation: TSX disabled
[    0.193697] Freeing SMP alternatives memory: 44K
[    0.193697] smpboot: CPU0: Intel(R) Core(TM) i7-8665U CPU @ 1.90GHz (family: 0x6, model: 0x8e, stepping: 0xc)
[    0.193697] Performance Events: Skylake events, 32-deep LBR, full-width counters, Intel PMU driver.
[    0.194334] ... version:                2
[    0.194590] ... bit width:              48
[    0.194700] ... generic registers:      4
[    0.194960] ... value mask:             0000ffffffffffff
[    0.195296] ... max period:             00007fffffffffff
[    0.195641] ... fixed-purpose events:   3
[    0.195699] ... event mask:             000000070000000f
[    0.196204] rcu: Hierarchical SRCU implementation.
[    0.197248] smp: Bringing up secondary CPUs ...
[    0.197551] smp: Brought up 1 node, 1 CPU
[    0.197701] smpboot: Max logical packages: 1
[    0.197979] smpboot: Total of 1 processors activated (4223.99 BogoMIPS)
[    0.200060] devtmpfs: initialized
[    0.200348] x86/mm: Memory block size: 128MB
[    0.201037] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[    0.201664] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
[    0.201788] pinctrl core: initialized pinctrl subsystem
[    0.202277] PM: RTC time: 11:23:27, date: 2022-02-09
[    0.202731] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.203210] DMA: preallocated 256 KiB GFP_KERNEL pool for atomic allocations
[    0.203672] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.203706] DMA: preallocated 256 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.204225] audit: initializing netlink subsys (disabled)
[    0.204792] thermal_sys: Registered thermal governor 'fair_share'
[    0.204793] thermal_sys: Registered thermal governor 'bang_bang'
[    0.205185] thermal_sys: Registered thermal governor 'step_wise'
[    0.205568] thermal_sys: Registered thermal governor 'user_space'
[    0.205705] cpuidle: using governor menu
[    0.206598] PCI: Using configuration type 1 for base access
[    0.207718] audit: type=2000 audit(1644405807.894:1): state=initialized audit_enabled=0 res=1
[    0.209047] Kprobes globally optimized
[    0.209439] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.209706] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.210309] alg: self-tests disabled
[    0.211005] cryptd: max_cpu_qlen set to 1000
[    0.212142] raid6: skip pq benchmark and using algorithm avx2x4
[    0.212557] raid6: using avx2x2 recovery algorithm
[    0.212748] ACPI: Interpreter disabled.
[    0.213048] iommu: Default domain type: Translated 
[    0.213386] iommu: DMA domain TLB invalidation policy: lazy mode 
[    0.213748] vgaarb: loaded
[    0.214016] SCSI subsystem initialized
[    0.214323] usbcore: USB support disabled
[    0.214619] pps_core: LinuxPPS API ver. 1 registered
[    0.214708] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.215301] PTP clock support registered
[    0.215599] EDAC MC: Ver: 3.0.0
[    0.215890] NetLabel: Initializing
[    0.216116] NetLabel:  domain hash size = 128
[    0.216410] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.216715] NetLabel:  unlabeled traffic allowed by default
[    0.217081] PCI: Probing PCI hardware
[    0.217337] PCI host bridge to bus 0000:00
[    0.217617] pci_bus 0000:00: root bus resource [io  0x0000-0xffff]
[    0.217700] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]
[    0.218144] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.218687] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.218918] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.219589] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.220572] pci 0000:00:01.1: reg 0x20: [io  0xc0a0-0xc0af]
[    0.221072] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.221532] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.221701] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.222258] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.222864] pci 0000:00:02.0: [1af4:1004] type 00 class 0x010000
[    0.223702] pci 0000:00:02.0: reg 0x10: [io  0xc000-0xc03f]
[    0.224559] pci 0000:00:02.0: reg 0x14: [mem 0xfebfe000-0xfebfefff]
[    0.226224] pci 0000:00:02.0: reg 0x20: [mem 0xfebf0000-0xfebf3fff 64bit pref]
[    0.227547] pci 0000:00:03.0: [1af4:1003] type 00 class 0x078000
[    0.228207] pci 0000:00:03.0: reg 0x10: [io  0xc040-0xc07f]
[    0.229050] pci 0000:00:03.0: reg 0x14: [mem 0xfebff000-0xfebfffff]
[    0.230434] pci 0000:00:03.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref]
[    0.231532] pci 0000:00:04.0: [1af4:1005] type 00 class 0x00ff00
[    0.232041] pci 0000:00:04.0: reg 0x10: [io  0xc080-0xc09f]
[    0.233637] pci 0000:00:04.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref]
[    0.234315] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    0.234717] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]
[    0.235367] clocksource: Switched to clocksource kvm-clock
[    0.346224] VFS: Disk quotas dquot_6.6.0
[    0.346523] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.347014] pnp: PnP ACPI: disabled
[    0.348593] NET: Registered PF_INET protocol family
[    0.349067] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.352713] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
[    0.353296] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.353879] TCP bind hash table entries: 16384 (order: 6, 262144 bytes, linear)
[    0.354475] TCP: Hash tables configured (established 16384 bind 16384)
[    0.355033] MPTCP token hash table entries: 2048 (order: 3, 49152 bytes, linear)
[    0.355529] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    0.355965] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    0.356451] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.356829] NET: Registered PF_XDP protocol family
[    0.357154] pci_bus 0000:00: resource 4 [io  0x0000-0xffff]
[    0.357512] pci_bus 0000:00: resource 5 [mem 0x00000000-0xffffffffff]
[    0.357932] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.358312] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.358706] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.359122] PCI: CLS 0 bytes, default 64
[    0.359439] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e71768ef8b, max_idle_ns: 440795218977 ns
[    0.360099] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.366356] Trying to unpack rootfs image as initramfs...
[    0.371845] Freeing initrd memory: 3696K
[    0.372486] Initialise system trusted keyrings
[    0.372810] Key type blacklist registered
[    0.373108] workingset: timestamp_bits=36 max_order=19 bucket_order=0
[    0.374591] zbud: loaded
[    0.375499] integrity: Platform Keyring initialized
[    0.381241] NET: Registered PF_ALG protocol family
[    0.381590] xor: automatically using best checksumming function   avx       
[    0.382057] Key type asymmetric registered
[    0.382330] Asymmetric key parser 'x509' registered
[    0.382678] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245)
[    0.383215] io scheduler mq-deadline registered
[    0.383511] io scheduler kyber registered
[    0.383802] io scheduler bfq registered
[    0.384338] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[    0.385048] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    0.385672] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 10
[    0.386689] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 11
[    0.387735] virtio-pci 0000:00:04.0: PCI->APIC IRQ transform: INT A -> IRQ 11
[    0.388722] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    0.389231] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    0.389938] Non-volatile memory driver v1.3
[    0.390448] Linux agpgart interface v0.103
[    0.391371] scsi host0: ata_piix
[    0.391744] scsi host1: ata_piix
[    0.392013] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14
[    0.392451] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15
[    0.392970] libphy: Fixed MDIO Bus: probed
[    0.393521] usbserial: usb_serial_init - registering generic driver failed
[    0.393972] usbserial: usb_serial_init - returning with error -19
[    0.394373] i8042: PNP: No PS/2 controller found.
[    0.394673] i8042: Probing ports directly.
[    0.395385] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.395710] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.396113] mousedev: PS/2 mouse device common for all mice
[    0.396819] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.397730] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[    0.398296] rtc_cmos rtc_cmos: registered as rtc0
[    0.398638] rtc_cmos rtc_cmos: setting system clock to 2022-02-09T11:23:27 UTC (1644405807)
[    0.399196] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram
[    0.399616] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    0.400413] device-mapper: uevent: version 1.0.3
[    0.400777] device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
[    0.401608] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2
[    0.402539] intel_pstate: CPU model not supported
[    0.402921] hid: raw HID events driver (C) Jiri Kosina
[    0.403338] intel_pmc_core intel_pmc_core.0:  initialized
[    0.403741] drop_monitor: Initializing network drop monitor service
[    0.404219] Initializing XFRM netlink socket
[    0.404569] NET: Registered PF_INET6 protocol family
[    0.405483] Segment Routing with IPv6
[    0.405753] RPL Segment Routing with IPv6
[    0.406020] In-situ OAM (IOAM) with IPv6
[    0.406291] mip6: Mobile IPv6
[    0.406482] NET: Registered PF_PACKET protocol family
[    0.406997] IPI shorthand broadcast: enabled
[    0.407292] AVX2 version of gcm_enc/dec engaged.
[    0.407604] AES CTR mode by8 optimization enabled
[    0.408186] sched_clock: Marking stable (362405579, 45508199)->(448403615, -40489837)
[    0.408812] registered taskstats version 1
[    0.409125] Loading compiled-in X.509 certificates
[    0.409879] Loaded X.509 cert 'Fedora kernel signing key: 261374f6539d47a88923f40083738a8ed9bf8d55'
[    0.410505] zswap: loaded using pool lzo/zbud
[    0.410877] page_owner is disabled
[    0.411139] Key type ._fscrypt registered
[    0.411401] Key type .fscrypt registered
[    0.411652] Key type fscrypt-provisioning registered
[    0.412276] Btrfs loaded, crc32c=crc32c-generic, zoned=yes, fsverity=yes
[    0.412726] Key type big_key registered
[    0.413098] Key type encrypted registered
[    0.413366] ima: No TPM chip found, activating TPM-bypass!
[    0.413728] Loading compiled-in module X.509 certificates
[    0.414450] Loaded X.509 cert 'Fedora kernel signing key: 261374f6539d47a88923f40083738a8ed9bf8d55'
[    0.415060] ima: Allocated hash algorithm: sha256
[    0.415383] ima: No architecture policies found
[    0.415706] evm: Initialising EVM extended attributes:
[    0.416035] evm: security.selinux
[    0.416251] evm: security.SMACK64 (disabled)
[    0.416519] evm: security.SMACK64EXEC (disabled)
[    0.416819] evm: security.SMACK64TRANSMUTE (disabled)
[    0.417147] evm: security.SMACK64MMAP (disabled)
[    0.417442] evm: security.apparmor (disabled)
[    0.417735] evm: security.ima
[    0.417994] evm: security.capability
[    0.418273] evm: HMAC attrs: 0x1
[    0.418837] PM:   Magic number: 10:638:377
[    0.419208] RAS: Correctable Errors collector initialized.
^H^H^H^H[    0.568738] Freeing unused decrypted memory: 2036K
[    0.574869] Freeing unused kernel image (initmem) memory: 2912K
[    0.576839] Write protecting the kernel read-only data: 28672k
[    0.582890] Freeing unused kernel image (text/rodata gap) memory: 2036K
[    0.584558] Freeing unused kernel image (rodata/data gap) memory: 40K
[    0.604787] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    0.605443] rodata_test: all tests were successful
[    0.605932] Run /init as init process
supermin: mounting /proc
supermin: ext2 mini initrd starting up: 5.3.1 dietlibc
supermin: cmdline: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=81e906d7-305a-43bd-b380-4bb614263725 selinux=0 guestfs_verbose=1 TERM=xterm-256color
supermin: uptime: 0.56 0.14
supermin: mounting /sys
supermin: internal insmod crc32-pclmul.ko
supermin: internal insmod crc32c-intel.ko
supermin: internal insmod crct10dif-pclmul.ko
supermin: internal insmod crc32_generic.ko
supermin: internal insmod libnvdimm.ko
supermin: internal insmod nfit.ko
insmod: init_module: nfit.ko: No such device
supermin: internal insmod virtio_blk.ko
supermin: internal insmod ecdh_generic.ko
supermin: internal insmod rfkill.ko
supermin: internal insmod bluetooth.ko
[    0.635432] Bluetooth: Core ver 2.22
[    0.635836] NET: Registered PF_BLUETOOTH protocol family
[    0.636261] Bluetooth: HCI device and connection manager initialized
[    0.636744] Bluetooth: HCI socket layer initialized
[    0.637124] Bluetooth: L2CAP socket layer initialized
[    0.637504] Bluetooth: SCO socket layer initialized
supermin: internal insmod virtio_bt.ko
supermin: internal insmod virtio_console.ko
supermin: internal insmod crypto_engine.ko
supermin: internal insmod virtio_crypto.ko
supermin: internal insmod failover.ko
supermin: internal insmod net_failover.ko
supermin: internal insmod virtio_net.ko
supermin: internal insmod nd_btt.ko
supermin: internal insmod nd_pmem.ko
supermin: internal insmod virtio_scsi.ko
[    0.662741] scsi host2: Virtio SCSI HBA
[    0.664060] scsi 2:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    0.665079] scsi 2:0:1:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    0.674115] sd 2:0:0:0: Power-on or device reset occurred
[    0.674544] sd 2:0:0:0: Attached scsi generic sg0 type 0
[    0.675192] sd 2:0:1:0: Power-on or device reset occurred
[    0.675592] sd 2:0:0:0: [sda] 204800 512-byte logical blocks: (105 MB/100 MiB)
[    0.676143] sd 2:0:1:0: Attached scsi generic sg1 type 0
[    0.676597] sd 2:0:0:0: [sda] Write Protect is off
[    0.677067] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.677945] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)
[    0.678607] sd 2:0:1:0: [sdb] Write Protect is off
[    0.679172] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.685975] sd 2:0:0:0: [sda] Attached SCSI disk
[    0.686323] sd 2:0:1:0: [sdb] Attached SCSI disk
supermin: internal insmod virtio_balloon.ko
supermin: internal insmod virtio_dma_buf.ko
supermin: internal insmod virtio_input.ko
supermin: internal insmod virtio_mem.ko
supermin: internal insmod virtio_mmio.ko
supermin: internal insmod vdpa.ko
supermin: internal insmod virtio_vdpa.ko
supermin: internal insmod fuse.ko
[    0.695704] fuse: init (API version 7.34)
supermin: internal insmod virtiofs.ko
supermin: internal insmod crc-itu-t.ko
supermin: internal insmod crc4.ko
supermin: internal insmod crc64.ko
supermin: internal insmod crc7.ko
supermin: internal insmod crc8.ko
supermin: internal insmod soundcore.ko
supermin: internal insmod snd.ko
supermin: internal insmod snd-timer.ko
supermin: internal insmod snd-pcm.ko
supermin: internal insmod virtio_snd.ko
supermin: picked 8:16 as root device
supermin: creating /dev/root as block special 8:16
supermin: mounting new root on /root
[    0.710066] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem
[    0.711920] EXT4-fs (sdb): mounted filesystem without journal. Opts: . Quota mode: none.
supermin: deleting initramfs files
supermin: chroot
/bin/bash: error while loading shared libraries: libtinfo.so.6: cannot open shared object file: No such file or directory
[    0.715609] Kernel panic - not syncing: Attempted to kill init! exitcode=0x00007f00
[    0.716111] CPU: 0 PID: 1 Comm: init Not tainted 5.15.16-100.fc34.x86_64 #1
[    0.716560] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.14.0-4.fc34 04/01/2014
[    0.717123] Call Trace:
[    0.717295]  <TASK>
[    0.717436]  dump_stack_lvl+0x46/0x5a
[    0.717691]  panic+0xe3/0x2bb
[    0.717895]  do_exit.cold+0x9f/0x9f
[    0.718177]  do_group_exit+0x33/0xa0
[    0.718442]  __x64_sys_exit_group+0x14/0x20
[    0.718738]  do_syscall_64+0x38/0x90
[    0.718978]  entry_SYSCALL_64_after_hwframe+0x44/0xae
[    0.719317] RIP: 0033:0x7f75de5035b1
[    0.719557] Code: 1f 84 00 00 00 00 00 66 90 f3 0f 1e fa be e7 00 00 00 ba 3c 00 00 00 eb 0d 89 d0 0f 05 48 3d 00 f0 ff ff 77 1c f4 89 f0 0f 05 <48> 3d 00 f0 ff ff 76 e7 f7 d8 89 05 e7 1b 01 00 eb dd 0f 1f 44 00
[    0.720773] RSP: 002b:00007ffe604bb7a8 EFLAGS: 00000246 ORIG_RAX: 00000000000000e7
[    0.721255] RAX: ffffffffffffffda RBX: 0000000000000002 RCX: 00007f75de5035b1
[    0.721719] RDX: 000000000000003c RSI: 00000000000000e7 RDI: 000000000000007f
[    0.722199] RBP: 00007ffe604bc220 R08: 0000000000000000 R09: 0000000000000000
[    0.722649] R10: 00000000ffffffff R11: 0000000000000246 R12: 00007f75de50c960
[    0.723101] R13: 00007f75de515e6f R14: 00007f75de515e80 R15: 0000000000000000
[    0.723550]  </TASK>
[    0.723722] Kernel Offset: 0x2000000 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffffbfffffff)
[    0.724398] Rebooting in 1 seconds..
libguestfs: error: appliance closed the connection unexpectedly, see earlier error messages
libguestfs: child_cleanup: 0x55cba865d1a0: child process died
libguestfs: error: guestfs_launch failed, see earlier error messages
libguestfs: trace: launch = -1 (error)
libguestfs: trace: close
libguestfs: closing guestfs handle 0x55cba865d1a0 (state 0)
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfswnJqGy
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /run/user/1000/libguestfsSGtFJR

frantisekz · 2022-02-09T11:29:04Z

Thanks, okay, I'll write an email to Richard, I'll relay the reply here if he doesn't chime in directly.

rwmjones · 2022-02-09T11:58:03Z

The problem isn't anything to do with TCG vs KVM:

/bin/bash: error while loading shared libraries: libtinfo.so.6: cannot open shared object file: No such file or directory

This is the Fedora 34 version of libguestfs? Is it modified in some way? The dependencies seem to be missing for some reason.

rwmjones · 2022-02-09T12:28:03Z

I'm not able to reproduce this with the Fedora package and all the deps look correct. Have you manually deleted /lib64/libtinfo.so.6? Does it work if you delete /var/tmp/.guestfs-* and rerun? What is the complete output of the following command (which will be very long)?

supermin -v -v -v --build -f ext2 /usr/lib64/guestfs/supermin.d -o /var/tmp/appliance

janhavlin · 2022-02-09T13:36:44Z

Have you manually deleted /lib64/libtinfo.so.6?

I'm quite certain that libtinfo.so.6 is present and unmodified.

Does it work if you delete /var/tmp/.guestfs-* and rerun?

Nope, same error occurs.

What is the complete output of the following command (which will be very long)?

Uploaded the output here: supermin-output.txt

rwmjones · 2022-02-09T13:51:39Z

I'm not sure I have any idea. It's copying the file into the appliance:

supermin: ext2: copy_file /usr/lib64/libtinfo.so.6 -> /usr/lib64/libtinfo.so.6
supermin: ext2: copy_file /usr/lib64/libtinfo.so.6.1 -> /usr/lib64/libtinfo.so.6.1

but why wouldn't bash then be able to load it at runtime?

These problems can sometimes be caused by unexpected changes in SONAMEs of libraries (although I don't think that is the case here). Just in case I am rebuilding libguestfs for F34, can you see if the new package fixes it? ~~https://koji.fedoraproject.org/koji/taskinfo?taskID=82599722~~ https://koji.fedoraproject.org/koji/taskinfo?taskID=82600583

janhavlin · 2022-02-09T17:24:11Z

After installing the new package, it is still failing. The output has changed but I still have no idea what's wrong.

output

$ tmt run
/var/tmp/tmt/run-003

/tests/public/guest-setup/centos-stream-8
    discover
        how: shell
        summary: 1 test selected
    provision
        how: virtual
        image: CentOS-Stream-8
        user: root
        memory: 2048 MB
        disk: 10 GB
        progress: booting...
libguestfs: trace: set_verbose true
libguestfs: trace: set_verbose = 0
libguestfs: create: flags = 0, handle = 0x556bb03021a0, program = virt-make-fs
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: disk_create "/var/tmp/tmt/testcloud/instances/tmt-003-qxKedtKl/tmt-003-qxKedtKl-seed.img" "raw" 294350
libguestfs: trace: disk_create = 0
libguestfs: trace: add_drive "/var/tmp/tmt/testcloud/instances/tmt-003-qxKedtKl/tmt-003-qxKedtKl-seed.img" "format:raw" "blocksize:0"
libguestfs: trace: add_drive = 0
libguestfs: trace: launch
libguestfs: trace: max_disks
libguestfs: trace: max_disks = 255
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: version
libguestfs: trace: version = <struct guestfs_version = major: 1, minor: 46, release: 2, extra: fedora=34,release=1.fc34.1,libvirt, >
libguestfs: trace: get_backend
libguestfs: trace: get_backend = "libvirt"
libguestfs: launch: program=virt-make-fs
libguestfs: launch: version=1.46.2fedora=34,release=1.fc34.1,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/libguestfsH8avap
libguestfs: launch: umask=0002
libguestfs: launch: euid=1000
libguestfs: libvirt version = 7000000 (7.0.0)
libguestfs: guest random name = guestfs-hwsokqk2yda9wxuo
libguestfs: connect to libvirt
libguestfs: opening libvirt handle: URI = qemu:///session, auth = default+wrapper, flags = 0
libguestfs: successfully opened libvirt handle: conn = 0x556bb0308030
libguestfs: qemu version (reported by libvirt) = 5002000 (5.2.0)
libguestfs: get libvirt capabilities
libguestfs: parsing capabilities XML
libguestfs: trace: get_backend_setting "force_kvm"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "force_tcg"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: parsing domcapabilities XML
libguestfs: trace: get_backend_setting "internal_libvirt_label"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "internal_libvirt_imagelabel"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_backend_setting "internal_libvirt_norelabel_disks"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: build appliance
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: begin building supermin appliance
libguestfs: 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-1000/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-1000/appliance.d
supermin: version: 5.3.1
supermin: rpm: detected RPM version 4.16
supermin: rpm: detected RPM architecture x86_64
supermin: package handler: fedora/rpm
supermin: acquiring lock on /var/tmp/.guestfs-1000/lock
supermin: if-newer: output does not need rebuilding
libguestfs: finished building supermin appliance
libguestfs: trace: disk_create "/tmp/libguestfsH8avap/overlay1.qcow2" "qcow2" -1 "backingfile:/var/tmp/.guestfs-1000/appliance.d/root"
libguestfs: trace: disk_format "/var/tmp/.guestfs-1000/appliance.d/root"
libguestfs: command: run: qemu-img --help | grep -sqE -- '\binfo\b.*-U\b'
libguestfs: command: run: qemu-img
libguestfs: command: run: \ info
libguestfs: command: run: \ -U
libguestfs: command: run: \ --output json
libguestfs: command: run: \ /var/tmp/.guestfs-1000/appliance.d/root
libguestfs: parse_json: qemu-img info JSON output:\n{\n    "virtual-size": 4294967296,\n    "filename": "/var/tmp/.guestfs-1000/appliance.d/root",\n    "format": "raw",\n    "actual-size": 418631680,\n    "dirty-flag": false\n}\n\n
libguestfs: trace: disk_format = "raw"
libguestfs: command: run: qemu-img
libguestfs: command: run: \ create
libguestfs: command: run: \ -f qcow2
libguestfs: command: run: \ -o backing_file=/var/tmp/.guestfs-1000/appliance.d/root,backing_fmt=raw
libguestfs: command: run: \ /tmp/libguestfsH8avap/overlay1.qcow2
Formatting '/tmp/libguestfsH8avap/overlay1.qcow2', fmt=qcow2 cluster_size=65536 extended_l2=off compression_type=zlib size=4294967296 backing_file=/var/tmp/.guestfs-1000/appliance.d/root backing_fmt=raw lazy_refcounts=off refcount_bits=16
libguestfs: trace: disk_create = 0
libguestfs: trace: get_sockdir
libguestfs: trace: get_sockdir = "/run/user/1000"
libguestfs: create libvirt XML
libguestfs: command: run: dmesg | grep -Eoh 'lpj=[[:digit:]]+'
libguestfs: read_lpj_from_dmesg: external command exited with error status 1
libguestfs: read_lpj_from_files: no boot messages files are readable
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: libvirt XML:\n<?xml version="1.0"?>\n<domain type="qemu" xmlns:qemu="http://libvirt.org/schemas/domain/qemu/1.0">\n  <name>guestfs-hwsokqk2yda9wxuo</name>\n  <memory unit="MiB">1280</memory>\n  <currentMemory unit="MiB">1280</currentMemory>\n  <cpu mode="host-model">\n    <model fallback="allow"/>\n  </cpu>\n  <vcpu>1</vcpu>\n  <clock offset="utc">\n    <timer name="rtc" tickpolicy="catchup"/>\n    <timer name="pit" tickpolicy="delay"/>\n    <timer name="hpet" present="no"/>\n  </clock>\n  <os>\n    <type>hvm</type>\n    <kernel>/var/tmp/.guestfs-1000/appliance.d/kernel</kernel>\n    <initrd>/var/tmp/.guestfs-1000/appliance.d/initrd</initrd>\n    <cmdline>panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=UUID=f812cf86-eba4-4a01-8110-6d6ed8bd7076 selinux=0 guestfs_verbose=1 TERM=xterm-256color</cmdline>\n    <bios useserial="yes"/>\n  </os>\n  <seclabel type="none"/>\n  <on_reboot>destroy</on_reboot>\n  <devices>\n    <emulator>/usr/bin/qemu-kvm</emulator>\n    <rng model="virtio">\n      <backend model="random">/dev/urandom</backend>\n    </rng>\n    <controller type="scsi" index="0" model="virtio-scsi"/>\n    <disk device="disk" type="file">\n      <source file="/var/tmp/tmt/testcloud/instances/tmt-003-qxKedtKl/tmt-003-qxKedtKl-seed.img"/>\n      <target dev="sda" bus="scsi"/>\n      <driver name="qemu" type="raw" cache="writeback"/>\n      <address type="drive" controller="0" bus="0" target="0" unit="0"/>\n    </disk>\n    <disk type="file" device="disk">\n      <source file="/tmp/libguestfsH8avap/overlay1.qcow2"/>\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    </disk>\n    <serial type="unix">\n      <source mode="connect" path="/run/user/1000/libguestfsd4Pkqk/console.sock"/>\n      <target port="0"/>\n    </serial>\n    <channel type="unix">\n      <source mode="connect" path="/run/user/1000/libguestfsd4Pkqk/guestfsd.sock"/>\n      <target type="virtio" name="org.libguestfs.channel.0"/>\n    </channel>\n    <controller type="usb" model="none"/>\n    <memballoon model="none"/>\n  </devices>\n  <qemu:commandline>\n    <qemu:env name="TMPDIR" value="/var/tmp"/>\n  </qemu:commandline>\n</domain>\n
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: command: run: ls
libguestfs: command: run: \ -a
libguestfs: command: run: \ -l
libguestfs: command: run: \ -R
libguestfs: command: run: \ -Z /var/tmp/.guestfs-1000
libguestfs: /var/tmp/.guestfs-1000:
libguestfs: total 12
libguestfs: drwxr-xr-x.  3 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0 4096 Feb  9 18:19 .
libguestfs: drwxrwxrwt. 24 root    root    system_u:object_r:tmp_t:s0          4096 Feb  9 18:19 ..
libguestfs: drwxr-xr-x.  2 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0 4096 Feb  9 18:18 appliance.d
libguestfs: -rw-r--r--.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    0 Feb  9 14:26 lock
libguestfs: 
libguestfs: /var/tmp/.guestfs-1000/appliance.d:
libguestfs: total 424176
libguestfs: drwxr-xr-x. 2 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0       4096 Feb  9 18:18 .
libguestfs: drwxr-xr-x. 3 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0       4096 Feb  9 18:19 ..
libguestfs: -rw-r--r--. 1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    4412416 Feb  9 18:19 initrd
libguestfs: -rwxr-xr-x. 1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0   11300464 Feb  9 18:19 kernel
libguestfs: -rw-r--r--. 1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0 4294967296 Feb  9 18:19 root
libguestfs: command: run: ls
libguestfs: command: run: \ -a
libguestfs: command: run: \ -l
libguestfs: command: run: \ -Z /run/user/1000/libguestfsd4Pkqk
libguestfs: total 0
libguestfs: drwx------.  2 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0   80 Feb  9 18:19 .
libguestfs: drwx------. 25 jhavlin jhavlin system_u:object_r:user_tmp_t:s0     1100 Feb  9 18:19 ..
libguestfs: srwxrwxr-x.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    0 Feb  9 18:19 console.sock
libguestfs: srwxrwxr-x.  1 jhavlin jhavlin unconfined_u:object_r:user_tmp_t:s0    0 Feb  9 18:19 guestfsd.sock
libguestfs: launch libvirt guest
libguestfs: responding to serial console Device Status Report
\x1b[1;256r\x1b[256;256H\x1b[6n
Google, Inc.
Serial Graphics Adapter 01/29/21
SGABIOS $Id$ (mockbuild@) Fri Jan 29 01:55:59 UTC 2021
Term: 80x24
4 0
SeaBIOS (version 1.14.0-4.fc34)
Machine UUID 4cb9c154-623f-4237-80fe-c5a4e52c1198
Booting from ROM...
\x1b[2Jlibguestfs: error: error reading console messages from the appliance: Connection reset by peer
libguestfs: error: guestfs_launch failed, see earlier error messages
libguestfs: trace: launch = -1 (error)
libguestfs: trace: close
libguestfs: closing guestfs handle 0x556bb03021a0 (state 0)
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfsH8avap
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /run/user/1000/libguestfsd4Pkqk
Seed image generation failed. Exiting
    finish
libvirt: QEMU Driver error : Domain not found: no domain with matching name 'tmt-003-qxKedtKl'

rwmjones · 2022-02-09T17:32:55Z

Can you try upgrading any/all of: kernel, qemu, seabios-bin and see if the problem goes away?

Since Fedora 34 support ends in less than a month, I would update to Fedora 35.

janhavlin · 2022-02-09T22:37:36Z

Can you try upgrading any/all of: kernel, qemu, seabios-bin and see if the problem goes away?

This did not help either.

Since Fedora 34 support ends in less than a month, I would update to Fedora 35.

I upgraded to Fedora 35 and it seems to be working now. It is possible that my Fedora 34 system was corrupted in some way.

I'm closing this issue since the original note is not even correct, this wasn't related to /dev/kvm at all. Thank you for your help.

janhavlin closed this as completed Feb 9, 2022

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Prettier report to the user when /dev/kvm is not accessible #1042

Prettier report to the user when /dev/kvm is not accessible #1042

janhavlin commented Feb 9, 2022

frantisekz commented Feb 9, 2022

frantisekz commented Feb 9, 2022

janhavlin commented Feb 9, 2022

frantisekz commented Feb 9, 2022

rwmjones commented Feb 9, 2022

rwmjones commented Feb 9, 2022

janhavlin commented Feb 9, 2022

rwmjones commented Feb 9, 2022 •

edited

janhavlin commented Feb 9, 2022

rwmjones commented Feb 9, 2022

janhavlin commented Feb 9, 2022

Prettier report to the user when /dev/kvm is not accessible #1042

Prettier report to the user when /dev/kvm is not accessible #1042

Comments

janhavlin commented Feb 9, 2022

frantisekz commented Feb 9, 2022

frantisekz commented Feb 9, 2022

janhavlin commented Feb 9, 2022

frantisekz commented Feb 9, 2022

rwmjones commented Feb 9, 2022

rwmjones commented Feb 9, 2022

janhavlin commented Feb 9, 2022

rwmjones commented Feb 9, 2022 • edited

janhavlin commented Feb 9, 2022

rwmjones commented Feb 9, 2022

janhavlin commented Feb 9, 2022

rwmjones commented Feb 9, 2022 •

edited