Warming up the libguestfs cache ... Running the tests in 2 passes ... pass 1: 582 events collected in 3793210566 ns pass 2: 583 events collected in 3802884535 ns pass 0 number of events collected 582 elapsed time 3793210566 ns #0: +204966 [trace] "launch" #1: +208836 [trace] "version" #2: +229553 [trace] "version = " #3: +232503 [trace] "get_backend" #4: +234373 [trace] "get_backend = "direct"" #5: +236454 [library] "launch: program=boot-analysis" #6: +237450 [library] "launch: version=1.33.15" #7: +238237 [library] "launch: backend registered: unix" #8: +238801 [library] "launch: backend registered: uml" #9: +239360 [library] "launch: backend registered: libvirt" #10: +239841 [library] "launch: backend registered: direct" #11: +240673 [library] "launch: backend=direct" #12: +241299 [library] "launch: tmpdir=/home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfsEeios0" #13: +281031 [library] "launch: umask=0002" #14: +282351 [library] "launch: euid=1000" #15: +300131 [trace] "get_backend_setting "force_tcg"" #16: +306131 [trace] "get_backend_setting = NULL (error)" #17: +315159 [trace] "get_cachedir" #18: +317290 [trace] "get_cachedir = "/home/kchamart/src/rwmj-fork-libguestfs/tmp"" #19: +332214 [library] "begin building supermin appliance" #20: +332741 [library] "run supermin" #21: +336119 [library] "command: run: /usr/bin/supermin" #22: +336737 [library] "command: run: \ --build" #23: +337206 [library] "command: run: \ --verbose" #24: +337641 [library] "command: run: \ --if-newer" #25: +338311 [library] "command: run: \ --lock /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/lock" #26: +339297 [library] "command: run: \ --copy-kernel" #27: +339930 [library] "command: run: \ -f ext2" #28: +340491 [library] "command: run: \ --host-cpu x86_64" #29: +341099 [library] "command: run: \ /home/kchamart/src/rwmj-fork-libguestfs/appliance/supermin.d" #30: +341703 [library] "command: run: \ -o /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d" #31: +12380114 [appliance] "supermin: version: 5.1.13" #32: +12385011 [appliance] "supermin: rpm: detected RPM version 4.13" #33: +12385532 [appliance] "supermin: package handler: fedora/rpm" #34: +12385888 [appliance] "supermin: acquiring lock on /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/lock" #35: +12388502 [appliance] "supermin: if-newer: output does not need rebuilding" #36: +15351707 [library] "finished building supermin appliance" #37: +15377157 [library] "begin testing qemu features" #38: +15380778 [library] "command: run: /usr/bin/qemu-kvm" #39: +15381464 [library] "command: run: \ -display none" #40: +15381972 [library] "command: run: \ -help" #41: +129303174 [library] "command: run: /usr/bin/qemu-kvm" #42: +129306124 [library] "command: run: \ -display none" #43: +129307065 [library] "command: run: \ -version" #44: +253173273 [library] "qemu version 2.4" #45: +253179480 [library] "command: run: /usr/bin/qemu-kvm" #46: +253181173 [library] "command: run: \ -display none" #47: +253182166 [library] "command: run: \ -machine accel=kvm:tcg" #48: +253183090 [library] "command: run: \ -device ?" #49: +368899584 [trace] "get_sockdir" #50: +368907764 [trace] "get_sockdir = "/tmp"" #51: +369039740 [library] "finished testing qemu features" #52: +369057668 [trace] "get_backend_setting "gdb"" #53: +369065075 [trace] "get_backend_setting = NULL (error)" #54: +371117149 [appliance] "[00369ms] /usr/bin/qemu-kvm \" #55: +371119932 [appliance] " -global virtio-blk-pci.scsi=off \" #56: +371121052 [appliance] " -nodefconfig \" #57: +371122245 [appliance] " -enable-fips \" #58: +371123258 [appliance] " -nodefaults \" #59: +371124331 [appliance] " -display none \" #60: +371125584 [appliance] " -machine accel=kvm:tcg \" #61: +371126688 [appliance] " -cpu host \" #62: +371127805 [appliance] " -m 500 \" #63: +371128865 [appliance] " -no-reboot \" #64: +371129908 [appliance] " -rtc driftfix=slew \" #65: +371131035 [appliance] " -no-hpet \" #66: +371132156 [appliance] " -global kvm-pit.lost_tick_policy=discard \" #67: +371133302 [appliance] " -kernel /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d/kernel \" #68: +371134565 [appliance] " -initrd /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d/initrd \" #69: +371135664 [appliance] " -device virtio-scsi-pci,id=scsi \" #70: +371136782 [appliance] " -drive file=/home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfsEeios0/devnull1,cache=writeback,id=hd0,if=none \" #71: +371137895 [appliance] " -device scsi-hd,drive=hd0 \" #72: +371139072 [appliance] " -drive file=/home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \" #73: +371140254 [appliance] " -device scsi-hd,drive=appliance \" #74: +371141295 [appliance] " -device virtio-serial-pci \" #75: +371142304 [appliance] " -serial stdio \" #76: +371143314 [appliance] " -device sga \" #77: +371144342 [appliance] " -chardev socket,path=/tmp/libguestfsCaJzYS/guestfsd.sock,id=channel0 \" #78: +371145458 [appliance] " -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \" #79: +371147091 [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=screen guestfs_boot_analysis=1'" #80: +486755883 [appliance] "WARNING: Image format was not specified for '/home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfsEeios0/devnull1' and probing guessed raw." #81: +486759150 [appliance] " Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted." #82: +486760507 [appliance] " Specify the 'raw' format explicitly to remove the restrictions." #83: +599957940 [appliance] "\x1b[1;256r\x1b[256;256H\x1b[6n" #84: +602009738 [appliance] "Google, Inc." #85: +602011273 [appliance] "Serial Graphics Adapter 06/19/15" #86: +602012383 [appliance] "SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (mockbuild@) Fri Jun 19 00:52:18 UTC 2015" #87: +603110207 [appliance] "Term: 80x24" #88: +603111756 [appliance] "4 0" #89: +603112753 [appliance] "\x1b[2J\x0dSeaBIOS (version 1.8.2-20150714_191134-)" #90: +722103141 [appliance] "\x0dBooting from ROM..." #91: +1814767036 [appliance] "\x0dProbing EDD (edd=off to disable)... ok" #92: +1814770620 [appliance] "\x1b[2J" #93: +1979490879 [appliance] "[ 0.000000] Initializing cgroup subsys cpuset" #94: +1979494333 [appliance] "[ 0.000000] Initializing cgroup subsys cpu" #95: +1980594439 [appliance] "[ 0.000000] Initializing cgroup subsys cpuacct" #96: +1980595943 [appliance] "[ 0.000000] Linux version 4.4.5-300.fc23.x86_64+debug (mockbuild@bkernel02.phx2.fedoraproject.org) (gcc version 5.3.1 20151207 (Red Hat 5.3.1-2) (GCC) ) #1 SMP Thu Mar 10 17:37:59 UTC 2016" #97: +1982835434 [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=screen guestfs_boot_analysis=1" #98: +1985059855 [appliance] "[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256" #99: +1986166507 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers'" #100: +1987271124 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers'" #101: +1988381208 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers'" #102: +1989492459 [appliance] "[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format." #103: +1990591088 [appliance] "[ 0.000000] x86/fpu: Using 'eager' FPU context switches." #104: +1990592904 [appliance] "[ 0.000000] e820: BIOS-provided physical RAM map:" #105: +1991690445 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable" #106: +1992786340 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved" #107: +1993900545 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved" #108: +1993902076 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3dffff] usable" #109: +1994998120 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000001f3e0000-0x000000001f3fffff] reserved" #110: +1996099386 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved" #111: +1997194661 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved" #112: +1998288123 [appliance] "[ 0.000000] NX (Execute Disable) protection: active" #113: +1998289464 [appliance] "[ 0.000000] SMBIOS 2.8 present." #114: +1999383823 [appliance] "[ 0.000000] Hypervisor detected: KVM" #115: +1999385110 [appliance] "[ 0.000000] e820: last_pfn = 0x1f3e0 max_arch_pfn = 0x400000000" #116: +2000477458 [appliance] "[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT " #117: +2001585666 [appliance] "[ 0.000000] found SMP MP-table at [mem 0x000f64d0-0x000f64df] mapped at [ffff8800000f64d0]" #118: +2002685792 [appliance] "[ 0.000000] Scanning 1 areas for low memory corruption" #119: +2002687129 [appliance] "[ 0.000000] Using GB pages for direct mapping" #120: +2003788937 [appliance] "[ 0.000000] RAMDISK: [mem 0x1f277000-0x1f3dffff]" #121: +2003790302 [appliance] "[ 0.000000] No NUMA configuration found" #122: +2004894996 [appliance] "[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3dffff]" #123: +2005989860 [appliance] "[ 0.000000] NODE_DATA(0) allocated [mem 0x1f264000-0x1f276fff]" #124: +2005991243 [appliance] "[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00" #125: +2007094974 [appliance] "[ 0.000000] kvm-clock: cpu 0, msr 0:1f254001, primary cpu clock" #126: +2008197946 [appliance] "[ 0.000000] kvm-clock: using sched offset of 1367160735 cycles" #127: +2009298125 [appliance] "[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns" #128: +2010395749 [appliance] "[ 0.000000] Zone ranges:" #129: +2010397054 [appliance] "[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]" #130: +2011490774 [appliance] "[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001f3dffff]" #131: +2012595079 [appliance] "[ 0.000000] Normal empty" #132: +2012596350 [appliance] "[ 0.000000] Movable zone start for each node" #133: +2012597275 [appliance] "[ 0.000000] Early memory node ranges" #134: +2013701627 [appliance] "[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]" #135: +2014803106 [appliance] "[ 0.000000] node 0: [mem 0x0000000000100000-0x000000001f3dffff]" #136: +2014804384 [appliance] "[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3dffff]" #137: +2015913872 [appliance] "[ 0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org" #138: +2017008821 [appliance] "[ 0.000000] Intel MultiProcessor Specification v1.4" #139: +2018110671 [appliance] "[ 0.000000] MPTABLE: OEM ID: BOCHSCPU" #140: +2018111827 [appliance] "[ 0.000000] MPTABLE: Product ID: 0.1 " #141: +2019215810 [appliance] "[ 0.000000] MPTABLE: APIC at: 0xFEE00000" #142: +2019217136 [appliance] "[ 0.000000] Processor #0 (Bootup-CPU)" #143: +2020310190 [appliance] "[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23" #144: +2020311448 [appliance] "[ 0.000000] Processors: 1" #145: +2021414827 [appliance] "[ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs" #146: +2021416113 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]" #147: +2022515675 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]" #148: +2023609001 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]" #149: +2024701893 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]" #150: +2024703120 [appliance] "[ 0.000000] e820: [mem 0x1f400000-0xfeffbfff] available for PCI devices" #151: +2025807335 [appliance] "[ 0.000000] Booting paravirtualized kernel on KVM" #152: +2026909732 [appliance] "[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns" #153: +2028015474 [appliance] "[ 0.000000] setup_percpu: NR_CPUS:1024 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1" #154: +2029109175 [appliance] "[ 0.000000] PERCPU: Embedded 483 pages/cpu @ffff88001ec00000 s1937944 r8192 d32232 u2097152" #155: +2030210472 [appliance] "[ 0.000000] KVM setup async PF for cpu 0" #156: +2031331247 [appliance] "[ 0.000000] kvm-stealtime: cpu 0, msr 1ec0db40" #157: +2031332639 [appliance] "[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 125849" #158: +2032422049 [appliance] "[ 0.000000] Policy zone: DMA32" #159: +2032423302 [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=screen guestfs_boot_analysis=1" #160: +2035706411 [appliance] "[ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)" #161: +2036799394 [appliance] "[ 0.000000] Memory: 462932K/511480K available (8747K kernel code, 1490K rwdata, 3660K rodata, 3536K init, 16580K bss, 48548K reserved, 0K cma-reserved)" #162: +2037898564 [appliance] "[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1" #163: +2038990231 [appliance] "[ 0.000000] Running RCU self tests" #164: +2040091330 [appliance] "[ 0.000000] Hierarchical RCU implementation." #165: +2040092681 [appliance] "[ 0.000000] \x09RCU lockdep checking is enabled." #166: +2041186403 [appliance] "[ 0.000000] \x09Build-time adjustment of leaf fanout to 64." #167: +2041187726 [appliance] "[ 0.000000] \x09RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=1." #168: +2042288443 [appliance] "[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1" #169: +2043394053 [appliance] "[ 0.000000] kmemleak: Kernel memory leak detector disabled" #170: +2043395274 [appliance] "[ 0.000000] NR_IRQS:65792 nr_irqs:256 16" #171: +2044488397 [appliance] "[ 0.000000] \x09Offload RCU callbacks from all CPUs" #172: +2044489323 [appliance] "[ 0.000000] \x09Offload RCU callbacks from CPUs: 0." #173: +2045591749 [appliance] "[ 0.000000] Console: colour *CGA 80x25" #174: +2046697043 [appliance] "[ 0.000000] console [ttyS0] enabled" #175: +2046698065 [appliance] "[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar" #176: +2047799174 [appliance] "[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8" #177: +2047800220 [appliance] "[ 0.000000] ... MAX_LOCK_DEPTH: 48" #178: +2048911144 [appliance] "[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191" #179: +2048912166 [appliance] "[ 0.000000] ... CLASSHASH_SIZE: 4096" #180: +2050011807 [appliance] "[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768" #181: +2050012837 [appliance] "[ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536" #182: +2051105022 [appliance] "[ 0.000000] ... CHAINHASH_SIZE: 32768" #183: +2052198545 [appliance] "[ 0.000000] memory used by lock dependency info: 8671 kB" #184: +2052199581 [appliance] "[ 0.000000] per task-struct memory footprint: 2688 bytes" #185: +2053275883 [appliance] "[ 0.000000] tsc: Detected 2099.998 MHz processor" #186: +2054371546 [appliance] "[ 0.124242] Calibrating delay loop (skipped) preset value.. 4199.99 BogoMIPS (lpj=2099998)" #187: +2055474134 [appliance] "[ 0.125394] pid_max: default: 32768 minimum: 301" #188: +2056561347 [appliance] "[ 0.126109] Security Framework initialized" #189: +2056562316 [appliance] "[ 0.126689] Yama: becoming mindful." #190: +2057649133 [appliance] "[ 0.127181] SELinux: Disabled at boot." #191: +2057650119 [appliance] "[ 0.127897] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)" #192: +2058741901 [appliance] "[ 0.129013] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)" #193: +2059837491 [appliance] "[ 0.130021] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)" #194: +2060939156 [appliance] "[ 0.130922] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)" #195: +2063694583 [appliance] "[ 0.132954] Initializing cgroup subsys io" #196: +2063695616 [appliance] "[ 0.133560] Initializing cgroup subsys memory" #197: +2064778798 [appliance] "[ 0.134482] Disabling memory control group subsystem" #198: +2065888012 [appliance] "[ 0.135151] Initializing cgroup subsys devices" #199: +2065889137 [appliance] "[ 0.135792] Initializing cgroup subsys freezer" #200: +2066988204 [appliance] "[ 0.136451] Initializing cgroup subsys net_cls" #201: +2066989129 [appliance] "[ 0.137057] Initializing cgroup subsys perf_event" #202: +2068079171 [appliance] "[ 0.137711] Initializing cgroup subsys net_prio" #203: +2068080135 [appliance] "[ 0.138336] Initializing cgroup subsys hugetlb" #204: +2069180691 [appliance] "[ 0.138963] Initializing cgroup subsys pids" #205: +2071270549 [appliance] "[ 0.140535] mce: CPU supports 10 MCE banks" #206: +2071271583 [appliance] "[ 0.141165] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8" #207: +2072370377 [appliance] "[ 0.141922] Last level dTLB entries: 4KB 512, 2MB 0, 4MB 0, 1GB 4" #208: +2088801681 [appliance] "[ 0.158063] Freeing SMP alternatives memory: 28K (ffffffff822ea000 - ffffffff822f1000)" #209: +2094267044 [appliance] "[ 0.163545] ftrace: allocating 29768 entries in 117 pages" #210: +2134076118 [appliance] "[ 0.203309] x2apic enabled" #211: +2134077681 [appliance] "[ 0.203998] Switched APIC routing to physical x2apic." #212: +2136575724 [appliance] "[ 0.205841] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1" #213: +2136576921 [appliance] "[ 0.206683] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2620 v2 @ 2.10GHz (family: 0x6, model: 0x3e, stepping: 0x4)" #214: +2138806113 [appliance] "[ 0.208084] Performance Events: 16-deep LBR, IvyBridge events, Intel PMU driver." #215: +2140985827 [appliance] "[ 0.210503] ... version: 2" #216: +2140987011 [appliance] "[ 0.211043] ... bit width: 48" #217: +2142080388 [appliance] "[ 0.211590] ... generic registers: 4" #218: +2142081398 [appliance] "[ 0.212131] ... value mask: 0000ffffffffffff" #219: +2143182532 [appliance] "[ 0.212841] ... max period: 000000007fffffff" #220: +2144274698 [appliance] "[ 0.213547] ... fixed-purpose events: 3" #221: +2144275686 [appliance] "[ 0.214077] ... event mask: 000000070000000f" #222: +2147239596 [appliance] "[ 0.216499] x86: Booted up 1 node, 1 CPUs" #223: +2147240610 [appliance] "[ 0.217056] smpboot: Total of 1 processors activated (4199.99 BogoMIPS)" #224: +2150053045 [appliance] "[ 0.219329] devtmpfs: initialized" #225: +2153907557 [appliance] "[ 0.223156] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns" #226: +2155000893 [appliance] "[ 0.224714] atomic64_test: passed for x86-64 platform with CX8 and with SSE" #227: +2156086119 [appliance] "[ 0.225653] pinctrl core: initialized pinctrl subsystem" #228: +2157357524 [appliance] "[ 0.226636] RTC time: 11:22:22, date: 03/21/16" #229: +2157358500 [appliance] "[ 0.227705] NET: Registered protocol family 16" #230: +2159777190 [appliance] "[ 0.229055] cpuidle: using governor menu" #231: +2159778218 [appliance] "[ 0.230098] PCI: Using configuration type 1 for base access" #232: +2160857973 [appliance] "[ 0.231213] perf_event_intel: PMU erratum BJ122, BV98, HSD29 workaround disabled, HT off" #233: +2169982614 [appliance] "[ 0.239242] ACPI: Interpreter disabled." #234: +2169983644 [appliance] "[ 0.240290] vgaarb: loaded" #235: +2171078990 [appliance] "[ 0.240965] SCSI subsystem initialized" #236: +2172159421 [appliance] "[ 0.241746] usbcore: registered new interface driver usbfs" #237: +2172160365 [appliance] "[ 0.242518] usbcore: registered new interface driver hub" #238: +2173271374 [appliance] "[ 0.243260] usbcore: registered new device driver usb" #239: +2174363330 [appliance] "[ 0.244220] PCI: Probing PCI hardware" #240: +2175626631 [appliance] "[ 0.244887] PCI host bridge to bus 0000:00" #241: +2175627939 [appliance] "[ 0.245455] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]" #242: +2176730529 [appliance] "[ 0.246269] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]" #243: +2177830450 [appliance] "[ 0.247198] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]" #244: +2186153303 [appliance] "[ 0.255401] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]" #245: +2186154940 [appliance] "[ 0.256355] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]" #246: +2187252445 [appliance] "[ 0.257192] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]" #247: +2188356608 [appliance] "[ 0.258121] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]" #248: +2189435388 [appliance] "[ 0.259723] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI" #249: +2192596858 [appliance] "[ 0.262541] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB" #250: +2215917683 [appliance] "[ 0.285142] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]" #251: +2220402549 [appliance] "[ 0.289652] NetLabel: Initializing" #252: +2220403719 [appliance] "[ 0.290130] NetLabel: domain hash size = 128" #253: +2220404429 [appliance] "[ 0.290706] NetLabel: protocols = UNLABELED CIPSOv4" #254: +2221498878 [appliance] "[ 0.291432] NetLabel: unlabeled traffic allowed by default" #255: +2222588789 [appliance] "[ 0.292401] clocksource: Switched to clocksource kvm-clock" #256: +2266187182 [appliance] "[ 0.335377] pnp: PnP ACPI: disabled" #257: +2273334425 [appliance] "[ 0.342560] NET: Registered protocol family 2" #258: +2274627996 [appliance] "[ 0.343879] TCP established hash table entries: 4096 (order: 3, 32768 bytes)" #259: +2274629545 [appliance] "[ 0.344904] TCP bind hash table entries: 4096 (order: 6, 327680 bytes)" #260: +2275723725 [appliance] "[ 0.346027] TCP: Hash tables configured (established 4096 bind 4096)" #261: +2276816964 [appliance] "[ 0.347116] UDP hash table entries: 256 (order: 3, 49152 bytes)" #262: +2277907490 [appliance] "[ 0.347956] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes)" #263: +2279035934 [appliance] "[ 0.349013] NET: Registered protocol family 1" #264: +2280152011 [appliance] "[ 0.349641] pci 0000:00:00.0: Limiting direct PCI/PCI transfers" #265: +2280153424 [appliance] "[ 0.350455] pci 0000:00:01.0: PIIX3: Enabling Passive Release" #266: +2281273166 [appliance] "[ 0.351231] pci 0000:00:01.0: Activating ISA DMA hang workarounds" #267: +2282368967 [appliance] "[ 0.352479] Unpacking initramfs..." #268: +2288060892 [appliance] "[ 0.357271] Freeing initrd memory: 1444K (ffff88001f277000 - ffff88001f3e0000)" #269: +2308174326 [appliance] "[ 0.377332] DMA-API: preallocated 65536 debug entries" #270: +2308177595 [appliance] "[ 0.378052] DMA-API: debugging enabled by kernel config" #271: +2309281608 [appliance] "[ 0.379052] platform rtc_cmos: registered platform RTC device (no PNP device found)" #272: +2311486451 [appliance] "[ 0.380742] Scanning for low memory corruption every 60 seconds" #273: +2313578599 [appliance] "[ 0.382798] futex hash table entries: 256 (order: 3, 32768 bytes)" #274: +2313580287 [appliance] "[ 0.383676] audit: initializing netlink subsys (disabled)" #275: +2314691761 [appliance] "[ 0.384485] audit: type=2000 audit(1458559343.283:1): initialized" #276: +2315791530 [appliance] "[ 0.385645] cryptomgr_test (27) used greatest stack depth: 14040 bytes left" #277: +2316888043 [appliance] "[ 0.387205] Initialise system trusted keyring" #278: +2319110446 [appliance] "[ 0.388357] HugeTLB registered 1 GB page size, pre-allocated 0 pages" #279: +2319112086 [appliance] "[ 0.389205] HugeTLB registered 2 MB page size, pre-allocated 0 pages" #280: +2329989950 [appliance] "[ 0.399226] zbud: loaded" #281: +2331143124 [appliance] "[ 0.400390] VFS: Disk quotas dquot_6.6.0" #282: +2331144778 [appliance] "[ 0.401087] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)" #283: +2335157491 [appliance] "[ 0.404390] Key type big_key registered" #284: +2336812159 [appliance] "[ 0.406042] cryptomgr_test (35) used greatest stack depth: 13816 bytes left" #285: +2338092717 [appliance] "[ 0.407345] cryptomgr_test (37) used greatest stack depth: 13688 bytes left" #286: +2342252943 [appliance] "[ 0.411478] cryptomgr_test (53) used greatest stack depth: 13312 bytes left" #287: +2344883066 [appliance] "[ 0.414089] NET: Registered protocol family 38" #288: +2344884773 [appliance] "[ 0.414747] Key type asymmetric registered" #289: +2345986112 [appliance] "[ 0.415328] Asymmetric key parser 'x509' registered" #290: +2345987525 [appliance] "[ 0.416135] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)" #291: +2347084627 [appliance] "[ 0.417387] io scheduler noop registered" #292: +2348200908 [appliance] "[ 0.418002] io scheduler deadline registered" #293: +2349527419 [appliance] "[ 0.418791] io scheduler cfq registered (default)" #294: +2352566503 [appliance] "[ 0.421791] pci_hotplug: PCI Hot Plug PCI Core version: 0.5" #295: +2352568195 [appliance] "[ 0.422584] pciehp: PCI Express Hot Plug Controller Driver version: 0.4" #296: +2353661177 [appliance] "[ 0.423899] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled" #297: +2380298118 [appliance] "[ 0.449560] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A" #298: +2387913520 [appliance] "[ 0.457145] Non-volatile memory driver v1.3" #299: +2387915202 [appliance] "[ 0.457856] Linux agpgart interface v0.103" #300: +2392625605 [appliance] "[ 0.461855] scsi host0: ata_piix" #301: +2392627228 [appliance] "[ 0.462767] scsi host1: ata_piix" #302: +2393741843 [appliance] "[ 0.463382] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14" #303: +2394843929 [appliance] "[ 0.464273] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15" #304: +2396727700 [appliance] "[ 0.465954] libphy: Fixed MDIO Bus: probed" #305: +2396729346 [appliance] "[ 0.466714] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver" #306: +2397833657 [appliance] "[ 0.467594] ehci-pci: EHCI PCI platform driver" #307: +2398950005 [appliance] "[ 0.468202] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver" #308: +2398951609 [appliance] "[ 0.469029] ohci-pci: OHCI PCI platform driver" #309: +2400067120 [appliance] "[ 0.469665] uhci_hcd: USB Universal Host Controller Interface driver" #310: +2401165939 [appliance] "[ 0.470941] usbcore: registered new interface driver usbserial" #311: +2402268612 [appliance] "[ 0.471737] usbcore: registered new interface driver usbserial_generic" #312: +2403443323 [appliance] "[ 0.472695] usbserial: USB Serial support registered for generic" #313: +2403445006 [appliance] "[ 0.473573] i8042: PNP: No PS/2 controller found. Probing ports directly." #314: +2406195958 [appliance] "[ 0.475425] serio: i8042 KBD port at 0x60,0x64 irq 1" #315: +2406197603 [appliance] "[ 0.476147] serio: i8042 AUX port at 0x60,0x64 irq 12" #316: +2407285220 [appliance] "[ 0.477513] mousedev: PS/2 mouse device common for all mice" #317: +2409898580 [appliance] "[ 0.479113] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0" #318: +2413700854 [appliance] "[ 0.482923] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3" #319: +2414814522 [appliance] "[ 0.484074] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0" #320: +2414816226 [appliance] "[ 0.485028] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram" #321: +2415924855 [appliance] "[ 0.486070] device-mapper: uevent: version 1.0.3" #322: +2418199458 [appliance] "[ 0.487446] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2" #323: +2419780237 [appliance] "[ 0.489017] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com" #324: +2421602045 [appliance] "[ 0.490838] hidraw: raw HID events driver (C) Jiri Kosina" #325: +2421603717 [appliance] "[ 0.491720] usbcore: registered new interface driver usbhid" #326: +2422705021 [appliance] "[ 0.492468] usbhid: USB HID core driver" #327: +2423986192 [appliance] "[ 0.493234] drop_monitor: Initializing network drop monitor service" #328: +2423987722 [appliance] "[ 0.494296] ip_tables: (C) 2000-2006 Netfilter Core Team" #329: +2425098478 [appliance] "[ 0.495148] Initializing XFRM netlink socket" #330: +2427345558 [appliance] "[ 0.496608] NET: Registered protocol family 10" #331: +2429006348 [appliance] "[ 0.498235] mip6: Mobile IPv6" #332: +2429007864 [appliance] "[ 0.498688] NET: Registered protocol family 17" #333: +2430650042 [appliance] "[ 0.499884] microcode: CPU0 sig=0x306e4, pf=0x1, revision=0x1" #334: +2430651601 [appliance] "[ 0.500778] microcode: Microcode Update Driver: v2.01 , Peter Oruba" #335: +2431757881 [appliance] "[ 0.501959] AVX version of gcm_enc/dec engaged." #336: +2432884700 [appliance] "[ 0.502575] AES CTR mode by8 optimization enabled" #337: +2434905657 [appliance] "[ 0.504142] cryptomgr_test (85) used greatest stack depth: 13224 bytes left" #338: +2463379736 [appliance] "[ 0.532592] registered taskstats version 1" #339: +2463381275 [appliance] "[ 0.533184] Loading compiled-in X.509 certificates" #340: +2465678710 [appliance] "[ 0.534923] Loaded X.509 cert 'Fedora kernel signing key: d33a3fa294f19598ff71e1ac24c3cdc70d17052c'" #341: +2466811449 [appliance] "[ 0.536147] cryptomgr_test (100) used greatest stack depth: 12696 bytes left" #342: +2468000978 [appliance] "[ 0.537264] zswap: loaded using pool lzo/zbud" #343: +2470082079 [appliance] "[ 0.539310] Magic number: 8:547:378" #344: +2470083539 [appliance] "[ 0.539946] rtc_cmos rtc_cmos: setting system clock to 2016-03-21 11:22:22 UTC (1458559342)" #345: +2555579600 [appliance] "[ 0.624783] Freeing unused kernel memory: 3536K (ffffffff81f76000 - ffffffff822ea000)" #346: +2555581488 [appliance] "[ 0.625833] Write protecting the kernel read-only data: 14336k" #347: +2558918549 [appliance] "[ 0.628129] Freeing unused kernel memory: 1480K (ffff88000188e000 - ffff880001a00000)" #348: +2561283402 [appliance] "[ 0.630509] Freeing unused kernel memory: 436K (ffff880001d93000 - ffff880001e00000)" #349: +2563375500 [appliance] "supermin: mounting /proc" #350: +2563376910 [appliance] "supermin: uptime: 0.46 0.13" #351: +2563377199 [appliance] "supermin: ext2 mini initrd starting up: 5.1.13 zlib xz" #352: +2564491778 [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=screen guestfs_boot_analysis=1" #353: +2566709041 [appliance] "supermin: mounting /sys" #354: +2566710228 [appliance] "supermin: internal insmod crc32-pclmul.ko.xz" #355: +2567799134 [appliance] "supermin: running xz" #356: +2568941649 [appliance] "done with xz 8587 read" #357: +2571016440 [appliance] "supermin: internal insmod crc32c-intel.ko.xz" #358: +2571017842 [appliance] "supermin: running xz" #359: +2572746462 [appliance] "done with xz 20019 read" #360: +2574594577 [appliance] "supermin: internal insmod crct10dif-pclmul.ko.xz" #361: +2574595891 [appliance] "supermin: running xz" #362: +2575912793 [appliance] "done with xz 11131 read" #363: +2577735245 [appliance] "supermin: internal insmod crc32.ko.xz" #364: +2577736658 [appliance] "supermin: running xz" #365: +2577737014 [appliance] "done with xz 6403 read" #366: +2580543141 [appliance] "supermin: internal insmod virtio.ko.xz" #367: +2580544501 [appliance] "supermin: running xz" #368: +2581885407 [appliance] "done with xz 15475 read" #369: +2583673938 [appliance] "supermin: internal insmod virtio_ring.ko.xz" #370: +2583675275 [appliance] "supermin: running xz" #371: +2585292953 [appliance] "done with xz 20051 read" #372: +2587001331 [appliance] "supermin: internal insmod virtio_blk.ko.xz" #373: +2587002741 [appliance] "supermin: running xz" #374: +2588750142 [appliance] "done with xz 24083 read" #375: +2590651105 [appliance] "supermin: internal insmod virtio-rng.ko.xz" #376: +2590652503 [appliance] "supermin: running xz" #377: +2591806094 [appliance] "done with xz 9123 read" #378: +2593517884 [appliance] "supermin: internal insmod virtio_console.ko.xz" #379: +2593519271 [appliance] "supermin: running xz" #380: +2595992146 [appliance] "done with xz 45811 read" #381: +2598247684 [appliance] "supermin: internal insmod drm.ko.xz" #382: +2598249103 [appliance] "supermin: running xz" #383: +2614412392 [appliance] "done with xz 548859 read" #384: +2622956818 [appliance] "[ 0.692168] [drm] Initialized drm 1.1.0 20060810" #385: +2622958194 [appliance] "supermin: internal insmod drm_kms_helper.ko.xz" #386: +2624046150 [appliance] "supermin: running xz" #387: +2630809693 [appliance] "done with xz 187291 read" #388: +2634602480 [appliance] "supermin: internal insmod ttm.ko.xz" #389: +2634603825 [appliance] "supermin: running xz" #390: +2639984074 [appliance] "done with xz 145403 read" #391: +2643526766 [appliance] "supermin: internal insmod virtio-gpu.ko.xz" #392: +2643528094 [appliance] "supermin: running xz" #393: +2647309480 [appliance] "done with xz 94891 read" #394: +2650337640 [appliance] "supermin: internal insmod ideapad_slidebar.ko.xz" #395: +2650339005 [appliance] "supermin: running xz" #396: +2651654482 [appliance] "done with xz 13523 read" #397: +2653346272 [appliance] "[ 0.722575] ideapad_slidebar: DMI does not match" #398: +2654454976 [appliance] "insmod: init_module: ideapad_slidebar.ko.xz: No such device" #399: +2654456411 [appliance] "supermin: internal insmod virtio_net.ko.xz" #400: +2654456758 [appliance] "supermin: running xz" #401: +2657753842 [appliance] "done with xz 49059 read" #402: +2659915759 [appliance] "supermin: internal insmod video.ko.xz" #403: +2659917153 [appliance] "supermin: running xz" #404: +2662297376 [appliance] "done with xz 53483 read" #405: +2664396771 [appliance] "supermin: internal insmod sparse-keymap.ko.xz" #406: +2664397931 [appliance] "supermin: running xz" #407: +2665627043 [appliance] "done with xz 9051 read" #408: +2667270335 [appliance] "supermin: internal insmod wmi.ko.xz" #409: +2667271705 [appliance] "supermin: running xz" #410: +2668818057 [appliance] "done with xz 21379 read" #411: +2670813736 [appliance] "insmod: init_module: wmi.ko.xz: No such device" #412: +2670815006 [appliance] "supermin: internal insmod rfkill.ko.xz" #413: +2670815309 [appliance] "supermin: running xz" #414: +2673612586 [appliance] "done with xz 44163 read" #415: +2675812747 [appliance] "supermin: internal insmod ideapad-laptop.ko.xz" #416: +2675814060 [appliance] "supermin: running xz" #417: +2677739404 [appliance] "done with xz 29379 read" #418: +2679322993 [appliance] "[ 0.748531] ideapad_laptop: Unknown symbol wmi_remove_notify_handler (err 0)" #419: +2679324312 [appliance] "[ 0.749532] ideapad_laptop: Unknown symbol wmi_install_notify_handler (err 0)" #420: +2680451341 [appliance] "insmod: init_module: ideapad-laptop.ko.xz: Unknown symbol in module" #421: +2681561898 [appliance] "supermin: internal insmod megaraid.ko.xz" #422: +2681562921 [appliance] "supermin: running xz" #423: +2685721393 [appliance] "done with xz 76995 read" #424: +2688186054 [appliance] "supermin: internal insmod megaraid_mm.ko.xz" #425: +2688187391 [appliance] "supermin: running xz" #426: +2689969959 [appliance] "done with xz 22363 read" #427: +2691726273 [appliance] "[ 0.760953] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006)" #428: +2692905429 [appliance] "supermin: internal insmod megaraid_mbox.ko.xz" #429: +2692906800 [appliance] "supermin: running xz" #430: +2696027340 [appliance] "done with xz 59331 read" #431: +2698269726 [appliance] "[ 0.767478] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006)" #432: +2698271111 [appliance] "supermin: internal insmod megaraid_sas.ko.xz" #433: +2699427823 [appliance] "supermin: running xz" #434: +2706593917 [appliance] "done with xz 182987 read" #435: +2710120887 [appliance] "[ 0.779329] megasas: 06.808.16.00-rc1" #436: +2710122315 [appliance] "supermin: internal insmod scsi_transport_spi.ko.xz" #437: +2711242969 [appliance] "supermin: running xz" #438: +2713216034 [appliance] "done with xz 46795 read" #439: +2715265520 [appliance] "supermin: internal insmod sym53c8xx.ko.xz" #440: +2715266869 [appliance] "supermin: running xz" #441: +2720549553 [appliance] "done with xz 120395 read" #442: +2723451834 [appliance] "supermin: internal insmod virtio_scsi.ko.xz" #443: +2723453157 [appliance] "supermin: running xz" #444: +2725377272 [appliance] "done with xz 27971 read" #445: +2727508364 [appliance] "supermin: internal insmod virtio_balloon.ko.xz" #446: +2727509739 [appliance] "supermin: running xz" #447: +2729039072 [appliance] "done with xz 18811 read" #448: +2730982897 [appliance] "supermin: internal insmod virtio_input.ko.xz" #449: +2730984264 [appliance] "supermin: running xz" #450: +2732489076 [appliance] "done with xz 13307 read" #451: +2734158994 [appliance] "supermin: internal insmod virtio_mmio.ko.xz" #452: +2734160407 [appliance] "supermin: running xz" #453: +2735604091 [appliance] "done with xz 14147 read" #454: +2737371384 [appliance] "supermin: internal insmod virtio_pci.ko.xz" #455: +2737372739 [appliance] "supermin: running xz" #456: +2739442320 [appliance] "done with xz 32355 read" #457: +2741423329 [appliance] "[ 0.810649] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 10" #458: +2742561340 [appliance] "[ 0.811812] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver" #459: +2745145506 [appliance] "[ 0.814381] scsi host2: Virtio SCSI HBA" #460: +2746754867 [appliance] "[ 0.815983] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 11" #461: +2746756289 [appliance] "[ 0.817028] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver" #462: +2753246593 [appliance] "[ 0.822432] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.4. PQ: 0 ANSI: 5" #463: +2757754272 [appliance] "[ 0.826969] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.4. PQ: 0 ANSI: 5" #464: +2932086936 [appliance] "[ 1.001136] sd 2:0:0:0: [sda] 8 512-byte logical blocks: (4.10 kB/4.00 KiB)" #465: +2933722027 [appliance] "[ 1.002941] sd 2:0:0:0: Attached scsi generic sg0 type 0" #466: +2935193483 [appliance] "[ 1.004380] sd 2:0:0:0: [sda] Write Protect is off" #467: +2935194822 [appliance] "[ 1.005297] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #468: +2937899477 [appliance] "[ 1.007111] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)" #469: +2939353783 [appliance] "[ 1.008578] sd 2:0:1:0: [sdb] Write Protect is off" #470: +2940631261 [appliance] "[ 1.009868] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #471: +2941759910 [appliance] "[ 1.011105] sd 2:0:1:0: Attached scsi generic sg1 type 0" #472: +2945378635 [appliance] "[ 1.014311] Dev sda: unable to read RDB block 8" #473: +2945381478 [appliance] "[ 1.015180] sda: unable to read partition table" #474: +2946567306 [appliance] "[ 1.015842] sda: partition table beyond EOD, enabling native capacity" #475: +2949424987 [appliance] "[ 1.018529] sd 2:0:1:0: [sdb] Attached SCSI disk" #476: +2949427967 [appliance] "[ 1.019468] Dev sda: unable to read RDB block 8" #477: +2950632257 [appliance] "[ 1.020122] sda: unable to read partition table" #478: +2950634677 [appliance] "[ 1.020764] sda: partition table beyond EOD, truncated" #479: +2953276545 [appliance] "[ 1.022496] sd 2:0:0:0: [sda] Attached SCSI disk" #480: +2953277975 [appliance] "supermin: internal insmod crc-ccitt.ko.xz" #481: +2954402196 [appliance] "supermin: running xz" #482: +2954403262 [appliance] "done with xz 4843 read" #483: +2956721434 [appliance] "supermin: internal insmod crc-itu-t.ko.xz" #484: +2956722851 [appliance] "supermin: running xz" #485: +2957839074 [appliance] "done with xz 4843 read" #486: +2959423805 [appliance] "supermin: internal insmod crc8.ko.xz" #487: +2959425257 [appliance] "supermin: running xz" #488: +2959425677 [appliance] "done with xz 4651 read" #489: +2961972386 [appliance] "supermin: internal insmod libcrc32c.ko.xz" #490: +2961973759 [appliance] "supermin: running xz" #491: +2963072475 [appliance] "done with xz 5667 read" #492: +2965034818 [appliance] "supermin: picked /sys/block/sdb/dev as root device" #493: +2966202648 [appliance] "supermin: creating /dev/root as block special 8:16" #494: +2966204057 [appliance] "supermin: mounting new root on /root" #495: +2967815026 [appliance] "[ 1.037049] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem" #496: +2974228612 [appliance] "[ 1.043433] EXT4-fs (sdb): mounted filesystem without journal. Opts: " #497: +2974230009 [appliance] "supermin: chroot" #498: +2997711451 [appliance] "Starting /init script ..." #499: +3157450020 [appliance] "[ 1.226613] ldconfig (378) used greatest stack depth: 12224 bytes left" #500: +3204763026 [appliance] "[ 1.273972] random: systemd-tmpfile urandom read with 71 bits of entropy available" #501: +3207461642 [appliance] "[/usr/lib/tmpfiles.d/journal-nocow.conf:26] Failed to replace specifiers: /var/log/journal/%m" #502: +3210506596 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:26] Failed to replace specifiers: /run/log/journal/%m" #503: +3210508049 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:28] Failed to replace specifiers: /run/log/journal/%m" #504: +3211618898 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:29] Failed to replace specifiers: /run/log/journal/%m" #505: +3212750010 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:32] Failed to replace specifiers: /var/log/journal/%m" #506: +3213857339 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:34] Failed to replace specifiers: /var/log/journal/%m" #507: +3215062678 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:35] Failed to replace specifiers: /var/log/journal/%m" #508: +3218644289 [appliance] "UDEVD=/usr/lib/systemd/systemd-udevd" #509: +3224182892 [appliance] "starting version 222" #510: +3311338812 [appliance] "[ 1.380518] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e452ea631d, max_idle_ns: 440795244572 ns" #511: +3324008166 [appliance] "[ 1.393213] udevadm (395) used greatest stack depth: 11392 bytes left" #512: +3375484756 [appliance] "[ 1.444684] piix4_smbus 0000:00:01.3: PCI->APIC IRQ transform: INT A -> IRQ 9" #513: +3385189443 [appliance] "[ 1.454430] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0" #514: +3425148928 [appliance] "[ 1.494361] random: nonblocking pool is initialized" #515: +3592392030 [appliance] "[ 1.661556] input: PC Speaker as /devices/platform/pcspkr/input/input4" #516: +3643641356 [appliance] "+ grep -sq guestfs_network=1 /proc/cmdline" #517: +3647933270 [appliance] "+ grep -sq guestfs_rescue=1 /proc/cmdline" #518: +3652091594 [appliance] "+ grep -sq guestfs_noreboot=1 /proc/cmdline" #519: +3658631442 [appliance] "+ grep -sq guestfs_boot_analysis=1 /proc/cmdline" #520: +3661091958 [appliance] "+ guestfs_boot_analysis=1" #521: +3661093265 [appliance] "++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline" #522: +3667007790 [appliance] "+ eval" #523: +3671090782 [appliance] "+ grep -sq selinux=1 /proc/cmdline" #524: +3675040249 [appliance] "+ shopt -s nullglob" #525: +3675041755 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #526: +3676249170 [appliance] "+ echo 300" #527: +3676250204 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #528: +3677357222 [appliance] "+ echo 300" #529: +3677358509 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #530: +3678444114 [appliance] "+ echo noop" #531: +3679960412 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #532: +3679961802 [appliance] "+ echo noop" #533: +3679962152 [appliance] "+ shopt -u nullglob" #534: +3681080993 [appliance] "+ ip addr add 127.0.0.1/8 brd + dev lo scope host" #535: +3686736446 [appliance] "+ ip link set dev lo up" #536: +3690058346 [appliance] "+ test '' = 1" #537: +3690059686 [appliance] "+ mdadm -As --auto=yes --run" #538: +3701984474 [appliance] "mdadm: No arrays found in config file or automatically" #539: +3701986274 [appliance] "+ modprobe dm_mod" #540: +3706126916 [appliance] "+ lvmetad" #541: +3714857336 [appliance] "+ lvm vgchange -aay --sysinit" #542: +3726028726 [appliance] " lvmetad is not active yet, using direct activation during sysinit" #543: +3731819569 [appliance] "+ ldmtool create all" #544: +3740770740 [appliance] "[" #545: +3740772118 [appliance] "]" #546: +3742634548 [appliance] "+ test 1 = 1" #547: +3742635818 [appliance] "+ test 1 '!=' 1" #548: +3742636211 [appliance] "+ test '' = 1" #549: +3742636382 [appliance] "+ cmd=guestfsd" #550: +3743747233 [appliance] "+ test x '!=' x" #551: +3743748510 [appliance] "+ test 1 = 1" #552: +3743748815 [appliance] "+ cmd='guestfsd --verbose'" #553: +3743748990 [appliance] "+ test '' = 1" #554: +3744910804 [appliance] "+ echo guestfsd --verbose" #555: +3744912119 [appliance] "guestfsd --verbose" #556: +3744912372 [appliance] "+ guestfsd --verbose" #557: +3762307722 [appliance] "trying to open virtio-serial channel '/dev/virtio-ports/org.libguestfs.channel.0'" #558: +3762309089 [appliance] "udevadm --debug settle" #559: +3768722562 [appliance] "calling: settle" #560: +3768895272 [launch_done] "launch done callback" #561: +3768908334 [library] "recv_from_daemon: received GUESTFS_LAUNCH_FLAG" #562: +3768910062 [library] "appliance is up" #563: +3768928838 [trace] "launch = 0" #564: +3768930551 [trace] "close" #565: +3768931708 [library] "closing guestfs handle 0x23237a0 (state 2)" #566: +3768935225 [trace] "internal_autosync" #567: +3772611445 [appliance] "guestfsd: main_loop: new request, len 0x28" #568: +3772613051 [appliance] "umount-all: /proc/mounts: fsname=/dev/root dir=/ type=ext2 opts=rw,noatime,block_validity,barrier,user_xattr,acl freq=0 passno=0" #569: +3773745144 [appliance] "umount-all: /proc/mounts: fsname=/proc dir=/proc type=proc opts=rw,relatime freq=0 passno=0" #570: +3774864891 [appliance] "umount-all: /proc/mounts: fsname=/sys dir=/sys type=sysfs opts=rw,relatime freq=0 passno=0" #571: +3775998938 [appliance] "umount-all: /proc/mounts: fsname=tmpfs dir=/run type=tmpfs opts=rw,nosuid,relatime,size=93972k,mode=755 freq=0 passno=0" #572: +3777131224 [appliance] "umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=231480k,nr_inodes=57870,mode=755 freq=0 passno=0" #573: +3784432155 [appliance] "fsync /dev/sda" #574: +3784434042 [appliance] "guestfsd: main_loop: proc 282 (internal_autosync) took 0.01 seconds" #575: +3785544697 [trace] "internal_autosync = 0" #576: +3785547452 [library] "sending SIGTERM to process 21839" #577: +3793209410 [close] "close callback" #578: +3793217919 [library] "command: run: rm" #579: +3793219204 [library] "command: run: \ -rf /home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfsEeios0" #580: +3795251482 [library] "command: run: rm" #581: +3795252981 [library] "command: run: \ -rf /tmp/libguestfsCaJzYS" pass 1 number of events collected 583 elapsed time 3802884535 ns #0: +172712 [trace] "launch" #1: +175125 [trace] "version" #2: +179413 [trace] "version = " #3: +196195 [trace] "get_backend" #4: +198879 [trace] "get_backend = "direct"" #5: +200295 [library] "launch: program=boot-analysis" #6: +201795 [library] "launch: version=1.33.15" #7: +202617 [library] "launch: backend registered: unix" #8: +203276 [library] "launch: backend registered: uml" #9: +203875 [library] "launch: backend registered: libvirt" #10: +204377 [library] "launch: backend registered: direct" #11: +204943 [library] "launch: backend=direct" #12: +223431 [library] "launch: tmpdir=/home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfs0OSUlT" #13: +291341 [library] "launch: umask=0002" #14: +293774 [library] "launch: euid=1000" #15: +319531 [trace] "get_backend_setting "force_tcg"" #16: +329317 [trace] "get_backend_setting = NULL (error)" #17: +346397 [trace] "get_cachedir" #18: +351231 [trace] "get_cachedir = "/home/kchamart/src/rwmj-fork-libguestfs/tmp"" #19: +376814 [library] "begin building supermin appliance" #20: +378064 [library] "run supermin" #21: +386014 [library] "command: run: /usr/bin/supermin" #22: +388347 [library] "command: run: \ --build" #23: +389724 [library] "command: run: \ --verbose" #24: +390981 [library] "command: run: \ --if-newer" #25: +392574 [library] "command: run: \ --lock /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/lock" #26: +394001 [library] "command: run: \ --copy-kernel" #27: +395457 [library] "command: run: \ -f ext2" #28: +396881 [library] "command: run: \ --host-cpu x86_64" #29: +398121 [library] "command: run: \ /home/kchamart/src/rwmj-fork-libguestfs/appliance/supermin.d" #30: +399677 [library] "command: run: \ -o /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d" #31: +12281293 [appliance] "supermin: version: 5.1.13" #32: +12282603 [appliance] "supermin: rpm: detected RPM version 4.13" #33: +12283390 [appliance] "supermin: package handler: fedora/rpm" #34: +12284006 [appliance] "supermin: acquiring lock on /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/lock" #35: +12284504 [appliance] "supermin: if-newer: output does not need rebuilding" #36: +15270678 [library] "finished building supermin appliance" #37: +15296145 [library] "begin testing qemu features" #38: +15309774 [library] "command: run: /usr/bin/qemu-kvm" #39: +15310671 [library] "command: run: \ -display none" #40: +15311320 [library] "command: run: \ -help" #41: +129567149 [library] "command: run: /usr/bin/qemu-kvm" #42: +129570192 [library] "command: run: \ -display none" #43: +129571147 [library] "command: run: \ -version" #44: +247264952 [library] "qemu version 2.4" #45: +247268685 [library] "command: run: /usr/bin/qemu-kvm" #46: +247269636 [library] "command: run: \ -display none" #47: +247270431 [library] "command: run: \ -machine accel=kvm:tcg" #48: +247271154 [library] "command: run: \ -device ?" #49: +366063471 [trace] "get_sockdir" #50: +366069595 [trace] "get_sockdir = "/tmp"" #51: +366227957 [library] "finished testing qemu features" #52: +366245617 [trace] "get_backend_setting "gdb"" #53: +366251445 [trace] "get_backend_setting = NULL (error)" #54: +368421027 [appliance] "[00367ms] /usr/bin/qemu-kvm \" #55: +368422826 [appliance] " -global virtio-blk-pci.scsi=off \" #56: +368423220 [appliance] " -nodefconfig \" #57: +368423654 [appliance] " -enable-fips \" #58: +368423973 [appliance] " -nodefaults \" #59: +368424343 [appliance] " -display none \" #60: +368424886 [appliance] " -machine accel=kvm:tcg \" #61: +368425237 [appliance] " -cpu host \" #62: +368425567 [appliance] " -m 500 \" #63: +368425896 [appliance] " -no-reboot \" #64: +368426200 [appliance] " -rtc driftfix=slew \" #65: +368426468 [appliance] " -no-hpet \" #66: +368426804 [appliance] " -global kvm-pit.lost_tick_policy=discard \" #67: +368427178 [appliance] " -kernel /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d/kernel \" #68: +368427694 [appliance] " -initrd /home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d/initrd \" #69: +368428177 [appliance] " -device virtio-scsi-pci,id=scsi \" #70: +368428710 [appliance] " -drive file=/home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfs0OSUlT/devnull1,cache=writeback,id=hd0,if=none \" #71: +368429206 [appliance] " -device scsi-hd,drive=hd0 \" #72: +368429650 [appliance] " -drive file=/home/kchamart/src/rwmj-fork-libguestfs/tmp/.guestfs-1000/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \" #73: +368430144 [appliance] " -device scsi-hd,drive=appliance \" #74: +368443174 [appliance] " -device virtio-serial-pci \" #75: +368443886 [appliance] " -serial stdio \" #76: +368444304 [appliance] " -device sga \" #77: +368444846 [appliance] " -chardev socket,path=/tmp/libguestfs9kjhzU/guestfsd.sock,id=channel0 \" #78: +368445378 [appliance] " -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \" #79: +368446040 [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=screen guestfs_boot_analysis=1'" #80: +485387805 [appliance] "WARNING: Image format was not specified for '/home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfs0OSUlT/devnull1' and probing guessed raw." #81: +485392208 [appliance] " Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted." #82: +485393705 [appliance] " Specify the 'raw' format explicitly to remove the restrictions." #83: +601306390 [appliance] "\x1b[1;256r\x1b[256;256H\x1b[6n" #84: +603350931 [appliance] "Google, Inc." #85: +603351778 [appliance] "Serial Graphics Adapter 06/19/15" #86: +603352513 [appliance] "SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (mockbuild@) Fri Jun 19 00:52:18 UTC 2015" #87: +604441357 [appliance] "Term: 80x24" #88: +604442887 [appliance] "4 0" #89: +604443530 [appliance] "\x1b[2J\x0dSeaBIOS (version 1.8.2-20150714_191134-)" #90: +718370347 [appliance] "\x0dBooting from ROM..." #91: +1876782099 [appliance] "\x0dProbing EDD (edd=off to disable)... ok" #92: +1876785148 [appliance] "\x1b[2J" #93: +2042048805 [appliance] "[ 0.000000] Initializing cgroup subsys cpuset" #94: +2042051524 [appliance] "[ 0.000000] Initializing cgroup subsys cpu" #95: +2043165297 [appliance] "[ 0.000000] Initializing cgroup subsys cpuacct" #96: +2043166087 [appliance] "[ 0.000000] Linux version 4.4.5-300.fc23.x86_64+debug (mockbuild@bkernel02.phx2.fedoraproject.org) (gcc version 5.3.1 20151207 (Red Hat 5.3.1-2) (GCC) ) #1 SMP Thu Mar 10 17:37:59 UTC 2016" #97: +2045368162 [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=screen guestfs_boot_analysis=1" #98: +2047557896 [appliance] "[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256" #99: +2048662858 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x01: 'x87 floating point registers'" #100: +2049767614 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x02: 'SSE registers'" #101: +2050871460 [appliance] "[ 0.000000] x86/fpu: Supporting XSAVE feature 0x04: 'AVX registers'" #102: +2051968375 [appliance] "[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format." #103: +2053067557 [appliance] "[ 0.000000] x86/fpu: Using 'eager' FPU context switches." #104: +2053068246 [appliance] "[ 0.000000] e820: BIOS-provided physical RAM map:" #105: +2054162416 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable" #106: +2055253180 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved" #107: +2055253882 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved" #108: +2056344683 [appliance] "[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3dffff] usable" #109: +2057439245 [appliance] "[ 0.000000] BIOS-e820: [mem 0x000000001f3e0000-0x000000001f3fffff] reserved" #110: +2058543101 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved" #111: +2059637974 [appliance] "[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved" #112: +2060738999 [appliance] "[ 0.000000] NX (Execute Disable) protection: active" #113: +2060739739 [appliance] "[ 0.000000] SMBIOS 2.8 present." #114: +2061829765 [appliance] "[ 0.000000] Hypervisor detected: KVM" #115: +2061830487 [appliance] "[ 0.000000] e820: last_pfn = 0x1f3e0 max_arch_pfn = 0x400000000" #116: +2062931513 [appliance] "[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT " #117: +2064022461 [appliance] "[ 0.000000] found SMP MP-table at [mem 0x000f64d0-0x000f64df] mapped at [ffff8800000f64d0]" #118: +2065116561 [appliance] "[ 0.000000] Scanning 1 areas for low memory corruption" #119: +2065117321 [appliance] "[ 0.000000] Using GB pages for direct mapping" #120: +2066223189 [appliance] "[ 0.000000] RAMDISK: [mem 0x1f277000-0x1f3dffff]" #121: +2066223893 [appliance] "[ 0.000000] No NUMA configuration found" #122: +2067316302 [appliance] "[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3dffff]" #123: +2067316998 [appliance] "[ 0.000000] NODE_DATA(0) allocated [mem 0x1f264000-0x1f276fff]" #124: +2068419394 [appliance] "[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00" #125: +2069520223 [appliance] "[ 0.000000] kvm-clock: cpu 0, msr 0:1f254001, primary cpu clock" #126: +2069520909 [appliance] "[ 0.000000] kvm-clock: using sched offset of 1431238474 cycles" #127: +2070623416 [appliance] "[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns" #128: +2071724030 [appliance] "[ 0.000000] Zone ranges:" #129: +2072821717 [appliance] "[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]" #130: +2072822387 [appliance] "[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001f3dffff]" #131: +2073926305 [appliance] "[ 0.000000] Normal empty" #132: +2075044853 [appliance] "[ 0.000000] Movable zone start for each node" #133: +2075045515 [appliance] "[ 0.000000] Early memory node ranges" #134: +2075045938 [appliance] "[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]" #135: +2076139186 [appliance] "[ 0.000000] node 0: [mem 0x0000000000100000-0x000000001f3dffff]" #136: +2077232516 [appliance] "[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3dffff]" #137: +2078333320 [appliance] "[ 0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org" #138: +2079448640 [appliance] "[ 0.000000] Intel MultiProcessor Specification v1.4" #139: +2079449440 [appliance] "[ 0.000000] MPTABLE: OEM ID: BOCHSCPU" #140: +2080556995 [appliance] "[ 0.000000] MPTABLE: Product ID: 0.1 " #141: +2080558441 [appliance] "[ 0.000000] MPTABLE: APIC at: 0xFEE00000" #142: +2081667361 [appliance] "[ 0.000000] Processor #0 (Bootup-CPU)" #143: +2081668768 [appliance] "[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23" #144: +2082776919 [appliance] "[ 0.000000] Processors: 1" #145: +2082778276 [appliance] "[ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs" #146: +2083948556 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]" #147: +2085059577 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]" #148: +2085061256 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]" #149: +2086179325 [appliance] "[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]" #150: +2087297074 [appliance] "[ 0.000000] e820: [mem 0x1f400000-0xfeffbfff] available for PCI devices" #151: +2088406427 [appliance] "[ 0.000000] Booting paravirtualized kernel on KVM" #152: +2088407823 [appliance] "[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns" #153: +2089514209 [appliance] "[ 0.000000] setup_percpu: NR_CPUS:1024 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1" #154: +2090629954 [appliance] "[ 0.000000] PERCPU: Embedded 483 pages/cpu @ffff88001ec00000 s1937944 r8192 d32232 u2097152" #155: +2091739564 [appliance] "[ 0.000000] KVM setup async PF for cpu 0" #156: +2092850458 [appliance] "[ 0.000000] kvm-stealtime: cpu 0, msr 1ec0db40" #157: +2092851860 [appliance] "[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 125849" #158: +2093959171 [appliance] "[ 0.000000] Policy zone: DMA32" #159: +2095076019 [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=screen guestfs_boot_analysis=1" #160: +2097302820 [appliance] "[ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)" #161: +2098418603 [appliance] "[ 0.000000] Memory: 462932K/511480K available (8747K kernel code, 1490K rwdata, 3660K rodata, 3536K init, 16580K bss, 48548K reserved, 0K cma-reserved)" #162: +2099528532 [appliance] "[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1" #163: +2100636350 [appliance] "[ 0.000000] Running RCU self tests" #164: +2100637684 [appliance] "[ 0.000000] Hierarchical RCU implementation." #165: +2101771764 [appliance] "[ 0.000000] \x09RCU lockdep checking is enabled." #166: +2101773064 [appliance] "[ 0.000000] \x09Build-time adjustment of leaf fanout to 64." #167: +2102880069 [appliance] "[ 0.000000] \x09RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=1." #168: +2103990005 [appliance] "[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1" #169: +2105107044 [appliance] "[ 0.000000] kmemleak: Kernel memory leak detector disabled" #170: +2105108353 [appliance] "[ 0.000000] NR_IRQS:65792 nr_irqs:256 16" #171: +2106235613 [appliance] "[ 0.000000] \x09Offload RCU callbacks from all CPUs" #172: +2106236879 [appliance] "[ 0.000000] \x09Offload RCU callbacks from CPUs: 0." #173: +2107342171 [appliance] "[ 0.000000] Console: colour *CGA 80x25" #174: +2107343505 [appliance] "[ 0.000000] console [ttyS0] enabled" #175: +2108459894 [appliance] "[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar" #176: +2109572875 [appliance] "[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8" #177: +2109574192 [appliance] "[ 0.000000] ... MAX_LOCK_DEPTH: 48" #178: +2110693297 [appliance] "[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191" #179: +2110694573 [appliance] "[ 0.000000] ... CLASSHASH_SIZE: 4096" #180: +2111803243 [appliance] "[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768" #181: +2111804189 [appliance] "[ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536" #182: +2112925298 [appliance] "[ 0.000000] ... CHAINHASH_SIZE: 32768" #183: +2112929375 [appliance] "[ 0.000000] memory used by lock dependency info: 8671 kB" #184: +2114032778 [appliance] "[ 0.000000] per task-struct memory footprint: 2688 bytes" #185: +2115680875 [appliance] "[ 0.000000] tsc: Detected 2099.998 MHz processor" #186: +2115682559 [appliance] "[ 0.122980] Calibrating delay loop (skipped) preset value.. 4199.99 BogoMIPS (lpj=2099998)" #187: +2116787481 [appliance] "[ 0.124130] pid_max: default: 32768 minimum: 301" #188: +2117901239 [appliance] "[ 0.124856] Security Framework initialized" #189: +2117902660 [appliance] "[ 0.125397] Yama: becoming mindful." #190: +2119002076 [appliance] "[ 0.125888] SELinux: Disabled at boot." #191: +2119003482 [appliance] "[ 0.126575] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)" #192: +2120106510 [appliance] "[ 0.127696] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)" #193: +2121223186 [appliance] "[ 0.128699] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)" #194: +2122330432 [appliance] "[ 0.129595] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)" #195: +2125085463 [appliance] "[ 0.131625] Initializing cgroup subsys io" #196: +2125087100 [appliance] "[ 0.132245] Initializing cgroup subsys memory" #197: +2126200019 [appliance] "[ 0.133162] Disabling memory control group subsystem" #198: +2126201504 [appliance] "[ 0.133823] Initializing cgroup subsys devices" #199: +2127306883 [appliance] "[ 0.134456] Initializing cgroup subsys freezer" #200: +2128419412 [appliance] "[ 0.135124] Initializing cgroup subsys net_cls" #201: +2128420755 [appliance] "[ 0.135712] Initializing cgroup subsys perf_event" #202: +2129535635 [appliance] "[ 0.136364] Initializing cgroup subsys net_prio" #203: +2129537065 [appliance] "[ 0.136972] Initializing cgroup subsys hugetlb" #204: +2130634863 [appliance] "[ 0.137586] Initializing cgroup subsys pids" #205: +2132574841 [appliance] "[ 0.139122] mce: CPU supports 10 MCE banks" #206: +2132576461 [appliance] "[ 0.139761] Last level iTLB entries: 4KB 512, 2MB 8, 4MB 8" #207: +2133688186 [appliance] "[ 0.140494] Last level dTLB entries: 4KB 512, 2MB 0, 4MB 0, 1GB 4" #208: +2149916211 [appliance] "[ 0.156404] Freeing SMP alternatives memory: 28K (ffffffff822ea000 - ffffffff822f1000)" #209: +2155198262 [appliance] "[ 0.161758] ftrace: allocating 29768 entries in 117 pages" #210: +2193801289 [appliance] "[ 0.200340] x2apic enabled" #211: +2193802869 [appliance] "[ 0.201042] Switched APIC routing to physical x2apic." #212: +2196302026 [appliance] "[ 0.202857] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1" #213: +2196304499 [appliance] "[ 0.203684] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2620 v2 @ 2.10GHz (family: 0x6, model: 0x3e, stepping: 0x4)" #214: +2197406881 [appliance] "[ 0.205076] Performance Events: 16-deep LBR, IvyBridge events, Intel PMU driver." #215: +2200389478 [appliance] "[ 0.207205] ... version: 2" #216: +2200390892 [appliance] "[ 0.207719] ... bit width: 48" #217: +2201499338 [appliance] "[ 0.208269] ... generic registers: 4" #218: +2201500451 [appliance] "[ 0.208793] ... value mask: 0000ffffffffffff" #219: +2202610633 [appliance] "[ 0.209505] ... max period: 000000007fffffff" #220: +2202611802 [appliance] "[ 0.210172] ... fixed-purpose events: 3" #221: +2203717738 [appliance] "[ 0.210722] ... event mask: 000000070000000f" #222: +2206556718 [appliance] "[ 0.213094] x86: Booted up 1 node, 1 CPUs" #223: +2206558396 [appliance] "[ 0.213654] smpboot: Total of 1 processors activated (4199.99 BogoMIPS)" #224: +2209363370 [appliance] "[ 0.215928] devtmpfs: initialized" #225: +2213231006 [appliance] "[ 0.219760] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns" #226: +2214341150 [appliance] "[ 0.221316] atomic64_test: passed for x86-64 platform with CX8 and with SSE" #227: +2215449757 [appliance] "[ 0.222228] pinctrl core: initialized pinctrl subsystem" #228: +2216598624 [appliance] "[ 0.223195] RTC time: 11:22:26, date: 03/21/16" #229: +2216600238 [appliance] "[ 0.224232] NET: Registered protocol family 16" #230: +2218987630 [appliance] "[ 0.225574] cpuidle: using governor menu" #231: +2218989305 [appliance] "[ 0.226612] PCI: Using configuration type 1 for base access" #232: +2220082914 [appliance] "[ 0.227717] perf_event_intel: PMU erratum BJ122, BV98, HSD29 workaround disabled, HT off" #233: +2229070028 [appliance] "[ 0.235622] ACPI: Interpreter disabled." #234: +2229071659 [appliance] "[ 0.236664] vgaarb: loaded" #235: +2230168287 [appliance] "[ 0.237344] SCSI subsystem initialized" #236: +2231529339 [appliance] "[ 0.238099] usbcore: registered new interface driver usbfs" #237: +2231531037 [appliance] "[ 0.238853] usbcore: registered new interface driver hub" #238: +2232632722 [appliance] "[ 0.239593] usbcore: registered new device driver usb" #239: +2233945110 [appliance] "[ 0.240519] PCI: Probing PCI hardware" #240: +2233946817 [appliance] "[ 0.241183] PCI host bridge to bus 0000:00" #241: +2235053402 [appliance] "[ 0.241723] pci_bus 0000:00: root bus resource [io 0x0000-0xffff]" #242: +2235054847 [appliance] "[ 0.242516] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff]" #243: +2236160798 [appliance] "[ 0.243434] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]" #244: +2244765500 [appliance] "[ 0.251313] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]" #245: +2244767236 [appliance] "[ 0.252242] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]" #246: +2245883368 [appliance] "[ 0.253116] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]" #247: +2246991971 [appliance] "[ 0.254069] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]" #248: +2248079833 [appliance] "[ 0.255611] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI" #249: +2250683200 [appliance] "[ 0.257707] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB" #250: +2272275520 [appliance] "[ 0.278831] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000]" #251: +2276073856 [appliance] "[ 0.282612] NetLabel: Initializing" #252: +2276075490 [appliance] "[ 0.283108] NetLabel: domain hash size = 128" #253: +2276076000 [appliance] "[ 0.283661] NetLabel: protocols = UNLABELED CIPSOv4" #254: +2277189380 [appliance] "[ 0.284368] NetLabel: unlabeled traffic allowed by default" #255: +2278290445 [appliance] "[ 0.285343] clocksource: Switched to clocksource kvm-clock" #256: +2320248181 [appliance] "[ 0.326753] pnp: PnP ACPI: disabled" #257: +2327213040 [appliance] "[ 0.333756] NET: Registered protocol family 2" #258: +2328532442 [appliance] "[ 0.335105] TCP established hash table entries: 4096 (order: 3, 32768 bytes)" #259: +2328534042 [appliance] "[ 0.336057] TCP bind hash table entries: 4096 (order: 6, 327680 bytes)" #260: +2329633801 [appliance] "[ 0.337131] TCP: Hash tables configured (established 4096 bind 4096)" #261: +2330733934 [appliance] "[ 0.338178] UDP hash table entries: 256 (order: 3, 49152 bytes)" #262: +2331841482 [appliance] "[ 0.339018] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes)" #263: +2332950416 [appliance] "[ 0.340062] NET: Registered protocol family 1" #264: +2334063158 [appliance] "[ 0.340637] pci 0000:00:00.0: Limiting direct PCI/PCI transfers" #265: +2334064625 [appliance] "[ 0.341403] pci 0000:00:01.0: PIIX3: Enabling Passive Release" #266: +2335172804 [appliance] "[ 0.342173] pci 0000:00:01.0: Activating ISA DMA hang workarounds" #267: +2336262706 [appliance] "[ 0.343319] Unpacking initramfs..." #268: +2341370123 [appliance] "[ 0.347917] Freeing initrd memory: 1444K (ffff88001f277000 - ffff88001f3e0000)" #269: +2361018732 [appliance] "[ 0.367482] DMA-API: preallocated 65536 debug entries" #270: +2361021263 [appliance] "[ 0.368213] DMA-API: debugging enabled by kernel config" #271: +2362124463 [appliance] "[ 0.369205] platform rtc_cmos: registered platform RTC device (no PNP device found)" #272: +2364981926 [appliance] "[ 0.371530] Scanning for low memory corruption every 60 seconds" #273: +2367066347 [appliance] "[ 0.373604] futex hash table entries: 256 (order: 3, 32768 bytes)" #274: +2367067919 [appliance] "[ 0.374527] audit: initializing netlink subsys (disabled)" #275: +2368215819 [appliance] "[ 0.375354] audit: type=2000 audit(1458559347.135:1): initialized" #276: +2369312811 [appliance] "[ 0.376531] cryptomgr_test (27) used greatest stack depth: 14040 bytes left" #277: +2371532133 [appliance] "[ 0.378123] Initialise system trusted keyring" #278: +2372690458 [appliance] "[ 0.379262] HugeTLB registered 1 GB page size, pre-allocated 0 pages" #279: +2372698931 [appliance] "[ 0.380125] HugeTLB registered 2 MB page size, pre-allocated 0 pages" #280: +2383580706 [appliance] "[ 0.390153] zbud: loaded" #281: +2384746688 [appliance] "[ 0.391310] VFS: Disk quotas dquot_6.6.0" #282: +2384748298 [appliance] "[ 0.392029] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)" #283: +2388805070 [appliance] "[ 0.395372] Key type big_key registered" #284: +2390440712 [appliance] "[ 0.396998] cryptomgr_test (35) used greatest stack depth: 13816 bytes left" #285: +2391717908 [appliance] "[ 0.398296] cryptomgr_test (37) used greatest stack depth: 13688 bytes left" #286: +2395897334 [appliance] "[ 0.402452] cryptomgr_test (53) used greatest stack depth: 13312 bytes left" #287: +2398528748 [appliance] "[ 0.405067] NET: Registered protocol family 38" #288: +2398530241 [appliance] "[ 0.405703] Key type asymmetric registered" #289: +2399630484 [appliance] "[ 0.406279] Asymmetric key parser 'x509' registered" #290: +2399631763 [appliance] "[ 0.407088] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)" #291: +2400732152 [appliance] "[ 0.408325] io scheduler noop registered" #292: +2401833955 [appliance] "[ 0.408942] io scheduler deadline registered" #293: +2403127634 [appliance] "[ 0.409717] io scheduler cfq registered (default)" #294: +2406110686 [appliance] "[ 0.412656] pci_hotplug: PCI Hot Plug PCI Core version: 0.5" #295: +2406112164 [appliance] "[ 0.413435] pciehp: PCI Express Hot Plug Controller Driver version: 0.4" #296: +2407215315 [appliance] "[ 0.414745] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled" #297: +2433753169 [appliance] "[ 0.440309] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A" #298: +2441362297 [appliance] "[ 0.447914] Non-volatile memory driver v1.3" #299: +2441363824 [appliance] "[ 0.448620] Linux agpgart interface v0.103" #300: +2446057242 [appliance] "[ 0.452618] scsi host0: ata_piix" #301: +2446058710 [appliance] "[ 0.453526] scsi host1: ata_piix" #302: +2447164294 [appliance] "[ 0.454127] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14" #303: +2448265156 [appliance] "[ 0.455011] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15" #304: +2450138497 [appliance] "[ 0.456693] libphy: Fixed MDIO Bus: probed" #305: +2450140002 [appliance] "[ 0.457463] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver" #306: +2451243894 [appliance] "[ 0.458325] ehci-pci: EHCI PCI platform driver" #307: +2452367502 [appliance] "[ 0.458947] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver" #308: +2452368975 [appliance] "[ 0.459766] ohci-pci: OHCI PCI platform driver" #309: +2453478147 [appliance] "[ 0.460395] uhci_hcd: USB Universal Host Controller Interface driver" #310: +2454576446 [appliance] "[ 0.461666] usbcore: registered new interface driver usbserial" #311: +2455676570 [appliance] "[ 0.462452] usbcore: registered new interface driver usbserial_generic" #312: +2456854775 [appliance] "[ 0.463434] usbserial: USB Serial support registered for generic" #313: +2456856304 [appliance] "[ 0.464282] i8042: PNP: No PS/2 controller found. Probing ports directly." #314: +2459563162 [appliance] "[ 0.466124] serio: i8042 KBD port at 0x60,0x64 irq 1" #315: +2459564679 [appliance] "[ 0.466857] serio: i8042 AUX port at 0x60,0x64 irq 12" #316: +2460659103 [appliance] "[ 0.468222] mousedev: PS/2 mouse device common for all mice" #317: +2463329367 [appliance] "[ 0.469876] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0" #318: +2467110713 [appliance] "[ 0.473655] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3" #319: +2468381890 [appliance] "[ 0.474959] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0" #320: +2468383399 [appliance] "[ 0.475906] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram" #321: +2469484181 [appliance] "[ 0.476960] device-mapper: uevent: version 1.0.3" #322: +2470574173 [appliance] "[ 0.478141] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2" #323: +2473096317 [appliance] "[ 0.479650] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com" #324: +2475049808 [appliance] "[ 0.481602] hidraw: raw HID events driver (C) Jiri Kosina" #325: +2475051249 [appliance] "[ 0.482479] usbcore: registered new interface driver usbhid" #326: +2476152294 [appliance] "[ 0.483224] usbhid: USB HID core driver" #327: +2477394457 [appliance] "[ 0.483977] drop_monitor: Initializing network drop monitor service" #328: +2477395969 [appliance] "[ 0.485025] ip_tables: (C) 2000-2006 Netfilter Core Team" #329: +2478498706 [appliance] "[ 0.485857] Initializing XFRM netlink socket" #330: +2480910009 [appliance] "[ 0.487495] NET: Registered protocol family 10" #331: +2482515751 [appliance] "[ 0.489050] mip6: Mobile IPv6" #332: +2482517251 [appliance] "[ 0.489492] NET: Registered protocol family 17" #333: +2484115486 [appliance] "[ 0.490632] microcode: CPU0 sig=0x306e4, pf=0x1, revision=0x1" #334: +2484117027 [appliance] "[ 0.491490] microcode: Microcode Update Driver: v2.01 , Peter Oruba" #335: +2485251193 [appliance] "[ 0.492659] AVX version of gcm_enc/dec engaged." #336: +2486376833 [appliance] "[ 0.493271] AES CTR mode by8 optimization enabled" #337: +2488307946 [appliance] "[ 0.494820] cryptomgr_test (85) used greatest stack depth: 13224 bytes left" #338: +2515890694 [appliance] "[ 0.522407] registered taskstats version 1" #339: +2515892153 [appliance] "[ 0.523038] Loading compiled-in X.509 certificates" #340: +2518221927 [appliance] "[ 0.524772] Loaded X.509 cert 'Fedora kernel signing key: d33a3fa294f19598ff71e1ac24c3cdc70d17052c'" #341: +2519375591 [appliance] "[ 0.525951] cryptomgr_test (100) used greatest stack depth: 12696 bytes left" #342: +2519377056 [appliance] "[ 0.527037] zswap: loaded using pool lzo/zbud" #343: +2522626246 [appliance] "[ 0.529081] Magic number: 8:547:378" #344: +2522627783 [appliance] "[ 0.529741] rtc_cmos rtc_cmos: setting system clock to 2016-03-21 11:22:26 UTC (1458559346)" #345: +2609862935 [appliance] "[ 0.616357] Freeing unused kernel memory: 3536K (ffffffff81f76000 - ffffffff822ea000)" #346: +2610973588 [appliance] "[ 0.617876] Write protecting the kernel read-only data: 14336k" #347: +2613627017 [appliance] "[ 0.620134] Freeing unused kernel memory: 1480K (ffff88000188e000 - ffff880001a00000)" #348: +2616995640 [appliance] "[ 0.623480] Freeing unused kernel memory: 436K (ffff880001d93000 - ffff880001e00000)" #349: +2618932388 [appliance] "supermin: mounting /proc" #350: +2618933752 [appliance] "supermin: uptime: 0.45 0.13" #351: +2618934001 [appliance] "supermin: ext2 mini initrd starting up: 5.1.13 zlib xz" #352: +2620054174 [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=screen guestfs_boot_analysis=1" #353: +2622346480 [appliance] "supermin: mounting /sys" #354: +2622347833 [appliance] "supermin: internal insmod crc32-pclmul.ko.xz" #355: +2622348160 [appliance] "supermin: running xz" #356: +2623441074 [appliance] "done with xz 8587 read" #357: +2626110836 [appliance] "supermin: internal insmod crc32c-intel.ko.xz" #358: +2626112254 [appliance] "supermin: running xz" #359: +2627697666 [appliance] "done with xz 20019 read" #360: +2629599890 [appliance] "supermin: internal insmod crct10dif-pclmul.ko.xz" #361: +2629601313 [appliance] "supermin: running xz" #362: +2630968971 [appliance] "done with xz 11131 read" #363: +2632609027 [appliance] "supermin: internal insmod crc32.ko.xz" #364: +2632610497 [appliance] "supermin: running xz" #365: +2632610773 [appliance] "done with xz 6403 read" #366: +2635441180 [appliance] "supermin: internal insmod virtio.ko.xz" #367: +2635442627 [appliance] "supermin: running xz" #368: +2636734906 [appliance] "done with xz 15475 read" #369: +2638547944 [appliance] "supermin: internal insmod virtio_ring.ko.xz" #370: +2638549374 [appliance] "supermin: running xz" #371: +2640103849 [appliance] "done with xz 20051 read" #372: +2641861905 [appliance] "supermin: internal insmod virtio_blk.ko.xz" #373: +2641863403 [appliance] "supermin: running xz" #374: +2643610833 [appliance] "done with xz 24083 read" #375: +2645488801 [appliance] "supermin: internal insmod virtio-rng.ko.xz" #376: +2645490250 [appliance] "supermin: running xz" #377: +2646646636 [appliance] "done with xz 9123 read" #378: +2648375893 [appliance] "supermin: internal insmod virtio_console.ko.xz" #379: +2648377297 [appliance] "supermin: running xz" #380: +2650792074 [appliance] "done with xz 45811 read" #381: +2653005505 [appliance] "supermin: internal insmod drm.ko.xz" #382: +2653006859 [appliance] "supermin: running xz" #383: +2668409524 [appliance] "done with xz 548859 read" #384: +2676568766 [appliance] "[ 0.683102] [drm] Initialized drm 1.1.0 20060810" #385: +2676570334 [appliance] "supermin: internal insmod drm_kms_helper.ko.xz" #386: +2677693883 [appliance] "supermin: running xz" #387: +2684250407 [appliance] "done with xz 187291 read" #388: +2687985054 [appliance] "supermin: internal insmod ttm.ko.xz" #389: +2687986437 [appliance] "supermin: running xz" #390: +2693132764 [appliance] "done with xz 145403 read" #391: +2696545984 [appliance] "supermin: internal insmod virtio-gpu.ko.xz" #392: +2696546715 [appliance] "supermin: running xz" #393: +2700255535 [appliance] "done with xz 94891 read" #394: +2703142254 [appliance] "supermin: internal insmod ideapad_slidebar.ko.xz" #395: +2703142787 [appliance] "supermin: running xz" #396: +2704437533 [appliance] "done with xz 13523 read" #397: +2706059569 [appliance] "[ 0.712659] ideapad_slidebar: DMI does not match" #398: +2707163037 [appliance] "insmod: init_module: ideapad_slidebar.ko.xz: No such device" #399: +2707163700 [appliance] "supermin: internal insmod virtio_net.ko.xz" #400: +2707164036 [appliance] "supermin: running xz" #401: +2710378730 [appliance] "done with xz 49059 read" #402: +2712516559 [appliance] "supermin: internal insmod video.ko.xz" #403: +2712517294 [appliance] "supermin: running xz" #404: +2714867190 [appliance] "done with xz 53483 read" #405: +2716997370 [appliance] "supermin: internal insmod sparse-keymap.ko.xz" #406: +2716997894 [appliance] "supermin: running xz" #407: +2718205659 [appliance] "done with xz 9051 read" #408: +2719799360 [appliance] "supermin: internal insmod wmi.ko.xz" #409: +2719808303 [appliance] "supermin: running xz" #410: +2721373751 [appliance] "done with xz 21379 read" #411: +2723260227 [appliance] "insmod: init_module: wmi.ko.xz: No such device" #412: +2723260897 [appliance] "supermin: internal insmod rfkill.ko.xz" #413: +2723261138 [appliance] "supermin: running xz" #414: +2725861085 [appliance] "done with xz 44163 read" #415: +2728055503 [appliance] "supermin: internal insmod ideapad-laptop.ko.xz" #416: +2728056288 [appliance] "supermin: running xz" #417: +2729837281 [appliance] "done with xz 29379 read" #418: +2731357955 [appliance] "[ 0.737948] ideapad_laptop: Unknown symbol wmi_remove_notify_handler (err 0)" #419: +2731358621 [appliance] "[ 0.738906] ideapad_laptop: Unknown symbol wmi_install_notify_handler (err 0)" #420: +2732458468 [appliance] "insmod: init_module: ideapad-laptop.ko.xz: Unknown symbol in module" #421: +2733550832 [appliance] "supermin: internal insmod megaraid.ko.xz" #422: +2733551429 [appliance] "supermin: running xz" #423: +2737538448 [appliance] "done with xz 76995 read" #424: +2739959564 [appliance] "supermin: internal insmod megaraid_mm.ko.xz" #425: +2739960126 [appliance] "supermin: running xz" #426: +2741624679 [appliance] "done with xz 22363 read" #427: +2743364312 [appliance] "[ 0.749955] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006)" #428: +2744518495 [appliance] "supermin: internal insmod megaraid_mbox.ko.xz" #429: +2744519175 [appliance] "supermin: running xz" #430: +2747522802 [appliance] "done with xz 59331 read" #431: +2749612253 [appliance] "[ 0.756201] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006)" #432: +2749613303 [appliance] "supermin: internal insmod megaraid_sas.ko.xz" #433: +2750700101 [appliance] "supermin: running xz" #434: +2757666997 [appliance] "done with xz 182987 read" #435: +2761093272 [appliance] "[ 0.767678] megasas: 06.808.16.00-rc1" #436: +2761093898 [appliance] "supermin: internal insmod scsi_transport_spi.ko.xz" #437: +2762261851 [appliance] "supermin: running xz" #438: +2764058169 [appliance] "done with xz 46795 read" #439: +2766110536 [appliance] "supermin: internal insmod sym53c8xx.ko.xz" #440: +2766111303 [appliance] "supermin: running xz" #441: +2771314990 [appliance] "done with xz 120395 read" #442: +2774078044 [appliance] "supermin: internal insmod virtio_scsi.ko.xz" #443: +2774078608 [appliance] "supermin: running xz" #444: +2775953816 [appliance] "done with xz 27971 read" #445: +2777932565 [appliance] "supermin: internal insmod virtio_balloon.ko.xz" #446: +2777933073 [appliance] "supermin: running xz" #447: +2779476976 [appliance] "done with xz 18811 read" #448: +2781291233 [appliance] "supermin: internal insmod virtio_input.ko.xz" #449: +2781291750 [appliance] "supermin: running xz" #450: +2782633785 [appliance] "done with xz 13307 read" #451: +2784394048 [appliance] "supermin: internal insmod virtio_mmio.ko.xz" #452: +2784394675 [appliance] "supermin: running xz" #453: +2785811459 [appliance] "done with xz 14147 read" #454: +2787507857 [appliance] "supermin: internal insmod virtio_pci.ko.xz" #455: +2787508504 [appliance] "supermin: running xz" #456: +2789513068 [appliance] "done with xz 32355 read" #457: +2791476441 [appliance] "[ 0.798070] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 10" #458: +2791477119 [appliance] "[ 0.799110] virtio-pci 0000:00:02.0: virtio_pci: leaving for legacy driver" #459: +2794906147 [appliance] "[ 0.801514] scsi host2: Virtio SCSI HBA" #460: +2796453428 [appliance] "[ 0.803048] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 11" #461: +2796454101 [appliance] "[ 0.804057] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver" #462: +2800071538 [appliance] "[ 0.806659] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.4. PQ: 0 ANSI: 5" #463: +2805775098 [appliance] "[ 0.812359] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.4. PQ: 0 ANSI: 5" #464: +2946444995 [appliance] "[ 0.953024] sd 2:0:0:0: [sda] 8 512-byte logical blocks: (4.10 kB/4.00 KiB)" #465: +2948358185 [appliance] "[ 0.954953] sd 2:0:0:0: Attached scsi generic sg0 type 0" #466: +2948358862 [appliance] "[ 0.955982] sd 2:0:0:0: [sda] Write Protect is off" #467: +2950851162 [appliance] "[ 0.957394] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #468: +2951939318 [appliance] "[ 0.958859] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)" #469: +2953033573 [appliance] "[ 0.960316] sd 2:0:1:0: [sdb] Write Protect is off" #470: +2954134665 [appliance] "[ 0.961150] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA" #471: +2956345881 [appliance] "[ 0.962901] sd 2:0:1:0: Attached scsi generic sg1 type 0" #472: +2959044917 [appliance] "[ 0.965586] Dev sda: unable to read RDB block 8" #473: +2959045491 [appliance] "[ 0.966272] sda: unable to read partition table" #474: +2960137699 [appliance] "[ 0.966928] sda: partition table beyond EOD, enabling native capacity" #475: +2963013893 [appliance] "[ 0.969601] sd 2:0:1:0: [sdb] Attached SCSI disk" #476: +2963014553 [appliance] "[ 0.970454] Dev sda: unable to read RDB block 8" #477: +2964113828 [appliance] "[ 0.971085] sda: unable to read partition table" #478: +2964114379 [appliance] "[ 0.971719] sda: partition table beyond EOD, truncated" #479: +2966805076 [appliance] "[ 0.973392] sd 2:0:0:0: [sda] Attached SCSI disk" #480: +2966805644 [appliance] "supermin: internal insmod crc-ccitt.ko.xz" #481: +2967966505 [appliance] "supermin: running xz" #482: +2967966949 [appliance] "done with xz 4843 read" #483: +2970029450 [appliance] "supermin: internal insmod crc-itu-t.ko.xz" #484: +2970030122 [appliance] "supermin: running xz" #485: +2971121546 [appliance] "done with xz 4843 read" #486: +2972589816 [appliance] "supermin: internal insmod crc8.ko.xz" #487: +2972590353 [appliance] "supermin: running xz" #488: +2972590517 [appliance] "done with xz 4651 read" #489: +2975123482 [appliance] "supermin: internal insmod libcrc32c.ko.xz" #490: +2975123995 [appliance] "supermin: running xz" #491: +2976208253 [appliance] "done with xz 5667 read" #492: +2978110365 [appliance] "supermin: picked /sys/block/sdb/dev as root device" #493: +2979255184 [appliance] "supermin: creating /dev/root as block special 8:16" #494: +2979255880 [appliance] "supermin: mounting new root on /root" #495: +2980838245 [appliance] "[ 0.987430] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem" #496: +2986653718 [appliance] "[ 0.993231] EXT4-fs (sdb): mounted filesystem without journal. Opts: " #497: +2986654410 [appliance] "supermin: chroot" #498: +3007602700 [appliance] "Starting /init script ..." #499: +3149458674 [appliance] "[ 1.155930] ldconfig (378) used greatest stack depth: 12112 bytes left" #500: +3197642328 [appliance] "[ 1.204196] random: systemd-tmpfile urandom read with 72 bits of entropy available" #501: +3200245772 [appliance] "[/usr/lib/tmpfiles.d/journal-nocow.conf:26] Failed to replace specifiers: /var/log/journal/%m" #502: +3203073710 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:26] Failed to replace specifiers: /run/log/journal/%m" #503: +3203074659 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:28] Failed to replace specifiers: /run/log/journal/%m" #504: +3204178479 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:29] Failed to replace specifiers: /run/log/journal/%m" #505: +3205284937 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:32] Failed to replace specifiers: /var/log/journal/%m" #506: +3206387632 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:34] Failed to replace specifiers: /var/log/journal/%m" #507: +3207480132 [appliance] "[/usr/lib/tmpfiles.d/systemd.conf:35] Failed to replace specifiers: /var/log/journal/%m" #508: +3211148797 [appliance] "UDEVD=/usr/lib/systemd/systemd-udevd" #509: +3216420746 [appliance] "starting version 222" #510: +3318987896 [appliance] "[ 1.325521] udevadm (395) used greatest stack depth: 11552 bytes left" #511: +3357880001 [appliance] "[ 1.364418] piix4_smbus 0000:00:01.3: PCI->APIC IRQ transform: INT A -> IRQ 9" #512: +3362934411 [appliance] "[ 1.369503] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e452ea631d, max_idle_ns: 440795244572 ns" #513: +3365506056 [appliance] "[ 1.372101] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0" #514: +3404506506 [appliance] "[ 1.411066] scsi_id (409) used greatest stack depth: 10752 bytes left" #515: +3445220228 [appliance] "[ 1.451747] random: nonblocking pool is initialized" #516: +3611921737 [appliance] "[ 1.618310] input: PC Speaker as /devices/platform/pcspkr/input/input4" #517: +3660580542 [appliance] "+ grep -sq guestfs_network=1 /proc/cmdline" #518: +3667324364 [appliance] "+ grep -sq guestfs_rescue=1 /proc/cmdline" #519: +3672736160 [appliance] "+ grep -sq guestfs_noreboot=1 /proc/cmdline" #520: +3678691438 [appliance] "+ grep -sq guestfs_boot_analysis=1 /proc/cmdline" #521: +3681401735 [appliance] "+ guestfs_boot_analysis=1" #522: +3681403033 [appliance] "++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline" #523: +3684497097 [appliance] "+ eval" #524: +3684498358 [appliance] "+ grep -sq selinux=1 /proc/cmdline" #525: +3687033252 [appliance] "+ shopt -s nullglob" #526: +3687034594 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #527: +3688129707 [appliance] "+ echo 300" #528: +3688130863 [appliance] "+ for f in '/sys/block/sd*/device/timeout'" #529: +3689279170 [appliance] "+ echo 300" #530: +3689280519 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #531: +3690363739 [appliance] "+ echo noop" #532: +3691863376 [appliance] "+ for f in '/sys/block/{h,s,ub,v}d*/queue/scheduler'" #533: +3691864641 [appliance] "+ echo noop" #534: +3691865056 [appliance] "+ shopt -u nullglob" #535: +3692971679 [appliance] "+ ip addr add 127.0.0.1/8 brd + dev lo scope host" #536: +3698042466 [appliance] "+ ip link set dev lo up" #537: +3700749815 [appliance] "+ test '' = 1" #538: +3700751212 [appliance] "+ mdadm -As --auto=yes --run" #539: +3711038460 [appliance] "mdadm: No arrays found in config file or automatically" #540: +3711039847 [appliance] "+ modprobe dm_mod" #541: +3715062465 [appliance] "+ lvmetad" #542: +3724269954 [appliance] "+ lvm vgchange -aay --sysinit" #543: +3735540584 [appliance] " lvmetad is not active yet, using direct activation during sysinit" #544: +3741890533 [appliance] "+ ldmtool create all" #545: +3751713602 [appliance] "[" #546: +3751714946 [appliance] "]" #547: +3753715937 [appliance] "+ test 1 = 1" #548: +3753717276 [appliance] "+ test 1 '!=' 1" #549: +3753717609 [appliance] "+ test '' = 1" #550: +3753717837 [appliance] "+ cmd=guestfsd" #551: +3754909838 [appliance] "+ test x '!=' x" #552: +3754911011 [appliance] "+ test 1 = 1" #553: +3754911338 [appliance] "+ cmd='guestfsd --verbose'" #554: +3754911546 [appliance] "+ test '' = 1" #555: +3756048527 [appliance] "+ echo guestfsd --verbose" #556: +3756049618 [appliance] "guestfsd --verbose" #557: +3756049891 [appliance] "+ guestfsd --verbose" #558: +3774030005 [appliance] "trying to open virtio-serial channel '/dev/virtio-ports/org.libguestfs.channel.0'" #559: +3774031661 [appliance] "udevadm --debug settle" #560: +3780103269 [appliance] "calling: settle" #561: +3780283453 [launch_done] "launch done callback" #562: +3780289700 [library] "recv_from_daemon: received GUESTFS_LAUNCH_FLAG" #563: +3780291455 [library] "appliance is up" #564: +3780339400 [trace] "launch = 0" #565: +3780341262 [trace] "close" #566: +3780342985 [library] "closing guestfs handle 0x23237a0 (state 2)" #567: +3780347342 [trace] "internal_autosync" #568: +3784787511 [appliance] "guestfsd: main_loop: new request, len 0x28" #569: +3784788735 [appliance] "umount-all: /proc/mounts: fsname=/dev/root dir=/ type=ext2 opts=rw,noatime,block_validity,barrier,user_xattr,acl freq=0 passno=0" #570: +3785900806 [appliance] "umount-all: /proc/mounts: fsname=/proc dir=/proc type=proc opts=rw,relatime freq=0 passno=0" #571: +3787014926 [appliance] "umount-all: /proc/mounts: fsname=/sys dir=/sys type=sysfs opts=rw,relatime freq=0 passno=0" #572: +3788130204 [appliance] "umount-all: /proc/mounts: fsname=tmpfs dir=/run type=tmpfs opts=rw,nosuid,relatime,size=93972k,mode=755 freq=0 passno=0" #573: +3789244402 [appliance] "umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=231480k,nr_inodes=57870,mode=755 freq=0 passno=0" #574: +3794828184 [appliance] "fsync /dev/sda" #575: +3794829257 [appliance] "guestfsd: main_loop: proc 282 (i" #576: +3794845111 [trace] "internal_autosync = 0" #577: +3794847954 [library] "sending SIGTERM to process 21880" #578: +3802883903 [close] "close callback" #579: +3802890603 [library] "command: run: rm" #580: +3802891809 [library] "command: run: \ -rf /home/kchamart/src/rwmj-fork-libguestfs/tmp/libguestfs0OSUlT" #581: +3804854198 [library] "command: run: rm" #582: +3804855660 [library] "command: run: \ -rf /tmp/libguestfs9kjhzU" Analyzing the results ... activity 0: name = run start - end = 0.0 - 3797857816.5 mean elapsed = 3797857817.5 variance = 23555234330502.2 s.d = 4853373.5 percent = 100.0 activity 1: name = supermin:build start - end = 165675.0 - 15122352.5 mean elapsed = 14956678.5 variance = 3945661410.2 s.d = 62814.5 percent = 0.4 activity 2: name = qemu:feature-detect start - end = 15147812.0 - 367445008.5 mean elapsed = 352297197.5 variance = 1864277563610.2 s.d = 1365385.5 percent = 9.3 activity 3: name = qemu start - end = 369583297.0 - 3797857816.5 mean elapsed = 3428274520.5 variance = 38268606476406.2 s.d = 6186162.5 percent = 90.3 activity 4: name = qemu:overhead start - end = 369583297.0 - 600443325.0 mean elapsed = 230860029.0 variance = 4093099505881.0 s.d = 2023141.0 percent = 6.1 activity 5: name = bios:overhead start - end = 600443326.0 - 2011692175.0 mean elapsed = 1411248850.0 variance = 937023954057409.0 s.d = 30610847.0 percent = 37.2 activity 6: name = sgabios start - end = 600443326.0 - 603589301.5 mean elapsed = 3145976.5 variance = 78083732.2 s.d = 8836.5 percent = 0.1 activity 7: name = seabios start - end = 603589302.5 - 2011692175.0 mean elapsed = 1408102873.5 variance = 937565017640172.2 s.d = 30619683.5 percent = 37.1 activity 8: name = kernel start - end = 2011692176.0 - 3797857816.5 mean elapsed = 1786165641.5 variance = 699487473678450.2 s.d = 26447825.5 percent = 47.0 activity 9: name = kernel:overhead start - end = 2011692176.0 - 2590966760.0 mean elapsed = 579274585.0 variance = 12296741502241.0 s.d = 3506671.0 percent = 15.3 activity 10: name = supermin:mini-initrd start - end = 2590966761.0 - 2980253369.5 mean elapsed = 389286609.5 variance = 465101004006200.2 s.d = 21566200.5 percent = 10.3 activity 11: name = supermin: internal insmod crc32-pclmul.ko.xz start - end = 2594340191.5 - 2598374798.0 mean elapsed = 4034607.5 variance = 73769004420.2 s.d = 271604.5 percent = 0.1 activity 12: name = supermin: internal insmod crc32c-intel.ko.xz start - end = 2598374799.0 - 2601908393.5 mean elapsed = 3533595.5 variance = 1983945222.2 s.d = 44541.5 percent = 0.1 activity 13: name = supermin: internal insmod crct10dif-pclmul.ko.xz start - end = 2601908394.5 - 2604983296.0 mean elapsed = 3074902.5 variance = 4325100990.2 s.d = 65765.5 percent = 0.1 activity 14: name = supermin: internal insmod crc32.ko.xz start - end = 2604983297.0 - 2607803320.5 mean elapsed = 2820024.5 variance = 147100512.2 s.d = 12128.5 percent = 0.1 activity 15: name = supermin: internal insmod virtio.ko.xz start - end = 2607803321.5 - 2610922101.0 mean elapsed = 3118780.5 variance = 144396272.2 s.d = 12016.5 percent = 0.1 activity 16: name = supermin: internal insmod virtio_ring.ko.xz start - end = 2610922102.0 - 2614242778.0 mean elapsed = 3320677.0 variance = 45104656.0 s.d = 6716.0 percent = 0.1 activity 17: name = supermin: internal insmod virtio_blk.ko.xz start - end = 2614242779.0 - 2617881113.0 mean elapsed = 3638335.0 variance = 130850721.0 s.d = 11439.0 percent = 0.1 activity 18: name = supermin: internal insmod virtio-rng.ko.xz start - end = 2617881114.0 - 2620758048.5 mean elapsed = 2876935.5 variance = 103154492.2 s.d = 10156.5 percent = 0.1 activity 19: name = supermin: internal insmod virtio_console.ko.xz start - end = 2620758049.5 - 2625437754.5 mean elapsed = 4679706.0 variance = 2509408836.0 s.d = 50094.0 percent = 0.1 activity 20: name = supermin: internal insmod drm.ko.xz start - end = 2625437755.5 - 2649575424.0 mean elapsed = 24137669.5 variance = 328146238440.2 s.d = 572840.5 percent = 0.6 activity 21: name = supermin: internal insmod drm_kms_helper.ko.xz start - end = 2649575425.0 - 2661104927.0 mean elapsed = 11529503.0 variance = 13175137089.0 s.d = 114783.0 percent = 0.3 activity 22: name = supermin: internal insmod ttm.ko.xz start - end = 2661104928.0 - 2669847535.0 mean elapsed = 8742608.0 variance = 33006895684.0 s.d = 181678.0 percent = 0.2 activity 23: name = supermin: internal insmod virtio-gpu.ko.xz start - end = 2669847536.0 - 2676551107.0 mean elapsed = 6703572.0 variance = 11513719204.0 s.d = 107302.0 percent = 0.2 activity 24: name = supermin: internal insmod ideapad_slidebar.ko.xz start - end = 2676551108.0 - 2680621215.5 mean elapsed = 4070108.5 variance = 2368038906.2 s.d = 48662.5 percent = 0.1 activity 25: name = supermin: internal insmod virtio_net.ko.xz start - end = 2680621216.5 - 2686027319.0 mean elapsed = 5406103.5 variance = 2834976780.2 s.d = 53244.5 percent = 0.1 activity 26: name = supermin: internal insmod video.ko.xz start - end = 2686027320.0 - 2690508230.5 mean elapsed = 4480911.5 variance = 10100.2 s.d = 100.5 percent = 0.1 activity 27: name = supermin: internal insmod sparse-keymap.ko.xz start - end = 2690508231.5 - 2693346007.5 mean elapsed = 2837777.0 variance = 1280709369.0 s.d = 35787.0 percent = 0.1 activity 28: name = supermin: internal insmod wmi.ko.xz start - end = 2693346008.5 - 2696849111.5 mean elapsed = 3503104.0 variance = 1727815489.0 s.d = 41567.0 percent = 0.1 activity 29: name = supermin: internal insmod rfkill.ko.xz start - end = 2696849112.5 - 2701745285.0 mean elapsed = 4896173.5 variance = 10315957056.2 s.d = 101567.5 percent = 0.1 activity 30: name = supermin: internal insmod ideapad-laptop.ko.xz start - end = 2701745286.0 - 2707367525.0 mean elapsed = 5622240.0 variance = 16106401921.0 s.d = 126911.0 percent = 0.1 activity 31: name = supermin: internal insmod megaraid.ko.xz start - end = 2707367526.0 - 2713883969.0 mean elapsed = 6516444.0 variance = 11601874944.0 s.d = 107712.0 percent = 0.2 activity 32: name = supermin: internal insmod megaraid_mm.ko.xz start - end = 2713883970.0 - 2718523122.0 mean elapsed = 4639153.0 variance = 6435569284.0 s.d = 80222.0 percent = 0.1 activity 33: name = supermin: internal insmod megaraid_mbox.ko.xz start - end = 2718523123.0 - 2723753367.0 mean elapsed = 5230245.0 variance = 18343180969.0 s.d = 135437.0 percent = 0.1 activity 34: name = supermin: internal insmod megaraid_sas.ko.xz start - end = 2723753368.0 - 2735419266.5 mean elapsed = 11665899.5 variance = 34337757720.2 s.d = 185304.5 percent = 0.3 activity 35: name = supermin: internal insmod scsi_transport_spi.ko.xz start - end = 2735419267.5 - 2740499188.0 mean elapsed = 5079921.5 variance = 4004801372.2 s.d = 63283.5 percent = 0.1 activity 36: name = supermin: internal insmod sym53c8xx.ko.xz start - end = 2740499189.0 - 2748576099.0 mean elapsed = 8076911.0 variance = 11969016409.0 s.d = 109403.0 percent = 0.2 activity 37: name = supermin: internal insmod virtio_scsi.ko.xz start - end = 2748576100.0 - 2752531624.5 mean elapsed = 3955525.5 variance = 10201909020.2 s.d = 101004.5 percent = 0.1 activity 38: name = supermin: internal insmod virtio_balloon.ko.xz start - end = 2752531625.5 - 2755948225.0 mean elapsed = 3416600.5 variance = 3356174556.2 s.d = 57932.5 percent = 0.1 activity 39: name = supermin: internal insmod virtio_input.ko.xz start - end = 2755948226.0 - 2759087681.0 mean elapsed = 3139456.0 variance = 1342562881.0 s.d = 36641.0 percent = 0.1 activity 40: name = supermin: internal insmod virtio_mmio.ko.xz start - end = 2759087682.0 - 2762250780.5 mean elapsed = 3163099.5 variance = 2429553390.2 s.d = 49290.5 percent = 0.1 activity 41: name = supermin: internal insmod virtio_pci.ko.xz start - end = 2762250781.5 - 2959852969.5 mean elapsed = 197602189.0 variance = 335051132577604.0 s.d = 18304402.0 percent = 5.2 activity 42: name = supermin: internal insmod crc-ccitt.ko.xz start - end = 2959852970.5 - 2963186602.0 mean elapsed = 3333632.5 variance = 12061860102.2 s.d = 109826.5 percent = 0.1 activity 43: name = supermin: internal insmod crc-itu-t.ko.xz start - end = 2963186603.0 - 2965817970.5 mean elapsed = 2631368.5 variance = 5041355006.2 s.d = 71002.5 percent = 0.1 activity 44: name = supermin: internal insmod crc8.ko.xz start - end = 2965817971.5 - 2968359094.0 mean elapsed = 2541123.5 variance = 55614306.2 s.d = 7457.5 percent = 0.1 activity 45: name = supermin: internal insmod libcrc32c.ko.xz start - Linux host20-rack09.scale.openstack.engineering.redhat.com 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) Xeon(R) CPU E5-2620 v2 @ 2.10GHz end = 2968359095.0 - 2971383751.5 mean elapsed = 3024657.5 variance = 1426912850.2 s.d = 37774.5 percent = 0.1 activity 46: name = /init start - end = 2980253370.5 - 3749141236.5 mean elapsed = 768887867.0 variance = 398084021721.0 s.d = 630939.0 percent = 20.2 activity 47: name = bash:overhead start - end = 2980253370.5 - 3002468235.5 mean elapsed = 22214866.0 variance = 1604214763776.0 s.d = 1266576.0 percent = 0.6 activity 48: name = guestfsd start - end = 3749141237.5 - 3789441329.5 mean elapsed = 40300093.0 variance = 146878263009.0 s.d = 383247.0 percent = 1.1 activity 49: name = shutdown start - end = 3774447067.5 - 3797857816.5 mean elapsed = 23410750.0 variance = 753613235881.0 s.d = 868109.0 percent = 0.6 libguestfs 1.33.15 Host: Appliance: qemu version 2.4 \x1b[2J\x0dSeaBIOS (version 1.8.2-20150714_191134-) [ 0.000000] Linux version 4.4.5-300.fc23.x86_64+debug (mockbuild@bkernel02.phx2.fedoraproject.org) (gcc version 5.3.1 20151207 (Red Hat 5.3.1-2) (GCC) ) #1 SMP Thu Mar 10 17:37:59 UTC 2016 supermin: ext2 mini initrd starting up: 5.1.13 zlib xz 0.000000s: ▲ run mean:3.797858s ±4.9ms (100.0%) 0.000166s: │ ▲ supermin:build mean:0.014957s ±0.1ms (0.4%) │ │ 0.015122s: │ ▼ 0.015148s: │ ▲ qemu:feature-detect mean:0.352297s ±1.4ms (9.3%) │ │ 0.367445s: │ ▼ │ 0.369583s: │ ▲ ▲ qemu mean:3.428275s ±6.2ms (90.3%) qemu:overhead mean:0.230860s ±2.0ms (6.1%) │ │ │ 0.600443s: │ │ ▼ 0.600443s: │ │ ▲ ▲ bios:overhead mean:1.411249s ±30.6ms (37.2%) sgabios mean:0.003146s ±0.0ms (0.1%) │ │ │ │ 0.603589s: │ │ │ ▼ 0.603589s: │ │ │ ▲ seabios mean:1.408103s ±30.6ms (37.1%) │ │ │ │ 2.011692s: │ │ ▼ ▼ 2.011692s: │ │ ▲ ▲ kernel mean:1.786166s ±26.4ms (47.0%) kernel:overhead mean:0.579275s ±3.5ms (15.3%) │ │ │ │ 2.590967s: │ │ │ ▼ 2.590967s: │ │ │ ▲ supermin:mini-initrd mean:0.389287s ±21.6ms (10.3%) │ │ │ │ 2.594340s: │ │ │ │ ▲ supermin: internal insmod crc32-pclmul.ko.xz mean:0.004035s ±0.3ms (0.1%) │ │ │ │ │ 2.598375s: │ │ │ │ ▼ 2.598375s: │ │ │ │ ▲ supermin: internal insmod crc32c-intel.ko.xz mean:0.003534s ±0.0ms (0.1%) │ │ │ │ │ 2.601908s: │ │ │ │ ▼ 2.601908s: │ │ │ │ ▲ supermin: internal insmod crct10dif-pclmul.ko.xz mean:0.003075s ±0.1ms (0.1%) │ │ │ │ │ 2.604983s: │ │ │ │ ▼ 2.604983s: │ │ │ │ ▲ supermin: internal insmod crc32.ko.xz mean:0.002820s ±0.0ms (0.1%) │ │ │ │ │ 2.607803s: │ │ │ │ ▼ 2.607803s: │ │ │ │ ▲ supermin: internal insmod virtio.ko.xz mean:0.003119s ±0.0ms (0.1%) │ │ │ │ │ 2.610922s: │ │ │ │ ▼ 2.610922s: │ │ │ │ ▲ supermin: internal insmod virtio_ring.ko.xz mean:0.003321s ±0.0ms (0.1%) │ │ │ │ │ 2.614243s: │ │ │ │ ▼ 2.614243s: │ │ │ │ ▲ supermin: internal insmod virtio_blk.ko.xz mean:0.003638s ±0.0ms (0.1%) │ │ │ │ │ 2.617881s: │ │ │ │ ▼ 2.617881s: │ │ │ │ ▲ supermin: internal insmod virtio-rng.ko.xz mean:0.002877s ±0.0ms (0.1%) │ │ │ │ │ 2.620758s: │ │ │ │ ▼ 2.620758s: │ │ │ │ ▲ supermin: internal insmod virtio_console.ko.xz mean:0.004680s ±0.1ms (0.1%) │ │ │ │ │ 2.625438s: │ │ │ │ ▼ 2.625438s: │ │ │ │ ▲ supermin: internal insmod drm.ko.xz mean:0.024138s ±0.6ms (0.6%) │ │ │ │ │ 2.649575s: │ │ │ │ ▼ 2.649575s: │ │ │ │ ▲ supermin: internal insmod drm_kms_helper.ko.xz mean:0.011530s ±0.1ms (0.3%) │ │ │ │ │ 2.661105s: │ │ │ │ ▼ 2.661105s: │ │ │ │ ▲ supermin: internal insmod ttm.ko.xz mean:0.008743s ±0.2ms (0.2%) │ │ │ │ │ 2.669848s: │ │ │ │ ▼ 2.669848s: │ │ │ │ ▲ supermin: internal insmod virtio-gpu.ko.xz mean:0.006704s ±0.1ms (0.2%) │ │ │ │ │ 2.676551s: │ │ │ │ ▼ 2.676551s: │ │ │ │ ▲ supermin: internal insmod ideapad_slidebar.ko.xz mean:0.004070s ±0.0ms (0.1%) │ │ │ │ │ 2.680621s: │ │ │ │ ▼ 2.680621s: │ │ │ │ ▲ supermin: internal insmod virtio_net.ko.xz mean:0.005406s ±0.1ms (0.1%) │ │ │ │ │ 2.686027s: │ │ │ │ ▼ 2.686027s: │ │ │ │ ▲ supermin: internal insmod video.ko.xz mean:0.004481s ±0.0ms (0.1%) │ │ │ │ │ 2.690508s: │ │ │ │ ▼ 2.690508s: │ │ │ │ ▲ supermin: internal insmod sparse-keymap.ko.xz mean:0.002838s ±0.0ms (0.1%) │ │ │ │ │ 2.693346s: │ │ │ │ ▼ 2.693346s: │ │ │ │ ▲ supermin: internal insmod wmi.ko.xz mean:0.003503s ±0.0ms (0.1%) │ │ │ │ │ 2.696849s: │ │ │ │ ▼ 2.696849s: │ │ │ │ ▲ supermin: internal insmod rfkill.ko.xz mean:0.004896s ±0.1ms (0.1%) │ │ │ │ │ 2.701745s: │ │ │ │ ▼ 2.701745s: │ │ │ │ ▲ supermin: internal insmod ideapad-laptop.ko.xz mean:0.005622s ±0.1ms (0.1%) │ │ │ │ │ 2.707368s: │ │ │ │ ▼ 2.707368s: │ │ │ │ ▲ supermin: internal insmod megaraid.ko.xz mean:0.006516s ±0.1ms (0.2%) │ │ │ │ │ 2.713884s: │ │ │ │ ▼ 2.713884s: │ │ │ │ ▲ supermin: internal insmod megaraid_mm.ko.xz mean:0.004639s ±0.1ms (0.1%) │ │ │ │ │ 2.718523s: │ │ │ │ ▼ 2.718523s: │ │ │ │ ▲ supermin: internal insmod megaraid_mbox.ko.xz mean:0.005230s ±0.1ms (0.1%) │ │ │ │ │ 2.723753s: │ │ │ │ ▼ 2.723753s: │ │ │ │ ▲ supermin: internal insmod megaraid_sas.ko.xz mean:0.011666s ±0.2ms (0.3%) │ │ │ │ │ 2.735419s: │ │ │ │ ▼ 2.735419s: │ │ │ │ ▲ supermin: internal insmod scsi_transport_spi.ko.xz mean:0.005080s ±0.1ms (0.1%) │ │ │ │ │ 2.740499s: │ │ │ │ ▼ 2.740499s: │ │ │ │ ▲ supermin: internal insmod sym53c8xx.ko.xz mean:0.008077s ±0.1ms (0.2%) │ │ │ │ │ 2.748576s: │ │ │ │ ▼ 2.748576s: │ │ │ │ ▲ supermin: internal insmod virtio_scsi.ko.xz mean:0.003956s ±0.1ms (0.1%) │ │ │ │ │ 2.752532s: │ │ │ │ ▼ 2.752532s: │ │ │ │ ▲ supermin: internal insmod virtio_balloon.ko.xz mean:0.003417s ±0.1ms (0.1%) │ │ │ │ │ 2.755948s: │ │ │ │ ▼ 2.755948s: │ │ │ │ ▲ supermin: internal insmod virtio_input.ko.xz mean:0.003139s ±0.0ms (0.1%) │ │ │ │ │ 2.759088s: │ │ │ │ ▼ 2.759088s: │ │ │ │ ▲ supermin: internal insmod virtio_mmio.ko.xz mean:0.003163s ±0.0ms (0.1%) │ │ │ │ │ 2.762251s: │ │ │ │ ▼ 2.762251s: │ │ │ │ ▲ supermin: internal insmod virtio_pci.ko.xz mean:0.197602s ±18.3ms (5.2%) │ │ │ │ │ 2.959853s: │ │ │ │ ▼ 2.959853s: │ │ │ │ ▲ supermin: internal insmod crc-ccitt.ko.xz mean:0.003334s ±0.1ms (0.1%) │ │ │ │ │ 2.963187s: │ │ │ │ ▼ 2.963187s: │ │ │ │ ▲ supermin: internal insmod crc-itu-t.ko.xz mean:0.002631s ±0.1ms (0.1%) │ │ │ │ │ 2.965818s: │ │ │ │ ▼ 2.965818s: │ │ │ │ ▲ supermin: internal insmod crc8.ko.xz mean:0.002541s ±0.0ms (0.1%) │ │ │ │ │ 2.968359s: │ │ │ │ ▼ 2.968359s: │ │ │ │ ▲ supermin: internal insmod libcrc32c.ko.xz mean:0.003025s ±0.0ms (0.1%) │ │ │ │ │ 2.971384s: │ │ │ │ ▼ │ │ │ │ 2.980253s: │ │ │ ▼ 2.980253s: │ │ │ ▲ ▲ /init mean:0.768888s ±0.6ms (20.2%) bash:overhead mean:0.022215s ±1.3ms (0.6%) │ │ │ │ │ 3.002468s: │ │ │ │ ▼ │ │ │ │ 3.749141s: │ │ │ ▼ 3.749141s: │ │ │ ▲ guestfsd mean:0.040300s ±0.4ms (1.1%) │ │ │ │ 3.774447s: │ │ │ │ ▲ shutdown mean:0.023411s ±0.9ms (0.6%) │ │ │ │ │ 3.789441s: │ │ │ ▼ │ │ │ │ │ 3.797858s: ▼ ▼ ▼ ▼