[Libguestfs] virt-customize is very slow in ubuntu 18.04/centos 7.5

Hi all, Sorry for bothering you in this dev mailing list, I'm using libguestfs to customize some vm using as bases the ubuntu cloud images. But I noticed that it takes a lot and then fails when trying to install a packet. Same behaviour on Ubuntu 18.04 and Centos 7.5 Ubuntu: virt-customize 1.36.13 libvirtd (libvirt) 4.0.0 Centos: virt-customize 1.36.10rhel=7,release=6.el7_5.2,libvirt libvirtd (libvirt) 3.9.0 This is the command I run: virt-customize --hostname ato_test --uninstall cloud-init --install ifupdown --run-command 'useradd -p $(openssl passwd -1 pass) fos -s /bin/bash && mkdir /home/fos && adduser fos sudo' -a test.img If I run using --no-network is faster, but the install obviously does not work. Here the output of the command with debug and trace enabled $ virt-customize --hostname ato_test --uninstall cloud-init --install ifupdown --run-command 'useradd -p $(openssl passwd -1 pass) fos -s /bin/bash && mkdir /home/fos && adduser fos sudo' -a test.img [ 0.0] Examining the guest ... libguestfs: trace: set_verbose true libguestfs: trace: set_verbose = 0 libguestfs: create: flags = 0, handle = 0x555bc8e84a10, program = virt-customize libguestfs: trace: set_network true libguestfs: trace: set_network = 0 libguestfs: trace: add_drive "test.img" "readonly:false" "protocol:file" "discard:besteffort" 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: 13, extra: , libguestfs: trace: get_backend libguestfs: trace: get_backend = "direct" libguestfs: launch: program=virt-customize libguestfs: launch: version=1.36.13 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/libguestfsjnSQmf libguestfs: launch: umask=0002 libguestfs: launch: euid=1000 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: loading previously cached test results libguestfs: qemu version: 2.11 libguestfs: qemu mandatory locking: yes libguestfs: trace: get_sockdir libguestfs: trace: get_sockdir = "/run/user/1000" libguestfs: finished testing qemu features libguestfs: trace: get_backend_setting "gdb" libguestfs: trace: get_backend_setting = NULL (error) [00231ms] /usr/bin/qemu-system-x86_64 \ -global virtio-blk-pci.scsi=off \ -enable-fips \ -nodefaults \ -display none \ -machine accel=kvm:tcg \ -cpu host \ -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=/home/ato/Templates/test.img,cache=writeback,discard=unmap,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=/run/user/1000/libguestfsHMk6Xd/guestfsd.sock,id=channel0 \ -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \ -netdev user,id=usernet,net=169.254.0.0/16 \ -device virtio-net-pci,netdev=usernet \ -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 guestfs_network=1 TERM=xterm-256color' 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 SeaBIOS (version 1.10.2-1ubuntu1) iPXE (http://ipxe.org) 00:05.0 C100 PCI2.10 PnP PMM+1F38DA30+1F2CDA30 C100 Booting from ROM... \x1b[2J[ 0.000000] Linux version 4.15.0-34-generic (buildd@lgw01-amd64-047) (gcc version 7.3.0 (Ubuntu 7.3.0-16ubuntu3)) #37-Ubuntu SMP Mon Aug 27 15:21:48 UTC 2018 (Ubuntu 4.15.0-34.37-generic 4.15.18) [ 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 guestfs_network=1 TERM=xterm-256color [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] AMD AuthenticAMD [ 0.000000] Centaur CentaurHauls [ 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 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1ubuntu1 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: last_pfn = 0x1f3de max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] found SMP MP-table at [mem 0x000f6a80-0x000f6a8f] mapped at [ (ptrval)] [ 0.000000] Scanning 1 areas for low memory corruption [ 0.000000] RAMDISK: [mem 0x1f35c000-0x1f3cffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68A0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000001F3E159D 00002C (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000001F3E1431 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000001F3E0040 0013F1 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000001F3E0000 000040 [ 0.000000] ACPI: APIC 0x000000001F3E1525 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 0x1f331000-0x1f35bfff] [ 0.000000] kvm-clock: cpu 0, msr 0:1f2b1001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 1108625828 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 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] Reserved but unavailable: 98 pages [ 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 17, 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-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns [ 0.000000] random: get_random_bytes called from start_kernel+0x99/0x4fd with crng_init=0 [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] percpu: Embedded 46 pages/cpu @ (ptrval) s151552 r8192 d28672 u2097152 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1f024040 [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes) [ 0.000000] Built 1 zonelists, 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 guestfs_network=1 TERM=xterm-256color [ 0.000000] Memory: 473852K/511472K available (12300K kernel code, 2471K rwdata, 4244K rodata, 2408K init, 2416K bss, 37620K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] Kernel/User page tables isolation: enabled [ 0.000000] ftrace: allocating 39147 entries in 153 pages [ 0.004000] Hierarchical RCU implementation. [ 0.004000] \tRCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1. [ 0.004000] \tTasks RCU enabled. [ 0.004000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.004000] NR_IRQS: 524544, nr_irqs: 256, preallocated irqs: 16 [ 0.004000] Console: colour *CGA 80x25 [ 0.004000] console [ttyS0] enabled [ 0.004000] ACPI: Core revision 20170831 [ 0.004000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.004014] APIC: Switch to symmetric I/O mode setup [ 0.005782] x2apic enabled [ 0.006956] Switched APIC routing to physical x2apic. [ 0.009797] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.011489] tsc: Detected 2659.982 MHz processor [ 0.012014] Calibrating delay loop (skipped) preset value.. 5319.96 BogoMIPS (lpj=10639928) [ 0.014283] pid_max: default: 32768 minimum: 301 [ 0.016036] Security Framework initialized [ 0.017165] Yama: becoming mindful. [ 0.018151] AppArmor: AppArmor initialized [ 0.020117] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.022065] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.023973] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.025807] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.028693] Disabling memory control group subsystem [ 0.030172] mce: CPU supports 10 MCE banks [ 0.031364] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.032004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.033625] Spectre V2 : Mitigation: Full generic retpoline [ 0.036003] Spectre V2 : Spectre v2 mitigation: Enabling Indirect Branch Prediction Barrier [ 0.038269] Spectre V2 : Enabling Restricted Speculation for firmware calls [ 0.040005] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp [ 0.063510] Freeing SMP alternatives memory: 36K [ 0.065409] smpboot: CPU0: Intel(R) Xeon(R) CPU X3450 @ 2.67GHz (family: 0x6, model: 0x1e, stepping: 0x5) [ 0.068000] Performance Events: Nehalem events, Intel PMU driver. [ 0.068007] core: CPUID marked event: 'bus cycles' unavailable [ 0.069612] ... version: 2 [ 0.070715] ... bit width: 48 [ 0.071836] ... generic registers: 4 [ 0.072007] ... value mask: 0000ffffffffffff [ 0.073458] ... max period: 000000007fffffff [ 0.074914] ... fixed-purpose events: 3 [ 0.076007] ... event mask: 000000070000000f [ 0.077565] Hierarchical SRCU implementation. [ 0.079463] smp: Bringing up secondary CPUs ... [ 0.080011] smp: Brought up 1 node, 1 CPU [ 0.081120] smpboot: Max logical packages: 1 [ 0.082294] smpboot: Total of 1 processors activated (5319.96 BogoMIPS) [ 0.084192] devtmpfs: initialized [ 0.085180] x86/mm: Memory block size: 128MB [ 0.086529] evm: security.selinux [ 0.087455] evm: security.SMACK64 [ 0.088010] evm: security.SMACK64EXEC [ 0.089014] evm: security.SMACK64TRANSMUTE [ 0.090132] evm: security.SMACK64MMAP [ 0.091151] evm: security.apparmor [ 0.092007] evm: security.ima [ 0.092828] evm: security.capability [ 0.093981] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.096020] futex hash table entries: 256 (order: 2, 16384 bytes) [ 0.097728] pinctrl core: initialized pinctrl subsystem [ 0.099419] RTC time: 8:19:59, date: 09/19/18 [ 0.100132] NET: Registered protocol family 16 [ 0.101492] audit: initializing netlink subsys (disabled) [ 0.103121] cpuidle: using governor ladder [ 0.104009] cpuidle: using governor menu [ 0.105209] ACPI: bus type PCI registered [ 0.106320] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.108013] audit: type=2000 audit(1537345200.440:1): state=initialized audit_enabled=0 res=1 [ 0.110520] PCI: Using configuration type 1 for base access [ 0.113423] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.115646] ACPI: Added _OSI(Module Device) [ 0.116010] ACPI: Added _OSI(Processor Device) [ 0.117231] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.118512] ACPI: Added _OSI(Processor Aggregator Device) [ 0.119978] ACPI: Added _OSI(Linux-Dell-Video) [ 0.120016] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.123252] ACPI: Interpreter enabled [ 0.124034] ACPI: (supports S0 S3 S4 S5) [ 0.125124] ACPI: Using IOAPIC for interrupt routing [ 0.126495] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.128258] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.133766] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.135456] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 0.136015] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.137813] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.140463] acpiphp: Slot [2] registered [ 0.141616] acpiphp: Slot [3] registered [ 0.142762] acpiphp: Slot [4] registered [ 0.143915] acpiphp: Slot [5] registered [ 0.144079] acpiphp: Slot [6] registered [ 0.145226] acpiphp: Slot [7] registered [ 0.146372] acpiphp: Slot [8] registered [ 0.147540] acpiphp: Slot [9] registered [ 0.148079] acpiphp: Slot [10] registered [ 0.149247] acpiphp: Slot [11] registered [ 0.150417] acpiphp: Slot [12] registered [ 0.151583] acpiphp: Slot [13] registered [ 0.152078] acpiphp: Slot [14] registered [ 0.153255] acpiphp: Slot [15] registered [ 0.154427] acpiphp: Slot [16] registered [ 0.155602] acpiphp: Slot [17] registered [ 0.156079] acpiphp: Slot [18] registered [ 0.157259] acpiphp: Slot [19] registered [ 0.158430] acpiphp: Slot [20] registered [ 0.159607] acpiphp: Slot [21] registered [ 0.160079] acpiphp: Slot [22] registered [ 0.161253] acpiphp: Slot [23] registered [ 0.162427] acpiphp: Slot [24] registered [ 0.163592] acpiphp: Slot [25] registered [ 0.164082] acpiphp: Slot [26] registered [ 0.165254] acpiphp: Slot [27] registered [ 0.166501] acpiphp: Slot [28] registered [ 0.167682] acpiphp: Slot [29] registered [ 0.168080] acpiphp: Slot [30] registered [ 0.169262] acpiphp: Slot [31] registered [ 0.170402] PCI host bridge to bus 0000:00 [ 0.171536] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.172009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.173875] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.175897] pci_bus 0000:00: root bus resource [mem 0x1f400000-0xfebfffff window] [ 0.176008] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] [ 0.178076] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.186241] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.188009] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.189785] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.191722] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.193279] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.195268] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.237498] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.239250] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.240605] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.242343] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.243991] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.244452] SCSI subsystem initialized [ 0.245591] vgaarb: loaded [ 0.246389] usbcore: USB support disabled [ 0.247572] EDAC MC: Ver: 3.0.0 [ 0.248401] PCI: Using ACPI for IRQ routing [ 0.249969] NetLabel: Initializing [ 0.250919] NetLabel: domain hash size = 128 [ 0.252007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.253582] NetLabel: unlabeled traffic allowed by default [ 0.255277] clocksource: Switched to clocksource kvm-clock [ 0.352466] VFS: Disk quotas dquot_6.6.0 [ 0.353620] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.355679] AppArmor: AppArmor Filesystem Enabled [ 0.357028] pnp: PnP ACPI init [ 0.358655] pnp: PnP ACPI: found 5 devices [ 0.366602] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.369113] NET: Registered protocol family 2 [ 0.370752] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 0.372737] TCP bind hash table entries: 4096 (order: 4, 65536 bytes) [ 0.374558] TCP: Hash tables configured (established 4096 bind 4096) [ 0.376385] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.377984] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.379776] NET: Registered protocol family 1 [ 0.381003] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.382658] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.384270] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.386099] Unpacking initramfs... [ 0.388416] Freeing initrd memory: 464K [ 0.389698] Scanning for low memory corruption every 60 seconds [ 0.392144] Initialise system trusted keyrings [ 0.393379] Key type blacklist registered [ 0.394561] workingset: timestamp_bits=36 max_order=17 bucket_order=0 [ 0.417976] zbud: loaded [ 0.419314] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.421138] fuse init (API version 7.26) [ 0.423743] Key type asymmetric registered [ 0.424910] Asymmetric key parser 'x509' registered [ 0.426304] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) [ 0.428552] io scheduler noop registered [ 0.429652] io scheduler deadline registered [ 0.430907] io scheduler cfq registered (default) [ 0.432532] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 0.434586] ACPI: Power Button [PWRF] [ 0.472020] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10 [ 0.511082] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 0.551116] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11 [ 0.590169] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 0.593432] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled [ 0.619711] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 0.676105] Linux agpgart interface v0.103 [ 0.700259] loop: module loaded [ 0.702228] scsi host0: ata_piix [ 0.703170] scsi host1: ata_piix [ 0.704057] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14 [ 0.705769] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15 [ 0.707955] libphy: Fixed MDIO Bus: probed [ 0.708993] tun: Universal TUN/TAP device driver, 1.6 [ 0.710691] PPP generic driver version 2.4.2 [ 0.711925] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 0.715148] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 0.716406] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 0.717815] mousedev: PS/2 mouse device common for all mice [ 0.719716] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 0.722401] rtc_cmos 00:00: RTC can wake from S4 [ 0.724045] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 0.725693] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram [ 0.727325] i2c /dev entries driver [ 0.728302] device-mapper: uevent: version 1.0.3 [ 0.729526] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised: dm-devel(a)redhat.com [ 0.731666] ledtrig-cpu: registered to indicate activity on CPUs [ 0.733553] NET: Registered protocol family 10 [ 0.735253] Segment Routing with IPv6 [ 0.736210] NET: Registered protocol family 17 [ 0.737372] Key type dns_resolver registered [ 0.738663] RAS: Correctable Errors collector initialized. [ 0.740082] sched_clock: Marking stable (740043101, 0)->(1149080556, -409037455) [ 0.742126] registered taskstats version 1 [ 0.743202] Loading compiled-in X.509 certificates [ 0.745529] Loaded X.509 cert 'Build time autogenerated kernel key: 197c80222fd2638086c51a9b9e4961ca73d6ea07' [ 0.748022] zswap: loaded using pool lzo/zbud [ 0.749492] Key type big_key registered [ 0.750471] Key type trusted registered [ 0.751534] Key type encrypted registered [ 0.752588] AppArmor: AppArmor sha1 policy hashing enabled [ 0.753942] ima: No TPM chip found, activating TPM-bypass! (rc=-19) [ 0.755487] ima: Allocated hash algorithm: sha1 [ 0.756679] evm: HMAC attrs: 0x1 [ 0.758184] Magic number: 2:564:321 [ 0.759117] tty tty46: hash matches [ 0.760033] virtio-pci 0000:00:02.0: hash matches [ 0.761397] rtc_cmos 00:00: setting system clock to 2018-09-19 08:20:00 UTC (1537345200) [ 0.763471] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 0.764992] EDD information not available. [ 0.870846] Freeing unused kernel memory: 2408K [ 0.880139] Write protecting the kernel read-only data: 20480k [ 0.882124] Freeing unused kernel memory: 2008K [ 0.887646] Freeing unused kernel memory: 1900K [ 0.897712] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 0.899322] x86/mm: Checking user space page tables [ 0.909377] 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 guestfs_network=1 TERM=xterm-256color supermin: uptime: 0.93 0.17 supermin: mounting /sys supermin: internal insmod crc32-pclmul.ko [ 0.942173] PCLMULQDQ-NI instructions are not detected. insmod: init_module: crc32-pclmul.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 nfit.ko supermin: internal insmod virtio_blk.ko supermin: internal insmod virtio-rng.ko supermin: internal insmod crypto_engine.ko supermin: internal insmod virtio_crypto.ko supermin: internal insmod virtio_net.ko [ 0.964054] random: fast init done [ 0.965084] random: crng init done supermin: internal insmod nd_btt.ko supermin: internal insmod nd_pmem.ko supermin: internal insmod rpmsg_core.ko supermin: internal insmod virtio_rpmsg_bus.ko supermin: internal insmod virtio_scsi.ko [ 0.982583] scsi host2: Virtio SCSI HBA [ 0.985958] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 0.988342] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 1.030553] sd 2:0:0:0: Power-on or device reset occurred [ 1.032239] sd 2:0:0:0: Attached scsi generic sg0 type 0 [ 1.033785] sd 2:0:0:0: [sda] 4612096 512-byte logical blocks: (2.36 GB/2.20 GiB) [ 1.035737] sd 2:0:1:0: Power-on or device reset occurred [ 1.037287] sd 2:0:0:0: [sda] Write Protect is off [ 1.038585] sd 2:0:1:0: Attached scsi generic sg1 type 0 [ 1.040074] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.042525] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB) [ 1.044523] sd 2:0:1:0: [sdb] Write Protect is off [ 1.045891] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.050439] sda: sda1 sda14 sda15 [ 1.051792] sd 2:0:1:0: [sdb] Attached SCSI disk [ 1.053117] sd 2:0:0:0: [sda] Attached SCSI disk supermin: internal insmod virtio_input.ko supermin: internal insmod crc-itu-t.ko supermin: internal insmod crc4.ko supermin: internal insmod crc7.ko supermin: internal insmod crc8.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 [ 1.069201] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem [ 1.087633] 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 guestfs_network=1 TERM=xterm-256color == *guestfs_network=1* ]] + 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 guestfs_network=1 TERM=xterm-256color == *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 guestfs_network=1 TERM=xterm-256color == *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 guestfs_network=1 TERM=xterm-256color == *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 + mkdir -p /dev/shm + mount -t tmpfs -o mode=1777 shmfs /dev/shm + [[ 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 guestfs_network=1 TERM=xterm-256color == *selinux=1* ]] + mkdir -p /run/tmpfiles.d + kmod static-nodes --format=tmpfiles --output=/run/tmpfiles.d/kmod.conf ++ od -x -A n ++ dd if=/dev/urandom bs=16 count=1 status=none + machine_id=' 6208 3c4e 6569 c062 8b92 a210 fbf0 fcb2' + echo 62083c4e6569c0628b92a210fbf0fcb2 + systemd-tmpfiles --prefix=/dev --prefix=/run --prefix=/var/run --create --boot [/usr/lib/tmpfiles.d/systemd.conf:11] Unknown group 'utmp'. [/usr/lib/tmpfiles.d/systemd.conf:19] Unknown user 'systemd-network'. [/usr/lib/tmpfiles.d/systemd.conf:20] Unknown user 'systemd-network'. [/usr/lib/tmpfiles.d/systemd.conf:21] Unknown user 'systemd-network'. [/usr/lib/tmpfiles.d/systemd.conf:25] Unknown group 'systemd-journal'. [/usr/lib/tmpfiles.d/systemd.conf:26] Unknown group 'systemd-journal'. Failed to parse ACL "d:group:adm:r-x": No such file or directory. Ignoring Failed to parse ACL "group:adm:r-x": No such file or directory. Ignoring Failed to parse ACL "group:adm:r--": No such file or directory. Ignoring Failed to parse ACL "d:group::r-x,d:group:adm:r-x": No such file or directory. Ignoring Failed to parse ACL "group::r-x,group:adm:r-x": No such file or directory. Ignoring Failed to parse ACL "d:group:adm:r-x": No such file or directory. Ignoring Failed to parse ACL "group:adm:r-x": No such file or directory. Ignoring Failed to parse ACL "group:adm:r--": No such file or directory. Ignoring + 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 237 + udevadm trigger + udevadm settle --timeout=600 + 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 /init: line 116: echo: write error: Invalid argument + for f in /sys/block/{h,s,ub,v}d*/queue/scheduler + echo noop /init: line 116: echo: write error: Invalid argument + shopt -u nullglob + ip addr add 127.0.0.1/8 brd + dev lo scope host + ip link set dev lo up + test 1 = 1 ++ ls -I all -I default -I lo /proc/sys/net/ipv4/conf + iface=eth0 + touch /etc/fstab + dhclient --version + dhclient eth0 + mdadm -As --auto=yes --run mdadm: No arrays found in config file or automatically + modprobe dm_mod + lvm vgchange -aay --sysinit WARNING: Failed to connect to lvmetad. Falling back to device scanning. + ldmtool create all [ ] + test 1 = 1 + test '' '!=' 1 + uname -a Linux (none) 4.15.0-34-generic #37-Ubuntu SMP Mon Aug 27 15:21:48 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux + ls -lR /dev /dev: total 0 crw------- 1 0 0 10, 235 Sep 19 08:20 autofs drwxr-xr-x 2 0 0 300 Sep 19 08:20 block drwxr-xr-x 2 0 0 80 Sep 19 08:20 bsg crw------- 1 0 0 10, 234 Sep 19 08:20 btrfs-control drwxr-xr-x 2 0 0 2220 Sep 19 08:20 char crw------- 1 0 0 5, 1 Sep 19 08:20 console lrwxrwxrwx 1 0 0 11 Sep 19 08:20 core -> /proc/kcore crw------- 1 0 0 10, 59 Sep 19 08:20 cpu_dma_latency crw------- 1 0 0 10, 203 Sep 19 08:20 cuse drwxr-xr-x 7 0 0 140 Sep 19 08:20 disk crw------- 1 0 0 10, 61 Sep 19 08:20 ecryptfs lrwxrwxrwx 1 0 0 13 Sep 19 08:20 fd -> /proc/self/fd crw-rw-rw- 1 0 0 1, 7 Sep 19 08:20 full crw-rw-rw- 1 0 0 10, 229 Sep 19 08:20 fuse crw------- 1 0 0 10, 228 Sep 19 08:20 hpet crw------- 1 0 0 10, 183 Sep 19 08:20 hwrng drwxr-xr-x 3 0 0 120 Sep 19 08:20 input crw-r--r-- 1 0 0 1, 11 Sep 19 08:20 kmsg drwxr-xr-x 2 0 0 60 Sep 19 08:20 lightnvm crw------- 1 0 0 10, 237 Sep 19 08:20 loop-control brw------- 1 0 0 7, 0 Sep 19 08:20 loop0 brw------- 1 0 0 7, 1 Sep 19 08:20 loop1 brw------- 1 0 0 7, 2 Sep 19 08:20 loop2 brw------- 1 0 0 7, 3 Sep 19 08:20 loop3 brw------- 1 0 0 7, 4 Sep 19 08:20 loop4 brw------- 1 0 0 7, 5 Sep 19 08:20 loop5 brw------- 1 0 0 7, 6 Sep 19 08:20 loop6 brw------- 1 0 0 7, 7 Sep 19 08:20 loop7 drwxr-xr-x 2 0 0 60 Sep 19 08:20 mapper crw------- 1 0 0 10, 227 Sep 19 08:20 mcelog crw------- 1 0 0 1, 1 Sep 19 08:20 mem crw------- 1 0 0 10, 56 Sep 19 08:20 memory_bandwidth drwxr-xr-x 2 0 0 60 Sep 19 08:20 net crw------- 1 0 0 10, 58 Sep 19 08:20 network_latency crw------- 1 0 0 10, 57 Sep 19 08:20 network_throughput crw-rw-rw- 1 0 0 1, 3 Sep 19 08:20 null crw------- 1 0 0 1, 4 Sep 19 08:20 port crw------- 1 0 0 108, 0 Sep 19 08:20 ppp crw------- 1 0 0 10, 1 Sep 19 08:20 psaux crw-rw-rw- 1 0 0 5, 2 Sep 19 08:20 ptmx drwxr-xr-x 2 0 0 0 Sep 19 08:20 pts crw-rw-rw- 1 0 0 1, 8 Sep 19 08:20 random crw------- 1 0 0 10, 62 Sep 19 08:20 rfkill lrwxrwxrwx 1 0 0 4 Sep 19 08:20 rtc -> rtc0 crw------- 1 0 0 249, 0 Sep 19 08:20 rtc0 brw------- 1 0 0 8, 0 Sep 19 08:20 sda brw------- 1 0 0 8, 1 Sep 19 08:20 sda1 brw------- 1 0 0 8, 14 Sep 19 08:20 sda14 brw------- 1 0 0 8, 15 Sep 19 08:20 sda15 brw------- 1 0 0 8, 16 Sep 19 08:20 sdb crw------- 1 0 0 21, 0 Sep 19 08:20 sg0 crw------- 1 0 0 21, 1 Sep 19 08:20 sg1 drwxrwxrwt 2 0 0 40 Sep 19 08:20 shm crw------- 1 0 0 10, 231 Sep 19 08:20 snapshot drwxr-xr-x 2 0 0 80 Sep 19 08:20 snd lrwxrwxrwx 1 0 0 15 Sep 19 08:20 stderr -> /proc/self/fd/2 lrwxrwxrwx 1 0 0 15 Sep 19 08:20 stdin -> /proc/self/fd/0 lrwxrwxrwx 1 0 0 15 Sep 19 08:20 stdout -> /proc/self/fd/1 crw-rw-rw- 1 0 0 5, 0 Sep 19 08:20 tty crw------- 1 0 0 4, 0 Sep 19 08:20 tty0 crw------- 1 0 0 4, 1 Sep 19 08:20 tty1 crw------- 1 0 0 4, 10 Sep 19 08:20 tty10 crw------- 1 0 0 4, 11 Sep 19 08:20 tty11 crw------- 1 0 0 4, 12 Sep 19 08:20 tty12 crw------- 1 0 0 4, 13 Sep 19 08:20 tty13 crw------- 1 0 0 4, 14 Sep 19 08:20 tty14 crw------- 1 0 0 4, 15 Sep 19 08:20 tty15 crw------- 1 0 0 4, 16 Sep 19 08:20 tty16 crw------- 1 0 0 4, 17 Sep 19 08:20 tty17 crw------- 1 0 0 4, 18 Sep 19 08:20 tty18 crw------- 1 0 0 4, 19 Sep 19 08:20 tty19 crw------- 1 0 0 4, 2 Sep 19 08:20 tty2 crw------- 1 0 0 4, 20 Sep 19 08:20 tty20 crw------- 1 0 0 4, 21 Sep 19 08:20 tty21 crw------- 1 0 0 4, 22 Sep 19 08:20 tty22 crw------- 1 0 0 4, 23 Sep 19 08:20 tty23 crw------- 1 0 0 4, 24 Sep 19 08:20 tty24 crw------- 1 0 0 4, 25 Sep 19 08:20 tty25 crw------- 1 0 0 4, 26 Sep 19 08:20 tty26 crw------- 1 0 0 4, 27 Sep 19 08:20 tty27 crw------- 1 0 0 4, 28 Sep 19 08:20 tty28 crw------- 1 0 0 4, 29 Sep 19 08:20 tty29 crw------- 1 0 0 4, 3 Sep 19 08:20 tty3 crw------- 1 0 0 4, 30 Sep 19 08:20 tty30 crw------- 1 0 0 4, 31 Sep 19 08:20 tty31 crw------- 1 0 0 4, 32 Sep 19 08:20 tty32 crw------- 1 0 0 4, 33 Sep 19 08:20 tty33 crw------- 1 0 0 4, 34 Sep 19 08:20 tty34 crw------- 1 0 0 4, 35 Sep 19 08:20 tty35 crw------- 1 0 0 4, 36 Sep 19 08:20 tty36 crw------- 1 0 0 4, 37 Sep 19 08:20 tty37 crw------- 1 0 0 4, 38 Sep 19 08:20 tty38 crw------- 1 0 0 4, 39 Sep 19 08:20 tty39 crw------- 1 0 0 4, 4 Sep 19 08:20 tty4 crw------- 1 0 0 4, 40 Sep 19 08:20 tty40 crw------- 1 0 0 4, 41 Sep 19 08:20 tty41 crw------- 1 0 0 4, 42 Sep 19 08:20 tty42 crw------- 1 0 0 4, 43 Sep 19 08:20 tty43 crw------- 1 0 0 4, 44 Sep 19 08:20 tty44 crw------- 1 0 0 4, 45 Sep 19 08:20 tty45 crw------- 1 0 0 4, 46 Sep 19 08:20 tty46 crw------- 1 0 0 4, 47 Sep 19 08:20 tty47 crw------- 1 0 0 4, 48 Sep 19 08:20 tty48 crw------- 1 0 0 4, 49 Sep 19 08:20 tty49 crw------- 1 0 0 4, 5 Sep 19 08:20 tty5 crw------- 1 0 0 4, 50 Sep 19 08:20 tty50 crw------- 1 0 0 4, 51 Sep 19 08:20 tty51 crw------- 1 0 0 4, 52 Sep 19 08:20 tty52 crw------- 1 0 0 4, 53 Sep 19 08:20 tty53 crw------- 1 0 0 4, 54 Sep 19 08:20 tty54 crw------- 1 0 0 4, 55 Sep 19 08:20 tty55 crw------- 1 0 0 4, 56 Sep 19 08:20 tty56 crw------- 1 0 0 4, 57 Sep 19 08:20 tty57 crw------- 1 0 0 4, 58 Sep 19 08:20 tty58 crw------- 1 0 0 4, 59 Sep 19 08:20 tty59 crw------- 1 0 0 4, 6 Sep 19 08:20 tty6 crw------- 1 0 0 4, 60 Sep 19 08:20 tty60 crw------- 1 0 0 4, 61 Sep 19 08:20 tty61 crw------- 1 0 0 4, 62 Sep 19 08:20 tty62 crw------- 1 0 0 4, 63 Sep 19 08:20 tty63 crw------- 1 0 0 4, 7 Sep 19 08:20 tty7 crw------- 1 0 0 4, 8 Sep 19 08:20 tty8 crw------- 1 0 0 4, 9 Sep 19 08:20 tty9 crw------- 1 0 0 4, 64 Sep 19 08:20 ttyS0 crw------- 1 0 0 5, 3 Sep 19 08:20 ttyprintk crw------- 1 0 0 10, 239 Sep 19 08:20 uhid crw------- 1 0 0 10, 223 Sep 19 08:20 uinput crw-rw-rw- 1 0 0 1, 9 Sep 19 08:20 urandom crw------- 1 0 0 10, 240 Sep 19 08:20 userio crw------- 1 0 0 7, 0 Sep 19 08:20 vcs crw------- 1 0 0 7, 1 Sep 19 08:20 vcs1 crw------- 1 0 0 7, 128 Sep 19 08:20 vcsa crw------- 1 0 0 7, 129 Sep 19 08:20 vcsa1 drwxr-xr-x 2 0 0 60 Sep 19 08:20 vfio crw------- 1 0 0 10, 63 Sep 19 08:20 vga_arbiter crw------- 1 0 0 10, 137 Sep 19 08:20 vhci crw------- 1 0 0 10, 238 Sep 19 08:20 vhost-net crw------- 1 0 0 10, 241 Sep 19 08:20 vhost-vsock drwxr-xr-x 2 0 0 60 Sep 19 08:20 virtio-ports crw------- 1 0 0 245, 1 Sep 19 08:20 vport2p1 crw-rw-rw- 1 0 0 1, 5 Sep 19 08:20 zero /dev/block: total 0 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:0 -> ../loop0 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:1 -> ../loop1 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:2 -> ../loop2 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:3 -> ../loop3 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:4 -> ../loop4 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:5 -> ../loop5 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:6 -> ../loop6 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:7 -> ../loop7 lrwxrwxrwx 1 0 0 6 Sep 19 08:20 8:0 -> ../sda lrwxrwxrwx 1 0 0 7 Sep 19 08:20 8:1 -> ../sda1 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 8:14 -> ../sda14 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 8:15 -> ../sda15 lrwxrwxrwx 1 0 0 6 Sep 19 08:20 8:16 -> ../sdb /dev/bsg: total 0 crw------- 1 0 0 246, 0 Sep 19 08:20 2:0:0:0 crw------- 1 0 0 246, 1 Sep 19 08:20 2:0:1:0 /dev/char: total 0 lrwxrwxrwx 1 0 0 6 Sep 19 08:20 108:0 -> ../ppp lrwxrwxrwx 1 0 0 8 Sep 19 08:20 10:1 -> ../psaux lrwxrwxrwx 1 0 0 8 Sep 19 08:20 10:183 -> ../hwrng lrwxrwxrwx 1 0 0 10 Sep 19 08:20 10:200 -> ../net/tun lrwxrwxrwx 1 0 0 9 Sep 19 08:20 10:223 -> ../uinput lrwxrwxrwx 1 0 0 9 Sep 19 08:20 10:227 -> ../mcelog lrwxrwxrwx 1 0 0 7 Sep 19 08:20 10:228 -> ../hpet lrwxrwxrwx 1 0 0 7 Sep 19 08:20 10:229 -> ../fuse lrwxrwxrwx 1 0 0 11 Sep 19 08:20 10:231 -> ../snapshot lrwxrwxrwx 1 0 0 17 Sep 19 08:20 10:236 -> ../mapper/control lrwxrwxrwx 1 0 0 15 Sep 19 08:20 10:237 -> ../loop-control lrwxrwxrwx 1 0 0 19 Sep 19 08:20 10:56 -> ../memory_bandwidth lrwxrwxrwx 1 0 0 21 Sep 19 08:20 10:57 -> ../network_throughput lrwxrwxrwx 1 0 0 18 Sep 19 08:20 10:58 -> ../network_latency lrwxrwxrwx 1 0 0 18 Sep 19 08:20 10:59 -> ../cpu_dma_latency lrwxrwxrwx 1 0 0 19 Sep 19 08:20 10:60 -> ../lightnvm/control lrwxrwxrwx 1 0 0 11 Sep 19 08:20 10:61 -> ../ecryptfs lrwxrwxrwx 1 0 0 9 Sep 19 08:20 10:62 -> ../rfkill lrwxrwxrwx 1 0 0 14 Sep 19 08:20 10:63 -> ../vga_arbiter lrwxrwxrwx 1 0 0 13 Sep 19 08:20 13:63 -> ../input/mice lrwxrwxrwx 1 0 0 15 Sep 19 08:20 13:64 -> ../input/event0 lrwxrwxrwx 1 0 0 15 Sep 19 08:20 13:65 -> ../input/event1 lrwxrwxrwx 1 0 0 6 Sep 19 08:20 1:1 -> ../mem lrwxrwxrwx 1 0 0 7 Sep 19 08:20 1:11 -> ../kmsg lrwxrwxrwx 1 0 0 7 Sep 19 08:20 1:3 -> ../null lrwxrwxrwx 1 0 0 7 Sep 19 08:20 1:4 -> ../port lrwxrwxrwx 1 0 0 7 Sep 19 08:20 1:5 -> ../zero lrwxrwxrwx 1 0 0 7 Sep 19 08:20 1:7 -> ../full lrwxrwxrwx 1 0 0 9 Sep 19 08:20 1:8 -> ../random lrwxrwxrwx 1 0 0 10 Sep 19 08:20 1:9 -> ../urandom lrwxrwxrwx 1 0 0 6 Sep 19 08:20 21:0 -> ../sg0 lrwxrwxrwx 1 0 0 6 Sep 19 08:20 21:1 -> ../sg1 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 245:1 -> ../vport2p1 lrwxrwxrwx 1 0 0 14 Sep 19 08:20 246:0 -> ../bsg/2:0:0:0 lrwxrwxrwx 1 0 0 14 Sep 19 08:20 246:1 -> ../bsg/2:0:1:0 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 249:0 -> ../rtc0 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:0 -> ../tty0 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:1 -> ../tty1 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:10 -> ../tty10 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:11 -> ../tty11 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:12 -> ../tty12 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:13 -> ../tty13 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:14 -> ../tty14 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:15 -> ../tty15 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:16 -> ../tty16 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:17 -> ../tty17 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:18 -> ../tty18 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:19 -> ../tty19 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:2 -> ../tty2 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:20 -> ../tty20 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:21 -> ../tty21 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:22 -> ../tty22 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:23 -> ../tty23 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:24 -> ../tty24 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:25 -> ../tty25 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:26 -> ../tty26 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:27 -> ../tty27 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:28 -> ../tty28 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:29 -> ../tty29 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:3 -> ../tty3 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:30 -> ../tty30 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:31 -> ../tty31 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:32 -> ../tty32 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:33 -> ../tty33 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:34 -> ../tty34 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:35 -> ../tty35 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:36 -> ../tty36 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:37 -> ../tty37 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:38 -> ../tty38 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:39 -> ../tty39 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:4 -> ../tty4 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:40 -> ../tty40 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:41 -> ../tty41 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:42 -> ../tty42 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:43 -> ../tty43 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:44 -> ../tty44 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:45 -> ../tty45 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:46 -> ../tty46 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:47 -> ../tty47 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:48 -> ../tty48 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:49 -> ../tty49 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:5 -> ../tty5 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:50 -> ../tty50 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:51 -> ../tty51 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:52 -> ../tty52 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:53 -> ../tty53 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:54 -> ../tty54 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:55 -> ../tty55 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:56 -> ../tty56 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:57 -> ../tty57 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:58 -> ../tty58 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:59 -> ../tty59 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:6 -> ../tty6 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:60 -> ../tty60 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:61 -> ../tty61 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:62 -> ../tty62 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:63 -> ../tty63 lrwxrwxrwx 1 0 0 8 Sep 19 08:20 4:64 -> ../ttyS0 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:7 -> ../tty7 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:8 -> ../tty8 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 4:9 -> ../tty9 lrwxrwxrwx 1 0 0 6 Sep 19 08:20 5:0 -> ../tty lrwxrwxrwx 1 0 0 10 Sep 19 08:20 5:1 -> ../console lrwxrwxrwx 1 0 0 7 Sep 19 08:20 5:2 -> ../ptmx lrwxrwxrwx 1 0 0 12 Sep 19 08:20 5:3 -> ../ttyprintk lrwxrwxrwx 1 0 0 6 Sep 19 08:20 7:0 -> ../vcs lrwxrwxrwx 1 0 0 7 Sep 19 08:20 7:1 -> ../vcs1 lrwxrwxrwx 1 0 0 7 Sep 19 08:20 7:128 -> ../vcsa lrwxrwxrwx 1 0 0 8 Sep 19 08:20 7:129 -> ../vcsa1 /dev/disk: total 0 drwxr-xr-x 2 0 0 140 Sep 19 08:20 by-id drwxr-xr-x 2 0 0 80 Sep 19 08:20 by-label drwxr-xr-x 2 0 0 100 Sep 19 08:20 by-partuuid drwxr-xr-x 2 0 0 140 Sep 19 08:20 by-path drwxr-xr-x 2 0 0 100 Sep 19 08:20 by-uuid /dev/disk/by-id: total 0 lrwxrwxrwx 1 0 0 9 Sep 19 08:20 scsi-0QEMU_QEMU_HARDDISK_appliance -../../sdb lrwxrwxrwx 1 0 0 9 Sep 19 08:20 scsi-0QEMU_QEMU_HARDDISK_hd0 -> ../../sda lrwxrwxrwx 1 0 0 10 Sep 19 08:20 scsi-0QEMU_QEMU_HARDDISK_hd0-part1 -../../sda1 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 scsi-0QEMU_QEMU_HARDDISK_hd0-part14 -../../sda14 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 scsi-0QEMU_QEMU_HARDDISK_hd0-part15 -../../sda15 /dev/disk/by-label: total 0 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 UEFI -> ../../sda15 lrwxrwxrwx 1 0 0 10 Sep 19 08:20 cloudimg-rootfs -> ../../sda1 /dev/disk/by-partuuid: total 0 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 1fd423f2-a7a8-4b55-9fdb-7b3b8af59343 -../../sda15 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 62f43dec-094b-4f0d-9239-daa763eef3d4 -../../sda14 lrwxrwxrwx 1 0 0 10 Sep 19 08:20 78998fbd-7937-4814-92ea-9776170c1b16 -../../sda1 /dev/disk/by-path: total 0 lrwxrwxrwx 1 0 0 9 Sep 19 08:20 pci-0000:00:03.0-scsi-0:0:0:0 -> ../../sda lrwxrwxrwx 1 0 0 10 Sep 19 08:20 pci-0000:00:03.0-scsi-0:0:0:0-part1 -../../sda1 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 pci-0000:00:03.0-scsi-0:0:0:0-part14 -../../sda14 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 pci-0000:00:03.0-scsi-0:0:0:0-part15 -../../sda15 lrwxrwxrwx 1 0 0 9 Sep 19 08:20 pci-0000:00:03.0-scsi-0:0:1:0 -> ../../sdb /dev/disk/by-uuid: total 0 lrwxrwxrwx 1 0 0 9 Sep 19 08:20 0885a6f8-3acc-4e5a-8352-4064de7bd8a8 -../../sdb lrwxrwxrwx 1 0 0 10 Sep 19 08:20 6274a9cd-0934-4ee8-97b0-0a0cd9ef0ac8 -../../sda1 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 88E5-EC53 -> ../../sda15 /dev/input: total 0 drwxr-xr-x 2 0 0 60 Sep 19 08:20 by-path crw------- 1 0 0 13, 64 Sep 19 08:20 event0 crw------- 1 0 0 13, 65 Sep 19 08:20 event1 crw------- 1 0 0 13, 63 Sep 19 08:20 mice /dev/input/by-path: total 0 lrwxrwxrwx 1 0 0 9 Sep 19 08:20 platform-i8042-serio-0-event-kbd -../event1 /dev/lightnvm: total 0 crw------- 1 0 0 10, 60 Sep 19 08:20 control /dev/mapper: total 0 crw------- 1 0 0 10, 236 Sep 19 08:20 control /dev/net: total 0 crw-rw-rw- 1 0 0 10, 200 Sep 19 08:20 tun /dev/pts: total 0 c--------- 1 0 0 5, 2 Sep 19 08:20 ptmx /dev/shm: total 0 /dev/snd: total 0 crw------- 1 0 0 116, 1 Sep 19 08:20 seq crw------- 1 0 0 116, 33 Sep 19 08:20 timer /dev/vfio: total 0 crw------- 1 0 0 10, 196 Sep 19 08:20 vfio /dev/virtio-ports: total 0 lrwxrwxrwx 1 0 0 11 Sep 19 08:20 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=96136k,mode=755 0 0 /dev /dev devtmpfs rw,relatime,size=236944k,nr_inodes=59236,mode=755 0 0 /dev/pts /dev/pts devpts rw,relatime,mode=600,ptmxmode=000 0 0 shmfs /dev/shm tmpfs rw,relatime 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 2: eth0: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000 link/ether 52:54:00:12:34:56 brd ff:ff:ff:ff:ff:ff + ip r + cat /etc/resolv.conf cat: /etc/resolv.conf: No such file or directory + lsmod Module Size Used by libcrc32c 16384 0 crc8 16384 0 crc7 16384 0 crc4 16384 0 crc_itu_t 16384 0 virtio_input 16384 0 virtio_scsi 20480 1 virtio_rpmsg_bus 20480 0 rpmsg_core 16384 1 virtio_rpmsg_bus nd_pmem 20480 0 nd_btt 24576 1 nd_pmem virtio_net 45056 0 virtio_crypto 20480 0 crypto_engine 16384 1 virtio_crypto virtio_rng 16384 0 virtio_blk 20480 0 nfit 57344 0 crc32_generic 16384 0 + date Wed Sep 19 08:25:03 UTC 2018 + echo -n 'clocksource: ' clocksource: + cat /sys/devices/system/clocksource/clocksource0/current_clocksource kvm-clock + echo -n 'uptime: ' uptime: + cat /proc/uptime 304.24 301.39 + test '' = 1 + cmd=guestfsd ++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline + eval + test x '!=' x + test 1 = 1 + cmd='guestfsd --verbose' + test 1 = 1 + cmd='guestfsd --verbose --network' + echo guestfsd --verbose --network guestfsd --verbose --network + guestfsd --verbose --network lvm config: lvmetad warning: lvmetad command failed 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: c_pointer libguestfs: trace: c_pointer = 93852701051408 libguestfs: trace: list_partitions guestfsd: main_loop: new request, len 0x28 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 libguestfs: trace: list_partitions = ["/dev/sda1", "/dev/sda14", "/dev/sda15"] libguestfs: trace: vfs_type "/dev/sda1" guestfsd: main_loop: proc 8 (list_partitions) took 0.03 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda1 libguestfs: trace: vfs_type = "ext4" libguestfs: trace: vfs_type "/dev/sda14" guestfsd: main_loop: proc 198 (vfs_type) took 0.04 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda14 libguestfs: trace: vfs_type = "" libguestfs: trace: vfs_type "/dev/sda15" guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda15 libguestfs: trace: vfs_type = "vfat" libguestfs: trace: inspect_os libguestfs: trace: umount_all guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds 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=96136k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=236944k,nr_inodes=59236,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=shmfs dir=/dev/shm type=tmpfs opts=rw,relatime freq=0 passno=0 libguestfs: trace: umount_all = 0 libguestfs: trace: list_filesystems libguestfs: trace: feature_available "lvm2" libguestfs: trace: internal_feature_available "lvm2" guestfsd: main_loop: proc 47 (umount_all) took 0.01 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 458 (internal_feature_available) took 0.00 seconds libguestfs: trace: internal_feature_available = 0 libguestfs: trace: feature_available = 1 libguestfs: trace: feature_available "ldm" libguestfs: trace: internal_feature_available "ldm" guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 458 (internal_feature_available) took 0.00 secondslibguestfs: trace: internal_feature_available = 0 libguestfs: trace: feature_available = 1 libguestfs: trace: list_devices guestfsd: main_loop: new request, len 0x28 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 libguestfs: trace: list_devices = ["/dev/sda"] libguestfs: trace: list_partitions guestfsd: main_loop: proc 7 (list_devices) took 0.03 seconds guestfsd: main_loop: new request, len 0x28 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 libguestfs: trace: list_partitions = ["/dev/sda1", "/dev/sda14", "/dev/sda15"] libguestfs: trace: list_md_devices guestfsd: main_loop: proc 8 (list_partitions) took 0.03 seconds guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 300 (list_md_devices) took 0.00 seconds libguestfs: trace: list_md_devices = [] libguestfs: trace: part_to_dev "/dev/sda1" guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_to_dev "/dev/sda14" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_to_dev "/dev/sda15" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_to_partnum "/dev/sda1" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: part_to_partnum = 1 libguestfs: trace: part_to_dev "/dev/sda1" guestfsd: main_loop: proc 293 (part_to_partnum) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_get_mbr_id "/dev/sda" 1 guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: sfdisk --help commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: sfdisk --part-type /dev/sda 1 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle calling: settle libguestfs: trace: part_get_mbr_id = 264650159 libguestfs: trace: vfs_type "/dev/sda1" guestfsd: main_loop: proc 235 (part_get_mbr_id) took 0.08 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda1 libguestfs: trace: vfs_type = "ext4" libguestfs: trace: part_to_partnum "/dev/sda14" guestfsd: main_loop: proc 198 (vfs_type) took 0.05 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle libguestfs: trace: part_to_partnum = 14 libguestfs: trace: part_to_dev "/dev/sda14" guestfsd: main_loop: proc 293 (part_to_partnum) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_get_mbr_id "/dev/sda" 14 guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 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=y stderr=y flags=0x0 commandrvf: sfdisk --part-type /dev/sda 14 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle calling: settle libguestfs: trace: part_get_mbr_id = 560488776 libguestfs: trace: vfs_type "/dev/sda14" guestfsd: main_loop: proc 235 (part_get_mbr_id) took 0.06 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda14 libguestfs: trace: vfs_type = "" libguestfs: trace: part_to_partnum "/dev/sda15" guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: part_to_partnum = 15 libguestfs: trace: part_to_dev "/dev/sda15" guestfsd: main_loop: proc 293 (part_to_partnum) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_get_mbr_id "/dev/sda" 15 guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 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=y stderr=y flags=0x0 commandrvf: sfdisk --part-type /dev/sda 15 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle calling: settle libguestfs: trace: part_get_mbr_id = -1054182616 libguestfs: trace: vfs_type "/dev/sda15" guestfsd: main_loop: proc 235 (part_get_mbr_id) took 0.06 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda15 libguestfs: trace: vfs_type = "vfat" libguestfs: trace: lvs guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds guestfsd: main_loop: new request, len 0x28 commandrvf: stdout=y stderr=y flags=0x0 commandrvf: lvm lvs --help WARNING: Failed to connect to lvmetad. Falling back to device scanning. commandrvf: stdout=y stderr=y flags=0x0 commandrvf: lvm lvs -o vg_name,lv_name -S "lv_role=public && lv_skip_activation!=yes" --noheadings --separator / WARNING: Failed to connect to lvmetad. Falling back to device scanning. libguestfs: trace: lvs = [] libguestfs: trace: list_ldm_volumes guestfsd: main_loop: proc 11 (lvs) took 0.07 seconds guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 380 (list_ldm_volumes) took 0.00 seconds libguestfs: trace: list_ldm_volumes = [] libguestfs: trace: list_ldm_partitions guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 381 (list_ldm_partitions) took 0.00 seconds libguestfs: trace: list_ldm_partitions = [] libguestfs: trace: list_filesystems = ["/dev/sda1", "ext4", "/dev/sda14", "unknown", "/dev/sda15", "vfat"] libguestfs: trace: vfs_type "/dev/sda1" guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda1 libguestfs: trace: vfs_type = "ext4" libguestfs: check_for_filesystem_on: /dev/sda1 (ext4) libguestfs: trace: internal_parse_mountable "/dev/sda1" guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: internal_parse_mountable = <struct guestfs_internal_mountable = im_type: 0, im_device: /dev/sda1, im_volume: , libguestfs: trace: is_whole_device "/dev/sda1" guestfsd: main_loop: proc 396 (internal_parse_mountable) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle guestfsd: main_loop: proc 395 (is_whole_device) took 0.01 secondlibguestfs: trace: is_whole_device = 0 libguestfs: trace: mount_ro "/dev/sda1" "/" s guestfsd: main_loop: new request, len 0x40 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: mount -o ro /dev/sda1 /sysroot/ [ 305.569493] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) libguestfs: trace: mount_ro = 0 libguestfs: trace: part_to_dev "/dev/sda1" guestfsd: main_loop: proc 73 (mount_ro) took 0.04 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: device_index "/dev/sda" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x34 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 -E /dev/sdb calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda calling: settle libguestfs: trace: device_index = 0 libguestfs: trace: part_to_partnum "/dev/sda1" guestfsd: main_loop: proc 335 (device_index) took 0.05 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: part_to_partnum = 1 libguestfs: trace: part_to_dev "/dev/sda1" guestfsd: main_loop: proc 293 (part_to_partnum) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_list "/dev/sda" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x34 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=y stderr=y flags=0x0 commandrvf: parted -m -s -- /dev/sda unit b print commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle calling: settle libguestfs: trace: part_list = <struct guestfs_partition_list(3) = [0]{part_num: 14, part_start: 1048576, part_end: 5242879, part_size: 4194304, } [1]{part_num: 15, part_start: 5242880, part_end: 116391935, part_size: 111149056, } [2]{part_num: 1, part_start: 116391936, part_end: 2361376255, part_size: 2244984320, }libguestfs: trace: is_dir "/etc" guestfsd: main_loop: proc 213 (part_list) took 0.18 seconds guestfsd: main_loop: new request, len 0x34 libguestfs: trace: is_dir = 1 libguestfs: trace: is_dir "/bin" guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 1 libguestfs: trace: is_dir "/share" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: is_file "/grub/menu.lst" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub/grub.conf" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub2/grub.cfg" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/etc/freebsd-update.conf" guestfsd: main_loop: new request, len 0x48 libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/netbsd" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/bsd" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/hurd/console" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/service/vm" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/etc/fstab" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: trace: is_file "/etc/os-release" "followsymlinks:true" guestfsd: main_loop: new request, len 0x40 libguestfs: trace: is_file = 1 libguestfs: trace: filesize "/etc/os-release" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 218 (filesize) took 0.00 seconds libguestfs: trace: filesize = 386 libguestfs: trace: read_lines "/etc/os-release" libguestfs: trace: read_file "/etc/os-release" libguestfs: trace: download "/etc/os-release" "/tmp/libguestfsjnSQmf/cat1" guestfsd: main_loop: new request, len 0x3c libguestfs: trace: download = 0 libguestfs: trace: read_file = "NAME="Ubuntu"\x0aVERSION="18.04.1 LTS (Bionic Beaver)"\x0aID=ubuntu\x0aID_LIKE=debian\x0aPRETTY_NAME="Ubuntu 18.04.1 LTS"\x0aVERSION_ID="18.04"\x0aHOME_URL="https://www.u... "\x0aSUPPORT_URL="https://help.ubuntu.com/"\x0aBUG_REPORT_URL=" https://bugs.launchpad.net/ubuntu/"\x0aP"<truncated, original size 386 byteslibguestfs: trace: read_lines = ["NAME="Ubuntu"", "VERSION="18.04.1 LTS (Bionic Beaver)"", "ID=ubuntu", "ID_LIKE=debian", "PRETTY_NAME="Ubuntu 18.04.1 LTS"", "VERSION_ID="18.04"", "HOME_URL="https://www.ubuntu.com/"", "SUPPORT_URL="https://help.ubuntu.com/"", "BUG_REPORT_URL=" https://bugs.launchpad.net/ubuntu/"";, "PRIVACY_POLICY_URL=" https://www.ubuntu.com/legal/terms-and-policies/privacy-policy"";, "VERSION_CODENAME=bionic", "UBUNTU_CODENAME=bionic"] libguestfs: trace: is_file "/bin/bash" "followsymlinks:true" guestfsd: main_loop: proc 67 (download) took 0.00 seconds guestfsd: main_loop: new request, len 0x3c libguestfs: trace: is_file = 1 libguestfs: trace: realpath "/bin/bash" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 163 (realpath) took 0.00 seconds libguestfs: trace: realpath = "/bin/bash" libguestfs: trace: file_architecture "/bin/bash" libguestfs: trace: file "/bin/bash" guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=y stderr=y flags=0x0 commandrvf: file -zb /sysroot/bin/bash guestfsd: main_loop: proc 49 (file) took 0.03 seconds libguestfs: trace: file = "ELF 64-bit LSB shared object, x86-64, version 1 (SYSV), dynamically linked, interpreter /lib64/ld-linux-x86-64.so.2, for GNU/Linux 3.2.0, BuildID[sha1]=b636f50d85c3cca7cf2518030446660c1d90d660, stripped" libguestfs: trace: file_architecture = "x86_64" libguestfs: trace: is_file "/etc/fstab" "followsymlinks:true" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: trace: filesize "/etc/fstab" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 218 (filesize) took 0.00 seconds libguestfs: trace: filesize = 82 libguestfs: trace: is_file "/etc/mdadm.conf" "followsymlinks:true" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: aug_init "/" 48 guestfsd: main_loop: new request, len 0x34 augeas version: 1.10.1 guestfsd: main_loop: proc 16 (aug_init) took 0.78 seconds libguestfs: trace: aug_init = 0 libguestfs: augeas pathexpr = /augeas/load/*[ "/etc/fstab/" !~ regexp('^') + glob(incl) + regexp('/.*') and "/etc/mdadm.conf/" !~ regexp('^') + glob(incl) + regexp('/.*') ] libguestfs: trace: aug_rm "/augeas/load/*[ "/etc/fstab/" !~ regexp('^') + glob(incl) + regexp('/.*') and "/etc/mdadm.conf/" !~ regexp('^') + glob(incl) + regexp('/.*') ]" guestfsd: main_loop: new request, len 0xbc libguestfs: trace: aug_rm = 1598 libguestfs: trace: aug_load guestfsd: main_loop: proc 22 (aug_rm) took 0.24 seconds guestfsd: main_loop: new request, len 0x28 libguestfs: trace: aug_load = 0 libguestfs: trace: aug_match "/augeas/files//error" guestfsd: main_loop: proc 27 (aug_load) took 0.03 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 24 (aug_match) took 0.00 seconds libguestfs: trace: aug_match = [] libguestfs: trace: list_md_devices guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 300 (list_md_devices) took 0.00 seconds libguestfs: trace: list_md_devices = [] libguestfs: trace: aug_match "/files/etc/fstab/*[label() != '#comment']" guestfsd: main_loop: new request, len 0x58 guestfsd: main_loop: proc 24 (aug_match) took 0.00 seconds libguestfs: trace: aug_match = ["/files/etc/fstab/1", "/files/etc/fstab/2"] libguestfs: trace: aug_get "/files/etc/fstab/1/spec" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "LABEL=cloudimg-rootfs" libguestfs: trace: aug_get "/files/etc/fstab/1/file" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "/" libguestfs: trace: findfs_label "cloudimg-rootfs" guestfsd: main_loop: new request, len 0x3c commandrvf: stdout=y stderr=y flags=0x0 commandrvf: findfs LABEL=cloudimg-rootfs libguestfs: trace: findfs_label = "/dev/sda1" libguestfs: trace: aug_get "/files/etc/fstab/1/vfstype" guestfsd: main_loop: proc 266 (findfs_label) took 0.01 seconds guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ext4" libguestfs: fstab: mountable=/dev/sda1 mountpoint=/ libguestfs: trace: aug_get "/files/etc/fstab/2/spec" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "LABEL=UEFI" libguestfs: trace: aug_get "/files/etc/fstab/2/file" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "/boot/efi" libguestfs: trace: findfs_label "UEFI" guestfsd: main_loop: new request, len 0x30 commandrvf: stdout=y stderr=y flags=0x0 commandrvf: findfs LABEL=UEFI libguestfs: trace: findfs_label = "/dev/sda15" libguestfs: trace: aug_get "/files/etc/fstab/2/vfstype" guestfsd: main_loop: proc 266 (findfs_label) took 0.00 seconds guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "vfat" libguestfs: fstab: mountable=/dev/sda15 mountpoint=/boot/efi libguestfs: trace: aug_close guestfsd: main_loop: new request, len 0x28 libguestfs: trace: aug_close = 0 libguestfs: trace: is_file "/etc/HOSTNAME" guestfsd: main_loop: proc 26 (aug_close) took 0.02 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/etc/hostname" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: trace: filesize "/etc/hostname" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 218 (filesize) took 0.00 seconds libguestfs: trace: filesize = 7 libguestfs: trace: head_n 1 "/etc/hostname" guestfsd: main_loop: new request, len 0x40 commandrvf: stdout=y stderr=y flags=0x20004 commandrvf: head -n 1 libguestfs: trace: head_n = ["ubuntu"] libguestfs: trace: umount_all guestfsd: main_loop: proc 122 (head_n) took 0.01 seconds 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=96136k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=236944k,nr_inodes=59236,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=shmfs dir=/dev/shm type=tmpfs opts=rw,relatime freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda1 dir=/sysroot type=ext4 opts=ro,relatime,data=ordered freq=0 passno=0 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: umount /sysroot libguestfs: trace: umount_all = 0 libguestfs: trace: vfs_type "/dev/sda14" guestfsd: main_loop: proc 47 (umount_all) took 0.03 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda14 libguestfs: trace: vfs_type = "" libguestfs: check_for_filesystem_on: /dev/sda14 () libguestfs: trace: internal_parse_mountable "/dev/sda14" guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle libguestfs: trace: internal_parse_mountable = <struct guestfs_internal_mountable = im_type: 0, im_device: /dev/sda14, im_volume: , libguestfs: trace: is_whole_device "/dev/sda14" guestfsd: main_loop: proc 396 (internal_parse_mountable) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle libguestfs: trace: is_whole_device = 0 libguestfs: trace: mount_ro "/dev/sda14" "/" guestfsd: main_loop: proc 395 (is_whole_device) took 0.01 seconds guestfsd: main_loop: new request, len 0x40 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda14 calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: mount -o ro /dev/sda14 /sysroot/ mount: /sysroot: wrong fs type, bad option, bad superblock on /dev/sda14, missing codepage or helper program, or other error. guestfsd: error: /dev/sda14 on / (options: 'ro'): mount: /sysroot: wrong fs type, bad option, bad superblock on /dev/sda14, missing codepage or helper program, or other error. guestfsd: main_loop: proc 73 (mount_ro) took 0.03 seconds libguestfs: trace: mount_ro = -1 (error) libguestfs: trace: vfs_type "/dev/sda15" guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle commandrvf: stdout=y stderr=y flags=0x0 commandrvf: blkid -c /dev/null -o value -s TYPE /dev/sda15 guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds libguestfs: trace: vfs_type = "vfat" libguestfs: check_for_filesystem_on: /dev/sda15 (vfat) libguestfs: trace: internal_parse_mountable "/dev/sda15" guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: internal_parse_mountable = <struct guestfs_internal_mountable = im_type: 0, im_device: /dev/sda15, im_volume: , libguestfs: trace: is_whole_device "/dev/sda15" guestfsd: main_loop: proc 396 (internal_parse_mountable) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: is_whole_device = 0 libguestfs: trace: mount_ro "/dev/sda15" "/" guestfsd: main_loop: proc 395 (is_whole_device) took 0.01 seconds guestfsd: main_loop: new request, len 0x40 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: mount -o ro /dev/sda15 /sysroot/ libguestfs: trace: mount_ro = 0 libguestfs: trace: part_to_dev "/dev/sda15" guestfsd: main_loop: proc 73 (mount_ro) took 0.05 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: device_index "/dev/sda" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x34 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 -E /dev/sdb calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda calling: settle libguestfs: trace: device_index = 0 libguestfs: trace: part_to_partnum "/dev/sda15" guestfsd: main_loop: proc 335 (device_index) took 0.05 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: part_to_partnum = 15 libguestfs: trace: part_to_dev "/dev/sda15" guestfsd: main_loop: proc 293 (part_to_partnum) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_list "/dev/sda" guestfsd: main_loop: proc 272 (part_to_dev) took 0.01 seconds guestfsd: main_loop: new request, len 0x34 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=y stderr=y flags=0x0 commandrvf: parted -m -s -- /dev/sda unit b print commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle calling: settle libguestfs: trace: part_list = <struct guestfs_partition_list(3) = [0]{part_num: 14, part_start: 1048576, part_end: 5242879, part_size: 4194304, } [1]{part_num: 15, part_start: 5242880, part_end: 116391935, part_size: 111149056, } [2]{part_num: 1, part_start: 116391936, part_end: 2361376255, part_size: 2244984320, }libguestfs: trace: is_dir "/etc" guestfsd: main_loop: proc 213 (part_list) took 0.13 seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: is_dir "/bin" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: is_dir "/share" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: is_file "/grub/menu.lst" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub/grub.conf" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub2/grub.cfg" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/hurd/console" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_dir "/log" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: case_sensitive_path "/windows" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/windows" libguestfs: trace: case_sensitive_path "/windows/system32" guestfsd: main_loop: new request, len 0x40 guestfsd: error: windows: no file or directory found with this name libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/winnt" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/winnt" libguestfs: trace: case_sensitive_path "/winnt/system32" guestfsd: main_loop: new request, len 0x3c guestfsd: error: winnt: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/win32" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/win32" libguestfs: trace: case_sensitive_path "/win32/system32" guestfsd: main_loop: new request, len 0x3c guestfsd: error: win32: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/win" guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/win" libguestfs: trace: case_sensitive_path "/win/system32" guestfsd: main_loop: new request, len 0x3c guestfsd: error: win: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/reactos" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/reactos" libguestfs: trace: case_sensitive_path "/reactos/system32" guestfsd: main_loop: new request, len 0x40 guestfsd: error: reactos: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/boot.ini" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/boot.ini" libguestfs: trace: is_file "/boot.ini" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: case_sensitive_path "/System Volume Information" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/System Volume Information" libguestfs: trace: is_dir "/System Volume Information" guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: case_sensitive_path "/System Volume Information" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/System Volume Information" libguestfs: trace: is_dir "/System Volume Information" guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: case_sensitive_path "/FDOS" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/FDOS" libguestfs: trace: is_dir "/FDOS" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: umount_all 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=96136k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=236944k,nr_inodes=59236,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=shmfs dir=/dev/shm type=tmpfs opts=rw,relatime freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda15 dir=/sysroot type=vfat opts=ro,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro freq=0 passno=0 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: umount /sysroot libguestfs: trace: umount_all = 0 libguestfs: trace: inspect_get_roots libguestfs: trace: inspect_get_roots = ["/dev/sda1"] libguestfs: trace: inspect_os = ["/dev/sda1"] libguestfs: trace: inspect_get_mountpoints "/dev/sda1" libguestfs: trace: inspect_get_mountpoints = ["/", "/dev/sda1", "/boot/efi", "/dev/sda15"] libguestfs: trace: mount "/dev/sda1" "/" guestfsd: main_loop: proc 47 (umount_all) took 0.03 seconds guestfsd: main_loop: new request, len 0x40 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda1 calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: mount -o /dev/sda1 /sysroot/ [ 307.894213] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) guestfsd: main_loop: proc 1 (mount) took 0.09 seconds libguestfs: trace: mount = 0 libguestfs: trace: mount "/dev/sda15" "/boot/efi" guestfsd: main_loop: new request, len 0x48 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: udevadm --debug settle -E /dev/sda15 calling: settle commandrvf: stdout=n stderr=y flags=0x0 commandrvf: mount -o /dev/sda15 /sysroot/boot/efi libguestfs: trace: mount = 0 libguestfs: trace: inspect_get_arch "/dev/sda1" libguestfs: trace: inspect_get_arch = "x86_64" libguestfs: trace: inspect_get_type "/dev/sda1" libguestfs: trace: inspect_get_type = "linux" libguestfs: trace: is_dir "/tmp" "followsymlinks:true" guestfsd: main_loop: proc 1 (mount) took 0.07 seconds guestfsd: main_loop: new request, len 0x34 libguestfs: trace: is_dir = 1 [ 310.6] Setting a random seed libguestfs: trace: inspect_get_type "/dev/sda1" libguestfs: trace: inspect_get_type = "linux" libguestfs: trace: is_file "/var/lib/random-seed" guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds guestfsd: main_loop: new request, len 0x44 libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/var/lib/systemd/random-seed" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x4c libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/var/lib/urandom/random-seed" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/var/lib/misc/random-seed" guestfsd: main_loop: new request, len 0x4c libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/etc/random-seed" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 libguestfs: trace: is_file = 0 libguestfs: trace: inspect_get_distro "/dev/sda1" libguestfs: trace: inspect_get_distro = "ubuntu" libguestfs: trace: is_dir "/var/lib/urandom" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 virt-customize: warning: random seed could not be set for this type of guest libguestfs: trace: lstatns "/etc/machine-id" guestfsd: main_loop: new request, len 0x3c libguestfs: trace: lstatns = <struct guestfs_statns = st_dev: 2049, st_ino: 830, st_mode: 33060, st_nlink: 1, st_uid: 0, st_gid: 0, st_rdev: 0, st_size: 0, st_blksize: 4096, st_blocks: 0, st_atime_sec: 1536595488, st_atime_nsec: 275188157, st_mtime_sec: 1536595471, st_mtime_nsec: 126960249, st_ctime_sec: 1536596093, st_ctime_nsec: 438935969, st_spare1: 0, st_spare2: 0, st_spare3: 0, st_spare4: 0, st_spare5: 0, st_spare6: 0, [ 310.6] Setting the machine ID in /etc/machine-id libguestfs: trace: write "/etc/machine-id" "19e2b4e8934ad715bc22f272da9815a9\x0a" libguestfs: trace: internal_write "/etc/machine-id" "19e2b4e8934ad715bc22f272da9815a9\x0a" guestfsd: main_loop: proc 422 (lstatns) took 0.00 seconds guestfsd: main_loop: new request, len 0x64 libguestfs: trace: internal_write = 0 libguestfs: trace: write = 0 [ 310.6] Setting the hostname: ato_test libguestfs: trace: inspect_get_type "/dev/sda1" libguestfs: trace: inspect_get_type = "linux" libguestfs: trace: inspect_get_distro "/dev/sda1" libguestfs: trace: inspect_get_distro = "ubuntu" libguestfs: trace: inspect_get_major_version "/dev/sda1" libguestfs: trace: inspect_get_major_version = 18 libguestfs: trace: is_file "/etc/hostname" guestfsd: main_loop: proc 246 (internal_write) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: trace: read_lines "/etc/hostname" libguestfs: trace: read_file "/etc/hostname" libguestfs: trace: download "/etc/hostname" "/tmp/libguestfsjnSQmf/cat2" guestfsd: main_loop: new request, len 0x3c libguestfs: trace: download = 0 libguestfs: trace: read_file = "ubuntu\x0a" libguestfs: trace: read_lines = ["ubuntu"] libguestfs: trace: write "/etc/hostname" "ato_test\x0a" libguestfs: trace: internal_write "/etc/hostname" "ato_test\x0a" guestfsd: main_loop: proc 67 (download) took 0.03 seconds guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 246 (internal_write) took 0.00 secondslibguestfs: trace: internal_write = 0 libguestfs: trace: write = 0 libguestfs: trace: is_file "/etc/hosts" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: trace: aug_init "/" 0 guestfsd: main_loop: new request, len 0x34 libguestfs: trace: aug_init = 0 libguestfs: trace: aug_match "/files/etc/hosts/*[label() != '#comment']/*[label() != 'ipaddr']" guestfsd: main_loop: proc 16 (aug_init) took 1.56 seconds guestfsd: main_loop: new request, len 0x6c guestfsd: main_loop: proc 24 (aug_match) took 0.00 seconds libguestfs: trace: aug_match = ["/files/etc/hosts/1/canonical", "/files/etc/hosts/2/canonical", "/files/etc/hosts/2/alias", "/files/etc/hosts/3/canonical", "/files/etc/hosts/4/canonical", "/files/etc/hosts/5/canonical", "/files/etc/hosts/6/canonical", "/files/etc/hosts/7/canonical"] libguestfs: trace: aug_get "/files/etc/hosts/1/canonical" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "localhost" libguestfs: trace: aug_get "/files/etc/hosts/2/canonical" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ip6-localhost" libguestfs: trace: aug_get "/files/etc/hosts/2/alias" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ip6-loopback" libguestfs: trace: aug_get "/files/etc/hosts/3/canonical" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ip6-localnet" libguestfs: trace: aug_get "/files/etc/hosts/4/canonical" guestfsd: main_loop: new request, len 0x48 guestfsd: main_llibguestfs: trace: aug_get = "ip6-mcastprefix" libguestfs: trace: aug_get "/files/etc/hosts/5/canonical" oop: proc 19 (aug_get) took 0.00 seconds guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ip6-allnodes" libguestfs: trace: aug_get "/files/etc/hosts/6/canonical" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ip6-allrouters" libguestfs: trace: aug_get "/files/etc/hosts/7/canonical" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 19 (aug_get) took 0.00 seconds libguestfs: trace: aug_get = "ip6-allhosts" libguestfs: trace: aug_save guestfsd: main_loop: new request, len 0x28 libguestfs: trace: aug_save = 0 [ 312.3] Uninstalling packages: cloud-init libguestfs: trace: inspect_get_package_management "/dev/sda1" libguestfs: trace: inspect_get_package_management = "apt" libguestfs: trace: sh "exec >>'/tmp/builder.log' 2>&1\n\n\n\n export DEBIAN_FRONTEND=noninteractive\n apt_opts='-q -y -o Dpkg::Options::=--force-confnew'\n apt-get $apt_opts remove 'cloud-init'\n \n" guestfsd: main_loop: proc 25 (aug_save) took 0.00 seconds guestfsd: main_loop: new request, len 0xf0 commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /dev /sysroot/dev commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /dev/pts /sysroot/dev/pts commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /proc /sysroot/proc commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /sys/fs/selinux /sysroot/selinux mount: /sysroot/selinux: mount point does not exist. commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /sys /sysroot/sys commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /sys/fs/selinux /sysroot/sys/fs/selinux mount: /sysroot/sys/fs/selinux: mount point does not exist. renaming /sysroot/etc/resolv.conf to /sysroot/etc/rb7upiiy commandrvf: stdout=n stderr=n flags=0x0 commandrvf: cp /etc/resolv.conf /sysroot/etc/resolv.conf cp: cannot stat '/etc/resolv.conf': No such file or directory commandrvf: stdout=y stderr=y flags=0x40000 commandrvf: /bin/sh -c "exec >>'/tmp/builder.log' 2>&1 export DEBIAN_FRONTEND=noninteractive apt_opts='-q -y -o Dpkg::Options::=--force-confnew' apt-get $apt_opts remove 'cloud-init' " commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/sys commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/proc commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/dev/pts commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/dev renaming /sysroot/etc/rb7upiiy to /sysroot/etc/resolv.conf guestfsd: main_loop: proc 111 (sh) took 6.20 seconds libguestfs: trace: sh = "" [ 318.5] Installing packages: ifupdown libguestfs: trace: inspect_get_package_management "/dev/sda1" libguestfs: trace: inspect_get_package_management = "apt" libguestfs: trace: sh "exec >>'/tmp/builder.log' 2>&1\n\n\n\n export DEBIAN_FRONTEND=noninteractive\n apt_opts='-q -y -o Dpkg::Options::=--force-confnew'\n apt-get $apt_opts update\n apt-get $apt_opts install 'ifupdown'\n \n" guestfsd: main_loop: new request, len 0x110 commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /dev /sysroot/dev commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /dev/pts /sysroot/dev/pts commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /proc /sysroot/proc commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /sys/fs/selinux /sysroot/selinux mount: /sysroot/selinux: mount point does not exist. commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /sys /sysroot/sys commandrvf: stdout=n stderr=n flags=0x0 commandrvf: mount --bind /sys/fs/selinux /sysroot/sys/fs/selinux mount: /sysroot/sys/fs/selinux: mount point does not exist. renaming /sysroot/etc/resolv.conf to /sysroot/etc/wp06h5fh commandrvf: stdout=n stderr=n flags=0x0 commandrvf: cp /etc/resolv.conf /sysroot/etc/resolv.conf cp: cannot stat '/etc/resolv.conf': No such file or directory commandrvf: stdout=y stderr=y flags=0x40000 commandrvf: /bin/sh -c "exec >>'/tmp/builder.log' 2>&1 export DEBIAN_FRONTEND=noninteractive apt_opts='-q -y -o Dpkg::Options::=--force-confnew' apt-get $apt_opts update apt-get $apt_opts install 'ifupdown' " commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/sys commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/proc commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/dev/pts commandrvf: stdout=n stderr=n flags=0x0 commandrvf: umount /sysroot/dev renaming /sysroot/etc/wp06h5fh to /sysroot/etc/resolv.conf guestfsd: error: guestfsd: main_loop: proc 111 (sh) took 0.52 seconds libguestfs: trace: sh = NULL (error) libguestfs: trace: download "/tmp/builder.log" "/dev/stderr" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 67 (download) took 0.00 seconds Reading package lists... Building dependency tree... Reading state information... The following package was automatically installed and is no longer required: grub-pc-bin Use 'apt autoremove' to remove it. The following packages will be REMOVED: cloud-init 0 upgraded, 0 newly installed, 1 to remove and 0 not upgraded. After this operation, 1839 kB disk space will be freed. (Reading database ... 59960 files and directories currently installed.) Removing cloud-init (18.3-9-g2e62cb8a-0ubuntu1~18.04.2) ... Err:1 http://security.ubuntu.com/ubuntu bionic-security InRelease Temporary failure resolving 'security.ubuntu.com' Err:2 http://archive.ubuntu.com/ubuntu bionic InRelease Temporary failure resolving 'archive.ubuntu.com' Err:3 http://archive.ubuntu.com/ubuntu bionic-updates InRelease Temporary failure resolving 'archive.ubuntu.com' Err:4 http://archive.ubuntu.com/ubuntu bionic-backports InRelease Temporary failure resolving 'archive.ubuntu.com' Reading package lists... W: Failed to fetch http://archive.ubuntu.com/ubuntu/dists/bionic/InRelease Temporary failure resolving 'archive.ubuntu.com' W: Failed to fetch http://archive.ubuntu.com/ubuntu/dists/bionic-updates/InRelease Temporary failure resolving 'archive.ubuntu.com' W: Failed to fetch http://archive.ubuntu.com/ubuntu/dists/bionic-backports/InRelease Temporary failure resolving 'archive.ubuntu.com' W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/bionic-security/InRelease Temporary failure resolving 'security.ubuntu.com' W: Some index files failed to download. They have been ignored, or old ones used instead. Reading package lists... Building dependency tree... Reading state information... The following package was automatically installed and is no longer required: grub-pc-bin Use 'apt autoremove' to remove it. Suggested packages: ppp rdnssd The following NEW packages will be installed: ifupdown 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Need to get 55.9 kB of archives. After this operation, 227 kB of additional disk space will be used. Err:1 http://archive.ubuntu.com/ubuntu bionic-updates/main amd64 ifupdown amd64 0.8.17ubuntu1.1 Temporary failure resolving 'archive.ubuntu.com' E: Failed to fetch http://archive.ubuntu.com/ubuntu/pool/main/i/ifupdown/ifupdown_0.8.17ubun... Temporary failure resolving 'archive.ubuntu.com' E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing? libguestfs: trace: download = 0 libguestfs: trace: get_network libguestfs: trace: get_network = 1 virt-customize: error: export DEBIAN_FRONTEND=noninteractive apt_opts='-q -y -o Dpkg::Options::=--force-confnew' apt-get $apt_opts update apt-get $apt_opts install 'ifupdown' : command exited with an error If reporting bugs, run virt-customize with debugging enabled and include the complete output: virt-customize -v -x [...] libguestfs: trace: close libguestfs: closing guestfs handle 0x555bc8e84a10 (state 2) 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=96136k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=236944k,nr_inodes=59236,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=shmfs dir=/dev/shm type=tmpfs opts=rw,relatime freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda1 dir=/sysroot type=ext4 opts=rw,relatime,data=ordered freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda15 dir=/sysroot/boot/efi type=vfat opts=rw,relatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro freq=0 passno=0 commandrvf: stdout=n stderr=y flags=0x0 commandrvf: umount /sysroot/boot/efi commandrvf: stdout=n stderr=y flags=0x0 commandrvf: umount /sysroot 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 fsync /dev/sda guestfsd: main_loop: proc 282 (internal_autosync) took 3.51 seconds libguestfs: trace: internal_autosync = 0 libguestfs: sending SIGTERM to process 2940 libguestfs: qemu maxrss 316692K libguestfs: command: run: rm libguestfs: command: run: \ -rf /tmp/libguestfsjnSQmf libguestfs: command: run: rm libguestfs: command: run: \ -rf /run/user/1000/libguestfsHMk6Xd Any suggestion?