Hi,
when I tested building nbdkit 1.1.15 in a current Debian chroot, I ran
into the following test failure. A repeated build went fine through the
tests and so far I have not been able to reproduce it with the previous
version.
The failing build was done using a clean Debian/sid, amd64 chroot
spawned by sbuild.
Cheers,
-Hilko
FAIL: test-python
================
/<<PKGBUILDDIR>>/src/nbdkit -U /tmp/nbdkit2LyKB1/sock -P
/tmp/nbdkit2LyKB1/pid -f -v
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so
script=test.py
nbdkit: debug: TLS disabled: could not load TLS certificates
nbdkit: debug: registering
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so
nbdkit: debug: registered
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so (name
python)
nbdkit: debug:
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so: load
nbdkit: debug:
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so:
config key=script, value=test.py
nbdkit: debug:
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so:
config_complete
nbdkit: debug: bound to unix socket /tmp/nbdkit2LyKB1/sock
nbdkit: debug: written pidfile /tmp/nbdkit2LyKB1/pid
libguestfs: trace: set_verbose true
libguestfs: trace: set_verbose = 0
libguestfs: trace: set_backend "appliance"
libguestfs: trace: set_backend = 0
libguestfs: create: flags = 0, handle = 0x559852bbbaf0, program = test-python
libguestfs: trace: add_drive "" "format:raw"
"protocol:nbd" "server:unix:/tmp/nbdkit2LyKB1/sock"
libguestfs: trace: add_drive = 0
libguestfs: trace: launch
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: version
libguestfs: trace: version = <struct guestfs_version = major: 1, minor: 36,
release: 6, extra: , >
libguestfs: trace: get_backend
libguestfs: trace: get_backend = "direct"
libguestfs: launch: program=test-python
libguestfs: launch: version=1.36.6
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/libguestfsq6xGkY
libguestfs: launch: umask=0002
libguestfs: launch: euid=1000
libguestfs: is_openable: /dev/kvm: No such file or directory
libguestfs: trace: get_backend_setting "force_tcg"
libguestfs: trace: get_backend_setting = NULL (error)
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/lib/x86_64-linux-gnu/guestfs/supermin.d
libguestfs: command: run: \ -o /var/tmp/.guestfs-1000/appliance.d
supermin: version: 5.1.19
supermin: package handler: debian/dpkg
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-system-x86_64, in /var/tmp/.guestfs-1000
libguestfs: command: run: /usr/bin/qemu-system-x86_64
libguestfs: command: run: \ -display none
libguestfs: command: run: \ -help
libguestfs: command: run: /usr/bin/qemu-system-x86_64
libguestfs: command: run: \ -display none
libguestfs: command: run: \ -machine accel=kvm:tcg
libguestfs: command: run: \ -device ?
libguestfs: command: run: echo '{ "execute":
"qmp_capabilities" }' '{ "execute":
"query-qmp-schema" }' '{ "execute": "quit"
}' | "/usr/bin/qemu-system-x86_64" -display none -machine
"accel=kvm:tcg" -qmp stdio
libguestfs: saving test results
libguestfs: qemu version: 2.10
libguestfs: qemu mandatory locking: yes
libguestfs: trace: get_sockdir
libguestfs: trace: get_sockdir = "/tmp"
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:]]+'
dmesg: read kernel buffer failed: Operation not permitted
libguestfs: read_lpj_from_dmesg: external command exited with error status 1
libguestfs: read_lpj_from_files: no boot messages files are readable
[12370ms] /usr/bin/qemu-system-x86_64 \
-global virtio-blk-pci.scsi=off \
-nodefconfig \
-enable-fips \
-nodefaults \
-display none \
-machine accel=kvm:tcg \
-m 500 \
-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=nbd:unix:/tmp/nbdkit2LyKB1/sock,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,format=raw
\
-device scsi-hd,drive=appliance \
-device virtio-serial-pci \
-serial stdio \
-device sga \
-chardev socket,path=/tmp/libguestfsnY8aXw/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=/dev/sdb
selinux=0 guestfs_verbose=1 TERM=linux'
nbdkit: Could not access KVM kernel module: No such file or directory
qemu-system-x86_64: failed to initialize KVM: No such file or directory
qemu-system-x86_64: Back to tcg accelerator
debug: accepted connection
nbdkit: debug:
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so: open
readonly=0
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: get_size
nbdkit: python[1]: debug: can_write
nbdkit: python[1]: debug: can_flush
nbdkit: python[1]: debug: is_rotational
nbdkit: python[1]: debug: can_trim
nbdkit: python[1]: debug: oldstyle negotiation: flags: global 0x0 export 0x6d
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
libguestfs: responding to serial console Device Status Report
\x1b[1;256r\x1b[256;256H\x1b[6n
Google, Inc.
Serial Graphics Adapter 03/05/17
SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (generic@generic) Sun,
05 Mar 2017 16:09:17 +0100
Term: 80x24
4 0
\x1b[2J
SeaBIOS (version 1.10.2-1)
Booting from ROM...
\x1b[2J[ 0.000000] Linux version 4.12.0-2-amd64
(debian-kernel@lists.debian.org) (gcc version 6.4.0 20170915 (Debian 6.4.0-6) )
#1 SMP Debian 4.12.13-1 (2017-09-19)
[ 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=/dev/sdb
selinux=0 guestfs_verbose=1 TERM=linux
[ 0.000000] x86/fpu: x87 FPU will use FXSAVE
[ 0.000000] e820: BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3ddfff] usable
[ 0.000000] BIOS-e820: [mem 0x000000001f3de000-0x000000001f3fffff] 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.10.2-1
04/01/2014
[ 0.000000] tsc: Unable to calibrate against PIT
[ 0.000000] tsc: No reference (HPET/PMTIMER) available
[ 0.000000] e820: last_pfn = 0x1f3de max_arch_pfn = 0x400000000
[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT
[ 0.000000] found SMP MP-table at [mem 0x000f6a90-0x000f6a9f] mapped at
[ffff90aac00f6a90]
[ 0.000000] RAMDISK: [mem 0x1f0e4000-0x1f3cffff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F68C0 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000001F3E156F 00002C (v01 BOCHS BXPCRSDT
00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x000000001F3E1403 000074 (v01 BOCHS BXPCFACP
00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x000000001F3E0040 0013C3 (v01 BOCHS BXPCDSDT
00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x000000001F3E0000 000040
[ 0.000000] ACPI: APIC 0x000000001F3E14F7 000078 (v01 BOCHS BXPCAPIC
00000001 BXPC 00000001)
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3ddfff]
[ 0.000000] NODE_DATA(0) allocated [mem 0x1f3d9000-0x1f3ddfff]
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001f3ddfff]
[ 0.000000] Normal empty
[ 0.000000] Device empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.000000] node 0: [mem 0x0000000000100000-0x000000001f3ddfff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3ddfff]
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.000000] e820: [mem 0x1f400000-0xfffbffff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on bare hardware
[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 7645519600211568 ns
[ 0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1
nr_node_ids:1
[ 0.000000] percpu: Embedded 38 pages/cpu @ffff90aadee00000 s114840 r8192
d32616 u2097152
[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total
pages: 125847
[ 0.000000] Policy zone: DMA32
[ 0.000000] 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=/dev/sdb selinux=0 guestfs_verbose=1 TERM=linux
[ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.000000] Memory: 484256K/511472K available (6485K kernel code, 1145K
rwdata, 2908K rodata, 1468K init, 704K bss, 27216K reserved, 0K cma-reserved)
[ 0.000000] ftrace: allocating 28157 entries in 110 pages
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] \tRCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[ 0.000000] NR_IRQS:33024 nr_irqs:256 16
[ 0.000000] Console: colour *CGA 80x25
[ 0.000000] console [ttyS0] enabled
[ 0.020000] tsc: Unable to calibrate against PIT
[ 0.020000] tsc: using PMTIMER reference calibration
[ 0.020000] tsc: Detected 2809.337 MHz processor
[ 0.020000] Calibrating delay loop (skipped), value calculated using timer
frequency.. 5618.67 BogoMIPS (lpj=11237348)
[ 0.020000] pid_max: default: 32768 minimum: 301
[ 0.024696] ACPI: Core revision 20170303
[ 0.064922] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.069246] Security Framework initialized
[ 0.069715] Yama: disabled by default; enable with sysctl kernel.yama.*
[ 0.071366] AppArmor: AppArmor disabled by boot time parameter
[ 0.072973] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.074818] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[ 0.076384] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[ 0.076721] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[ 0.107160] Disabling memory control group subsystem
[ 0.111224] mce: CPU supports 10 MCE banks
[ 0.113243] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.113453] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.530091] Freeing SMP alternatives memory: 24K
[ 0.569547] smpboot: Max logical packages: 1
[ 0.577621] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.580000] smpboot: CPU0: AMD QEMU Virtual CPU version 2.5+ (family: 0x6,
model: 0x6, stepping: 0x3)
[ 0.584522] Performance Events: PMU not available due to virtualization,
using software events only.
[ 0.605761] NMI watchdog: disabled (cpu0): hardware events not enabled
[ 0.606080] NMI watchdog: Shutting down hard lockup detector on all cpus
[ 0.609091] smp: Bringing up secondary CPUs ...
[ 0.609418] smp: Brought up 1 node, 1 CPU
[ 0.609657] smpboot: Total of 1 processors activated (5618.67 BogoMIPS)
[ 0.617582] sched_clock: Marking stable (616000000, 0)->(811869750,
-195869750)
[ 0.815783] devtmpfs: initialized
[ 0.821093] x86/mm: Memory block size: 128MB
[ 0.861830] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff,
max_idle_ns: 7645041785100000 ns
[ 0.862517] futex hash table entries: 256 (order: 2, 16384 bytes)
[ 0.866879] pinctrl core: initialized pinctrl subsystem
[ 0.874662] NET: Registered protocol family 16
[ 0.883387] cpuidle: using governor ladder
[ 0.883943] cpuidle: using governor menu
[ 0.884183] PCCT header not found.
[ 0.885768] ACPI: bus type PCI registered
[ 0.886078] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.889587] PCI: Using configuration type 1 for base access
[ 0.912976] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 0.924204] ACPI: Added _OSI(Module Device)
[ 0.924435] ACPI: Added _OSI(Processor Device)
[ 0.924567] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.924692] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.968957] ACPI: Interpreter enabled
[ 0.970574] ACPI: (supports S0 S3 S4 S5)
[ 0.970807] ACPI: Using IOAPIC for interrupt routing
[ 0.972576] PCI: Using host bridge windows from ACPI; if necessary, use
"pci=nocrs" and report a bug
[ 1.059981] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 1.060822] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[ 1.061516] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 1.062424] acpi PNP0A03:00: fail to add MMCONFIG information, can't
access extended PCI configuration space under this bridge.
[ 1.074614] acpiphp: Slot [2] registered
[ 1.075170] acpiphp: Slot [3] registered
[ 1.075883] acpiphp: Slot [4] registered
[ 1.076295] acpiphp: Slot [5] registered
[ 1.076678] acpiphp: Slot [6] registered
[ 1.077124] acpiphp: Slot [7] registered
[ 1.077508] acpiphp: Slot [8] registered
[ 1.077850] acpiphp: Slot [9] registered
[ 1.078332] acpiphp: Slot [10] registered
[ 1.078742] acpiphp: Slot [11] registered
[ 1.079148] acpiphp: Slot [12] registered
[ 1.079798] acpiphp: Slot [13] registered
[ 1.080226] acpiphp: Slot [14] registered
[ 1.080700] acpiphp: Slot [15] registered
[ 1.081368] acpiphp: Slot [16] registered
[ 1.081749] acpiphp: Slot [17] registered
[ 1.082181] acpiphp: Slot [18] registered
[ 1.082563] acpiphp: Slot [19] registered
[ 1.082903] acpiphp: Slot [20] registered
[ 1.083235] acpiphp: Slot [21] registered
[ 1.083921] acpiphp: Slot [22] registered
[ 1.084332] acpiphp: Slot [23] registered
[ 1.084807] acpiphp: Slot [24] registered
[ 1.085161] acpiphp: Slot [25] registered
[ 1.085496] acpiphp: Slot [26] registered
[ 1.085943] acpiphp: Slot [27] registered
[ 1.086285] acpiphp: Slot [28] registered
[ 1.086617] acpiphp: Slot [29] registered
[ 1.087058] acpiphp: Slot [30] registered
[ 1.087592] acpiphp: Slot [31] registered
[ 1.088223] PCI host bridge to bus 0000:00
[ 1.088653] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 1.088862] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 1.089102] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff
window]
[ 1.089522] pci_bus 0000:00: root bus resource [mem 0x1f400000-0xfebfffff
window]
[ 1.089913] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.108768] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 1.109194] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 1.109432] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 1.109654] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 1.114521] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4
ACPI
[ 1.114874] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 1.227071] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 1.229506] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 1.231268] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 1.233376] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 1.234268] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 1.238235] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 1.245441] vgaarb: loaded
[ 1.247695] EDAC MC: Ver: 3.0.0
[ 1.250975] PCI: Using ACPI for IRQ routing
[ 1.265703] clocksource: Switched to clocksource refined-jiffies
[ 1.569922] VFS: Disk quotas dquot_6.6.0
[ 1.570569] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 1.577571] pnp: PnP ACPI init
[ 1.591981] pnp: PnP ACPI: found 5 devices
[ 1.672977] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff,
max_idle_ns: 2085701024 ns
[ 1.675221] clocksource: Switched to clocksource acpi_pm
[ 1.680332] NET: Registered protocol family 2
[ 1.690310] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[ 1.691038] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
[ 1.691826] TCP: Hash tables configured (established 4096 bind 4096)
[ 1.693880] UDP hash table entries: 256 (order: 1, 8192 bytes)
[ 1.694416] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[ 1.697366] NET: Registered protocol family 1
[ 1.698237] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 1.698670] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 1.699097] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 1.712887] Unpacking initramfs...
[ 2.207961] Freeing initrd memory: 2992K
[ 2.221109] audit: initializing netlink subsys (disabled)
[ 2.228064] audit: type=2000 audit(1506547297.871:1): state=initialized
audit_enabled=0 res=1
[ 2.229687] workingset: timestamp_bits=40 max_order=17 bucket_order=0
[ 2.230553] zbud: loaded
[ 2.236193] alg: self-tests disabled
[ 2.248999] Key type asymmetric registered
[ 2.249311] Asymmetric key parser 'x509' registered
[ 2.249906] Block layer SCSI generic (bsg) driver version 0.4 loaded (major
249)
[ 2.251087] io scheduler noop registered
[ 2.252477] io scheduler deadline registered
[ 2.253048] io scheduler cfq registered (default)
[ 2.253238] io scheduler mq-deadline registered
[ 2.262063] GHES: HEST is not enabled!
[ 2.268660] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[ 2.296298] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a
16550A
[ 2.300638] Linux agpgart interface v0.103
[ 2.304751] AMD IOMMUv2 driver by Joerg Roedel <jroedel@suse.de>
[ 2.305011] AMD IOMMUv2 functionality not available on this system
[ 2.309874] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at
0x60,0x64 irq 1,12
[ 2.314572] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 2.315184] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 2.319146] mousedev: PS/2 mouse device common for all mice
[ 2.325248] rtc_cmos 00:00: RTC can wake from S4
[ 2.327781] input: AT Translated Set 2 keyboard as
/devices/platform/i8042/serio0/input/input0
[ 2.355104] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[ 2.356772] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram
[ 2.358002] ledtrig-cpu: registered to indicate activity on CPUs
[ 2.362026] NET: Registered protocol family 10
[ 2.456357] Segment Routing with IPv6
[ 2.457247] mip6: Mobile IPv6
[ 2.457600] NET: Registered protocol family 17
[ 2.457928] mpls_gso: MPLS GSO support
[ 2.464942] registered taskstats version 1
[ 2.466631] zswap: loaded using pool lzo/zbud
[ 2.472281] ima: No TPM chip found, activating TPM-bypass! (rc=-19)
[ 2.480663] rtc_cmos 00:00: setting system clock to 2017-09-27 21:21:39 UTC
(1506547299)
[ 2.543466] Freeing unused kernel memory: 1468K
[ 2.543791] Write protecting the kernel read-only data: 12288k
[ 2.549544] Freeing unused kernel memory: 1692K
[ 2.573006] Freeing unused kernel memory: 1188K
[ 2.590905] x86/mm: Checked W+X mappings: passed, no W+X pages found.
supermin: mounting /proc
supermin: ext2 mini initrd starting up: 5.1.19
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=/dev/sdb
selinux=0 guestfs_verbose=1 TERM=linux
supermin: uptime: 2.42 0.55
supermin: mounting /sys
supermin: internal insmod crc32-pclmul.ko
[ 2.796365] PCLMULQDQ-NI instructions are not detected.
insmod: init_module: crc32-pclmul.ko: No such device
supermin: internal insmod crc32c-intel.ko
insmod: init_module: crc32c-intel.ko: No such device
supermin: internal insmod crct10dif-pclmul.ko
insmod: init_module: crct10dif-pclmul.ko: No such device
supermin: internal insmod crc32_generic.ko
supermin: internal insmod crc32c_generic.ko[ 2.811273] random: fast init done
supermin: internal insmod libnvdimm.ko
supermin: internal insmod nfit.ko
supermin: internal insmod scsi_mod.ko
[ 2.878577] SCSI subsystem initialized
supermin: internal insmod libata.ko
supermin: internal insmod ata_piix.ko
[ 2.916719] scsi host0: ata_piix
[ 2.920426] scsi host1: ata_piix
[ 2.921525] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14
[ 2.921871] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15
supermin: internal insmod virtio.ko
supermin: internal insmod virtio_ring.ko
supermin: internal insmod virtio_blk.ko
supermin: internal insmod rng-core.ko
supermin: internal insmod virtio-rng.ko
supermin: internal insmod virtio_console.ko
supermin: internal insmod crypto_engine.ko
supermin: internal insmod virtio_crypto.ko
supermin: internal insmod virtio_net.ko
supermin: internal insmod nd_btt.ko
supermin: internal insmod nd_pmem.ko
supermin: internal insmod sd_mod.ko
supermin: internal insmod virtio_scsi.ko
supermin: internal insmod virtio_balloon.ko
supermin: internal insmod virtio_input.ko
supermin: internal insmod virtio_pci.ko
[ 3.231765] tsc: Refined TSC clocksource calibration: 2809.540 MHz
[ 3.232146] clocksource: tsc: mask: 0xffffffffffffffff max_cycles:
0x287f7507e0e, max_idle_ns: 440795332139 ns
[ 3.232650] clocksource: Switched to clocksource tsc
[ 3.526614] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
[ 3.544943] random: crng init done
[ 3.905582] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[ 3.916506] scsi host2: Virtio SCSI HBA
[ 4.085237] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+
PQ: 0 ANSI: 5
[ 4.101132] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.5+
PQ: 0 ANSI: 5
[ 5.091034] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[ 5.197797] sd 2:0:0:0: [sda] 2048 512-byte logical blocks: (1.05 MB/1.00
MiB)
[ 5.200235] sd 2:0:0:0: [sda] Write Protect is off
[ 5.202565] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
[ 5.212573] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00
GiB)
[ 5.213258] sd 2:0:1:0: [sdb] Write Protect is off
[ 5.214193] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled,
doesn't support DPO or FUA
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=4096
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=12288
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
[ 5.447240] sd 2:0:0:0: [sda] Attached SCSI disk
[ 5.448602] sd 2:0:1:0: [sdb] Attached SCSI disk
supermin: internal insmod fscrypto.ko
supermin: internal insmod jbd2.ko
supermin: internal insmod mbcache.ko
supermin: internal insmod crc16.ko
supermin: internal insmod ext4.ko
supermin: internal insmod crc-ccitt.ko
supermin: internal insmod crc-itu-t.ko
supermin: internal insmod crc7.ko
supermin: internal insmod libcrc32c.ko
supermin: picked /sys/block/sdb/dev (8:16) as root device
supermin: creating /dev/root as block special 8:16
supermin: mounting new root on /root
[ 5.705542] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem
[ 5.740756] EXT4-fs (sdb): mounted filesystem without journal. Opts:
supermin: deleting initramfs files
supermin: chroot
Starting /init script ...
+ [[ 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=/dev/sdb selinux=0
guestfs_verbose=1 TERM=linux == *guestfs_network=1* ]]
+ [[ 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=/dev/sdb selinux=0
guestfs_verbose=1 TERM=linux == *guestfs_rescue=1* ]]
+ [[ 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=/dev/sdb selinux=0
guestfs_verbose=1 TERM=linux == *guestfs_noreboot=1* ]]
+ [[ 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=/dev/sdb selinux=0
guestfs_verbose=1 TERM=linux == *guestfs_boot_analysis=1* ]]
+ '[' '!' -d /sys ']'
+ mkdir -p /sys
+ mount -t sysfs /sys /sys
+ mkdir -p /run
+ mount -t tmpfs -o nosuid,size=20%,mode=0755 tmpfs /run
+ mkdir -p /run/lock
+ ln -s ../run/lock /var/lock
+ test -e /etc/mtab
+ ln -s /proc/mounts /etc/mtab
+ mount -t devtmpfs /dev /dev
+ mkdir -p /dev/pts
+ mount -t devpts /dev/pts /dev/pts
+ [[ 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=/dev/sdb selinux=0
guestfs_verbose=1 TERM=linux == *selinux=1* ]]
+ mkdir -p /run/tmpfiles.d
+ kmod static-nodes --format=tmpfiles --output=/run/tmpfiles.d/kmod.conf
++ dd if=/dev/urandom bs=16 count=1 status=none
++ od -x -A n
+ machine_id=' cbcf 3ac5 d693 ea4a 5b65 9e89 28f2 c87d'
+ echo cbcf3ac5d693ea4a5b659e8928f2c87d
+ systemd-tmpfiles --prefix=/dev --prefix=/run --prefix=/var/run --create --boot
/init: line 93: systemd-tmpfiles: command not found
+ for f in /lib/systemd/systemd-udevd /usr/lib/systemd/systemd-udevd /sbin/udevd
/lib/udev/udevd /usr/lib/udev/udevd
+ '[' -x /lib/systemd/systemd-udevd ']'
+ UDEVD=/lib/systemd/systemd-udevd
+ break
+ '[' -z /lib/systemd/systemd-udevd ']'
+ /lib/systemd/systemd-udevd --daemon
starting version 234
+ udevadm trigger
+ udevadm settle --timeout=600
[ 10.798233] input: Power Button as
/devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[ 10.798692] ACPI: Power Button [PWRF]
[ 11.167124] FDC 0 is a S82078B
[ 11.311061] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700,
revision 0
[ 11.452951] sd 2:0:0:0: Attached scsi generic sg0 type 0
[ 11.458553] sd 2:0:1:0: Attached scsi generic sg1 type 0
[ 11.693743] input: PC Speaker as /devices/platform/pcspkr/input/input3
[ 12.602984] input: ImExPS/2 Generic Explorer Mouse as
/devices/platform/i8042/serio1/input/input4
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=12288
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
[ 12.925229] kvm: Nested Virtualization enabled
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=4096
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=28672
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=61440
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=8192
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=32768
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=262144
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=16384
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=1044480
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
[ 14.437147] ppdev: user-space parallel port driver
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=12288
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=4096
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=28672
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=61440
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=8192
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=32768
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=262144
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=16384
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=1044480
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
+ shopt -s nullglob
+ for f in /sys/block/sd*/device/timeout
+ echo 300
+ for f in /sys/block/sd*/device/timeout
+ echo 300
+ for f in /sys/block/{h,s,ub,v}d*/queue/scheduler
+ echo noop
+ for f in /sys/block/{h,s,ub,v}d*/queue/scheduler
+ echo noop
+ shopt -u nullglob
+ ip addr add 127.0.0.1/8 brd + dev lo scope host
+ ip link set dev lo up
+ test '' = 1
+ mdadm -As --auto=yes --run
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=983040
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=1040384
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=4096
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=1047552
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
mdadm: No arrays found in config file or automatically
+ modprobe dm_mod
[ 17.313389] device-mapper: uevent: version 1.0.3
[ 17.318195] device-mapper: ioctl: 4.35.0-ioctl (2016-06-23) initialised:
dm-devel@redhat.com
+ lvm vgchange -aay --sysinit
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ ldmtool create all
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=16384 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
[
]
+ test 1 = 1
+ test '' '!=' 1
+ uname -a
Linux (none) 4.12.0-2-amd64 #1 SMP Debian 4.12.13-1 (2017-09-19) x86_64
GNU/Linux
+ ls -lR /dev
/dev:
total 0
drwxr-xr-x 2 0 0 80 Sep 27 21:21 block
drwxr-xr-x 2 0 0 80 Sep 27 21:21 bsg
drwxr-xr-x 2 0 0 2100 Sep 27 21:21 char
crw------- 1 0 0 5, 1 Sep 27 21:21 console
lrwxrwxrwx 1 0 0 11 Sep 27 21:21 core -> /proc/kcore
crw------- 1 0 0 10, 62 Sep 27 21:21 cpu_dma_latency
drwxr-xr-x 5 0 0 100 Sep 27 21:21 disk
lrwxrwxrwx 1 0 0 13 Sep 27 21:21 fd -> /proc/self/fd
crw-rw-rw- 1 0 0 1, 7 Sep 27 21:21 full
crw------- 1 0 0 10, 228 Sep 27 21:21 hpet
crw------- 1 0 0 10, 183 Sep 27 21:21 hwrng
drwxr-xr-x 3 0 0 180 Sep 27 21:21 input
crw-r--r-- 1 0 0 1, 11 Sep 27 21:21 kmsg
crw------- 1 0 0 10, 232 Sep 27 21:21 kvm
drwxr-xr-x 2 0 0 60 Sep 27 21:21 mapper
crw------- 1 0 0 1, 1 Sep 27 21:21 mem
crw------- 1 0 0 10, 59 Sep 27 21:21 memory_bandwidth
crw------- 1 0 0 10, 61 Sep 27 21:21 network_latency
crw------- 1 0 0 10, 60 Sep 27 21:21 network_throughput
crw-rw-rw- 1 0 0 1, 3 Sep 27 21:21 null
crw------- 1 0 0 1, 4 Sep 27 21:21 port
crw------- 1 0 0 10, 1 Sep 27 21:21 psaux
crw-rw-rw- 1 0 0 5, 2 Sep 27 21:21 ptmx
drwxr-xr-x 2 0 0 0 Sep 27 21:21 pts
crw-rw-rw- 1 0 0 1, 8 Sep 27 21:21 random
lrwxrwxrwx 1 0 0 4 Sep 27 21:21 rtc -> rtc0
crw------- 1 0 0 251, 0 Sep 27 21:21 rtc0
brw------- 1 0 0 8, 0 Sep 27 21:21 sda
brw------- 1 0 0 8, 16 Sep 27 21:21 sdb
crw------- 1 0 0 21, 0 Sep 27 21:21 sg0
crw------- 1 0 0 21, 1 Sep 27 21:21 sg1
crw------- 1 0 0 10, 231 Sep 27 21:21 snapshot
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 stderr -> /proc/self/fd/2
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 stdin -> /proc/self/fd/0
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 stdout -> /proc/self/fd/1
crw-rw-rw- 1 0 0 5, 0 Sep 27 21:21 tty
crw------- 1 0 0 4, 0 Sep 27 21:21 tty0
crw------- 1 0 0 4, 1 Sep 27 21:21 tty1
crw------- 1 0 0 4, 10 Sep 27 21:21 tty10
crw------- 1 0 0 4, 11 Sep 27 21:21 tty11
crw------- 1 0 0 4, 12 Sep 27 21:21 tty12
crw------- 1 0 0 4, 13 Sep 27 21:21 tty13
crw------- 1 0 0 4, 14 Sep 27 21:21 tty14
crw------- 1 0 0 4, 15 Sep 27 21:21 tty15
crw------- 1 0 0 4, 16 Sep 27 21:21 tty16
crw------- 1 0 0 4, 17 Sep 27 21:21 tty17
crw------- 1 0 0 4, 18 Sep 27 21:21 tty18
crw------- 1 0 0 4, 19 Sep 27 21:21 tty19
crw------- 1 0 0 4, 2 Sep 27 21:21 tty2
crw------- 1 0 0 4, 20 Sep 27 21:21 tty20
crw------- 1 0 0 4, 21 Sep 27 21:21 tty21
crw------- 1 0 0 4, 22 Sep 27 21:21 tty22
crw------- 1 0 0 4, 23 Sep 27 21:21 tty23
crw------- 1 0 0 4, 24 Sep 27 21:21 tty24
crw------- 1 0 0 4, 25 Sep 27 21:21 tty25
crw------- 1 0 0 4, 26 Sep 27 21:21 tty26
crw------- 1 0 0 4, 27 Sep 27 21:21 tty27
crw------- 1 0 0 4, 28 Sep 27 21:21 tty28
crw------- 1 0 0 4, 29 Sep 27 21:21 tty29
crw------- 1 0 0 4, 3 Sep 27 21:21 tty3
crw------- 1 0 0 4, 30 Sep 27 21:21 tty30
crw------- 1 0 0 4, 31 Sep 27 21:21 tty31
crw------- 1 0 0 4, 32 Sep 27 21:21 tty32
crw------- 1 0 0 4, 33 Sep 27 21:21 tty33
crw------- 1 0 0 4, 34 Sep 27 21:21 tty34
crw------- 1 0 0 4, 35 Sep 27 21:21 tty35
crw------- 1 0 0 4, 36 Sep 27 21:21 tty36
crw------- 1 0 0 4, 37 Sep 27 21:21 tty37
crw------- 1 0 0 4, 38 Sep 27 21:21 tty38
crw------- 1 0 0 4, 39 Sep 27 21:21 tty39
crw------- 1 0 0 4, 4 Sep 27 21:21 tty4
crw------- 1 0 0 4, 40 Sep 27 21:21 tty40
crw------- 1 0 0 4, 41 Sep 27 21:21 tty41
crw------- 1 0 0 4, 42 Sep 27 21:21 tty42
crw------- 1 0 0 4, 43 Sep 27 21:21 tty43
crw------- 1 0 0 4, 44 Sep 27 21:21 tty44
crw------- 1 0 0 4, 45 Sep 27 21:21 tty45
crw------- 1 0 0 4, 46 Sep 27 21:21 tty46
crw------- 1 0 0 4, 47 Sep 27 21:21 tty47
crw------- 1 0 0 4, 48 Sep 27 21:21 tty48
crw------- 1 0 0 4, 49 Sep 27 21:21 tty49
crw------- 1 0 0 4, 5 Sep 27 21:21 tty5
crw------- 1 0 0 4, 50 Sep 27 21:21 tty50
crw------- 1 0 0 4, 51 Sep 27 21:21 tty51
crw------- 1 0 0 4, 52 Sep 27 21:21 tty52
crw------- 1 0 0 4, 53 Sep 27 21:21 tty53
crw------- 1 0 0 4, 54 Sep 27 21:21 tty54
crw------- 1 0 0 4, 55 Sep 27 21:21 tty55
crw------- 1 0 0 4, 56 Sep 27 21:21 tty56
crw------- 1 0 0 4, 57 Sep 27 21:21 tty57
crw------- 1 0 0 4, 58 Sep 27 21:21 tty58
crw------- 1 0 0 4, 59 Sep 27 21:21 tty59
crw------- 1 0 0 4, 6 Sep 27 21:21 tty6
crw------- 1 0 0 4, 60 Sep 27 21:21 tty60
crw------- 1 0 0 4, 61 Sep 27 21:21 tty61
crw------- 1 0 0 4, 62 Sep 27 21:21 tty62
crw------- 1 0 0 4, 63 Sep 27 21:21 tty63
crw------- 1 0 0 4, 7 Sep 27 21:21 tty7
crw------- 1 0 0 4, 8 Sep 27 21:21 tty8
crw------- 1 0 0 4, 9 Sep 27 21:21 tty9
crw------- 1 0 0 4, 64 Sep 27 21:21 ttyS0
crw-rw-rw- 1 0 0 1, 9 Sep 27 21:21 urandom
crw------- 1 0 0 7, 0 Sep 27 21:21 vcs
crw------- 1 0 0 7, 1 Sep 27 21:21 vcs1
crw------- 1 0 0 7, 128 Sep 27 21:21 vcsa
crw------- 1 0 0 7, 129 Sep 27 21:21 vcsa1
crw------- 1 0 0 10, 63 Sep 27 21:21 vga_arbiter
drwxr-xr-x 2 0 0 60 Sep 27 21:21 virtio-ports
crw------- 1 0 0 246, 1 Sep 27 21:21 vport2p1
crw-rw-rw- 1 0 0 1, 5 Sep 27 21:21 zero
/dev/block:
total 0
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 8:0 -> ../sda
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 8:16 -> ../sdb
/dev/bsg:
total 0
crw------- 1 0 0 249, 0 Sep 27 21:21 2:0:0:0
crw------- 1 0 0 249, 1 Sep 27 21:21 2:0:1:0
/dev/char:
total 0
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 10:1 -> ../psaux
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 10:183 -> ../hwrng
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 10:228 -> ../hpet
lrwxrwxrwx 1 0 0 11 Sep 27 21:21 10:231 -> ../snapshot
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 10:232 -> ../kvm
lrwxrwxrwx 1 0 0 17 Sep 27 21:21 10:236 -> ../mapper/control
lrwxrwxrwx 1 0 0 19 Sep 27 21:21 10:59 -> ../memory_bandwidth
lrwxrwxrwx 1 0 0 21 Sep 27 21:21 10:60 -> ../network_throughput
lrwxrwxrwx 1 0 0 18 Sep 27 21:21 10:61 -> ../network_latency
lrwxrwxrwx 1 0 0 18 Sep 27 21:21 10:62 -> ../cpu_dma_latency
lrwxrwxrwx 1 0 0 14 Sep 27 21:21 10:63 -> ../vga_arbiter
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 13:32 -> ../input/mouse0
lrwxrwxrwx 1 0 0 13 Sep 27 21:21 13:63 -> ../input/mice
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 13:64 -> ../input/event0
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 13:65 -> ../input/event1
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 13:66 -> ../input/event2
lrwxrwxrwx 1 0 0 15 Sep 27 21:21 13:67 -> ../input/event3
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 1:1 -> ../mem
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 1:11 -> ../kmsg
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 1:3 -> ../null
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 1:4 -> ../port
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 1:5 -> ../zero
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 1:7 -> ../full
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 1:8 -> ../random
lrwxrwxrwx 1 0 0 10 Sep 27 21:21 1:9 -> ../urandom
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 21:0 -> ../sg0
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 21:1 -> ../sg1
lrwxrwxrwx 1 0 0 11 Sep 27 21:21 246:1 -> ../vport2p1
lrwxrwxrwx 1 0 0 14 Sep 27 21:21 249:0 -> ../bsg/2:0:0:0
lrwxrwxrwx 1 0 0 14 Sep 27 21:21 249:1 -> ../bsg/2:0:1:0
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 251:0 -> ../rtc0
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:0 -> ../tty0
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:1 -> ../tty1
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:10 -> ../tty10
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:11 -> ../tty11
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:12 -> ../tty12
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:13 -> ../tty13
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:14 -> ../tty14
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:15 -> ../tty15
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:16 -> ../tty16
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:17 -> ../tty17
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:18 -> ../tty18
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:19 -> ../tty19
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:2 -> ../tty2
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:20 -> ../tty20
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:21 -> ../tty21
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:22 -> ../tty22
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:23 -> ../tty23
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:24 -> ../tty24
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:25 -> ../tty25
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:26 -> ../tty26
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:27 -> ../tty27
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:28 -> ../tty28
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:29 -> ../tty29
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:3 -> ../tty3
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:30 -> ../tty30
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:31 -> ../tty31
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:32 -> ../tty32
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:33 -> ../tty33
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:34 -> ../tty34
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:35 -> ../tty35
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:36 -> ../tty36
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:37 -> ../tty37
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:38 -> ../tty38
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:39 -> ../tty39
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:4 -> ../tty4
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:40 -> ../tty40
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:41 -> ../tty41
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:42 -> ../tty42
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:43 -> ../tty43
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:44 -> ../tty44
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:45 -> ../tty45
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:46 -> ../tty46
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:47 -> ../tty47
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:48 -> ../tty48
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:49 -> ../tty49
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:5 -> ../tty5
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:50 -> ../tty50
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:51 -> ../tty51
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:52 -> ../tty52
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:53 -> ../tty53
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:54 -> ../tty54
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:55 -> ../tty55
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:56 -> ../tty56
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:57 -> ../tty57
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:58 -> ../tty58
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:59 -> ../tty59
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:6 -> ../tty6
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:60 -> ../tty60
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:61 -> ../tty61
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:62 -> ../tty62
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:63 -> ../tty63
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 4:64 -> ../ttyS0
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:7 -> ../tty7
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:8 -> ../tty8
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 4:9 -> ../tty9
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 5:0 -> ../tty
lrwxrwxrwx 1 0 0 10 Sep 27 21:21 5:1 -> ../console
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 5:2 -> ../ptmx
lrwxrwxrwx 1 0 0 6 Sep 27 21:21 7:0 -> ../vcs
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 7:1 -> ../vcs1
lrwxrwxrwx 1 0 0 7 Sep 27 21:21 7:128 -> ../vcsa
lrwxrwxrwx 1 0 0 8 Sep 27 21:21 7:129 -> ../vcsa1
/dev/disk:
total 0
drwxr-xr-x 2 0 0 80 Sep 27 21:21 by-id
drwxr-xr-x 2 0 0 80 Sep 27 21:21 by-path
drwxr-xr-x 2 0 0 60 Sep 27 21:21 by-uuid
/dev/disk/by-id:
total 0
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 scsi-0QEMU_QEMU_HARDDISK_appliance ->
../../sdb
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 scsi-0QEMU_QEMU_HARDDISK_hd0 -> ../../sda
/dev/disk/by-path:
total 0
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 pci-0000:00:03.0-scsi-0:0:0:0 -> ../../sda
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 pci-0000:00:03.0-scsi-0:0:1:0 -> ../../sdb
/dev/disk/by-uuid:
total 0
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 c29d0990-7528-4412-b71d-e90f1fd59a78 ->
../../sdb
/dev/input:
total 0
drwxr-xr-x 2 0 0 60 Sep 27 21:21 by-path
crw------- 1 0 0 13, 64 Sep 27 21:21 event0
crw------- 1 0 0 13, 65 Sep 27 21:21 event1
crw------- 1 0 0 13, 66 Sep 27 21:21 event2
crw------- 1 0 0 13, 67 Sep 27 21:21 event3
crw------- 1 0 0 13, 63 Sep 27 21:21 mice
crw------- 1 0 0 13, 32 Sep 27 21:21 mouse0
/dev/input/by-path:
total 0
lrwxrwxrwx 1 0 0 9 Sep 27 21:21 platform-pcspkr-event-spkr -> ../event2
/dev/mapper:
total 0
crw------- 1 0 0 10, 236 Sep 27 21:21 control
/dev/pts:
total 0
c--------- 1 0 0 5, 2 Sep 27 21:21 ptmx
/dev/virtio-ports:
total 0
lrwxrwxrwx 1 0 0 11 Sep 27 21:21 org.libguestfs.channel.0 -> ../vport2p1
+ cat /proc/mounts
/dev/root / ext2 rw,noatime,block_validity,barrier,user_xattr,acl 0 0
/proc /proc proc rw,relatime 0 0
/sys /sys sysfs rw,relatime 0 0
tmpfs /run tmpfs rw,nosuid,relatime,size=98324k,mode=755 0 0
/dev /dev devtmpfs rw,relatime,size=242140k,nr_inodes=60535,mode=755 0 0
/dev/pts /dev/pts devpts rw,relatime,mode=600,ptmxmode=000 0 0
+ lvm pvs
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ lvm vgs
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ lvm lvs
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ ip a
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group
default qlen 1000
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 brd 127.255.255.255 scope host lo
valid_lft forever preferred_lft forever
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
+ ip r
+ lsmod
Module Size Used by
dm_mod 114688 0
ppdev 20480 0
kvm_amd 2179072 0
kvm 577536 1 kvm_amd
irqbypass 16384 1 kvm
evdev 24576 0
psmouse 143360 0
pcspkr 16384 0
serio_raw 16384 0
sg 32768 0
i2c_piix4 24576 0
ata_generic 16384 0
parport_pc 28672 0
parport 49152 2 parport_pc,ppdev
floppy 73728 0
button 16384 0
libcrc32c 16384 0
crc7 16384 0
crc_itu_t 16384 0
crc_ccitt 16384 0
ext4 593920 1
crc16 16384 1 ext4
mbcache 16384 1 ext4
jbd2 98304 1 ext4
fscrypto 28672 1 ext4
virtio_pci 24576 0
virtio_input 16384 0
virtio_balloon 16384 0
virtio_scsi 20480 1
sd_mod 49152 1
nd_pmem 16384 0
nd_btt 24576 1 nd_pmem
virtio_net 40960 0
virtio_crypto 20480 0
crypto_engine 16384 1 virtio_crypto
virtio_console 24576 0
virtio_rng 16384 0
rng_core 16384 1 virtio_rng
virtio_blk 20480 0
virtio_ring 24576 9
virtio_input,virtio_blk,virtio_net,virtio_rng,virtio_scsi,virtio_balloon,virtio_crypto,virtio_console,virtio_pci
virtio 16384 9
virtio_input,virtio_blk,virtio_net,virtio_rng,virtio_scsi,virtio_balloon,virtio_crypto,virtio_console,virtio_pci
ata_piix 36864 0
libata 229376 2 ata_piix,ata_generic
scsi_mod 212992 4 sd_mod,virtio_scsi,libata,sg
nfit 53248 0
libnvdimm 135168 3 nd_btt,nd_pmem,nfit
crc32c_generic 16384 1
crc32_generic 16384 0
+ date
Wed Sep 27 21:22:00 UTC 2017
+ echo -n 'clocksource: '
clocksource: + cat
/sys/devices/system/clocksource/clocksource0/current_clocksource
tsc
+ echo -n 'uptime: '
uptime: + cat /proc/uptime
23.51 1.04
+ test '' = 1
+ cmd=guestfsd
++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline
+ eval
+ test x '!=' x
+ test 1 = 1
+ cmd='guestfsd --verbose'
+ test '' = 1
+ echo guestfsd --verbose
guestfsd --verbose
+ guestfsd --verbose
lvm config:
lvmetad
trying to open virtio-serial channel
'/dev/virtio-ports/org.libguestfs.channel.0'
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle
calling: settle
libguestfs: recv_from_daemon: received GUESTFS_LAUNCH_FLAG
libguestfs: appliance is up
libguestfs: trace: launch = 0
libguestfs: trace: part_disk "/dev/sda" "mbr"
guestfsd: main_loop: new request, len 0x3c
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda
calling: settle
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle
calling: settle
commandrvf: stdout=e stderr=y flags=0x10000
commandrvf: parted -s -- /dev/sda mklabel msdos mkpart primary 128s -128s
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=16384 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=32768 offset=16384
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=131072
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=1044480
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=262144
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=1036288
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=12288 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=12288 offset=1036288
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=16384 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
Warning: The resulting partition is not properly aligned for best performance.
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=69632
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=94208
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=126976
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=73728
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=131072
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=98304
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=327680
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=81920
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
[ 29.285769] sda: sda1
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle
calling: settle
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=69632
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=94208
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=126976
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=73728
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=131072
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=98304
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=327680
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=81920
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
libguestfs: trace: part_disk = 0
libguestfs: trace: mkfs "ext4" "/dev/sda1"
guestfsd: main_loop: proc 210 (part_disk) took 2.02 seconds
guestfsd: main_loop: new request, len 0x54
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda1
calling: settle
commandrvf: stdout=y stderr=y flags=0x0
commandrvf: wipefs --help
commandrvf: stdout=n stderr=n flags=0x0
commandrvf: wipefs -a --force /dev/sda1
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=69632
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=94208
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=126976
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=73728
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=131072
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=98304
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=327680
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=81920
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: mke2fs -t ext4 -F /dev/sda1
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=69632
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=94208
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=126976
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=73728
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=131072
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=98304
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=327680
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=81920
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
mke2fs 1.43.6 (29-Aug-2017)
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=16384 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
Filesystem too small for a journal
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=66048
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=106496
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=107008
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=108544
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=109056
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=109568
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=512 offset=110080
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=23552 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=2048 offset=90112
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=2048 offset=107520
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=64512 offset=917504
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=982016
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=66560
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
libguestfs: trace: mkfs = 0
libguestfs: trace: mount "/dev/sda1" "/"
guestfsd: main_loop: proc 278 (mkfs) took 0.53 seconds
guestfsd: main_loop: new request, len 0x40
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda1
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=69632
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=94208
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=126976
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
calling: settle
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: mount -o /dev/sda1 /sysroot/
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=69632
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=65536
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=77824
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=94208
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=126976
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=4096 offset=0
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=66560
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=67584
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=108544
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=107520
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=66560
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
[ 30.415473] EXT4-fs (sda1): mounted filesystem without journal. Opts: (null)
libguestfs: trace: mount = 0
libguestfs: trace: write "/hello.txt" "hello, people of the
world"
libguestfs: trace: internal_write "/hello.txt" "hello, people of
the world"
guestfsd: main_loop: proc 1 (mount) took 0.27 seconds
guestfsd: main_loop: new request, len 0x58
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=75776
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=91136
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
libguestfs: trace: internal_write = 0
libguestfs: trace: write = 0
libguestfs: trace: cat "/hello.txt"
libguestfs: trace: read_file "/hello.txt"
libguestfs: trace: download "/hello.txt"
"/tmp/libguestfsq6xGkY/cat2"
guestfsd: main_loop: proc 246 (internal_write) took 0.01 seconds
guestfsd: main_loop: new request, len 0x38
guestfsd: main_loop: proc 67 (download) took 0.00 seconds
libguestfs: trace: download = 0
libguestfs: trace: read_file = "hello, people of the world"
libguestfs: trace: cat = "hello, people of the world"
libguestfs: trace: sync
guestfsd: main_loop: new request, len 0x28
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=2048 offset=66560
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pread count=1024 offset=74752
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=75776
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=91136
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=2048 offset=107520
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=89088
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=67584
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=74752
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=108544
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sdb
calling: settle
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda
calling: settle
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
fsync /dev/sda
libguestfs: trace: sync = 0
libguestfs: trace: fstrim "/"
guestfsd: main_loop: proc 2 (sync) took 0.24 seconds
guestfsd: main_loop: new request, len 0x48
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sdb
calling: settle
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda
calling: settle
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
fsync /dev/sda
commandrvf: stdout=y stderr=y flags=0x0
commandrvf: fstrim -v /sysroot/
/sysroot/: 856 KiB (876544 bytes) trimmed
guestfsd: main_loop: proc 334 (fstrim) took 0.28 seconds
libguestfs: trace: fstrim = 0
libguestfs: trace: shutdown
libguestfs: trace: internal_autosync
guestfsd: main_loop: new request, len 0x28
umount-all: /proc/mounts: fsname=/dev/root dir=/ type=ext2
opts=rw,noatime,block_validity,barrier,user_xattr,acl freq=0 passno=0
umount-all: /proc/mounts: fsname=/proc dir=/proc type=proc opts=rw,relatime
freq=0 passno=0
umount-all: /proc/mounts: fsname=/sys dir=/sys type=sysfs opts=rw,relatime
freq=0 passno=0
umount-all: /proc/mounts: fsname=tmpfs dir=/run type=tmpfs
opts=rw,nosuid,relatime,size=98324k,mode=755 freq=0 passno=0
umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs
opts=rw,relatime,size=242140k,nr_inodes=60535,mode=755 freq=0 passno=0
umount-all: /proc/mounts: fsname=/dev/pts dir=/dev/pts type=devpts
opts=rw,relatime,mode=600,ptmxmode=000 freq=0 passno=0
umount-all: /proc/mounts: fsname=/dev/sda1 dir=/sysroot type=ext4
opts=rw,relatime,block_validity,delalloc,nojournal_checksum,barrier,user_xattr,acl
freq=0 passno=0
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: umount /sysroot
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: pwrite count=1024 offset=66560
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sdb
calling: settle
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda
calling: settle
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
fsync /dev/sda
libguestfs: trace: internal_autosync = 0
libguestfs: sending SIGTERM to process 17299
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: acquire global request lock
nbdkit: python[1]: debug: acquire per-connection request lock
nbdkit: python[1]: debug: flush
nbdkit: python[1]: debug: release per-connection request lock
nbdkit: python[1]: debug: release global request lock
nbdkit: python[1]: debug: client sent disconnect command, closing connection
nbdkit: python[1]: debug: close
libguestfs: qemu maxrss 301548K
libguestfs: trace: shutdown = 0
libguestfs: trace: close
libguestfs: closing guestfs handle 0x559852bbbaf0 (state 0)
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfsq6xGkY
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfsnY8aXw
nbdkit: debug:
/<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so:
unload
Exception AttributeError: "'module' object has no attribute
'close'" in 'garbage collection' ignored
Fatal Python error: unexpected exception during garbage collection
nbdkit terminated by signal 6
FAIL test-python (exit status: 1)
Richard W.M. Jones
2017-Sep-28 09:09 UTC
Re: [Libguestfs] nbdkit 1.1.15 -- test-python failure
On Wed, Sep 27, 2017 at 11:54:48PM +0200, Hilko Bengen wrote:> Hi, > > when I tested building nbdkit 1.1.15 in a current Debian chroot, I ran > into the following test failure. A repeated build went fine through the > tests and so far I have not been able to reproduce it with the previous > version. > > The failing build was done using a clean Debian/sid, amd64 chroot > spawned by sbuild. > nbdkit: debug: /<<PKGBUILDDIR>>/plugins/python/.libs/nbdkit-python-plugin.so: unload > Exception AttributeError: "'module' object has no attribute 'close'" in 'garbage collection' ignored > Fatal Python error: unexpected exception during garbage collection > nbdkit terminated by signal 6 > FAIL test-python (exit status: 1)I've not seen this particular problem, but I did see another segfault during plugin unload which you can (rarely) reproduce like this: make && while make check TESTS=test-socket-activation >& /tmp/log ; do echo -n . ; done It can take many iterations to see the crash. When the tests exit, examine both /tmp/log and tests/test-suite.log. The stack trace from the crash is: Thread 2 (Thread 0x7f67a975d800 (LWP 22742)): #0 0x00007f67a9579250 in _dl_sort_fini () from /lib64/ld-linux-x86-64.so.2 #1 0x00007f67a9579402 in _dl_fini () from /lib64/ld-linux-x86-64.so.2 #2 0x00007f67a8817c68 in __run_exit_handlers () from /lib64/libc.so.6 #3 0x00007f67a8817cba in exit () from /lib64/libc.so.6 #4 0x00000000004046ac in main (argc=<optimized out>, argv=<optimized out>) at main.c:506 Thread 1 (Thread 0x7f67a099b700 (LWP 22745)): #0 0x00007f67a8870fc6 in strlen () from /lib64/libc.so.6 #1 0x00007f67a884f465 in fputs () from /lib64/libc.so.6 #2 0x0000000000406715 in prologue (type=0x409928 "debug") at errors.c:54 #3 0x000000000040685d in nbdkit_debug (fs=fs@entry=0x40ace6 "close") at errors.c:91 #4 0x00000000004077d9 in plugin_close (conn=conn@entry=0x7f679c000910) at plugins.c:386 #5 0x0000000000404de3 in free_connection (conn=0x7f679c000910) at connections.c:228 #6 0x00000000004059fd in _handle_single_connection (sockout=<optimized out>, sockin=<optimized out>) at connections.c:179 #7 handle_single_connection (sockin=<optimized out>, sockout=<optimized out>) at connections.c:189 #8 0x0000000000408023 in start_thread (datav=0x7fff1a4fbbe0) at sockets.c:262 #9 0x00007f67a8bb536d in start_thread () from /lib64/libpthread.so.0 #10 0x00007f67a88edbbf in clone () from /lib64/libc.so.6 It seems to indicate some kind of race condition between the plugin being unloaded from memory while plugin_close is being called. However I wasn't able to determine exactly what is happening. I'll take another look in a minute. Rich. -- Richard Jones, Virtualization Group, Red Hat http://people.redhat.com/~rjones Read my programming and virtualization blog: http://rwmj.wordpress.com libguestfs lets you edit virtual machines. Supports shell scripting, bindings from many languages. http://libguestfs.org
Richard W.M. Jones
2017-Sep-28 09:45 UTC
Re: [Libguestfs] nbdkit 1.1.15 -- test-python failure
This seems to make the test more robust for me. Rich. -- Richard Jones, Virtualization Group, Red Hat http://people.redhat.com/~rjones Read my programming and virtualization blog: http://rwmj.wordpress.com Fedora Windows cross-compiler. Compile Windows programs, test, and build Windows installers. Over 100 libraries supported. http://fedoraproject.org/wiki/MinGW