Gabriele Baldoni
2018-Sep-19 08:32 UTC
[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@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.ubuntu.com/
"\x0aSUPPORT_URL="https://help.ubuntu.com/"\x0aBUG_REPORT_URL="
https://bugs.launchpad.net/ubuntu/"\x0aP"<truncated, original size
386
bytes>
libguestfs: 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.17ubuntu1.1_amd64.deb
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?
Richard W.M. Jones
2018-Sep-19 19:00 UTC
Re: [Libguestfs] virt-customize is very slow in ubuntu 18.04/centos 7.5
On Wed, Sep 19, 2018 at 10:32:24AM +0200, Gabriele Baldoni wrote:> 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.The network/--no-network thing is a bug in Ubuntu's DHCP support. I think it's this one: https://bugs.launchpad.net/ubuntu/+source/libguestfs/+bug/1632405>From the log it also looks like packages can't be installed becausename resolution isn't working, which could be related to the above. This should however all work fine on CentOS since that's one of our primary development platforms. I'm not sure what else to say. If you wanted to investigate this further then you might try compiling from source. With the package built from source you could explore changing appliance/init to try to fix the DHCP problem. Make sure to read this first: http://libguestfs.org/guestfs-building.1.html Rich. -- Richard Jones, Virtualization Group, Red Hat http://people.redhat.com/~rjones Read my programming and virtualization blog: http://rwmj.wordpress.com virt-df lists disk usage of guests without needing to install any software inside the virtual machine. Supports Linux and Windows. http://people.redhat.com/~rjones/virt-df/
Gabriele Baldoni
2018-Sep-20 06:41 UTC
Re: [Libguestfs] virt-customize is very slow in ubuntu 18.04/centos 7.5
Thanks Rich for the fast response. I think I will add some comment also to the ubuntu bug tracker. In the mean while I will try to build from source. Thanks, Gabriele On Wed, Sep 19, 2018 at 9:00 PM Richard W.M. Jones <rjones@redhat.com> wrote:> On Wed, Sep 19, 2018 at 10:32:24AM +0200, Gabriele Baldoni wrote: > > 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. > > The network/--no-network thing is a bug in Ubuntu's DHCP support. I > think it's this one: > > https://bugs.launchpad.net/ubuntu/+source/libguestfs/+bug/1632405 > > From the log it also looks like packages can't be installed because > name resolution isn't working, which could be related to the above. > > This should however all work fine on CentOS since that's one of our > primary development platforms. > > I'm not sure what else to say. If you wanted to investigate this > further then you might try compiling from source. With the package > built from source you could explore changing appliance/init to try to > fix the DHCP problem. > > Make sure to read this first: > > http://libguestfs.org/guestfs-building.1.html > > Rich. > > -- > Richard Jones, Virtualization Group, Red Hat > http://people.redhat.com/~rjones > Read my programming and virtualization blog: http://rwmj.wordpress.com > virt-df lists disk usage of guests without needing to install any > software inside the virtual machine. Supports Linux and Windows. > http://people.redhat.com/~rjones/virt-df/ >