Linux moo.home.annexia.org 4.4.4-301.fc23.x86_64 #1 SMP Fri Mar 4 17:42:42 UTC 2016 x86_64 x86_64 x86_64 GNU/Linux model name : Intel(R) Core(TM) i7-5600U CPU @ 2.60GHz Warming up the libguestfs cache ... Running the tests in 2 passes ... pass 1: 461 events collected in 2225687928 ns pass 2: 461 events collected in 2245359074 ns pass 0 number of events collected 461 elapsed time 2225687928 ns #0: +97056 [trace] "launch" #1: +99375 [trace] "version" #2: +102941 [trace] "version = " #3: +104764 [trace] "get_backend" #4: +106283 [trace] "get_backend = "direct"" #5: +107087 [library] "launch: program=boot-analysis" #6: +107817 [library] "launch: version=1.33.15" #7: +108501 [library] "launch: backend registered: unix" #8: +109093 [library] "launch: backend registered: uml" #9: +109563 [library] "launch: backend registered: libvirt" #10: +109974 [library] "launch: backend registered: direct" #11: +110351 [library] "launch: backend=direct" #12: +110837 [library] "launch: tmpdir=/home/rjones/d/libguestfs/tmp/libguestfs8sCP3g" #13: +130042 [library] "launch: umask=0002" #14: +130664 [library] "launch: euid=1000" #15: +141444 [trace] "get_backend_setting "force_tcg"" #16: +144353 [trace] "get_backend_setting = NULL (error)" #17: +149190 [trace] "get_cachedir" #18: +150661 [trace] "get_cachedir = "/home/rjones/d/libguestfs/tmp"" #19: +160857 [library] "[00000ms] begin building supermin appliance" #20: +161663 [library] "[00000ms] run supermin" #21: +163979 [library] "command: run: /usr/bin/supermin" #22: +164362 [library] "command: run: \ --build" #23: +164687 [library] "command: run: \ --verbose" #24: +164988 [library] "command: run: \ --if-newer" #25: +165486 [library] "command: run: \ --lock /home/rjones/d/libguestfs/tmp/.guestfs-1000/lock" #26: +166046 [library] "command: run: \ --copy-kernel" #27: +166438 [library] "command: run: \ -f ext2" #28: +166825 [library] "command: run: \ --host-cpu x86_64" #29: +167210 [library] "command: run: \ /home/rjones/d/libguestfs/appliance/supermin.d" #30: +167639 [library] "command: run: \ -o /home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d" #31: +7977059 [appliance] "supermin: version: 5.1.15" #32: +7977825 [appliance] "supermin: rpm: detected RPM version 4.13" #33: +7978229 [appliance] "supermin: package handler: fedora/rpm" #34: +7978451 [appliance] "supermin: acquiring lock on /home/rjones/d/libguestfs/tmp/.guestfs-1000/lock" #35: +7980242 [appliance] "supermin: if-newer: output does not need rebuilding" #36: +10380455 [library] "[00010ms] finished building supermin appliance" #37: +10400123 [library] "[00010ms] begin testing qemu features" #38: +10402506 [library] "command: run: /usr/bin/qemu-kvm" #39: +10402987 [library] "command: run: \ -display none" #40: +10403324 [library] "command: run: \ -help" #41: +104765465 [library] "command: run: /usr/bin/qemu-kvm" #42: +104766714 [library] "command: run: \ -display none" #43: +104767288 [library] "command: run: \ -version" #44: +202653873 [library] "qemu version 2.5" #45: +202657614 [library] "command: run: /usr/bin/qemu-kvm" #46: +202658496 [library] "command: run: \ -display none" #47: +202659149 [library] "command: run: \ -machine accel=kvm:tcg" #48: +202659767 [library] "command: run: \ -device ?" #49: +298757574 [trace] "get_sockdir" #50: +298763200 [trace] "get_sockdir = "/run/user/1000"" #51: +299096021 [library] "[00298ms] finished testing qemu features" #52: +299106806 [trace] "get_backend_setting "gdb"" #53: +299112199 [trace] "get_backend_setting = NULL (error)" #54: +300843518 [appliance] "[00299ms] /usr/bin/qemu-kvm \" #55: +300845906 [appliance] " -global virtio-blk-pci.scsi=off \" #56: +300846753 [appliance] " -nodefconfig \" #57: +300847651 [appliance] " -enable-fips \" #58: +300848437 [appliance] " -nodefaults \" #59: +300849245 [appliance] " -display none \" #60: +300850119 [appliance] " -machine accel=kvm:tcg \" #61: +300850938 [appliance] " -cpu host \" #62: +300851744 [appliance] " -m 500 \" #63: +300852472 [appliance] " -no-reboot \" #64: +300853218 [appliance] " -rtc driftfix=slew \" #65: +300853946 [appliance] " -no-hpet \" #66: +300854764 [appliance] " -global kvm-pit.lost_tick_policy=discard \" #67: +300855667 [appliance] " -kernel /home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d/kernel \" #68: +300856630 [appliance] " -initrd /home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d/initrd \" #69: +300857530 [appliance] " -device virtio-scsi-pci,id=scsi \" #70: +300858666 [appliance] " -drive file=/home/rjones/d/libguestfs/tmp/libguestfs8sCP3g/devnull1,cache=writeback,id=hd0,if=none \" #71: +300859526 [appliance] " -device scsi-hd,drive=hd0 \" #72: +300860403 [appliance] " -drive file=/home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \" #73: +300861333 [appliance] " -device scsi-hd,drive=appliance \" #74: +300862214 [appliance] " -device virtio-serial-pci \" #75: +300862972 [appliance] " -serial stdio \" #76: +300863725 [appliance] " -device sga \" #77: +300864509 [appliance] " -chardev socket,path=/run/user/1000/libguestfsHISXnh/guestfsd.sock,id=channel0 \" #78: +300865352 [appliance] " -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \" #79: +300866237 [appliance] " -append 'panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1'" #80: +394940831 [appliance] "WARNING: Image format was not specified for '/home/rjones/d/libguestfs/tmp/libguestfs8sCP3g/devnull1' and probing guessed raw." #81: +394943216 [appliance] " Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted." #82: +394944138 [appliance] " Specify the 'raw' format explicitly to remove the restrictions." #83: +460731351 [appliance] "\x1b[1;256r\x1b[256;256H\x1b[6n" #84: +720512029 [appliance] "Google, Inc." #85: +720514529 [appliance] "Serial Graphics Adapter 06/19/15" #86: +720516269 [appliance] "SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (mockbuild@) Fri Jun 19 00:52:18 UTC 2015" #87: +720518119 [appliance] "Term: 80x24" #88: +720519624 [appliance] "4 0" #89: +720521032 [appliance] "\x1b[2J\x0dSeaBIOS (version 1.8.2-20150714_191134-)" #90: +831045679 [appliance] "\x0dBooting from ROM..." #91: +1484676723 [appliance] "\x0dProbing EDD (edd=off to disable)... ok" #92: +1484683728 [appliance] "\x1b[2J[ 0.000000] Initializing cgroup subsys cpuset" #93: +1564390068 [appliance] "[ 0.000000] Initializing cgroup subsys cpu" #94: +1564391677 [appliance] "[ 0.000000] Initializing cgroup subsys cpuacct" #95: +1564392523 [appliance] "[ 0.000000] Linux version 4.4.4-301.fc23.x86_64 (address@hidden) (gcc version 5.3.1 20151207 (Red Hat 5.3.1-2) (GCC) ) #1 SMP Fri Mar 4 17:42:42 UTC 2016" #96: +1565551586 [appliance] "[ 0.000000] Command line: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1" #97: +1566686375 [appliance] "[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256" #98: +1567821975 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers'" #99: +1567824023 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers'" #100: +1568960944 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers'" #101: +1568962466 [appliance] "[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format." #102: +1570098436 [appliance] "[ 0.000000] x86/fpu: Using 'eager' FPU context switches." #103: +1570099889 [appliance] "[ 0.000000] e820: BIOS-provided physical RAM map:" #104: +1570100738 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable" #105: +1571236219 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved" #106: +1571237650 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved" #107: +1571238489 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3dffff] usable" #108: +1572375870 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000001f3e0000-0x000000001f3fffff] reserved" #109: +1572377379 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved" #110: +1573511176 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved" #111: +1573512531 [appliance] "[ 0.000000] NX (Execute Disable) protection: active" #112: +1573513372 [appliance] "[ 0.000000] SMBIOS 2.8 present." #113: +1573514154 [appliance] "[ 0.000000] Hypervisor detected: KVM" #114: +1574648609 [appliance] "[ 0.000000] e820: last_pfn = 0x1f3e0 max_arch_pfn = 0x400000000" #115: +1574650066 [appliance] "[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT " #116: +1574650874 [appliance] "[ 0.000000] found SMP MP-table at [mem 0x000f64d0-0x000f64df] mapped at [ffff8800000f64d0]" #117: +1575784326 [appliance] "[ 0.000000] Using GB pages for direct mapping" #118: +1575785725 [appliance] "[ 0.000000] RAMDISK: [mem 0x1f38a000-0x1f3dffff]" #119: +1575786474 [appliance] "[ 0.000000] No NUMA configuration found" #120: +1576912443 [appliance] "[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3dffff]" #121: +1576913522 [appliance] "[ 0.000000] NODE_DATA(0) allocated [mem 0x1f378000-0x1f389fff]" #122: +1576914317 [appliance] "[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00" #123: +1578047938 [appliance] "[ 0.000000] kvm-clock: cpu 0, msr 0:1f368001, primary cpu clock" #124: +1578049370 [appliance] "[ 0.000000] kvm-clock: using sched offset of 1121123956 cycles" #125: +1578050134 [appliance] "[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns" #126: +1579184946 [appliance] "[ 0.000000] Zone ranges:" #127: +1579186293 [appliance] "[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]" #128: +1580320735 [appliance] "[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001f3dffff]" #129: +1580322101 [appliance] "[ 0.000000] Normal empty" #130: +1580323007 [appliance] "[ 0.000000] Movable zone start for each node" #131: +1580323742 [appliance] "[ 0.000000] Early memory node ranges" #132: +1581460226 [appliance] "[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]" #133: +1581461580 [appliance] "[ 0.000000] node 0: [mem 0x0000000000100000-0x000000001f3dffff]" #134: +1581462306 [appliance] "[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3dffff]" #135: +1582595748 [appliance] "[ 0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org" #136: +1582597114 [appliance] "[ 0.000000] Intel MultiProcessor Specification v1.4" #137: +1582597993 [appliance] "[ 0.000000] MPTABLE: OEM ID: BOCHSCPU" #138: +1583731540 [appliance] "[ 0.000000] MPTABLE: Product ID: 0.1 " #139: +1583732917 [appliance] "[ 0.000000] MPTABLE: APIC at: 0xFEE00000" #140: +1583733687 [appliance] "[ 0.000000] Processor #0 (Bootup-CPU)" #141: +1583734450 [appliance] "[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23" #142: +1584872191 [appliance] "[ 0.000000] Processors: 1" #143: +1584874323 [appliance] "[ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs" #144: +1584875790 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]" #145: +1586012051 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]" #146: +1586013496 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]" #147: +1586014349 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]" #148: +1587150643 [appliance] "[ 0.000000] e820: [mem 0x1f400000-0xfeffbfff] available for PCI devices" #149: +1587151970 [appliance] "[ 0.000000] Booting paravirtualized kernel on KVM" #150: +1587152833 [appliance] "[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns" #151: +1588288872 [appliance] "[ 0.000000] setup_percpu: NR_CPUS:1024 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1" #152: +1589427436 [appliance] "[ 0.000000] PERCPU: Embedded 34 pages/cpu @ffff88001f000000 s98712 r8192 d32360 u2097152" #153: +1589428809 [appliance] "[ 0.000000] KVM setup async PF for cpu 0" #154: +1589429686 [appliance] "[ 0.000000] kvm-stealtime: cpu 0, msr 1f00d900" #155: +1589430443 [appliance] "[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 125849" #156: +1590574603 [appliance] "[ 0.000000] Policy zone: DMA32" #157: +1590575949 [appliance] "[ 0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1" #158: +1591711162 [appliance] "[ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)" #159: +1592845248 [appliance] "[ 0.000000] Memory: 485620K/511480K available (7827K kernel code, 1307K rwdata, 3444K rodata, 1528K init, 1544K bss, 25860K reserved, 0K cma-reserved)" #160: +1593977835 [appliance] "[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1" #161: +1593979226 [appliance] "[ 0.000000] Hierarchical RCU implementation." #162: +1593980071 [appliance] "[ 0.000000] \x09Build-time adjustment of leaf fanout to 64." #163: +1593980907 [appliance] "[ 0.000000] \x09RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=1." #164: +1595108808 [appliance] "[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1" #165: +1595110083 [appliance] "[ 0.000000] NR_IRQS:65792 nr_irqs:256 16" #166: +1595110857 [appliance] "[ 0.000000] \x09Offload RCU callbacks from all CPUs" #167: +1596239957 [appliance] "[ 0.000000] \x09Offload RCU callbacks from CPUs: 0." #168: +1596241288 [appliance] "[ 0.000000] Console: colour *CGA 80x25" #169: +1596242091 [appliance] "[ 0.000000] console [ttyS0] enabled" #170: +1597420805 [appliance] "[ 0.000000] tsc: Detected 2593.994 MHz processor" #171: +1597422043 [appliance] "[ 0.041768] Calibrating delay loop (skipped) preset value.. 5187.98 BogoMIPS (lpj=2593994)" #172: +1597423841 [appliance] "[ 0.042313] pid_max: default: 32768 minimum: 301" #173: +1598557713 [appliance] "[ 0.042679] Security Framework initialized" #174: +1598558889 [appliance] "[ 0.042952] Yama: becoming mindful." #175: +1598559478 [appliance] "[ 0.043185] SELinux: Disabled at boot." #176: +1598560031 [appliance] "[ 0.043510] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)" #177: +1599665786 [appliance] "[ 0.044066] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)" #178: +1599666943 [appliance] "[ 0.044575] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)" #179: +1600790774 [appliance] "[ 0.045038] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)" #180: +1600792471 [appliance] "[ 0.045637] Initializing cgroup subsys io" #181: +1601919376 [appliance] "[ 0.045934] Initializing cgroup subsys memory" #182: +1601920658 [appliance] "[ 0.046373] Disabling memory control group subsystem" #183: +1601921283 [appliance] "[ 0.046723] Initializing cgroup subsys devices" #184: +1603045613 [appliance] "[ 0.047045] Initializing cgroup subsys freezer" #185: +1603046766 [appliance] "[ 0.047361] Initializing cgroup subsys net_cls" #186: +1603047390 [appliance] "[ 0.047676] Initializing cgroup subsys perf_event" #187: +1603047967 [appliance] "[ 0.047999] Initializing cgroup subsys net_prio" #188: +1604162814 [appliance] "[ 0.048320] Initializing cgroup subsys hugetlb" #189: +1604163939 [appliance] "[ 0.048633] Initializing cgroup subsys pids" #190: +1605936583 [appliance] "[ 0.049893] mce: CPU supports 10 MCE banks" #191: +1605937754 [appliance] "[ 0.050217] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0" #192: +1605938396 [appliance] "[ 0.050588] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0" #193: +1614982494 [appliance] "[ 0.058961] Freeing SMP alternatives memory: 28K (ffffffff81ec6000 - ffffffff81ecd000)" #194: +1617579634 [appliance] "[ 0.061573] ftrace: allocating 29522 entries in 116 pages" #195: +1638502712 [appliance] "[ 0.082440] x2apic enabled" #196: +1638504244 [appliance] "[ 0.082839] Switched APIC routing to physical x2apic." #197: +1639917480 [appliance] "[ 0.083884] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1" #198: +1639918772 [appliance] "[ 0.084315] smpboot: CPU0: Intel(R) Core(TM) i7-5600U CPU @ 2.60GHz (family: 0x6, model: 0x3d, stepping: 0x4)" #199: +1641046836 [appliance] "[ 0.085021] Performance Events: 16-deep LBR, Broadwell events, Intel PMU driver." #200: +1641047685 [appliance] "[ 0.085559] ... version: 2" #201: +1641048294 [appliance] "[ 0.085822] ... bit width: 48" #202: +1641048853 [appliance] "[ 0.086090] ... generic registers: 4" #203: +1642188713 [appliance] "[ 0.086377] ... value mask: 0000ffffffffffff" #204: +1642189844 [appliance] "[ 0.086723] ... max period: 000000007fffffff" #205: +1642190474 [appliance] "[ 0.087064] ... fixed-purpose events: 3" #206: +1643305181 [appliance] "[ 0.087345] ... event mask: 000000070000000f" #207: +1643306297 [appliance] "[ 0.088129] x86: Booted up 1 node, 1 CPUs" #208: +1643306907 [appliance] "[ 0.088403] smpboot: Total of 1 processors activated (5187.98 BogoMIPS)" #209: +1644417578 [appliance] "[ 0.089054] devtmpfs: initialized" #210: +1646593775 [appliance] "[ 0.090543] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns" #211: +1646595023 [appliance] "[ 0.091249] atomic64_test: passed for x86-64 platform with CX8 and with SSE" #212: +1647730726 [appliance] "[ 0.091711] pinctrl core: initialized pinctrl subsystem" #213: +1647731886 [appliance] "[ 0.092104] RTC time: 20:24:23, date: 03/19/16" #214: +1647732531 [appliance] "[ 0.092477] NET: Registered protocol family 16" #215: +1648928412 [appliance] "[ 0.092903] cpuidle: using governor menu" #216: +1648929587 [appliance] "[ 0.093341] PCI: Using configuration type 1 for base access" #217: +1650588506 [appliance] "[ 0.094544] ACPI: Interpreter disabled." #218: +1650589675 [appliance] "[ 0.094872] vgaarb: loaded" #219: +1650590315 [appliance] "[ 0.095108] SCSI subsystem initialized" #220: +1650590941 [appliance] "[ 0.095393] usbcore: registered new interface driver usbfs" #221: +1651720369 [appliance] "[ 0.095775] usbcore: registered new interface driver hub" #222: +1651722218 [appliance] "[ 0.096140] usbcore: registered new device driver usb" #223: +1651723272 [appliance] "[ 0.096532] PCI: Probing PCI hardware" #224: +1651724299 [appliance] "[ 0.096808] PCI host bridge to bus 0000:00" #225: +1652859284 [appliance] "[ 0.097087] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]" #226: +1652860564 [appliance] "[ 0.097503] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]" #227: +1653965462 [appliance] "[ 0.097968] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]" #228: +1658270908 [appliance] "[ 0.102200] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]" #229: +1658274529 [appliance] "[ 0.102698] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]" #230: +1658275180 [appliance] "[ 0.103124] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]" #231: +1659394050 [appliance] "[ 0.103610] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]" #232: +1659395216 [appliance] "[ 0.104369] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI" #233: +1660511396 [appliance] "[ 0.104860] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB" #234: +1675343712 [appliance] "[ 0.119267] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]" #235: +1675345382 [appliance] "[ 0.119850] NetLabel: Initializing" #236: +1675346034 [appliance] "[ 0.120078] NetLabel: domain hash size = 128" #237: +1675346592 [appliance] "[ 0.120363] NetLabel: protocols = UNLABELED CIPSOv4" #238: +1676471989 [appliance] "[ 0.120718] NetLabel: unlabeled traffic allowed by default" #239: +1676473069 [appliance] "[ 0.121161] clocksource: Switched to clocksource kvm-clock" #240: +1681957428 [appliance] "[ 0.125895] pnp: PnP ACPI: disabled" #241: +1681959070 [appliance] "[ 0.126920] NET: Registered protocol family 2" #242: +1683089205 [appliance] "[ 0.127351] TCP established hash table entries: 4096 (order: 3, 32768 bytes)" #243: +1683090366 [appliance] "[ 0.127819] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)" #244: +1684191645 [appliance] "[ 0.128268] TCP: Hash tables configured (established 4096 bind 4096)" #245: +1684213221 [appliance] "[ 0.128847] UDP hash table entries: 256 (order: 1, 8192 bytes)" #246: +1684213896 [appliance] "[ 0.129245] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)" #247: +1685326502 [appliance] "[ 0.129710] NET: Registered protocol family 1" #248: +1685327276 [appliance] "[ 0.130010] pci 0000:00:00.0: Limiting direct PCI/PCI transfers" #249: +1685327639 [appliance] "[ 0.130422] pci 0000:00:01.0: PIIX3: Enabling Passive Release" #250: +1686452661 [appliance] "[ 0.130811] pci 0000:00:01.0: Activating ISA DMA hang workarounds" #251: +1686453430 [appliance] "[ 0.131278] Unpacking initramfs..." #252: +1687767672 [appliance] "[ 0.131724] Freeing initrd memory: 344K (ffff88001f38a000 - ffff88001f3e0000)" #253: +1687768544 [appliance] "[ 0.132298] platform rtc_cmos: registered platform RTC device (no PNP device found)" #254: +1689093961 [appliance] "[ 0.133046] futex hash table entries: 256 (order: 2, 16384 bytes)" #255: +1689094910 [appliance] "[ 0.133496] audit: initializing netlink subsys (disabled)" #256: +1689095164 [appliance] "[ 0.133857] audit: type=2000 audit(1458419064.040:1): initialized" #257: +1690217078 [appliance] "[ 0.134465] Initialise system trusted keyring" #258: +1690217803 [appliance] "[ 0.134816] HugeTLB registered 1 GB page size, pre-allocated 0 pages" #259: +1690218075 [appliance] "[ 0.135239] HugeTLB registered 2 MB page size, pre-allocated 0 pages" #260: +1692581624 [appliance] "[ 0.136545] zbud: loaded" #261: +1692582396 [appliance] "[ 0.136920] VFS: Disk quotas dquot_6.6.0" #262: +1692582640 [appliance] "[ 0.137212] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)" #263: +1693946182 [appliance] "[ 0.137935] Key type big_key registered" #264: +1695787814 [appliance] "[ 0.139728] NET: Registered protocol family 38" #265: +1695788638 [appliance] "[ 0.140060] Key type asymmetric registered" #266: +1695788857 [appliance] "[ 0.140333] Asymmetric key parser 'x509' registered" #267: +1695789023 [appliance] "[ 0.140691] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)" #268: +1696920526 [appliance] "[ 0.141235] io scheduler noop registered" #269: +1696921273 [appliance] "[ 0.141496] io scheduler deadline registered" #270: +1696921450 [appliance] "[ 0.141802] io scheduler cfq registered (default)" #271: +1698043321 [appliance] "[ 0.142202] pci_hotplug: PCI Hot Plug PCI Core version: 0.5" #272: +1698044107 [appliance] "[ 0.142565] pciehp: PCI Express Hot Plug Controller Driver version: 0.4" #273: +1698044318 [appliance] "[ 0.143090] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled" #274: +1721976002 [appliance] "[ 0.165988] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A" #275: +1723232005 [appliance] "[ 0.167250] Non-volatile memory driver v1.3" #276: +1723232658 [appliance] "[ 0.167552] Linux agpgart interface v0.103" #277: +1724587999 [appliance] "[ 0.168570] scsi host0: ata_piix" #278: +1724588359 [appliance] "[ 0.168848] scsi host1: ata_piix" #279: +1724588571 [appliance] "[ 0.169094] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14" #280: +1724588819 [appliance] "[ 0.169552] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15" #281: +1725686630 [appliance] "[ 0.170205] libphy: Fixed MDIO Bus: probed" #282: +1725686951 [appliance] "[ 0.170513] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver" #283: +1726791570 [appliance] "[ 0.170956] ehci-pci: EHCI PCI platform driver" #284: +1726791847 [appliance] "[ 0.171261] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver" #285: +1726792035 [appliance] "[ 0.171671] ohci-pci: OHCI PCI platform driver" #286: +1727900392 [appliance] "[ 0.171971] uhci_hcd: USB Universal Host Controller Interface driver" #287: +1727900963 [appliance] "[ 0.172423] usbcore: registered new interface driver usbserial" #288: +1727901137 [appliance] "[ 0.172811] usbcore: registered new interface driver usbserial_generic" #289: +1729004634 [appliance] "[ 0.173244] usbserial: USB Serial support registered for generic" #290: +1729004947 [appliance] "[ 0.173656] i8042: PNP: No PS/2 controller found. Probing ports directly." #291: +1730574973 [appliance] "[ 0.174578] serio: i8042 KBD port at 0x60,0x64 irq 1" #292: +1730575324 [appliance] "[ 0.174913] serio: i8042 AUX port at 0x60,0x64 irq 12" #293: +1730575507 [appliance] "[ 0.175461] mousedev: PS/2 mouse device common for all mice" #294: +1731664398 [appliance] "[ 0.175988] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0" #295: +1732942557 [appliance] "[ 0.176945] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3" #296: +1732942978 [appliance] "[ 0.177642] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2" #297: +1734062409 [appliance] "[ 0.178378] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0" #298: +1734063168 [appliance] "[ 0.178869] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram" #299: +1735189517 [appliance] "[ 0.179346] device-mapper: uevent: version 1.0.3" #300: +1735190567 [appliance] "[ 0.179766] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: address@hidden" #301: +1736320716 [appliance] "[ 0.180413] hidraw: raw HID events driver (C) Jiri Kosina" #302: +1736321604 [appliance] "[ 0.180816] usbcore: registered new interface driver usbhid" #303: +1736321757 [appliance] "[ 0.181179] usbhid: USB HID core driver" #304: +1737523474 [appliance] "[ 0.181480] drop_monitor: Initializing network drop monitor service" #305: +1737524334 [appliance] "[ 0.181957] ip_tables: (C) 2000-2006 Netfilter Core Team" #306: +1737524569 [appliance] "[ 0.182337] Initializing XFRM netlink socket" #307: +1738772931 [appliance] "[ 0.182736] NET: Registered protocol family 10" #308: +1738773755 [appliance] "[ 0.183165] mip6: Mobile IPv6" #309: +1738773992 [appliance] "[ 0.183365] NET: Registered protocol family 17" #310: +1738774164 [appliance] "[ 0.183774] microcode: CPU0 sig=0x306d4, pf=0x1, revision=0x1" #311: +1739910131 [appliance] "[ 0.184192] microcode: Microcode Update Driver: v2.01 , Peter Oruba" #312: +1739910880 [appliance] "[ 0.184768] AVX2 version of gcm_enc/dec engaged." #313: +1741008523 [appliance] "[ 0.185087] AES CTR mode by8 optimization enabled" #314: +1757695446 [appliance] "[ 0.201639] registered taskstats version 1" #315: +1757696640 [appliance] "[ 0.201973] Loading compiled-in X.509 certificates" #316: +1758907668 [appliance] "[ 0.202865] Loaded X.509 cert 'Fedora kernel signing key: ff671496ff3f386a8ef2031bac2201b2edb4da2a'" #317: +1758908553 [appliance] "[ 0.203496] zswap: loaded using pool lzo/zbud" #318: +1758908777 [appliance] "[ 0.203916] Magic number: 8:904:447" #319: +1760021610 [appliance] "[ 0.204255] rtc_cmos rtc_cmos: setting system clock to 2016-03-19 20:24:23 UTC (1458419063)" #320: +1883240938 [appliance] "[ 0.327173] Freeing unused kernel memory: 1528K (ffffffff81d48000 - ffffffff81ec6000)" #321: +1883242402 [appliance] "[ 0.327731] Write protecting the kernel read-only data: 12288k" #322: +1883242626 [appliance] "[ 0.328225] Freeing unused kernel memory: 352K (ffff8800017a8000 - ffff880001800000)" #323: +1885922945 [appliance] "[ 0.329893] Freeing unused kernel memory: 652K (ffff880001b5d000 - ffff880001c00000)" #324: +1885924101 [appliance] "supermin: mounting /proc" #325: +1885924317 [appliance] "supermin: ext2 mini initrd starting up: 5.1.15 dietlibc" #326: +1887118652 [appliance] "supermin: cmdline: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1" #327: +1887119552 [appliance] "supermin: uptime: 0.27 0.14" #328: +1888227367 [appliance] "supermin: mounting /sys" #329: +1888228166 [appliance] "supermin: internal insmod crc32-pclmul.ko" #330: +1889562959 [appliance] "supermin: internal insmod crc32c-intel.ko" #331: +1889563774 [appliance] "supermin: internal insmod crct10dif-pclmul.ko" #332: +1890663856 [appliance] "supermin: internal insmod crc32.ko" #333: +1891817677 [appliance] "supermin: internal insmod virtio.ko" #334: +1893524104 [appliance] "supermin: internal insmod virtio_ring.ko" #335: +1893524905 [appliance] "supermin: internal insmod virtio_blk.ko" #336: +1894672466 [appliance] "supermin: internal insmod virtio-rng.ko" #337: +1895840304 [appliance] "supermin: internal insmod virtio_console.ko" #338: +1897793718 [appliance] "supermin: internal insmod virtio_net.ko" #339: +1899046048 [appliance] "supermin: internal insmod virtio_scsi.ko" #340: +1899046839 [appliance] "supermin: internal insmod virtio_balloon.ko" #341: +1900146680 [appliance] "supermin: internal insmod virtio_input.ko" #342: +1901240581 [appliance] "supermin: internal insmod virtio_mmio.ko" #343: +1902341062 [appliance] "supermin: internal insmod virtio_pci.ko" #344: +1903450918 [appliance] "[ 0.348355] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 10" #345: +1904553627 [appliance] "[ 0.348868] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver" #346: +1905855077 [appliance] "[ 0.349838] scsi host2: Virtio SCSI HBA" #347: +1905855945 [appliance] "[ 0.350266] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 11" #348: +1905856243 [appliance] "[ 0.350772] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver" #349: +1909907211 [appliance] "[ 0.353855] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5" #350: +1909908494 [appliance] "[ 0.354604] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5" #351: +1925324057 [appliance] "[ 0.369308] sd 2:0:0:0: Attached scsi generic sg0 type 0" #352: +1925325343 [appliance] "[ 0.369799] sd 2:0:0:0: [sda] 8 512-byte logical blocks: (4.10 kB/4.00 KiB)" #353: +1926475148 [appliance] "[ 0.370451] sd 2:0:1:0: Attached scsi generic sg1 type 0" #354: +1926475686 [appliance] "[ 0.370865] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)" #355: +1926475929 [appliance] "[ 0.371491] sd 2:0:0:0: [sda] Write Protect is off" #356: +1927603547 [appliance] "[ 0.371876] sd 2:0:1:0: [sdb] Write Protect is off" #357: +1927604332 [appliance] "[ 0.372256] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #358: +1928714150 [appliance] "[ 0.372920] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #359: +1930326528 [appliance] "[ 0.374272] Dev sda: unable to read RDB block 8" #360: +1930327543 [appliance] "[ 0.374606] sda: unable to read partition table" #361: +1930327968 [appliance] "[ 0.374919] sda: partition table beyond EOD, enabling native capacity" #362: +1931896087 [appliance] "[ 0.375846] Dev sda: unable to read RDB block 8" #363: +1931896564 [appliance] "[ 0.376184] sda: unable to read partition table" #364: +1931896756 [appliance] "[ 0.376484] sda: partition table beyond EOD, truncated" #365: +1931896971 [appliance] "[ 0.376832] sd 2:0:1:0: [sdb] Attached SCSI disk" #366: +1932999490 [appliance] "[ 0.377412] sd 2:0:0:0: [sda] Attached SCSI disk" #367: +1932999869 [appliance] "supermin: internal insmod crc-ccitt.ko" #368: +1934672292 [appliance] "supermin: internal insmod crc-itu-t.ko" #369: +1934673332 [appliance] "supermin: internal insmod crc8.ko" #370: +1935773990 [appliance] "supermin: internal insmod libcrc32c.ko" #371: +1937440659 [appliance] "supermin: picked /sys/block/sdb/dev as root device" #372: +1937441654 [appliance] "supermin: creating /dev/root as block special 8:16" #373: +1937442036 [appliance] "supermin: mounting new root on /root" #374: +1938727914 [appliance] "[ 0.382706] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem" #375: +1941295363 [appliance] "[ 0.385272] EXT4-fs (sdb): mounted filesystem without journal. Opts: " #376: +1941296851 [appliance] "supermin: chroot" #377: +1947912424 [appliance] "Starting /init script ..." #378: +2026634598 [appliance] "[ 0.470592] random: systemd-tmpfile urandom read with 78 bits of entropy available" #379: +2027804195 [appliance] "[/usr/lib/tmpfiles.d/journal-nocow.conf:26] Failed to replace specifiers: /var/log/journal/%m" #380: +2029093160 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:26] Failed to replace specifiers: /run/log/journal/%m" #381: +2029093558 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:28] Failed to replace specifiers: /run/log/journal/%m" #382: +2029097127 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:29] Failed to replace specifiers: /run/log/journal/%m" #383: +2030220164 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:32] Failed to replace specifiers: /var/log/journal/%m" #384: +2030220571 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:33] Failed to replace specifiers: /var/log/journal/%m/system.journal" #385: +2031595491 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:37] Failed to replace specifiers: /var/log/journal/%m" #386: +2031595985 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:38] Failed to replace specifiers: /var/log/journal/%m" #387: +2031596233 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:39] Failed to replace specifiers: /var/log/journal/%m/system.journal" #388: +2032665234 [appliance] "UDEVD=/usr/lib/systemd/systemd-udevd" #389: +2035829022 [appliance] "starting version 229" #390: +2107339884 [appliance] "[ 0.551317] piix4_smbus 0000:00:01.3: PCI->APIC IRQ transform: INT A -> IRQ 9" #391: +2107341200 [appliance] "[ 0.551842] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0" #392: +2127386470 [appliance] "[ 0.571342] intel_rapl: no valid rapl domains found in package 0" #393: +2163284964 [appliance] "[ 0.607246] random: nonblocking pool is initialized" #394: +2165510155 [appliance] "[ 0.609480] input: PC Speaker as /devices/platform/pcspkr/input/input4" #395: +2170014463 [appliance] "+ grep -sq guestfs_network=1 /proc/cmdline" #396: +2170015881 [appliance] "+ grep -sq guestfs_rescue=1 /proc/cmdline" #397: +2171577224 [appliance] "+ grep -sq guestfs_noreboot=1 /proc/cmdline" #398: +2171578171 [appliance] "+ grep -sq guestfs_boot_analysis=1 /proc/cmdline" #399: +2173013270 [appliance] "+ guestfs_boot_analysis=1" #400: +2173014095 [appliance] "++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline" #401: +2173014345 [appliance] "+ eval" #402: +2174138706 [appliance] "+ grep -sq selinux=1 /proc/cmdline" #403: +2174139636 [appliance] "+ shopt -s nullglob" #404: +2174139838 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #405: +2175340218 [appliance] "+ echo 300" #406: +2175341067 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #407: +2175341309 [appliance] "+ echo 300" #408: +2175341449 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #409: +2175341603 [appliance] "+ echo noop" #410: +2175341750 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #411: +2176432219 [appliance] "+ echo noop" #412: +2176433365 [appliance] "+ shopt -u nullglob" #413: +2176433657 [appliance] "+ ip addr add 127.0.0.1/8 brd + dev lo scope host" #414: +2178502546 [appliance] "+ ip link set dev lo up" #415: +2178504010 [appliance] "+ test '' = 1" #416: +2178504335 [appliance] "+ mdadm -As --auto=yes --run" #417: +2182347657 [appliance] "mdadm: No arrays found in config file or automatically" #418: +2182349001 [appliance] "+ modprobe dm_mod" #419: +2183606913 [appliance] "+ lvmetad" #420: +2185979651 [appliance] "+ lvm vgchange -aay --sysinit" #421: +2189813889 [appliance] " Configuration setting "global/notify_dbus" unknown." #422: +2189815065 [appliance] " lvmetad is not active yet, using direct activation during sysinit" #423: +2192653829 [appliance] "+ ldmtool create all" #424: +2196483642 [appliance] "[" #425: +2196484840 [appliance] "]" #426: +2196485000 [appliance] "+ test 1 = 1" #427: +2196485162 [appliance] "+ test 1 '!=' 1" #428: +2196485325 [appliance] "+ test '' = 1" #429: +2196485460 [appliance] "+ cmd=guestfsd" #430: +2197649210 [appliance] "+ test x '!=' x" #431: +2197649599 [appliance] "+ test 1 = 1" #432: +2197649736 [appliance] "+ cmd='guestfsd --verbose'" #433: +2197649891 [appliance] "+ test '' = 1" #434: +2197650016 [appliance] "+ echo guestfsd --verbose" #435: +2197650158 [appliance] "guestfsd --verbose" #436: +2197650282 [appliance] "+ guestfsd --verbose" #437: +2204508785 [appliance] "trying to open virtio-serial channel '/dev/virtio-ports/org.libguestfs.channel.0'" #438: +2204510368 [appliance] "udevadm --debug settle" #439: +2206648777 [appliance] "calling: settle" #440: +2206652890 [launch_done] "launch done callback" #441: +2206654841 [library] "recv_from_daemon: received GUESTFS_LAUNCH_FLAG" #442: +2206658453 [library] "[02206ms] appliance is up" #443: +2206675099 [trace] "launch = 0" #444: +2206676517 [trace] "close" #445: +2206677593 [library] "closing guestfs handle 0x564c2a8cd7a0 (state 2)" #446: +2206688305 [trace] "internal_autosync" #447: +2209320588 [appliance] "guestfsd: main_loop: new request, len 0x28" #448: +2209321513 [appliance] "umount-all: /proc/mounts: fsname=/dev/root dir=/ type=ext2 opts=rw,noatime,block_validity,barrier,user_xattr,acl freq=0 passno=0" #449: +2209322505 [appliance] "umount-all: /proc/mounts: fsname=/proc dir=/proc type=proc opts=rw,relatime freq=0 passno=0" #450: +2210463724 [appliance] "umount-all: /proc/mounts: fsname=/sys dir=/sys type=sysfs opts=rw,relatime freq=0 passno=0" #451: +2210464272 [appliance] "umount-all: /proc/mounts: fsname=tmpfs dir=/run type=tmpfs opts=rw,nosuid,relatime,size=97708k,mode=755 freq=0 passno=0" #452: +2211579272 [appliance] "umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=242824k,nr_inodes=60706,mode=755 freq=0 passno=0" #453: +2214058401 [appliance] "fsync /dev/sda" #454: +2221147592 [trace] "internal_autosync = 0" #455: +2221149584 [library] "sending SIGTERM to process 18868" #456: +2225687281 [close] "close callback" #457: +2225696157 [library] "command: run: rm" #458: +2225696982 [library] "command: run: \ -rf /home/rjones/d/libguestfs/tmp/libguestfs8sCP3g" #459: +2226817341 [library] "command: run: rm" #460: +2226817932 [library] "command: run: \ -rf /run/user/1000/libguestfsHISXnh" pass 1 number of events collected 461 elapsed time 2245359074 ns #0: +88342 [trace] "launch" #1: +89906 [trace] "version" #2: +92918 [trace] "version = " #3: +94119 [trace] "get_backend" #4: +95678 [trace] "get_backend = "direct"" #5: +96686 [library] "launch: program=boot-analysis" #6: +97582 [library] "launch: version=1.33.15" #7: +98186 [library] "launch: backend registered: unix" #8: +98632 [library] "launch: backend registered: uml" #9: +99079 [library] "launch: backend registered: libvirt" #10: +99536 [library] "launch: backend registered: direct" #11: +99967 [library] "launch: backend=direct" #12: +100443 [library] "launch: tmpdir=/home/rjones/d/libguestfs/tmp/libguestfsINdKan" #13: +118231 [library] "launch: umask=0002" #14: +118896 [library] "launch: euid=1000" #15: +127149 [trace] "get_backend_setting "force_tcg"" #16: +130902 [trace] "get_backend_setting = NULL (error)" #17: +136380 [trace] "get_cachedir" #18: +137987 [trace] "get_cachedir = "/home/rjones/d/libguestfs/tmp"" #19: +148893 [library] "[00000ms] begin building supermin appliance" #20: +149957 [library] "[00000ms] run supermin" #21: +152507 [library] "command: run: /usr/bin/supermin" #22: +153044 [library] "command: run: \ --build" #23: +153500 [library] "command: run: \ --verbose" #24: +154175 [library] "command: run: \ --if-newer" #25: +154812 [library] "command: run: \ --lock /home/rjones/d/libguestfs/tmp/.guestfs-1000/lock" #26: +155382 [library] "command: run: \ --copy-kernel" #27: +155932 [library] "command: run: \ -f ext2" #28: +156428 [library] "command: run: \ --host-cpu x86_64" #29: +156961 [library] "command: run: \ /home/rjones/d/libguestfs/appliance/supermin.d" #30: +157535 [library] "command: run: \ -o /home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d" #31: +7971631 [appliance] "supermin: version: 5.1.15" #32: +7972754 [appliance] "supermin: rpm: detected RPM version 4.13" #33: +7973571 [appliance] "supermin: package handler: fedora/rpm" #34: +7973943 [appliance] "supermin: acquiring lock on /home/rjones/d/libguestfs/tmp/.guestfs-1000/lock" #35: +7986500 [appliance] "supermin: if-newer: output does not need rebuilding" #36: +10338598 [library] "[00010ms] finished building supermin appliance" #37: +10358658 [library] "[00010ms] begin testing qemu features" #38: +10366255 [library] "command: run: /usr/bin/qemu-kvm" #39: +10367245 [library] "command: run: \ -display none" #40: +10367729 [library] "command: run: \ -help" #41: +105568776 [library] "command: run: /usr/bin/qemu-kvm" #42: +105569684 [library] "command: run: \ -display none" #43: +105570201 [library] "command: run: \ -version" #44: +204912517 [library] "qemu version 2.5" #45: +204915502 [library] "command: run: /usr/bin/qemu-kvm" #46: +204916205 [library] "command: run: \ -display none" #47: +204916761 [library] "command: run: \ -machine accel=kvm:tcg" #48: +204917388 [library] "command: run: \ -device ?" #49: +306994830 [trace] "get_sockdir" #50: +306999078 [trace] "get_sockdir = "/run/user/1000"" #51: +307310294 [library] "[00307ms] finished testing qemu features" #52: +307320819 [trace] "get_backend_setting "gdb"" #53: +307325324 [trace] "get_backend_setting = NULL (error)" #54: +309055139 [appliance] "[00307ms] /usr/bin/qemu-kvm \" #55: +309057848 [appliance] " -global virtio-blk-pci.scsi=off \" #56: +309058347 [appliance] " -nodefconfig \" #57: +309058675 [appliance] " -enable-fips \" #58: +309059006 [appliance] " -nodefaults \" #59: +309059326 [appliance] " -display none \" #60: +309059643 [appliance] " -machine accel=kvm:tcg \" #61: +309061645 [appliance] " -cpu host \" #62: +309062087 [appliance] " -m 500 \" #63: +309062434 [appliance] " -no-reboot \" #64: +309062776 [appliance] " -rtc driftfix=slew \" #65: +309063058 [appliance] " -no-hpet \" #66: +309063387 [appliance] " -global kvm-pit.lost_tick_policy=discard \" #67: +309063768 [appliance] " -kernel /home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d/kernel \" #68: +309064237 [appliance] " -initrd /home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d/initrd \" #69: +309064588 [appliance] " -device virtio-scsi-pci,id=scsi \" #70: +309064976 [appliance] " -drive file=/home/rjones/d/libguestfs/tmp/libguestfsINdKan/devnull1,cache=writeback,id=hd0,if=none \" #71: +309065345 [appliance] " -device scsi-hd,drive=hd0 \" #72: +309065741 [appliance] " -drive file=/home/rjones/d/libguestfs/tmp/.guestfs-1000/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \" #73: +309066215 [appliance] " -device scsi-hd,drive=appliance \" #74: +309066563 [appliance] " -device virtio-serial-pci \" #75: +309066884 [appliance] " -serial stdio \" #76: +309067193 [appliance] " -device sga \" #77: +309067515 [appliance] " -chardev socket,path=/run/user/1000/libguestfsABLpPt/guestfsd.sock,id=channel0 \" #78: +309067917 [appliance] " -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \" #79: +309068381 [appliance] " -append 'panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1'" #80: +403905232 [appliance] "WARNING: Image format was not specified for '/home/rjones/d/libguestfs/tmp/libguestfsINdKan/devnull1' and probing guessed raw." #81: +403906795 [appliance] " Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted." #82: +403912252 [appliance] " Specify the 'raw' format explicitly to remove the restrictions." #83: +472490769 [appliance] "\x1b[1;256r\x1b[256;256H\x1b[6n" #84: +741331593 [appliance] "Google, Inc." #85: +741333333 [appliance] "Serial Graphics Adapter 06/19/15" #86: +741334141 [appliance] "SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (mockbuild@) Fri Jun 19 00:52:18 UTC 2015" #87: +741334803 [appliance] "Term: 80x24" #88: +741335302 [appliance] "4 0" #89: +741335821 [appliance] "\x1b[2J\x0dSeaBIOS (version 1.8.2-20150714_191134-)" #90: +851607087 [appliance] "\x0dBooting from ROM..." #91: +1498605209 [appliance] "\x0dProbing EDD (edd=off to disable)... ok" #92: +1498607336 [appliance] "\x1b[2J[ 0.000000] Initializing cgroup subsys cpuset" #93: +1578556344 [appliance] "[ 0.000000] Initializing cgroup subsys cpu" #94: +1578557786 [appliance] "[ 0.000000] Initializing cgroup subsys cpuacct" #95: +1578558207 [appliance] "[ 0.000000] Linux version 4.4.4-301.fc23.x86_64 (address@hidden) (gcc version 5.3.1 20151207 (Red Hat 5.3.1-2) (GCC) ) #1 SMP Fri Mar 4 17:42:42 UTC 2016" #96: +1579693960 [appliance] "[ 0.000000] Command line: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1" #97: +1580826954 [appliance] "[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256" #98: +1581964769 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers'" #99: +1581965730 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers'" #100: +1583100777 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers'" #101: +1583102010 [appliance] "[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format." #102: +1584231905 [appliance] "[ 0.000000] x86/fpu: Using 'eager' FPU context switches." #103: +1584233043 [appliance] "[ 0.000000] e820: BIOS-provided physical RAM map:" #104: +1584233511 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable" #105: +1585367817 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved" #106: +1585368724 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved" #107: +1585369021 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3dffff] usable" #108: +1586503833 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000001f3e0000-0x000000001f3fffff] reserved" #109: +1586504760 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved" #110: +1587638208 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved" #111: +1587639086 [appliance] "[ 0.000000] NX (Execute Disable) protection: active" #112: +1587639506 [appliance] "[ 0.000000] SMBIOS 2.8 present." #113: +1588782924 [appliance] "[ 0.000000] Hypervisor detected: KVM" #114: +1588783788 [appliance] "[ 0.000000] e820: last_pfn = 0x1f3e0 max_arch_pfn = 0x400000000" #115: +1588784214 [appliance] "[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT " #116: +1589917196 [appliance] "[ 0.000000] found SMP MP-table at [mem 0x000f64d0-0x000f64df] mapped at [ffff8800000f64d0]" #117: +1589918113 [appliance] "[ 0.000000] Using GB pages for direct mapping" #118: +1589918530 [appliance] "[ 0.000000] RAMDISK: [mem 0x1f38a000-0x1f3dffff]" #119: +1591051535 [appliance] "[ 0.000000] No NUMA configuration found" #120: +1591052734 [appliance] "[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3dffff]" #121: +1591053481 [appliance] "[ 0.000000] NODE_DATA(0) allocated [mem 0x1f378000-0x1f389fff]" #122: +1592188495 [appliance] "[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00" #123: +1592190485 [appliance] "[ 0.000000] kvm-clock: cpu 0, msr 0:1f368001, primary cpu clock" #124: +1592190850 [appliance] "[ 0.000000] kvm-clock: using sched offset of 1126450732 cycles" #125: +1593323936 [appliance] "[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns" #126: +1593335462 [appliance] "[ 0.000000] Zone ranges:" #127: +1593336036 [appliance] "[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]" #128: +1594471825 [appliance] "[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001f3dffff]" #129: +1594472774 [appliance] "[ 0.000000] Normal empty" #130: +1594473193 [appliance] "[ 0.000000] Movable zone start for each node" #131: +1595607624 [appliance] "[ 0.000000] Early memory node ranges" #132: +1595608520 [appliance] "[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]" #133: +1595608930 [appliance] "[ 0.000000] node 0: [mem 0x0000000000100000-0x000000001f3dffff]" #134: +1596757197 [appliance] "[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3dffff]" #135: +1596758218 [appliance] "[ 0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org" #136: +1596758558 [appliance] "[ 0.000000] Intel MultiProcessor Specification v1.4" #137: +1597891709 [appliance] "[ 0.000000] MPTABLE: OEM ID: BOCHSCPU" #138: +1597892592 [appliance] "[ 0.000000] MPTABLE: Product ID: 0.1 " #139: +1597892938 [appliance] "[ 0.000000] MPTABLE: APIC at: 0xFEE00000" #140: +1597893228 [appliance] "[ 0.000000] Processor #0 (Bootup-CPU)" #141: +1599026696 [appliance] "[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23" #142: +1599027601 [appliance] "[ 0.000000] Processors: 1" #143: +1599027967 [appliance] "[ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs" #144: +1599028299 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]" #145: +1600161951 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]" #146: +1600162827 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]" #147: +1601296449 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]" #148: +1601297373 [appliance] "[ 0.000000] e820: [mem 0x1f400000-0xfeffbfff] available for PCI devices" #149: +1601297697 [appliance] "[ 0.000000] Booting paravirtualized kernel on KVM" #150: +1602431621 [appliance] "[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns" #151: +1602432518 [appliance] "[ 0.000000] setup_percpu: NR_CPUS:1024 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1" #152: +1603548781 [appliance] "[ 0.000000] PERCPU: Embedded 34 pages/cpu @ffff88001f000000 s98712 r8192 d32360 u2097152" #153: +1603549667 [appliance] "[ 0.000000] KVM setup async PF for cpu 0" #154: +1604698847 [appliance] "[ 0.000000] kvm-stealtime: cpu 0, msr 1f00d900" #155: +1604699774 [appliance] "[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 125849" #156: +1604700182 [appliance] "[ 0.000000] Policy zone: DMA32" #157: +1604700500 [appliance] "[ 0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1" #158: +1606970171 [appliance] "[ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)" #159: +1606971332 [appliance] "[ 0.000000] Memory: 485620K/511480K available (7827K kernel code, 1307K rwdata, 3444K rodata, 1528K init, 1544K bss, 25860K reserved, 0K cma-reserved)" #160: +1608107744 [appliance] "[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1" #161: +1608108658 [appliance] "[ 0.000000] Hierarchical RCU implementation." #162: +1609246246 [appliance] "[ 0.000000] \x09Build-time adjustment of leaf fanout to 64." #163: +1609247167 [appliance] "[ 0.000000] \x09RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=1." #164: +1609247587 [appliance] "[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1" #165: +1610381809 [appliance] "[ 0.000000] NR_IRQS:65792 nr_irqs:256 16" #166: +1610382639 [appliance] "[ 0.000000] \x09Offload RCU callbacks from all CPUs" #167: +1610383021 [appliance] "[ 0.000000] \x09Offload RCU callbacks from CPUs: 0." #168: +1610383329 [appliance] "[ 0.000000] Console: colour *CGA 80x25" #169: +1611513704 [appliance] "[ 0.000000] console [ttyS0] enabled" #170: +1611523387 [appliance] "[ 0.000000] tsc: Detected 2593.994 MHz processor" #171: +1611525750 [appliance] "[ 0.042262] Calibrating delay loop (skipped) preset value.. 5187.98 BogoMIPS (lpj=2593994)" #172: +1612652134 [appliance] "[ 0.042843] pid_max: default: 32768 minimum: 301" #173: +1612653040 [appliance] "[ 0.043193] Security Framework initialized" #174: +1612653487 [appliance] "[ 0.043472] Yama: becoming mindful." #175: +1612653880 [appliance] "[ 0.043711] SELinux: Disabled at boot." #176: +1613794459 [appliance] "[ 0.044068] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)" #177: +1613795431 [appliance] "[ 0.044604] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)" #178: +1614925368 [appliance] "[ 0.045121] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)" #179: +1614926332 [appliance] "[ 0.045559] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)" #180: +1616045743 [appliance] "[ 0.046187] Initializing cgroup subsys io" #181: +1616046722 [appliance] "[ 0.046460] Initializing cgroup subsys memory" #182: +1616047234 [appliance] "[ 0.046887] Disabling memory control group subsystem" #183: +1617175974 [appliance] "[ 0.047259] Initializing cgroup subsys devices" #184: +1617176910 [appliance] "[ 0.047557] Initializing cgroup subsys freezer" #185: +1617177322 [appliance] "[ 0.047855] Initializing cgroup subsys net_cls" #186: +1617177718 [appliance] "[ 0.048174] Initializing cgroup subsys perf_event" #187: +1618307939 [appliance] "[ 0.048515] Initializing cgroup subsys net_prio" #188: +1618308848 [appliance] "[ 0.048818] Initializing cgroup subsys hugetlb" #189: +1618309245 [appliance] "[ 0.049139] Initializing cgroup subsys pids" #190: +1620414486 [appliance] "[ 0.050387] mce: CPU supports 10 MCE banks" #191: +1620415475 [appliance] "[ 0.050707] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0" #192: +1620415939 [appliance] "[ 0.051085] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0" #193: +1630064973 [appliance] "[ 0.060050] Freeing SMP alternatives memory: 28K (ffffffff81ec6000 - ffffffff81ecd000)" #194: +1632649831 [appliance] "[ 0.062646] ftrace: allocating 29522 entries in 116 pages" #195: +1653444401 [appliance] "[ 0.083393] x2apic enabled" #196: +1653445645 [appliance] "[ 0.083796] Switched APIC routing to physical x2apic." #197: +1654838246 [appliance] "[ 0.084840] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1" #198: +1654839305 [appliance] "[ 0.085289] smpboot: CPU0: Intel(R) Core(TM) i7-5600U CPU @ 2.60GHz (family: 0x6, model: 0x3d, stepping: 0x4)" #199: +1655960262 [appliance] "[ 0.086006] Performance Events: 16-deep LBR, Broadwell events, Intel PMU driver." #200: +1655960789 [appliance] "[ 0.086531] ... version: 2" #201: +1655961291 [appliance] "[ 0.086794] ... bit width: 48" #202: +1657108178 [appliance] "[ 0.087084] ... generic registers: 4" #203: +1657109474 [appliance] "[ 0.087359] ... value mask: 0000ffffffffffff" #204: +1657110234 [appliance] "[ 0.087701] ... max period: 000000007fffffff" #205: +1657110836 [appliance] "[ 0.088063] ... fixed-purpose events: 3" #206: +1658223691 [appliance] "[ 0.088330] ... event mask: 000000070000000f" #207: +1658225233 [appliance] "[ 0.089151] x86: Booted up 1 node, 1 CPUs" #208: +1659340853 [appliance] "[ 0.089448] smpboot: Total of 1 processors activated (5187.98 BogoMIPS)" #209: +1659341827 [appliance] "[ 0.090115] devtmpfs: initialized" #210: +1661568260 [appliance] "[ 0.091530] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns" #211: +1661569302 [appliance] "[ 0.092268] atomic64_test: passed for x86-64 platform with CX8 and with SSE" #212: +1662694646 [appliance] "[ 0.092750] pinctrl core: initialized pinctrl subsystem" #213: +1662695546 [appliance] "[ 0.093153] RTC time: 20:24:26, date: 03/19/16" #214: +1662695977 [appliance] "[ 0.093539] NET: Registered protocol family 16" #215: +1663806449 [appliance] "[ 0.093980] cpuidle: using governor menu" #216: +1663807345 [appliance] "[ 0.094401] PCI: Using configuration type 1 for base access" #217: +1665652219 [appliance] "[ 0.095616] ACPI: Interpreter disabled." #218: +1665653139 [appliance] "[ 0.095946] vgaarb: loaded" #219: +1665653560 [appliance] "[ 0.096187] SCSI subsystem initialized" #220: +1665653949 [appliance] "[ 0.096475] usbcore: registered new interface driver usbfs" #221: +1666792431 [appliance] "[ 0.096863] usbcore: registered new interface driver hub" #222: +1666793353 [appliance] "[ 0.097225] usbcore: registered new device driver usb" #223: +1666793735 [appliance] "[ 0.097607] PCI: Probing PCI hardware" #224: +1666794102 [appliance] "[ 0.097887] PCI host bridge to bus 0000:00" #225: +1667925873 [appliance] "[ 0.098167] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]" #226: +1667926863 [appliance] "[ 0.098571] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]" #227: +1667927268 [appliance] "[ 0.099039] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]" #228: +1673343067 [appliance] "[ 0.103280] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]" #229: +1673345585 [appliance] "[ 0.103777] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]" #230: +1673346357 [appliance] "[ 0.104230] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]" #231: +1674466811 [appliance] "[ 0.104709] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]" #232: +1674467844 [appliance] "[ 0.105490] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI" #233: +1675584768 [appliance] "[ 0.105985] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB" #234: +1689964535 [appliance] "[ 0.119922] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]" #235: +1689967098 [appliance] "[ 0.120499] NetLabel: Initializing" #236: +1689967761 [appliance] "[ 0.120728] NetLabel: domain hash size = 128" #237: +1689968323 [appliance] "[ 0.121053] NetLabel: protocols = UNLABELED CIPSOv4" #238: +1691088290 [appliance] "[ 0.121401] NetLabel: unlabeled traffic allowed by default" #239: +1691089981 [appliance] "[ 0.121858] clocksource: Switched to clocksource kvm-clock" #240: +1697995693 [appliance] "[ 0.127981] pnp: PnP ACPI: disabled" #241: +1697997632 [appliance] "[ 0.128998] NET: Registered protocol family 2" #242: +1699112458 [appliance] "[ 0.129415] TCP established hash table entries: 4096 (order: 3, 32768 bytes)" #243: +1699113625 [appliance] "[ 0.129895] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)" #244: +1700236700 [appliance] "[ 0.130351] TCP: Hash tables configured (established 4096 bind 4096)" #245: +1700237620 [appliance] "[ 0.130953] UDP hash table entries: 256 (order: 1, 8192 bytes)" #246: +1701368207 [appliance] "[ 0.131366] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)" #247: +1701369177 [appliance] "[ 0.131815] NET: Registered protocol family 1" #248: +1701370076 [appliance] "[ 0.132122] pci 0000:00:00.0: Limiting direct PCI/PCI transfers" #249: +1702483982 [appliance] "[ 0.132544] pci 0000:00:01.0: PIIX3: Enabling Passive Release" #250: +1702484956 [appliance] "[ 0.132936] pci 0000:00:01.0: Activating ISA DMA hang workarounds" #251: +1702485470 [appliance] "[ 0.133402] Unpacking initramfs..." #252: +1703589522 [appliance] "[ 0.133881] Freeing initrd memory: 344K (ffff88001f38a000 - ffff88001f3e0000)" #253: +1703590458 [appliance] "[ 0.134430] platform rtc_cmos: registered platform RTC device (no PNP device found)" #254: +1704697303 [appliance] "[ 0.135184] futex hash table entries: 256 (order: 2, 16384 bytes)" #255: +1704698333 [appliance] "[ 0.135602] audit: initializing netlink subsys (disabled)" #256: +1705808749 [appliance] "[ 0.136001] audit: type=2000 audit(1458419066.282:1): initialized" #257: +1705809552 [appliance] "[ 0.136568] Initialise system trusted keyring" #258: +1706944466 [appliance] "[ 0.136937] HugeTLB registered 1 GB page size, pre-allocated 0 pages" #259: +1706945546 [appliance] "[ 0.137379] HugeTLB registered 2 MB page size, pre-allocated 0 pages" #260: +1708707540 [appliance] "[ 0.138708] zbud: loaded" #261: +1708708476 [appliance] "[ 0.139070] VFS: Disk quotas dquot_6.6.0" #262: +1708708988 [appliance] "[ 0.139358] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)" #263: +1710072737 [appliance] "[ 0.140101] Key type big_key registered" #264: +1711920499 [appliance] "[ 0.141902] NET: Registered protocol family 38" #265: +1711921601 [appliance] "[ 0.142223] Key type asymmetric registered" #266: +1711922113 [appliance] "[ 0.142495] Asymmetric key parser 'x509' registered" #267: +1711922572 [appliance] "[ 0.142854] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)" #268: +1713037042 [appliance] "[ 0.143410] io scheduler noop registered" #269: +1713037914 [appliance] "[ 0.143674] io scheduler deadline registered" #270: +1713038392 [appliance] "[ 0.143995] io scheduler cfq registered (default)" #271: +1714157166 [appliance] "[ 0.144399] pci_hotplug: PCI Hot Plug PCI Core version: 0.5" #272: +1714158085 [appliance] "[ 0.144768] pciehp: PCI Express Hot Plug Controller Driver version: 0.4" #273: +1715294317 [appliance] "[ 0.145318] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled" #274: +1738178724 [appliance] "[ 0.168189] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A" #275: +1739478589 [appliance] "[ 0.169486] Non-volatile memory driver v1.3" #276: +1739479707 [appliance] "[ 0.169799] Linux agpgart interface v0.103" #277: +1740837183 [appliance] "[ 0.170827] scsi host0: ata_piix" #278: +1740838312 [appliance] "[ 0.171121] scsi host1: ata_piix" #279: +1740838817 [appliance] "[ 0.171365] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14" #280: +1740839458 [appliance] "[ 0.171818] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15" #281: +1741948975 [appliance] "[ 0.172507] libphy: Fixed MDIO Bus: probed" #282: +1741949994 [appliance] "[ 0.172827] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver" #283: +1743066395 [appliance] "[ 0.173299] ehci-pci: EHCI PCI platform driver" #284: +1743067201 [appliance] "[ 0.173611] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver" #285: +1743067716 [appliance] "[ 0.174032] ohci-pci: OHCI PCI platform driver" #286: +1744190185 [appliance] "[ 0.174367] uhci_hcd: USB Universal Host Controller Interface driver" #287: +1744191069 [appliance] "[ 0.174825] usbcore: registered new interface driver usbserial" #288: +1744191514 [appliance] "[ 0.175232] usbcore: registered new interface driver usbserial_generic" #289: +1745311011 [appliance] "[ 0.175702] usbserial: USB Serial support registered for generic" #290: +1745311905 [appliance] "[ 0.176130] i8042: PNP: No PS/2 controller found. Probing ports directly." #291: +1746416901 [appliance] "[ 0.177094] serio: i8042 KBD port at 0x60,0x64 irq 1" #292: +1746417700 [appliance] "[ 0.177431] serio: i8042 AUX port at 0x60,0x64 irq 12" #293: +1747521164 [appliance] "[ 0.178020] mousedev: PS/2 mouse device common for all mice" #294: +1747522144 [appliance] "[ 0.178549] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0" #295: +1748624000 [appliance] "[ 0.179544] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3" #296: +1749740522 [appliance] "[ 0.180276] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2" #297: +1751057789 [appliance] "[ 0.181056] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0" #298: +1751058783 [appliance] "[ 0.181537] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram" #299: +1751059336 [appliance] "[ 0.182007] device-mapper: uevent: version 1.0.3" #300: +1752170554 [appliance] "[ 0.182432] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: address@hidden" #301: +1752171475 [appliance] "[ 0.183078] hidraw: raw HID events driver (C) Jiri Kosina" #302: +1753291705 [appliance] "[ 0.183492] usbcore: registered new interface driver usbhid" #303: +1753292462 [appliance] "[ 0.183878] usbhid: USB HID core driver" #304: +1753293005 [appliance] "[ 0.184171] drop_monitor: Initializing network drop monitor service" #305: +1754402243 [appliance] "[ 0.184665] ip_tables: (C) 2000-2006 Netfilter Core Team" #306: +1754403029 [appliance] "[ 0.185067] Initializing XFRM netlink socket" #307: +1754403502 [appliance] "[ 0.185452] NET: Registered protocol family 10" #308: +1755513666 [appliance] "[ 0.185896] mip6: Mobile IPv6" #309: +1755514550 [appliance] "[ 0.186107] NET: Registered protocol family 17" #310: +1755515005 [appliance] "[ 0.186529] microcode: CPU0 sig=0x306d4, pf=0x1, revision=0x1" #311: +1756638526 [appliance] "[ 0.186963] microcode: Microcode Update Driver: v2.01 , Peter Oruba" #312: +1756639342 [appliance] "[ 0.187560] AVX2 version of gcm_enc/dec engaged." #313: +1757732608 [appliance] "[ 0.187904] AES CTR mode by8 optimization enabled" #314: +1774292059 [appliance] "[ 0.204288] registered taskstats version 1" #315: +1774293554 [appliance] "[ 0.204603] Loading compiled-in X.509 certificates" #316: +1775522607 [appliance] "[ 0.205522] Loaded X.509 cert 'Fedora kernel signing key: ff671496ff3f386a8ef2031bac2201b2edb4da2a'" #317: +1775523720 [appliance] "[ 0.206146] zswap: loaded using pool lzo/zbud" #318: +1775524249 [appliance] "[ 0.206560] Magic number: 8:904:447" #319: +1776615754 [appliance] "[ 0.206901] rtc_cmos rtc_cmos: setting system clock to 2016-03-19 20:24:26 UTC (1458419066)" #320: +1899834142 [appliance] "[ 0.329811] Freeing unused kernel memory: 1528K (ffffffff81d48000 - ffffffff81ec6000)" #321: +1899835969 [appliance] "[ 0.330370] Write protecting the kernel read-only data: 12288k" #322: +1899836653 [appliance] "[ 0.330865] Freeing unused kernel memory: 352K (ffff8800017a8000 - ffff880001800000)" #323: +1902526760 [appliance] "[ 0.332524] Freeing unused kernel memory: 652K (ffff880001b5d000 - ffff880001c00000)" #324: +1902527912 [appliance] "supermin: mounting /proc" #325: +1902528424 [appliance] "supermin: ext2 mini initrd starting up: 5.1.15 dietlibc" #326: +1903700620 [appliance] "supermin: cmdline: panic=1 console=ttyS0 udevtimeout=6000 udev.event-timeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color guestfs_boot_analysis=1" #327: +1903701422 [appliance] "supermin: uptime: 0.27 0.14" #328: +1904800827 [appliance] "supermin: mounting /sys" #329: +1904801564 [appliance] "supermin: internal insmod crc32-pclmul.ko" #330: +1906130645 [appliance] "supermin: internal insmod crc32c-intel.ko" #331: +1906131567 [appliance] "supermin: internal insmod crct10dif-pclmul.ko" #332: +1907219029 [appliance] "supermin: internal insmod crc32.ko" #333: +1908298803 [appliance] "supermin: internal insmod virtio.ko" #334: +1909384589 [appliance] "supermin: internal insmod virtio_ring.ko" #335: +1911134934 [appliance] "supermin: internal insmod virtio_blk.ko" #336: +1911135677 [appliance] "supermin: internal insmod virtio-rng.ko" #337: +1912222514 [appliance] "supermin: internal insmod virtio_console.ko" #338: +1913287906 [appliance] "supermin: internal insmod virtio_net.ko" #339: +1915581045 [appliance] "supermin: internal insmod virtio_scsi.ko" #340: +1916666873 [appliance] "supermin: internal insmod virtio_balloon.ko" #341: +1916667598 [appliance] "supermin: internal insmod virtio_input.ko" #342: +1917772686 [appliance] "supermin: internal insmod virtio_mmio.ko" #343: +1918850939 [appliance] "supermin: internal insmod virtio_pci.ko" #344: +1920841207 [appliance] "[ 0.350827] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 10" #345: +1920842110 [appliance] "[ 0.351341] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver" #346: +1922318872 [appliance] "[ 0.352322] scsi host2: Virtio SCSI HBA" #347: +1922319637 [appliance] "[ 0.352709] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 11" #348: +1922320008 [appliance] "[ 0.353237] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver" #349: +1926506345 [appliance] "[ 0.356482] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5" #350: +1926508007 [appliance] "[ 0.357293] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5" #351: +1941735286 [appliance] "[ 0.371697] sd 2:0:0:0: Attached scsi generic sg0 type 0" #352: +1941737135 [appliance] "[ 0.372347] sd 2:0:1:0: Attached scsi generic sg1 type 0" #353: +1941737445 [appliance] "[ 0.372797] sd 2:0:0:0: [sda] 8 512-byte logical blocks: (4.10 kB/4.00 KiB)" #354: +1942832586 [appliance] "[ 0.373588] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)" #355: +1943931328 [appliance] "[ 0.374267] sd 2:0:1:0: [sdb] Write Protect is off" #356: +1943931871 [appliance] "[ 0.374602] sd 2:0:0:0: [sda] Write Protect is off" #357: +1943932055 [appliance] "[ 0.375015] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #358: +1945058902 [appliance] "[ 0.375652] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #359: +1946142611 [appliance] "[ 0.376759] Dev sda: unable to read RDB block 8" #360: +1946143180 [appliance] "[ 0.377104] sda: unable to read partition table" #361: +1947252857 [appliance] "[ 0.377427] sda: partition table beyond EOD, enabling native capacity" #362: +1947253337 [appliance] "[ 0.378152] sd 2:0:1:0: [sdb] Attached SCSI disk" #363: +1948369443 [appliance] "[ 0.378661] Dev sda: unable to read RDB block 8" #364: +1948370141 [appliance] "[ 0.378986] sda: unable to read partition table" #365: +1948370331 [appliance] "[ 0.379290] sda: partition table beyond EOD, truncated" #366: +1949481499 [appliance] "[ 0.379922] sd 2:0:0:0: [sda] Attached SCSI disk" #367: +1949482241 [appliance] "supermin: internal insmod crc-ccitt.ko" #368: +1951190886 [appliance] "supermin: internal insmod crc-itu-t.ko" #369: +1951192058 [appliance] "supermin: internal insmod crc8.ko" #370: +1952951762 [appliance] "supermin: internal insmod libcrc32c.ko" #371: +1952952616 [appliance] "supermin: picked /sys/block/sdb/dev as root device" #372: +1954066614 [appliance] "supermin: creating /dev/root as block special 8:16" #373: +1954067396 [appliance] "supermin: mounting new root on /root" #374: +1954067589 [appliance] "[ 0.385094] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem" #375: +1957705934 [appliance] "[ 0.387689] EXT4-fs (sdb): mounted filesystem without journal. Opts: " #376: +1957711226 [appliance] "supermin: chroot" #377: +1964770744 [appliance] "Starting /init script ..." #378: +2057477213 [appliance] "[ 0.487433] random: systemd-tmpfile urandom read with 82 bits of entropy available" #379: +2058797589 [appliance] "[/usr/lib/tmpfiles.d/journal-nocow.conf:26] Failed to replace specifiers: /var/log/journal/%m" #380: +2060086479 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:26] Failed to replace specifiers: /run/log/journal/%m" #381: +2060087195 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:28] Failed to replace specifiers: /run/log/journal/%m" #382: +2060087505 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:29] Failed to replace specifiers: /run/log/journal/%m" #383: +2061211993 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:32] Failed to replace specifiers: /var/log/journal/%m" #384: +2061212343 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:33] Failed to replace specifiers: /var/log/journal/%m/system.journal" #385: +2062639298 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:37] Failed to replace specifiers: /var/log/journal/%m" #386: +2062639869 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:38] Failed to replace specifiers: /var/log/journal/%m" #387: +2062640037 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:39] Failed to replace specifiers: /var/log/journal/%m/system.journal" #388: +2064805195 [appliance] "UDEVD=/usr/lib/systemd/systemd-udevd" #389: +2067059008 [appliance] "starting version 229" #390: +2137052680 [appliance] "[ 0.567034] piix4_smbus 0000:00:01.3: PCI->APIC IRQ transform: INT A -> IRQ 9" #391: +2137054745 [appliance] "[ 0.567539] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0" #392: +2164342152 [appliance] "[ 0.594332] intel_rapl: no valid rapl domains found in package 0" #393: +2185435472 [appliance] "[ 0.615415] random: nonblocking pool is initialized" #394: +2192538565 [appliance] "[ 0.622520] input: PC Speaker as /devices/platform/pcspkr/input/input4" #395: +2197190077 [appliance] "+ grep -sq guestfs_network=1 /proc/cmdline" #396: +2197191974 [appliance] "+ grep -sq guestfs_rescue=1 /proc/cmdline" #397: +2198655423 [appliance] "+ grep -sq guestfs_noreboot=1 /proc/cmdline" #398: +2198656623 [appliance] "+ grep -sq guestfs_boot_analysis=1 /proc/cmdline" #399: +2200104858 [appliance] "+ guestfs_boot_analysis=1" #400: +2200105994 [appliance] "++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline" #401: +2200106415 [appliance] "+ eval" #402: +2201229477 [appliance] "+ grep -sq selinux=1 /proc/cmdline" #403: +2201230815 [appliance] "+ shopt -s nullglob" #404: +2201231171 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #405: +2202373812 [appliance] "+ echo 300" #406: +2202375057 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #407: +2202376044 [appliance] "+ echo 300" #408: +2202376324 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #409: +2202376557 [appliance] "+ echo noop" #410: +2202376837 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #411: +2203484518 [appliance] "+ echo noop" #412: +2203485424 [appliance] "+ shopt -u nullglob" #413: +2203485700 [appliance] "+ ip addr add 127.0.0.1/8 brd + dev lo scope host" #414: +2205455231 [appliance] "+ ip link set dev lo up" #415: +2205456930 [appliance] "+ test '' = 1" #416: +2205457258 [appliance] "+ mdadm -As --auto=yes --run" #417: +2208984940 [appliance] "mdadm: No arrays found in config file or automatically" #418: +2208987086 [appliance] "+ modprobe dm_mod" #419: +2210276415 [appliance] "+ lvmetad" #420: +2212639128 [appliance] "+ lvm vgchange -aay --sysinit" #421: +2215852737 [appliance] " Configuration setting "global/notify_dbus" unknown." #422: +2215854439 [appliance] " lvmetad is not active yet, using direct activation during sysinit" #423: +2218414676 [appliance] "+ ldmtool create all" #424: +2221658701 [appliance] "[" #425: +2221660373 [appliance] "]" #426: +2221660624 [appliance] "+ test 1 = 1" #427: +2221660871 [appliance] "+ test 1 '!=' 1" #428: +2221661083 [appliance] "+ test '' = 1" #429: +2221661302 [appliance] "+ cmd=guestfsd" #430: +2222804645 [appliance] "+ test x '!=' x" #431: +2222805648 [appliance] "+ test 1 = 1" #432: +2222805929 [appliance] "+ cmd='guestfsd --verbose'" #433: +2222806165 [appliance] "+ test '' = 1" #434: +2222806353 [appliance] "+ echo guestfsd --verbose" #435: +2222806589 [appliance] "guestfsd --verbose" #436: +2222806830 [appliance] "+ guestfsd --verbose" #437: +2229192787 [appliance] "trying to open virtio-serial channel '/dev/virtio-ports/org.libguestfs.channel.0'" #438: +2229194610 [appliance] "udevadm --debug settle" #439: +2231316033 [appliance] "calling: settle" #440: +2231321317 [launch_done] "launch done callback" #441: +2231323092 [library] "recv_from_daemon: received GUESTFS_LAUNCH_FLAG" #442: +2231328308 [library] "[02231ms] appliance is up" #443: +2231350150 [trace] "launch = 0" #444: +2231351178 [trace] "close" #445: +2231352166 [library] "closing guestfs handle 0x564c2a8cd7a0 (state 2)" #446: +2231363554 [trace] "internal_autosync" #447: +2233992986 [appliance] "guestfsd: main_loop: new request, len 0x28" #448: +2233994297 [appliance] "umount-all: /proc/mounts: fsname=/dev/root dir=/ type=ext2 opts=rw,noatime,block_validity,barrier,user_xattr,acl freq=0 passno=0" #449: +2233994744 [appliance] "umount-all: /proc/mounts: fsname=/proc dir=/proc type=proc opts=rw,relatime freq=0 passno=0" #450: +2235135420 [appliance] "umount-all: /proc/mounts: fsname=/sys dir=/sys type=sysfs opts=rw,relatime freq=0 passno=0" #451: +2235136263 [appliance] "umount-all: /proc/mounts: fsname=tmpfs dir=/run type=tmpfs opts=rw,nosuid,relatime,size=97708k,mode=755 freq=0 passno=0" #452: +2236272664 [appliance] "umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=242824k,nr_inodes=60706,mode=755 freq=0 passno=0" #453: +2238617992 [appliance] "fsync /dev/sda" #454: +2240396178 [trace] "internal_autosync = 0" #455: +2240398977 [library] "sending SIGTERM to process 18887" #456: +2245358223 [close] "close callback" #457: +2245364966 [library] "command: run: rm" #458: +2245365846 [library] "command: run: \ -rf /home/rjones/d/libguestfs/tmp/libguestfsINdKan" #459: +2246591810 [library] "command: run: rm" #460: +2246592735 [library] "command: run: \ -rf /run/user/1000/libguestfsABLpPt" Analyzing the results ... activity 0: name = run start - end = 0.0 - 2235430052.0 mean elapsed = 2235430053.0 variance = 96822215069584.0 s.d = 9839828.0 percent = 100.0 activity 1: name = supermin:build start - end = 62176.0 - 10266826.5 mean elapsed = 10204651.5 variance = 223397862.2 s.d = 14946.5 percent = 0.5 activity 2: name = qemu:feature-detect start - end = 10286691.5 - 303110457.5 mean elapsed = 292823767.0 variance = 17039302481161.0 s.d = 4127869.0 percent = 13.1 activity 3: name = qemu start - end = 304859851.0 - 2235430052.0 mean elapsed = 1930570202.0 variance = 32829164146276.0 s.d = 5729674.0 percent = 86.4 activity 4: name = qemu:overhead start - end = 304859851.0 - 466518360.0 mean elapsed = 161658510.0 variance = 3146763783744.0 s.d = 1773912.0 percent = 7.2 activity 5: name = seabios:overhead start - end = 466518361.0 - 1571382665.0 mean elapsed = 1104864305.0 variance = 1447529015689.0 s.d = 1203133.0 percent = 49.4 activity 6: name = kernel start - end = 1571382666.0 - 2235430052.0 mean elapsed = 664047387.0 variance = 7576966411641.0 s.d = 2752629.0 percent = 29.7 activity 7: name = kernel:overhead start - end = 1571382666.0 - 1894133670.5 mean elapsed = 322751005.5 variance = 1486476681732.2 s.d = 1219211.5 percent = 14.4 activity 8: name = supermin:mini-initrd start - end = 1894133671.5 - 1949411338.5 mean elapsed = 55277668.0 variance = 8999557956.0 s.d = 94866.0 percent = 2.5 activity 9: name = supermin: internal insmod crc32-pclmul.ko start - end = 1896422166.0 - 1897754102.0 mean elapsed = 1331937.0 variance = 8156736.0 s.d = 2856.0 percent = 0.1 activity 10: name = supermin: internal insmod crc32c-intel.ko start - end = 1897754103.0 - 1897754970.5 mean elapsed = 868.5 variance = 2862.2 s.d = 53.5 percent = 0.0 activity 11: name = supermin: internal insmod crct10dif-pclmul.ko start - end = 1897754971.5 - 1898848742.5 mean elapsed = 1093772.0 variance = 39816100.0 s.d = 6310.0 percent = 0.0 activity 12: name = supermin: internal insmod crc32.ko start - end = 1898848743.5 - 1899965540.0 mean elapsed = 1116797.5 variance = 1370739552.2 s.d = 37023.5 percent = 0.0 activity 13: name = supermin: internal insmod virtio.ko start - end = 1899965541.0 - 1901361646.5 mean elapsed = 1396106.5 variance = 96298812720.2 s.d = 310320.5 percent = 0.1 activity 14: name = supermin: internal insmod virtio_ring.ko start - end = 1901361647.5 - 1902237219.5 mean elapsed = 875573.0 variance = 765226051984.0 s.d = 874772.0 percent = 0.0 activity 15: name = supermin: internal insmod virtio_blk.ko start - end = 1902237220.5 - 1902811371.5 mean elapsed = 574152.0 variance = 328797881281.0 s.d = 573409.0 percent = 0.0 activity 16: name = supermin: internal insmod virtio-rng.ko start - end = 1902811372.5 - 1903938709.0 mean elapsed = 1127337.5 variance = 1640290500.2 s.d = 40500.5 percent = 0.1 activity 17: name = supermin: internal insmod virtio_console.ko start - end = 1903938710.0 - 1905448112.0 mean elapsed = 1509403.0 variance = 197145768121.0 s.d = 444011.0 percent = 0.1 activity 18: name = supermin: internal insmod virtio_net.ko start - end = 1905448113.0 - 1907220846.5 mean elapsed = 1772734.5 variance = 270820843620.2 s.d = 520404.5 percent = 0.1 activity 19: name = supermin: internal insmod virtio_scsi.ko start - end = 1907220847.5 - 1907764156.0 mean elapsed = 543309.5 variance = 294326322842.2 s.d = 542518.5 percent = 0.0 activity 20: name = supermin: internal insmod virtio_balloon.ko start - end = 1907764157.0 - 1908314439.0 mean elapsed = 550283.0 variance = 302013995364.0 s.d = 549558.0 percent = 0.0 activity 21: name = supermin: internal insmod virtio_input.ko start - end = 1908314440.0 - 1909413933.5 mean elapsed = 1099494.5 variance = 31287242.2 s.d = 5593.5 percent = 0.0 activity 22: name = supermin: internal insmod virtio_mmio.ko start - end = 1909413934.5 - 1910503300.5 mean elapsed = 1089367.0 variance = 123520996.0 s.d = 11114.0 percent = 0.0 activity 23: name = supermin: internal insmod virtio_pci.ko start - end = 1910503301.5 - 1941148355.0 mean elapsed = 30645054.5 variance = 189131256.2 s.d = 13752.5 percent = 1.4 activity 24: name = supermin: internal insmod crc-ccitt.ko start - end = 1941148356.0 - 1942838889.0 mean elapsed = 1690534.0 variance = 328008321.0 s.d = 18111.0 percent = 0.1 activity 25: name = supermin: internal insmod crc-itu-t.ko start - end = 1942838890.0 - 1942839995.0 mean elapsed = 1106.0 variance = 4356.0 s.d = 66.0 percent = 0.0 activity 26: name = supermin: internal insmod crc8.ko start - end = 1942839996.0 - 1944270176.0 mean elapsed = 1430181.0 variance = 108585407529.0 s.d = 329523.0 percent = 0.1 activity 27: name = supermin: internal insmod libcrc32c.ko start - end = 1944270177.0 - 1945103937.5 mean elapsed = 833761.5 variance = 693734903556.2 s.d = 832907.5 percent = 0.0 activity 28: name = /init start - end = 1949411339.5 - 2210135132.5 mean elapsed = 260723794.0 variance = 19104845486281.0 s.d = 4370909.0 percent = 11.7 activity 29: name = bash:overhead start - end = 1949411339.5 - 1956248884.0 mean elapsed = 6837545.5 variance = 49271790756.2 s.d = 221972.5 percent = 0.3 activity 30: name = guestfsd start - end = 2210135133.5 - 2226245496.5 mean elapsed = 16110364.0 variance = 88983486601.0 s.d = 298301.0 percent = 0.7 activity 31: name = shutdown start - end = 2218921148.5 - 2235430052.0 mean elapsed = 16508904.5 variance = 6259300957740.2 s.d = 2501859.5 percent = 0.7 libguestfs 1.33.15 Host: Appliance: qemu version 2.5 \x1b[2J\x0dSeaBIOS (version 1.8.2-20150714_191134-) [ 0.000000] Linux version 4.4.4-301.fc23.x86_64 (address@hidden) (gcc version 5.3.1 20151207 (Red Hat 5.3.1-2) (GCC) ) #1 SMP Fri Mar 4 17:42:42 UTC 2016 supermin: ext2 mini initrd starting up: 5.1.15 dietlibc 0.000000s: ▲ run mean:2.235430s ±9.8ms (100.0%) 0.000062s: │ ▲ supermin:build mean:0.010205s ±0.0ms (0.5%) │ │ 0.010267s: │ ▼ 0.010287s: │ ▲ qemu:feature-detect mean:0.292824s ±4.1ms (13.1%) │ │ 0.303110s: │ ▼ │ 0.304860s: │ ▲ ▲ qemu mean:1.930570s ±5.7ms (86.4%) qemu:overhead mean:0.161659s ±1.8ms (7.2%) │ │ │ 0.466518s: │ │ ▼ 0.466518s: │ │ ▲ seabios:overhead mean:1.104864s ±1.2ms (49.4%) │ │ │ 1.571383s: │ │ ▼ 1.571383s: │ │ ▲ ▲ kernel mean:0.664047s ±2.8ms (29.7%) kernel:overhead mean:0.322751s ±1.2ms (14.4%) │ │ │ │ 1.894134s: │ │ │ ▼ 1.894134s: │ │ │ ▲ supermin:mini-initrd mean:0.055278s ±0.1ms (2.5%) │ │ │ │ 1.896422s: │ │ │ │ ▲ supermin: internal insmod crc32-pclmul.ko mean:0.001332s ±0.0ms (0.1%) │ │ │ │ │ 1.897754s: │ │ │ │ ▼ 1.897754s: │ │ │ │ ▲ supermin: internal insmod crc32c-intel.ko mean:0.000001s ±0.0ms (0.0%) 1.897755s: │ │ │ │ ▼ 1.897755s: │ │ │ │ ▲ supermin: internal insmod crct10dif-pclmul.ko mean:0.001094s ±0.0ms (0.0%) │ │ │ │ │ 1.898849s: │ │ │ │ ▼ 1.898849s: │ │ │ │ ▲ supermin: internal insmod crc32.ko mean:0.001117s ±0.0ms (0.0%) │ │ │ │ │ 1.899966s: │ │ │ │ ▼ 1.899966s: │ │ │ │ ▲ supermin: internal insmod virtio.ko mean:0.001396s ±0.3ms (0.1%) │ │ │ │ │ 1.901362s: │ │ │ │ ▼ 1.901362s: │ │ │ │ ▲ supermin: internal insmod virtio_ring.ko mean:0.000876s ±0.9ms (0.0%) 1.902237s: │ │ │ │ ▼ 1.902237s: │ │ │ │ ▲ supermin: internal insmod virtio_blk.ko mean:0.000574s ±0.6ms (0.0%) 1.902811s: │ │ │ │ ▼ 1.902811s: │ │ │ │ ▲ supermin: internal insmod virtio-rng.ko mean:0.001127s ±0.0ms (0.1%) │ │ │ │ │ 1.903939s: │ │ │ │ ▼ 1.903939s: │ │ │ │ ▲ supermin: internal insmod virtio_console.ko mean:0.001509s ±0.4ms (0.1%) │ │ │ │ │ 1.905448s: │ │ │ │ ▼ 1.905448s: │ │ │ │ ▲ supermin: internal insmod virtio_net.ko mean:0.001773s ±0.5ms (0.1%) │ │ │ │ │ 1.907221s: │ │ │ │ ▼ 1.907221s: │ │ │ │ ▲ supermin: internal insmod virtio_scsi.ko mean:0.000543s ±0.5ms (0.0%) 1.907764s: │ │ │ │ ▼ 1.907764s: │ │ │ │ ▲ supermin: internal insmod virtio_balloon.ko mean:0.000550s ±0.5ms (0.0%) 1.908314s: │ │ │ │ ▼ 1.908314s: │ │ │ │ ▲ supermin: internal insmod virtio_input.ko mean:0.001099s ±0.0ms (0.0%) │ │ │ │ │ 1.909414s: │ │ │ │ ▼ 1.909414s: │ │ │ │ ▲ supermin: internal insmod virtio_mmio.ko mean:0.001089s ±0.0ms (0.0%) │ │ │ │ │ 1.910503s: │ │ │ │ ▼ 1.910503s: │ │ │ │ ▲ supermin: internal insmod virtio_pci.ko mean:0.030645s ±0.0ms (1.4%) │ │ │ │ │ 1.941148s: │ │ │ │ ▼ 1.941148s: │ │ │ │ ▲ supermin: internal insmod crc-ccitt.ko mean:0.001691s ±0.0ms (0.1%) │ │ │ │ │ 1.942839s: │ │ │ │ ▼ 1.942839s: │ │ │ │ ▲ supermin: internal insmod crc-itu-t.ko mean:0.000001s ±0.0ms (0.0%) 1.942840s: │ │ │ │ ▼ 1.942840s: │ │ │ │ ▲ supermin: internal insmod crc8.ko mean:0.001430s ±0.3ms (0.1%) │ │ │ │ │ 1.944270s: │ │ │ │ ▼ 1.944270s: │ │ │ │ ▲ supermin: internal insmod libcrc32c.ko mean:0.000834s ±0.8ms (0.0%) 1.945104s: │ │ │ │ ▼ │ │ │ │ 1.949411s: │ │ │ ▼ 1.949411s: │ │ │ ▲ ▲ /init mean:0.260724s ±4.4ms (11.7%) bash:overhead mean:0.006838s ±0.2ms (0.3%) │ │ │ │ │ 1.956249s: │ │ │ │ ▼ │ │ │ │ 2.210135s: │ │ │ ▼ 2.210135s: │ │ │ ▲ guestfsd mean:0.016110s ±0.3ms (0.7%) │ │ │ │ 2.218921s: │ │ │ │ ▲ shutdown mean:0.016509s ±2.5ms (0.7%) │ │ │ │ │ 2.226245s: │ │ │ ▼ │ │ │ │ │ 2.235430s: ▼ ▼ ▼ ▼