search

kata-containers / runtime

Kata Containers version 1.x runtime (for version 2.x see https://github.com/kata-containers/kata-containers).
https://katacontainers.io/
Apache License 2.0
2.1k stars 374 forks source link

Firecracker: Fix slow bootup with Kata #1622

Closed mcastelino closed 5 years ago

mcastelino commented 5 years ago

Description of problem

Firecracker is able to bootup the kata kernel with the kata bootdisk in under .5s. However when running with Kata the same kernel and bootdisk takes 3-4s to boot.

As a comparison see below

We need to root cause both the first and second difference.

[    0.000000] Linux version 4.19.28-23.container (abuild@build70) (gcc version 7.3.0 (Ubuntu 7.3.0-16ubuntu3)) #1 SMP Thu Apr 18 00:22:49 UTC 2019
[    0.000000] Command line: init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service systemd.mask=systemd-udevd.socket sy
stemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.timer systemd.mask=tmp.mount systemd.mask=systemd-random-seed.service agent.log=debug console=ttyS0 reboot=k pa
nic=1 pci=off root=/dev/vda1 random.trust_cpu=on virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7
[    0.000000] KERNEL supported cpus:
...
[    0.407333] Write protecting the kernel read-only data: 16384k
[    0.412082] Freeing unused kernel image memory: 2016K
[    0.415942] Freeing unused kernel image memory: 1204K
[    0.417660] Run /usr/lib/systemd/systemd as init process
[    0.450930] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT -SELINUX +IMA -APPARMOR -SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=legacy)
[    0.456160] systemd[1]: Detected virtualization kvm.
[    0.457412] systemd[1]: Detected architecture x86-64.

When running the same with Kata

[    0.000000] Linux version 4.19.28 (mrcastel@kata) (gcc version 7.3.0 (Ubuntu 7.3.0-27ubuntu1~18.04)) #1 SMP Thu May 2 18:07:43 UTC 2019
[    0.000000] Command line: init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service systemd.mask=systemd-udevd.socket sy
stemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.timer systemd.mask=tmp.mount systemd.mask=systemd-random-seed.service agent.log=debug root=/dev/vda1 pci=off re
boot=k panic=1 iommu=off net.ifnames=0 random.trust_cpu=on console=ttyS0 acpi=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@0xd0005
[    0.000000] KERNEL supported cpus:
...
[    2.415184] Freeing unused kernel image memory: 1204K
[    2.420092] Run /usr/lib/systemd/systemd as init process
[    2.633864] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT -SELINUX +IMA -APPARMOR -SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=legacy)
[    2.653849] systemd[1]: Detected virtualization kvm.
[    2.658947] systemd[1]: Detected architecture x86-64.
[    3.364302] systemd[1]: Condition check resulted in Load Kernel Modules being skipped.
[    3.381648] systemd[1]: Starting Apply Kernel Variables...
[    3.390480] systemd[76]: systemd-sysctl.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory
[    3.406403] systemd[1]: systemd-udevd-kernel.socket: Socket service systemd-udevd.service not loaded, refusing.
[    3.425097] systemd[1]: Failed to listen on udev Kernel Socket.
[    3.464027] systemd-sysctl[76]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory
[    3.473313] systemd[1]: Condition check resulted in Remount Root and Kernel File Systems being skipped.
[    3.484431] systemd[1]: Condition check resulted in Flush Journal to Persistent Storage being skipped.
[    3.506693] systemd[1]: Reached target Slices.
[    3.741342] systemd[81]: chronyd.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory
[    5.305366] virtio_blk virtio1: [vdb] new size: 0 512-byte logical blocks (0 B/0 B)
[    5.317624] virtio_blk virtio1: [vdb] new size: 0 512-byte logical blocks (0 B/0 B)
[    5.847864] XFS (vdb): Mounting V5 Filesystem
[    5.930890] XFS (vdb): Ending clean mount

Also there is a 0.5s difference in or before the MP Table setup

[    0.111042] Early memory node ranges
[    0.111043]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.111043]   node   0: [mem 0x0000000000100000-0x000000007fffffff]
[    0.111184] Reserved but unavailable: 98 pages
[    0.111217] Initmem setup node 0 [mem 0x0000000000001000-0x000000007fffffff]
[    0.111221] On node 0 totalpages: 524190
[    0.111254]   DMA zone: 64 pages used for memmap
[    0.111255]   DMA zone: 21 pages reserved
[    0.111255]   DMA zone: 3998 pages, LIFO batch:0
[    0.113219]   DMA32 zone: 8128 pages used for memmap
[    0.113221]   DMA32 zone: 520192 pages, LIFO batch:63
[    0.502425] Intel MultiProcessor Specification v1.4
[    0.502433] MPTABLE: OEM ID: FC
[    0.502433] MPTABLE: Product ID: 000000000000
[    0.502434] MPTABLE: APIC at: 0xFEE00000

vs

[    0.001285] Movable zone start for each node
[    0.001286] Early memory node ranges
[    0.001288]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.001290]   node   0: [mem 0x0000000000100000-0x000000001fffffff]
[    0.001327] Reserved but unavailable: 98 pages
[    0.001334] Initmem setup node 0 [mem 0x0000000000001000-0x000000001fffffff]
[    0.014769] Intel MultiProcessor Specification v1.4
[    0.014775] MPTABLE: OEM ID: FC
[    0.014776] MPTABLE: Product ID: 000000000000
[    0.014777] MPTABLE: APIC at: 0xFEE00000
mcastelino commented 5 years ago

/cc @grahamwhaley @egernst @devimc

mcastelino commented 5 years ago
Firecracker Bootlog w/o Kata

``` [ 0.000000] Linux version 4.19.28-23.container (abuild@build70) (gcc version 7.3.0 (Ubuntu 7.3.0-16ubuntu3)) #1 SMP Thu Apr 18 00:22:49 UTC 2019 [ 0.000000] Command line: init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service systemd.mask=systemd-udevd.socket systemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.timer systemd.mask=tmp.mount systemd.mask=systemd-random-seed.service agent.log=debug console=ttyS0 reboot=k panic=1 pci=off root=/dev/vda1 random.trust_cpu=on virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 [ 0.000000] x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 [ 0.000000] x86/fpu: xstate_offset[5]: 960, xstate_sizes[5]: 64 [ 0.000000] x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]: 512 [ 0.000000] x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024 [ 0.000000] x86/fpu: Enabled xstate features 0xff, context size is 2560 bytes, using 'compacted' format. [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001fffffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000001] kvm-clock: cpu 0, msr 24ae001, primary cpu clock [ 0.000001] kvm-clock: using sched offset of 5393570255 cycles [ 0.000011] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000022] tsc: Detected 2592.000 MHz processor [ 0.000405] last_pfn = 0x20000 max_arch_pfn = 0x400000000 [ 0.000516] Disabled [ 0.000538] CPU MTRRs all blank - virtualized system. [ 0.000544] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.000633] found SMP MP-table at [mem 0x0009fc00-0x0009fc0f] mapped at [(____ptrval____)] [ 0.000716] Using GB pages for direct mapping [ 0.000899] ACPI: Early table checksum verification disabled [ 0.001157] ACPI BIOS Error (bug): A valid RSDP was not found (20180810/tbxfroot-210) [ 0.001256] Zone ranges: [ 0.001263] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.001267] DMA32 [mem 0x0000000001000000-0x000000001fffffff] [ 0.001269] Normal empty [ 0.001271] Device empty [ 0.001274] Movable zone start for each node [ 0.001275] Early memory node ranges [ 0.001277] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.001280] node 0: [mem 0x0000000000100000-0x000000001fffffff] [ 0.001321] Reserved but unavailable: 98 pages [ 0.001327] Initmem setup node 0 [mem 0x0000000000001000-0x000000001fffffff] [ 0.015302] Intel MultiProcessor Specification v1.4 [ 0.015308] MPTABLE: OEM ID: FC [ 0.015309] MPTABLE: Product ID: 000000000000 [ 0.015310] MPTABLE: APIC at: 0xFEE00000 [ 0.015321] Processor #0 (Bootup-CPU) [ 0.015333] Processor #1 [ 0.015334] Processor #2 [ 0.015335] Processor #3 [ 0.015395] IOAPIC[0]: apic_id 5, version 17, address 0xfec00000, GSI 0-23 [ 0.015398] Processors: 4 [ 0.015404] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.015439] [mem 0x20000000-0xffffffff] available for PCI devices [ 0.015441] Booting paravirtualized kernel on KVM [ 0.015443] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.070663] setup_percpu: NR_CPUS:240 nr_cpumask_bits:240 nr_cpu_ids:4 nr_node_ids:1 [ 0.071878] percpu: Embedded 42 pages/cpu @(____ptrval____) s132888 r8192 d30952 u524288 [ 0.071901] KVM setup async PF for cpu 0 [ 0.071905] kvm-stealtime: cpu 0, msr 1f214e80 [ 0.071914] Built 1 zonelists, mobility grouping on. Total pages: 128905 [ 0.071915] Kernel command line: init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service systemd.mask=systemd-udevd.socket systemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.timer systemd.mask=tmp.mount systemd.mask=systemd-random-seed.service agent.log=debug console=ttyS0 reboot=k panic=1 pci=off root=/dev/vda1 random.trust_cpu=on virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 [ 0.072526] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.072670] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.073533] Memory: 491556K/523896K available (10248K kernel code, 629K rwdata, 2892K rodata, 836K init, 1416K bss, 32340K reserved, 0K cma-reserved) [ 0.073595] Kernel/User page tables isolation: enabled [ 0.073692] rcu: Hierarchical RCU implementation. [ 0.073693] rcu: RCU restricting CPUs from NR_CPUS=240 to nr_cpu_ids=4. [ 0.073695] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.073709] NR_IRQS: 15616, nr_irqs: 456, preallocated irqs: 16 [ 0.073828] rcu: Offload RCU callbacks from CPUs: (none). [ 0.073957] Console: colour dummy device 132x25 [ 0.137676] console [ttyS0] enabled [ 0.138103] APIC: Switch to symmetric I/O mode setup [ 0.138901] x2apic enabled [ 0.139487] Switched APIC routing to physical x2apic. [ 0.140074] KVM setup pv IPIs [ 0.141456] ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 [ 0.142118] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x255cb6cc5db, max_idle_ns: 440795203504 ns [ 0.143230] Calibrating delay loop (skipped) preset value.. 5184.00 BogoMIPS (lpj=2592000) [ 0.144231] pid_max: default: 32768 minimum: 301 [ 0.144892] Security Framework initialized [ 0.145267] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.145972] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.146539] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8 [ 0.147231] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4 [ 0.148235] Spectre V2 : Mitigation: Full generic retpoline [ 0.148876] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.149230] Spectre V2 : Enabling Restricted Speculation for firmware calls [ 0.150231] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier [ 0.151232] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp [ 0.152522] Freeing SMP alternatives memory: 32K [ 0.154359] smpboot: CPU0: Intel(R) Xeon(R) Processor @ 2.60GHz (family: 0x6, model: 0x55, stepping: 0x4) [ 0.155373] Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. [ 0.156281] rcu: Hierarchical SRCU implementation. [ 0.156892] random: crng done (trusting CPU's manufacturer) [ 0.157321] smp: Bringing up secondary CPUs ... [ 0.157924] x86: Booting SMP configuration: [ 0.158234] .... node #0, CPUs: #1 [ 0.066398] kvm-clock: cpu 1, msr 24ae041, secondary cpu clock [ 0.159913] KVM setup async PF for cpu 1 [ 0.160230] kvm-stealtime: cpu 1, msr 1f294e80 [ 0.164279] #2 [ 0.066398] kvm-clock: cpu 2, msr 24ae081, secondary cpu clock [ 0.165379] KVM setup async PF for cpu 2 [ 0.166230] kvm-stealtime: cpu 2, msr 1f314e80 [ 0.169350] #3 [ 0.066398] kvm-clock: cpu 3, msr 24ae0c1, secondary cpu clock [ 0.170998] KVM setup async PF for cpu 3 [ 0.171230] kvm-stealtime: cpu 3, msr 1f394e80 [ 0.175245] smp: Brought up 1 node, 4 CPUs [ 0.175800] smpboot: Max logical packages: 1 [ 0.176233] smpboot: Total of 4 processors activated (20736.00 BogoMIPS) [ 0.177400] devtmpfs: initialized [ 0.177742] x86/mm: Memory block size: 128MB [ 0.178405] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.179242] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 0.180611] NET: Registered protocol family 16 [ 0.181505] cpuidle: using governor menu [ 0.182486] KVM setup pv remote TLB flush [ 0.184246] PCI: Fatal: No config space access function found [ 0.188240] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.189236] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.190313] cryptd: max_cpu_qlen set to 1000 [ 0.191359] ACPI: Interpreter disabled. [ 0.192342] SCSI subsystem initialized [ 0.193133] pps_core: LinuxPPS API ver. 1 registered [ 0.193234] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.194236] PTP clock support registered [ 0.195023] PCI: System does not support PCI [ 0.195503] clocksource: Switched to clocksource kvm-clock [ 0.196098] pnp: PnP ACPI: disabled [ 0.199474] NET: Registered protocol family 2 [ 0.200347] tcp_listen_portaddr_hash hash table entries: 256 (order: 0, 4096 bytes) [ 0.202763] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 0.204960] TCP bind hash table entries: 4096 (order: 4, 65536 bytes) [ 0.207013] TCP: Hash tables configured (established 4096 bind 4096) [ 0.209095] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.210888] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.212900] NET: Registered protocol family 1 [ 0.214581] virtio-mmio: Registering device virtio-mmio.0 at 0xd0000000-0xd0000fff, IRQ 5. [ 0.216328] virtio-mmio: Registering device virtio-mmio.1 at 0xd0001000-0xd0001fff, IRQ 6. [ 0.217951] virtio-mmio: Registering device virtio-mmio.2 at 0xd0002000-0xd0002fff, IRQ 7. [ 0.219573] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x255cb6cc5db, max_idle_ns: 440795203504 ns [ 0.221525] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 0.224557] workingset: timestamp_bits=46 max_order=17 bucket_order=0 [ 0.226007] SGI XFS with security attributes, no debug enabled [ 0.227255] 9p: Installing v9fs 9p2000 file system support [ 0.231982] NET: Registered protocol family 38 [ 0.233509] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 0.235895] io scheduler noop registered [ 0.237189] io scheduler deadline registered (default) [ 0.238814] io scheduler cfq registered [ 0.240011] io scheduler mq-deadline registered (default) [ 0.241665] io scheduler kyber registered [ 0.243295] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 0.245756] virtio-mmio virtio-mmio.0: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 0.248102] virtio-mmio virtio-mmio.1: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 0.250438] virtio-mmio virtio-mmio.2: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 0.252834] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.276764] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a U6_16550A [ 0.299386] serial8250: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a U6_16550A [ 0.321856] serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a U6_16550A [ 0.324736] brd: module loaded [ 0.326634] loop: module loaded [ 0.327375] virtio_blk virtio0: [vda] 262144 512-byte logical blocks (134 MB/128 MiB) [ 0.328881] vda: vda1 [ 0.329592] tun: Universal TUN/TAP device driver, 1.6 [ 0.330917] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k [ 0.331731] ixgbe: Copyright (c) 1999-2016 Intel Corporation. [ 0.332479] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver - version 4.1.0-k [ 0.333452] ixgbevf: Copyright (c) 2009 - 2015 Intel Corporation. [ 0.334347] i40e: Intel(R) Ethernet Connection XL710 Network Driver - version 2.3.2-k [ 0.335165] i40e: Copyright (c) 2013 - 2014 Intel Corporation. [ 0.335883] i40evf: Intel(R) 40-10 Gigabit Virtual Function Network Driver - version 3.2.2-k [ 0.338512] Copyright (c) 2013 - 2015 Intel Corporation. [ 0.340660] VFIO - User Level meta-driver version: 0.3 [ 0.342587] IR NEC protocol handler initialized [ 0.344045] IR RC5(x/sz) protocol handler initialized [ 0.345642] IR RC6 protocol handler initialized [ 0.347050] IR JVC protocol handler initialized [ 0.348430] IR Sony protocol handler initialized [ 0.349841] IR SANYO protocol handler initialized [ 0.351269] IR Sharp protocol handler initialized [ 0.352704] IR MCE Keyboard/mouse protocol handler initialized [ 0.354471] IR XMP protocol handler initialized [ 0.356152] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 0.358670] xt_time: kernel timezone is -0000 [ 0.359577] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 0.360776] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 0.362312] IPVS: ipvs loaded. [ 0.362936] IPVS: [rr] scheduler registered. [ 0.363793] IPVS: [wrr] scheduler registered. [ 0.364665] IPVS: [lc] scheduler registered. [ 0.365518] IPVS: [wlc] scheduler registered. [ 0.366395] IPVS: [fo] scheduler registered. [ 0.367250] IPVS: [ovf] scheduler registered. [ 0.367881] IPVS: [lblc] scheduler registered. [ 0.368480] IPVS: [lblcr] scheduler registered. [ 0.369079] IPVS: [dh] scheduler registered. [ 0.369646] IPVS: [sh] scheduler registered. [ 0.370217] IPVS: [sed] scheduler registered. [ 0.370800] IPVS: [nq] scheduler registered. [ 0.371372] IPVS: ftp: loaded support on port[0] = 21 [ 0.372039] IPVS: [sip] pe registered. [ 0.372684] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully [ 0.373555] Initializing XFRM netlink socket [ 0.374166] NET: Registered protocol family 10 [ 0.375189] Segment Routing with IPv6 [ 0.376204] NET: Registered protocol family 17 [ 0.377386] 9pnet: Installing 9P2000 support [ 0.379865] NET: Registered protocol family 40 [ 0.383814] sched_clock: Marking stable (318203742, 65398146)->(389475533, -5873645) [ 0.386665] registered taskstats version 1 [ 0.391290] EXT4-fs (vda1): recovery complete [ 0.392681] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null) [ 0.395029] VFS: Mounted root (ext4 filesystem) on device 254:1. [ 0.397410] devtmpfs: mounted [ 0.399526] Freeing unused kernel image memory: 836K [ 0.407333] Write protecting the kernel read-only data: 16384k [ 0.412082] Freeing unused kernel image memory: 2016K [ 0.415942] Freeing unused kernel image memory: 1204K [ 0.417660] Run /usr/lib/systemd/systemd as init process [ 0.450930] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT -SELINUX +IMA -APPARMOR -SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=legacy) [ 0.456160] systemd[1]: Detected virtualization kvm. [ 0.457412] systemd[1]: Detected architecture x86-64. [ 0.458668] systemd[1]: Running with unpopulated /etc. Welcome to Clear Linux OS! [ 0.463553] systemd[1]: Initializing machine ID from random generator. [ 0.506181] systemd[1]: systemd-udevd-control.socket: Socket service systemd-udevd.service not loaded, refusing. [ 0.507838] systemd[1]: Failed to listen on udev Control Socket. [FAILED] Failed to listen on udev Control Socket. See 'systemctl status systemd-udevd-control.socket' for details. [ 0.510464] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 0.511834] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ OK ] Set up automount Arbitrary…s File System Automount Point. [ 0.513951] systemd[1]: Condition check resulted in Remount Root and Kernel File Systems being skipped. [ OK ] Reached target Local File Systems (Pre). [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Swap. [FAILED] Failed to listen on udev Kernel Socket. See 'systemctl status systemd-udevd-kernel.socket' for details. [ OK ] Started Dispatch Password …ts to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [FAILED] Failed to listen on Journal Socket (/dev/log). See 'systemctl status systemd-journald-dev-log.socket' for details. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK [ 0.525208] systemd[98]: systemd-sysctl.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory ] Reached target Local File Systems. [ OK ] Listening on Process Core Dump Socket. [ 0.535162] systemd-sysctl[98]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory [ OK ] Started Apply Kernel Variables. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK [ 0.546714] systemd[103]: chronyd.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory ] Started NTP client/server. [ OK ] Started Kata Containers Agent. [ OK ] Reached target Kata Containers Agent Target. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. time="2019-05-02T21:30:09.757651243Z" level=info msg=announce device-handlers="mmioblk,blk,scsi,nvdimm" name=kata-agent pid=104 source=agent storage-handlers="9p,virtio-fs,blk,mmioblk,scsi,ephemeral,local" system-memory="495644 kB" version=1.7.0-alpha1-e3967e783b9aeda631d95be89cb742409bf718db time="2019-05-02T21:30:09.760663313Z" level=debug msg="Vsock channel type detected" name=kata-agent pid=104 source=agent time="2019-05-02T21:30:09.763266782Z" level=info msg="agent grpc server starts" name=kata-agent pid=104 source=agent time="2019-05-02T21:30:09.763361391Z" level=info msg="Waiting for stopServer signal..." name=kata-agent pid=104 source=agent subsystem=stopserverwatcher time="2019-05-02T21:30:09.763370879Z" level=info msg="Started listening for uevents" name=kata-agent pid=104 source=agent subsystem=udevlistener [ 914.103625] systemd[1]: Starting Cleanup of Temporary Directories... [ 914.104380] systemd[115]: systemd-tmpfiles-clean.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory [ 914.121658] systemd[1]: systemd-tmpfiles-clean.service: Succeeded. [ 914.124136] systemd[1]: Started Cleanup of Temporary Directories. ```

Firecracker Bootlog with Kata

``` [ 0.000000] Linux version 4.19.28 (mrcastel@kata) (gcc version 7.3.0 (Ubuntu 7.3.0-27ubuntu1~18.04)) #1 SMP Thu May 2 18:07:43 UTC 2019 [ 0.000000] Command line: init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service systemd.mask=systemd-udevd.socket systemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.timer systemd.mask=tmp.mount systemd.mask=systemd-random-seed.service agent.log=debug root=/dev/vda1 pci=off reboot=k panic=1 iommu=off net.ifnames=0 random.trust_cpu=on console=ttyS0 acpi=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@0xd0005 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 [ 0.000000] x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 [ 0.000000] x86/fpu: xstate_offset[5]: 960, xstate_sizes[5]: 64 [ 0.000000] x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]: 512 [ 0.000000] x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024 [ 0.000000] x86/fpu: Enabled xstate features 0xff, context size is 2560 bytes, using 'compacted' format. [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007fffffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.100662] kvm-clock: cpu 0, msr 24ae001, primary cpu clock [ 0.100662] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.100706] tsc: Detected 2592.016 MHz processor [ 0.102280] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.102283] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.102285] last_pfn = 0x80000 max_arch_pfn = 0x400000000 [ 0.102887] MTRR default type: uncachable [ 0.102888] MTRR fixed ranges disabled: [ 0.102889] 00000-FFFFF uncachable [ 0.102889] MTRR variable ranges disabled: [ 0.102890] 0 disabled [ 0.102890] 1 disabled [ 0.102890] 2 disabled [ 0.102890] 3 disabled [ 0.102890] 4 disabled [ 0.102891] 5 disabled [ 0.102891] 6 disabled [ 0.102891] 7 disabled [ 0.103062] Disabled [ 0.103225] CPU MTRRs all blank - virtualized system. [ 0.103247] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.103382] found SMP MP-table at [mem 0x0009fc00-0x0009fc0f] mapped at [(____ptrval____)] [ 0.103623] Base memory trampoline at [(____ptrval____)] 99000 size 24576 [ 0.103683] Using GB pages for direct mapping [ 0.103715] BRK [0x02600000, 0x02600fff] PGTABLE [ 0.103717] BRK [0x02601000, 0x02601fff] PGTABLE [ 0.103718] BRK [0x02602000, 0x02602fff] PGTABLE [ 0.104454] Zone ranges: [ 0.104525] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.104527] DMA32 [mem 0x0000000001000000-0x000000007fffffff] [ 0.104528] Normal empty [ 0.104556] Device empty [ 0.104560] Movable zone start for each node [ 0.104560] Early memory node ranges [ 0.104561] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.104562] node 0: [mem 0x0000000000100000-0x000000007fffffff] [ 0.104752] Reserved but unavailable: 98 pages [ 0.104786] Initmem setup node 0 [mem 0x0000000000001000-0x000000007fffffff] [ 0.104790] On node 0 totalpages: 524190 [ 0.104825] DMA zone: 64 pages used for memmap [ 0.104826] DMA zone: 21 pages reserved [ 0.104827] DMA zone: 3998 pages, LIFO batch:0 [ 0.106823] DMA32 zone: 8128 pages used for memmap [ 0.106824] DMA32 zone: 520192 pages, LIFO batch:63 [ 0.493012] Intel MultiProcessor Specification v1.4 [ 0.493019] MPTABLE: OEM ID: FC [ 0.493020] MPTABLE: Product ID: 000000000000 [ 0.493021] MPTABLE: APIC at: 0xFEE00000 [ 0.493388] Processor #0 (Bootup-CPU) [ 0.493657] IOAPIC[0]: apic_id 2, version 17, address 0xfec00000, GSI 0-23 [ 0.493659] Processors: 1 [ 0.493694] smpboot: Allowing 1 CPUs, 0 hotplug CPUs [ 0.493956] [mem 0x80000000-0xffffffff] available for PCI devices [ 0.493958] Booting paravirtualized kernel on KVM [ 0.493960] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.554656] setup_percpu: NR_CPUS:240 nr_cpumask_bits:240 nr_cpu_ids:1 nr_node_ids:1 [ 0.579890] percpu: Embedded 42 pages/cpu @(____ptrval____) s132888 r8192 d30952 u2097152 [ 0.579900] pcpu-alloc: s132888 r8192 d30952 u2097152 alloc=1*2097152 [ 0.579901] pcpu-alloc: [0] 0 [ 0.580066] KVM setup async PF for cpu 0 [ 0.580123] kvm-stealtime: cpu 0, msr 7da14e80 [ 0.580222] Built 1 zonelists, mobility grouping on. Total pages: 515977 [ 0.580226] Kernel command line: init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service systemd.mask=systemd-udevd.socket systemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.timer systemd.mask=tmp.mount systemd.mask=systemd-random-seed.service agent.log=debug root=/dev/vda1 pci=off reboot=k panic=1 iommu=off net.ifnames=0 random.trust_cpu=on console=ttyS0 acpi=off virtio_mmio.device=4K@0xd0000000:5 virtio_mmio.device=4K@0xd0001000:6 virtio_mmio.device=4K@0xd0002000:7 virtio_mmio.device=4K@0xd0003000:8 virtio_mmio.device=4K@0xd0004000:9 virtio_mmio.device=4K@ [ 0.605736] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.615717] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.621670] Memory: 2038040K/2096760K available (10248K kernel code, 629K rwdata, 2892K rodata, 836K init, 1416K bss, 58720K reserved, 0K cma-reserved) [ 0.622240] Kernel/User page tables isolation: enabled [ 0.623265] rcu: Hierarchical RCU implementation. [ 0.623269] rcu: RCU restricting CPUs from NR_CPUS=240 to nr_cpu_ids=1. [ 0.623272] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.623460] NR_IRQS: 15616, nr_irqs: 256, preallocated irqs: 16 [ 0.624054] rcu: Offload RCU callbacks from CPUs: (none). [ 0.624832] Console: colour dummy device 132x25 [ 1.128315] console [ttyS0] enabled [ 1.131718] APIC: Switch to symmetric I/O mode setup [ 1.139214] x2apic enabled [ 1.144283] Switched APIC routing to physical x2apic. [ 1.160747] ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 [ 1.166353] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x255cc622729, max_idle_ns: 440795286227 ns [ 1.175646] Calibrating delay loop (skipped) preset value.. 5184.03 BogoMIPS (lpj=2592016) [ 1.176648] pid_max: default: 32768 minimum: 301 [ 1.198369] Security Framework initialized [ 1.201961] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 1.208781] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 1.219603] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 1.219648] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 1.229650] Spectre V2 : Mitigation: Full generic retpoline [ 1.230646] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 1.231646] Spectre V2 : Enabling Restricted Speculation for firmware calls [ 1.232648] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier [ 1.233647] Speculative Store Bypass: Vulnerable [ 1.307441] Freeing SMP alternatives memory: 32K [ 1.315343] TSC deadline timer enabled [ 1.315367] smpboot: CPU0: Intel(R) Xeon(R) Processor @ 2.60GHz (family: 0x6, model: 0x55, stepping: 0x4) [ 1.317515] Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. [ 1.318395] rcu: Hierarchical SRCU implementation. [ 1.319675] random: crng done (trusting CPU's manufacturer) [ 1.321065] smp: Bringing up secondary CPUs ... [ 1.321668] smp: Brought up 1 node, 1 CPU [ 1.322667] smpboot: Max logical packages: 1 [ 1.323667] smpboot: Total of 1 processors activated (5184.03 BogoMIPS) [ 1.325715] devtmpfs: initialized [ 1.327006] x86/mm: Memory block size: 128MB [ 1.329349] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 1.329671] futex hash table entries: 256 (order: 2, 16384 bytes) [ 1.332823] NET: Registered protocol family 16 [ 1.334644] cpuidle: using governor menu [ 1.334837] PCI: Fatal: No config space access function found [ 1.340075] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 1.340669] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 1.343128] cryptd: max_cpu_qlen set to 1000 [ 1.344588] ACPI: Interpreter disabled. [ 1.345325] SCSI subsystem initialized [ 1.345829] pps_core: LinuxPPS API ver. 1 registered [ 1.346667] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.347669] PTP clock support registered [ 1.348684] PCI: System does not support PCI [ 1.350294] clocksource: Switched to clocksource kvm-clock [ 1.355129] pnp: PnP ACPI: disabled [ 1.363101] NET: Registered protocol family 2 [ 1.368333] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes) [ 1.375529] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 1.383058] TCP bind hash table entries: 16384 (order: 6, 262144 bytes) [ 1.391202] TCP: Hash tables configured (established 16384 bind 16384) [ 1.398378] UDP hash table entries: 1024 (order: 3, 32768 bytes) [ 1.404240] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) [ 1.410440] NET: Registered protocol family 1 [ 1.414314] PCI: CLS 0 bytes, default 64 [ 1.414575] virtio-mmio: Registering device virtio-mmio.0 at 0xd0000000-0xd0000fff, IRQ 5. [ 1.421867] virtio-mmio: Registering device virtio-mmio.1 at 0xd0001000-0xd0001fff, IRQ 6. [ 1.429177] virtio-mmio: Registering device virtio-mmio.2 at 0xd0002000-0xd0002fff, IRQ 7. [ 1.436516] virtio-mmio: Registering device virtio-mmio.3 at 0xd0003000-0xd0003fff, IRQ 8. [ 1.443789] virtio-mmio: Registering device virtio-mmio.4 at 0xd0004000-0xd0004fff, IRQ 9. [ 1.451089] virtio-mmio: Registering device virtio-mmio.5 at 0xd0005000-0xd0005fff, IRQ 10. [ 1.458372] virtio-mmio: Registering device virtio-mmio.6 at 0xd0006000-0xd0006fff, IRQ 11. [ 1.465848] virtio-mmio: Registering device virtio-mmio.7 at 0xd0007000-0xd0007fff, IRQ 12. [ 1.473248] virtio-mmio: Registering device virtio-mmio.8 at 0xd0008000-0xd0008fff, IRQ 13. [ 1.480537] virtio-mmio: Registering device virtio-mmio.9 at 0xd0009000-0xd0009fff, IRQ 14. [ 1.488296] virtio-mmio: Registering device virtio-mmio.10 at 0xd000a000-0xd000afff, IRQ 15. [ 1.495911] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x255cc622729, max_idle_ns: 440795286227 ns [ 1.504718] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 1.513631] workingset: timestamp_bits=46 max_order=19 bucket_order=0 [ 1.519967] SGI XFS with security attributes, no debug enabled [ 1.528190] 9p: Installing v9fs 9p2000 file system support [ 1.537916] NET: Registered protocol family 38 [ 1.541950] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 1.548387] io scheduler noop registered [ 1.551930] io scheduler deadline registered (default) [ 1.556517] io scheduler cfq registered [ 1.560030] io scheduler mq-deadline registered (default) [ 1.564843] io scheduler kyber registered [ 1.568581] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 1.575046] virtio-mmio virtio-mmio.0: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.585034] virtio-mmio virtio-mmio.1: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.595075] virtio-mmio virtio-mmio.2: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.605079] virtio-mmio virtio-mmio.3: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.615106] virtio-mmio virtio-mmio.4: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.625175] virtio-mmio virtio-mmio.5: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.635147] virtio-mmio virtio-mmio.6: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.645072] virtio-mmio virtio-mmio.7: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.655195] virtio-mmio virtio-mmio.8: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.665170] virtio-mmio virtio-mmio.9: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.675115] virtio-mmio virtio-mmio.10: Failed to enable 64-bit or 32-bit DMA. Trying to continue, but this might not work. [ 1.685336] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 1.727579] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a U6_16550A [ 1.771714] serial8250: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a U6_16550A [ 1.815477] serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a U6_16550A [ 1.830811] brd: module loaded [ 1.856817] loop: module loaded [ 1.864890] virtio_blk virtio0: [vda] 262144 512-byte logical blocks (134 MB/128 MiB) [ 1.873402] vda: vda1 [ 1.880987] virtio_blk virtio1: [vdb] 0 512-byte logical blocks (0 B/0 B) [ 1.891269] virtio_blk virtio2: [vdc] 0 512-byte logical blocks (0 B/0 B) [ 1.901828] virtio_blk virtio3: [vdd] 0 512-byte logical blocks (0 B/0 B) [ 1.912486] virtio_blk virtio4: [vde] 0 512-byte logical blocks (0 B/0 B) [ 1.923449] virtio_blk virtio5: [vdf] 0 512-byte logical blocks (0 B/0 B) [ 1.934881] virtio_blk virtio6: [vdg] 0 512-byte logical blocks (0 B/0 B) [ 1.946344] virtio_blk virtio7: [vdh] 0 512-byte logical blocks (0 B/0 B) [ 1.957919] virtio_blk virtio8: [vdi] 0 512-byte logical blocks (0 B/0 B) [ 1.965716] tun: Universal TUN/TAP device driver, 1.6 [ 1.974591] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k [ 1.981405] ixgbe: Copyright (c) 1999-2016 Intel Corporation. [ 1.987009] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver - version 4.1.0-k [ 1.995234] ixgbevf: Copyright (c) 2009 - 2015 Intel Corporation. [ 2.001002] i40e: Intel(R) Ethernet Connection XL710 Network Driver - version 2.3.2-k [ 2.007958] i40e: Copyright (c) 2013 - 2014 Intel Corporation. [ 2.013548] i40evf: Intel(R) 40-10 Gigabit Virtual Function Network Driver - version 3.2.2-k [ 2.020975] Copyright (c) 2013 - 2015 Intel Corporation. [ 2.026399] VFIO - User Level meta-driver version: 0.3 [ 2.031730] IR NEC protocol handler initialized [ 2.035866] IR RC5(x/sz) protocol handler initialized [ 2.040388] IR RC6 protocol handler initialized [ 2.044432] IR JVC protocol handler initialized [ 2.048494] IR Sony protocol handler initialized [ 2.052619] IR SANYO protocol handler initialized [ 2.056842] IR Sharp protocol handler initialized [ 2.061034] IR MCE Keyboard/mouse protocol handler initialized [ 2.066632] IR XMP protocol handler initialized [ 2.070793] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 2.082884] xt_time: kernel timezone is -0000 [ 2.087049] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 2.092436] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 2.099611] IPVS: ipvs loaded. [ 2.102435] IPVS: [rr] scheduler registered. [ 2.106253] IPVS: [wrr] scheduler registered. [ 2.110161] IPVS: [lc] scheduler registered. [ 2.113999] IPVS: [wlc] scheduler registered. [ 2.117924] IPVS: [fo] scheduler registered. [ 2.121775] IPVS: [ovf] scheduler registered. [ 2.125703] IPVS: [lblc] scheduler registered. [ 2.129724] IPVS: [lblcr] scheduler registered. [ 2.133830] IPVS: [dh] scheduler registered. [ 2.137754] IPVS: [sh] scheduler registered. [ 2.141630] IPVS: [sed] scheduler registered. [ 2.145527] IPVS: [nq] scheduler registered. [ 2.149383] IPVS: ftp: loaded support on port[0] = 21 [ 2.153902] IPVS: [sip] pe registered. [ 2.157810] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully [ 2.163464] Initializing XFRM netlink socket [ 2.167457] NET: Registered protocol family 10 [ 2.173356] Segment Routing with IPv6 [ 2.176760] NET: Registered protocol family 17 [ 2.180788] 9pnet: Installing 9P2000 support [ 2.187976] NET: Registered protocol family 40 [ 2.207262] registered taskstats version 1 [ 2.234468] EXT4-fs (vda1): recovery complete [ 2.239041] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null) [ 2.245942] VFS: Mounted root (ext4 filesystem) on device 254:1. [ 2.252113] devtmpfs: mounted [ 2.259398] Freeing unused kernel image memory: 836K [ 2.265877] Write protecting the kernel read-only data: 16384k [ 2.295411] Freeing unused kernel image memory: 2016K [ 2.315430] Freeing unused kernel image memory: 1204K [ 2.319906] Run /usr/lib/systemd/systemd as init process [ 2.543329] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT -SELINUX +IMA -APPARMOR -SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=legacy) [ 2.561833] systemd[1]: Detected virtualization kvm. [ 2.566552] systemd[1]: Detected architecture x86-64. [ 3.538526] systemd[1]: Condition check resulted in Remount Root and Kernel File Systems being skipped. [ 3.546931] systemd[1]: Reached target Local File Systems (Pre). [ 3.558136] systemd[1]: Reached target Local File Systems. [ 3.568548] systemd[1]: Condition check resulted in Flush Journal to Persistent Storage being skipped. [ 3.577119] systemd[1]: Condition check resulted in Load Kernel Modules being skipped. [ 3.594561] systemd[1]: Starting Apply Kernel Variables... [ 3.603244] systemd[76]: systemd-sysctl.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory [ 3.618429] systemd[1]: Condition check resulted in Create list of required static device nodes for the current kernel being skipped. [ 3.680920] systemd-sysctl[76]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory [ 3.964409] systemd[82]: chronyd.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory [ 5.485949] virtio_blk virtio1: [vdb] new size: 0 512-byte logical blocks (0 B/0 B) [ 5.498711] virtio_blk virtio1: [vdb] new size: 0 512-byte logical blocks (0 B/0 B) [ 5.996101] XFS (vdb): Mounting V5 Filesystem [ 6.072166] XFS (vdb): Ending clean mount ```

QEMU Bootlog with Kata

``` [ 0.000000] Linux version 4.19.28 (mrcastel@kata) (gcc version 7.3.0 (Ubuntu 7.3.0-27ubuntu1~18.04)) #1 SMP Thu May 2 18:07:43 UTC 2019 [ 0.000000] Command line: tsc=reliable no_timer_check rcupdate.rcu_expedited=1 i8042.direct=1 i8042.dumbkbd=1 i8042.nopnp=1 i8042.noaux=1 noreplace-smp reboot=k console=hvc0 console=hvc1 iommu=off cryptomgr.notests net.ifnames=0 pci=lastbus=0 root=/dev/pmem0p1 rootflags=dax,data=ordered,errors=remount-ro ro rootfstype=ext4 debug systemd.show_status=true systemd.log_level=debug panic=1 nr_cpus=8 agent.use_vsock=true init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.service sy stemd.mask=systemd-udevd.socket systemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-cleanup.time [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: xstate_offset[3]: 832, xstate_sizes[3]: 64 [ 0.000000] x86/fpu: xstate_offset[4]: 896, xstate_sizes[4]: 64 [ 0.000000] x86/fpu: xstate_offset[5]: 960, xstate_sizes[5]: 64 [ 0.000000] x86/fpu: xstate_offset[6]: 1024, xstate_sizes[6]: 512 [ 0.000000] x86/fpu: xstate_offset[7]: 1536, xstate_sizes[7]: 1024 [ 0.000000] x86/fpu: Enabled xstate features 0xff, context size is 2560 bytes, using 'compacted' format. [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007ffdefff] usable [ 0.000000] BIOS-e820: [mem 0x000000007ffdf000-0x000000007fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.380594] kvm-clock: cpu 0, msr 24ae001, primary cpu clock [ 0.380595] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.380596] tsc: Detected 2592.016 MHz processor [ 0.382248] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.382250] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.382252] last_pfn = 0x7ffdf max_arch_pfn = 0x400000000 [ 0.382832] MTRR default type: write-back [ 0.382832] MTRR fixed ranges enabled: [ 0.382833] 00000-9FFFF write-back [ 0.382834] A0000-BFFFF uncachable [ 0.382834] C0000-FFFFF write-protect [ 0.382835] MTRR variable ranges enabled: [ 0.382836] 0 base 0080000000 mask FF80000000 uncachable [ 0.382836] 1 disabled [ 0.382836] 2 disabled [ 0.382837] 3 disabled [ 0.382837] 4 disabled [ 0.382837] 5 disabled [ 0.382838] 6 disabled [ 0.382838] 7 disabled [ 0.383041] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.385960] found SMP MP-table at [mem 0x000f5ac0-0x000f5acf] mapped at [(____ptrval____)] [ 0.385965] Base memory trampoline at [(____ptrval____)] 99000 size 24576 [ 0.386077] Using GB pages for direct mapping [ 0.386100] BRK [0x02600000, 0x02600fff] PGTABLE [ 0.386102] BRK [0x02601000, 0x02601fff] PGTABLE [ 0.386102] BRK [0x02602000, 0x02602fff] PGTABLE [ 0.386117] BRK [0x02603000, 0x02603fff] PGTABLE [ 0.386169] BRK [0x02604000, 0x02604fff] PGTABLE [ 0.386254] ACPI: Early table checksum verification disabled [ 0.386282] ACPI: RSDP 0x00000000000F58C0 000014 (v00 BOCHS ) [ 0.386285] ACPI: RSDT 0x000000007FFE1E63 00003C (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.386289] ACPI: FACP 0x000000007FFE16CA 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.386292] ACPI: DSDT 0x000000007FFDF040 00268A (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.386294] ACPI: FACS 0x000000007FFDF000 000040 [ 0.386295] ACPI: APIC 0x000000007FFE17BE 0000B0 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.386297] ACPI: HPET 0x000000007FFE186E 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.386299] ACPI: SRAT 0x000000007FFE18A6 000150 (v01 BOCHS BXPCSRAT 00000001 BXPC 00000001) [ 0.386301] ACPI: SSDT 0x000000007FFE19F6 00038D (v01 BOCHS NVDIMM 00000001 BXPC 00000001) [ 0.386303] ACPI: NFIT 0x000000007FFE1D83 0000E0 (v01 BOCHS BXPCNFIT 00000001 BXPC 00000001) [ 0.386307] ACPI: Local APIC address 0xfee00000 [ 0.387246] Zone ranges: [ 0.387247] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.387248] DMA32 [mem 0x0000000001000000-0x000000007ffdefff] [ 0.387249] Normal empty [ 0.387250] Device empty [ 0.387251] Movable zone start for each node [ 0.387251] Early memory node ranges [ 0.387252] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.387252] node 0: [mem 0x0000000000100000-0x000000007ffdefff] [ 0.387437] Reserved but unavailable: 98 pages [ 0.387438] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdefff] [ 0.387439] On node 0 totalpages: 524157 [ 0.387440] DMA zone: 64 pages used for memmap [ 0.387441] DMA zone: 21 pages reserved [ 0.387441] DMA zone: 3998 pages, LIFO batch:0 [ 0.387481] DMA32 zone: 8128 pages used for memmap [ 0.387481] DMA32 zone: 520159 pages, LIFO batch:63 [ 0.414093] ACPI: Local APIC address 0xfee00000 [ 0.414135] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.414377] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.414379] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.414380] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.414381] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.414382] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.414382] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.414383] ACPI: IRQ0 used by override. [ 0.414384] ACPI: IRQ5 used by override. [ 0.414384] ACPI: IRQ9 used by override. [ 0.414385] ACPI: IRQ10 used by override. [ 0.414385] ACPI: IRQ11 used by override. [ 0.414386] Using ACPI (MADT) for SMP configuration information [ 0.414387] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.414390] smpboot: Allowing 8 CPUs, 7 hotplug CPUs [ 0.414485] [mem 0x80000000-0xfeffbfff] available for PCI devices [ 0.414486] Booting paravirtualized kernel on KVM [ 0.414488] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.463764] setup_percpu: NR_CPUS:240 nr_cpumask_bits:240 nr_cpu_ids:8 nr_node_ids:1 [ 0.465928] percpu: Embedded 42 pages/cpu @(____ptrval____) s132888 r8192 d30952 u262144 [ 0.465933] pcpu-alloc: s132888 r8192 d30952 u262144 alloc=1*2097152 [ 0.465934] pcpu-alloc: [0] 0 1 2 3 4 5 6 7 [ 0.466009] KVM setup async PF for cpu 0 [ 0.466069] kvm-stealtime: cpu 0, msr 7da14e80 [ 0.466073] Built 1 zonelists, mobility grouping on. Total pages: 515944 [ 0.466074] Kernel command line: tsc=reliable no_timer_check rcupdate.rcu_expedited=1 i8042.direct=1 i8042.dumbkbd=1 i8042.nopnp=1 i8042.noaux=1 noreplace-smp reboot=k console=hvc0 console=hvc1 iommu=off cryptomgr.notests net.ifnames=0 pci=lastbus=0 root=/dev/pmem0p1 rootflags=dax,data=ordered,errors=remount-ro ro rootfstype=ext4 debug systemd.show_statu s=true systemd.log_level=debug panic=1 nr_cpus=8 agent.use_vsock=true init=/usr/lib/systemd/systemd systemd.unit=kata-containers.target systemd.mask=systemd-networkd.service systemd.mask=systemd-networkd.socket systemd.mask=systemd-journald.service systemd.mask=systemd-journald.socket systemd.mask=systemd-journal-flush.service systemd.mask=systemd-udevd.ser vice systemd.mask=systemd-udevd.socket systemd.mask=systemd-udev-trigger.service systemd.mask=systemd-timesyncd.service systemd.mask=systemd-update-utmp.service systemd.mask=systemd-tmpfiles-setup.service systemd.mask=systemd-tmpfiles-cleanup.service systemd.mask=systemd-tmpfiles-clean [ 0.466750] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.466801] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.469908] Memory: 2036600K/2096628K available (10248K kernel code, 629K rwdata, 2892K rodata, 836K init, 1416K bss, 60028K reserved, 0K cma-reserved) [ 0.471703] Kernel/User page tables isolation: enabled [ 0.473511] rcu: Hierarchical RCU implementation. [ 0.473513] rcu: RCU restricting CPUs from NR_CPUS=240 to nr_cpu_ids=8. [ 0.473513] All grace periods are expedited (rcu_expedited). [ 0.473514] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8 [ 0.473591] NR_IRQS: 15616, nr_irqs: 488, preallocated irqs: 16 [ 0.473893] rcu: Offload RCU callbacks from CPUs: (none). [ 0.474571] Console: colour *CGA 80x25 [ 0.474576] ACPI: Core revision 20180810 [ 0.475542] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.476091] hpet clockevent registered [ 0.476115] APIC: Switch to symmetric I/O mode setup [ 0.478792] x2apic enabled [ 0.481489] Switched APIC routing to physical x2apic. [ 0.492891] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.493100] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x255cc622729, max_idle_ns: 440795286227 ns [246/9911] [ 0.493104] Calibrating delay loop (skipped) preset value.. 5184.03 BogoMIPS (lpj=2592016) [ 0.493106] pid_max: default: 32768 minimum: 301 [ 0.493184] Security Framework initialized [ 0.493229] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.493246] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.494489] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.494490] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.494493] Spectre V2 : Mitigation: Full generic retpoline [ 0.494494] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.494494] Speculative Store Bypass: Vulnerable [ 0.494623] Freeing SMP alternatives memory: 32K [ 0.495623] TSC deadline timer enabled [ 0.495733] smpboot: CPU0: Intel(R) Core(TM) i9-7980XE CPU @ 2.60GHz (family: 0x6, model: 0x55, stepping: 0x4) [ 0.495870] Performance Events: unsupported p6 CPU model 85 no PMU driver, software events only. [ 0.495935] rcu: Hierarchical SRCU implementation. [ 0.496022] random: crng done (trusting CPU's manufacturer) [ 0.496104] smp: Bringing up secondary CPUs ... [ 0.496104] smp: Brought up 1 node, 1 CPU [ 0.496104] smpboot: Max logical packages: 8 [ 0.496104] smpboot: Total of 1 processors activated (5184.03 BogoMIPS) [ 0.496255] devtmpfs: initialized [ 0.496279] x86/mm: Memory block size: 128MB [ 0.496593] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.496595] futex hash table entries: 2048 (order: 5, 131072 bytes) [ 0.496765] NET: Registered protocol family 16 [ 0.496912] cpuidle: using governor menu [ 0.497016] ACPI: bus type PCI registered [ 0.497017] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.497476] PCI: Using configuration type 1 for base access [ 0.498436] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.498437] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.498670] cryptd: max_cpu_qlen set to 1000 [ 0.498718] ACPI: Added _OSI(Module Device) [ 0.498719] ACPI: Added _OSI(Processor Device) [ 0.498720] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.498721] ACPI: Added _OSI(Processor Aggregator Device) [ 0.498722] ACPI: Added _OSI(Linux-Dell-Video) [ 0.498723] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.499459] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 0.501896] ACPI: Interpreter enabled [ 0.501900] ACPI: (supports S0 S5) [ 0.501901] ACPI: Using IOAPIC for interrupt routing [ 0.501908] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.502104] ACPI: Enabled 4 GPEs in block 00 to 0F [ 0.509692] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.509695] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 0.509698] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.509701] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.510285] acpiphp: Slot [3] registered [ 0.510515] acpiphp: Slot [4] registered [ 0.510750] acpiphp: Slot [5] registered [ 0.510968] acpiphp: Slot [6] registered [ 0.511204] acpiphp: Slot [7] registered [ 0.511433] acpiphp: Slot [8] registered [ 0.511662] acpiphp: Slot [9] registered [ 0.511890] acpiphp: Slot [10] registered [ 0.512128] acpiphp: Slot [11] registered [ 0.512359] acpiphp: Slot [12] registered [ 0.512577] acpiphp: Slot [13] registered [ 0.512795] acpiphp: Slot [14] registered [ 0.513013] acpiphp: Slot [15] registered [ 0.513270] acpiphp: Slot [16] registered [ 0.513516] acpiphp: Slot [17] registered [ 0.513744] acpiphp: Slot [18] registered [ 0.513972] acpiphp: Slot [19] registered [ 0.514203] acpiphp: Slot [20] registered [ 0.514432] acpiphp: Slot [21] registered [ 0.514663] acpiphp: Slot [22] registered [ 0.514880] acpiphp: Slot [23] registered [ 0.515097] acpiphp: Slot [24] registered [ 0.515272] acpiphp: Slot [25] registered [ 0.515489] acpiphp: Slot [26] registered [ 0.515707] acpiphp: Slot [27] registered [ 0.515925] acpiphp: Slot [28] registered [ 0.516127] acpiphp: Slot [29] registered [ 0.516345] acpiphp: Slot [30] registered [ 0.516562] acpiphp: Slot [31] registered [ 0.516636] PCI host bridge to bus 0000:00 [ 0.516638] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.516639] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.516640] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.516641] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] [ 0.516642] pci_bus 0000:00: root bus resource [mem 0x540000000-0x5bfffffff window] [ 0.516643] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.516952] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.520197] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.525074] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.529701] pci 0000:00:01.1: reg 0x20: [io 0xd140-0xd14f] [ 0.531659] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.531661] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.531663] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.532680] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.536745] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.536817] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.538556] pci 0000:00:02.0: [1b36:0001] type 01 class 0x060400 [ 0.540999] pci 0000:00:02.0: reg 0x10: [mem 0xfe600000-0xfe6000ff 64bit] [ 0.545677] pci 0000:00:03.0: [1af4:1003] type 00 class 0x078000 [ 0.547669] pci 0000:00:03.0: reg 0x10: [io 0xd000-0xd03f] [ 0.548913] pci 0000:00:03.0: reg 0x14: [mem 0xfe601000-0xfe601fff] [ 0.556126] pci 0000:00:04.0: [1af4:1004] type 00 class 0x010000 [ 0.557883] pci 0000:00:04.0: reg 0x10: [io 0xd040-0xd07f] [ 0.559715] pci 0000:00:04.0: reg 0x14: [mem 0xfe602000-0xfe602fff] [ 0.567206] pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 [ 0.569676] pci 0000:00:05.0: reg 0x10: [io 0xd100-0xd11f] [ 0.573793] pci 0000:00:05.0: reg 0x20: [mem 0xfea00000-0xfea03fff 64bit pref] [ 0.579908] pci 0000:00:06.0: [1af4:1012] type 00 class 0x078000 [ 0.581590] pci 0000:00:06.0: reg 0x10: [io 0xd120-0xd13f] [ 0.582567] pci 0000:00:06.0: reg 0x14: [mem 0xfe603000-0xfe603fff] [ 0.590062] pci 0000:00:07.0: [1af4:1009] type 00 class 0x000200 [ 0.591680] pci 0000:00:07.0: reg 0x10: [io 0xd080-0xd0bf] [ 0.592642] pci 0000:00:07.0: reg 0x14: [mem 0xfe604000-0xfe604fff] [ 0.600411] pci 0000:00:08.0: [1af4:1000] type 00 class 0x020000 [ 0.602018] pci 0000:00:08.0: reg 0x10: [io 0xd0c0-0xd0ff] [ 0.602748] pci 0000:00:08.0: reg 0x14: [mem 0xfe605000-0xfe605fff] [ 0.613665] pci_bus 0000:01: extended config space not accessible [ 0.613941] acpiphp: Slot [0] registered [ 0.614128] acpiphp: Slot [1] registered [ 0.614366] acpiphp: Slot [2] registered [ 0.614586] acpiphp: Slot [3-2] registered [ 0.614805] acpiphp: Slot [4-2] registered [ 0.615023] acpiphp: Slot [5-2] registered [ 0.615200] acpiphp: Slot [6-2] registered [ 0.615419] acpiphp: Slot [7-2] registered [ 0.615638] acpiphp: Slot [8-2] registered [ 0.615857] acpiphp: Slot [9-2] registered [ 0.616076] acpiphp: Slot [10-2] registered [ 0.616273] acpiphp: Slot [11-2] registered [ 0.616490] acpiphp: Slot [12-2] registered [ 0.616708] acpiphp: Slot [13-2] registered [ 0.616937] acpiphp: Slot [14-2] registered [ 0.617130] acpiphp: Slot [15-2] registered [ 0.617361] acpiphp: Slot [16-2] registered [ 0.617591] acpiphp: Slot [17-2] registered [ 0.617821] acpiphp: Slot [18-2] registered [ 0.618051] acpiphp: Slot [19-2] registered [ 0.618274] acpiphp: Slot [20-2] registered [ 0.618493] acpiphp: Slot [21-2] registered [ 0.618738] acpiphp: Slot [22-2] registered [ 0.618968] acpiphp: Slot [23-2] registered [ 0.619204] acpiphp: Slot [24-2] registered [ 0.619434] acpiphp: Slot [25-2] registered [ 0.619674] acpiphp: Slot [26-2] registered [ 0.619893] acpiphp: Slot [27-2] registered [ 0.620129] acpiphp: Slot [28-2] registered [ 0.620347] acpiphp: Slot [29-2] registered [ 0.620575] acpiphp: Slot [30-2] registered [ 0.620812] acpiphp: Slot [31-2] registered [ 0.623125] pci 0000:00:02.0: PCI bridge to [bus 01] [ 0.623267] pci 0000:00:02.0: bridge window [io 0xc000-0xcfff] [ 0.623409] pci 0000:00:02.0: bridge window [mem 0xfe400000-0xfe5fffff] [ 0.623691] pci 0000:00:02.0: bridge window [mem 0xfe800000-0xfe9fffff 64bit pref] [ 0.624345] pci_bus 0000:00: on NUMA node 0 [ 0.624988] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.625302] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.625641] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.625956] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.626132] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.627775] SCSI subsystem initialized [ 0.627783] pps_core: LinuxPPS API ver. 1 registered [ 0.627783] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.627785] PTP clock support registered [ 0.627787] PCI: Using ACPI for IRQ routing [ 0.627788] PCI: pci_cache_line_size set to 64 bytes [ 0.629357] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 0.629358] e820: reserve RAM buffer [mem 0x7ffdf000-0x7fffffff] [ 0.629581] clocksource: Switched to clocksource kvm-clock [ 0.629641] pnp: PnP ACPI init [ 0.629660] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.629672] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.629681] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.629686] pnp 00:03: [dma 2] [ 0.629691] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.630851] pnp: PnP ACPI: found 4 devices [ 0.632073] pci 0000:00:02.0: PCI bridge to [bus 01] [ 0.632193] pci 0000:00:02.0: bridge window [io 0xc000-0xcfff] [ 0.632854] pci 0000:00:02.0: bridge window [mem 0xfe400000-0xfe5fffff] [ 0.633299] pci 0000:00:02.0: bridge window [mem 0xfe800000-0xfe9fffff 64bit pref] [ 0.634125] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.634153] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.634154] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] [ 0.634155] pci_bus 0000:00: resource 8 [mem 0x540000000-0x5bfffffff window] [ 0.634156] pci_bus 0000:01: resource 0 [io 0xc000-0xcfff] [ 0.634157] pci_bus 0000:01: resource 1 [mem 0xfe400000-0xfe5fffff] [ 0.634158] pci_bus 0000:01: resource 2 [mem 0xfe800000-0xfe9fffff 64bit pref] [ 0.634214] NET: Registered protocol family 2 [ 0.634294] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes) [ 0.634301] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 0.634313] TCP bind hash table entries: 16384 (order: 6, 262144 bytes) [ 0.634329] TCP: Hash tables configured (established 16384 bind 16384) [ 0.634346] UDP hash table entries: 1024 (order: 3, 32768 bytes) [ 0.634349] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) [ 0.634377] NET: Registered protocol family 1 [ 0.634466] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.634535] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.634609] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.635359] PCI: CLS 0 bytes, default 64 [ 0.636582] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x255cc622729, max_idle_ns: 440795286227 ns [ 0.636640] alg: self-tests disabled [ 0.636744] workingset: timestamp_bits=46 max_order=19 bucket_order=0 [ 0.636832] SGI XFS with security attributes, no debug enabled [ 0.636937] 9p: Installing v9fs 9p2000 file system support [ 0.637259] NET: Registered protocol family 38 [ 0.637264] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) [ 0.637265] io scheduler noop registered [ 0.637265] io scheduler deadline registered (default) [ 0.637271] io scheduler cfq registered [ 0.637272] io scheduler mq-deadline registered (default) [ 0.637273] io scheduler kyber registered [ 0.637691] shpchp 0000:00:02.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.S10_) [ 0.637694] shpchp 0000:00:02.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) [ 0.637696] shpchp 0000:00:02.0: Cannot get control of SHPC hotplug [ 0.637703] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 0.637738] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 0.637740] ACPI: Power Button [PWRF] [ 0.657591] PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 0.658167] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver [ 0.674425] PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 0.674781] virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver [ 0.689392] PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 0.710283] PCI Interrupt Link [LNKB] enabled at IRQ 11 [ 0.710762] virtio-pci 0000:00:06.0: virtio_pci: leaving for legacy driver [ 0.725961] virtio-pci 0000:00:07.0: virtio_pci: leaving for legacy driver [ 0.741393] virtio-pci 0000:00:08.0: virtio_pci: leaving for legacy driver [ 0.742206] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 0.781367] console [hvc0] enabled [ 0.782717] brd: module loaded [ 0.785908] loop: module loaded [ 0.793639] pmem0: p1 [ 0.793875] pmem0: detected capacity change from 0 to 132120576 [ 0.799950] scsi host0: Virtio SCSI HBA [ 0.841364] tun: Universal TUN/TAP device driver, 1.6 [ 0.846286] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 5.1.0-k [ 0.846443] ixgbe: Copyright (c) 1999-2016 Intel Corporation. [ 0.846689] ixgbevf: Intel(R) 10 Gigabit PCI Express Virtual Function Network Driver - version 4.1.0-k [ 0.846872] ixgbevf: Copyright (c) 2009 - 2015 Intel Corporation. [ 0.847018] i40e: Intel(R) Ethernet Connection XL710 Network Driver - version 2.3.2-k [ 0.847222] i40e: Copyright (c) 2013 - 2014 Intel Corporation. [ 0.847371] i40evf: Intel(R) 40-10 Gigabit Virtual Function Network Driver - version 3.2.2-k [ 0.847561] Copyright (c) 2013 - 2015 Intel Corporation. [ 0.847712] VFIO - User Level meta-driver version: 0.3 [ 0.847852] IR NEC protocol handler initialized [ 0.847955] IR RC5(x/sz) protocol handler initialized [ 0.848059] IR RC6 protocol handler initialized [ 0.848205] IR JVC protocol handler initialized [ 0.848308] IR Sony protocol handler initialized [ 0.848412] IR SANYO protocol handler initialized [ 0.848516] IR Sharp protocol handler initialized [ 0.848619] IR MCE Keyboard/mouse protocol handler initialized [ 0.848747] IR XMP protocol handler initialized [ 0.848897] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 0.849250] xt_time: kernel timezone is -0000 [ 0.849371] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 0.849502] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 0.849682] IPVS: ipvs loaded. [ 0.849761] IPVS: [rr] scheduler registered. [ 0.849864] IPVS: [wrr] scheduler registered. [ 0.849967] IPVS: [lc] scheduler registered. [ 0.850070] IPVS: [wlc] scheduler registered. [ 0.850212] IPVS: [fo] scheduler registered. [ 0.850315] IPVS: [ovf] scheduler registered. [ 0.850420] IPVS: [lblc] scheduler registered. [ 0.850525] IPVS: [lblcr] scheduler registered. [ 0.850628] IPVS: [dh] scheduler registered. [ 0.850732] IPVS: [sh] scheduler registered. [ 0.850835] IPVS: [sed] scheduler registered. [ 0.850938] IPVS: [nq] scheduler registered. [ 0.851043] IPVS: ftp: loaded support on port[0] = 21 [ 0.848205] IR JVC protocol handler initialized [ 0.848308] IR Sony protocol handler initialized [ 0.848412] IR SANYO protocol handler initialized [ 0.848516] IR Sharp protocol handler initialized [ 0.848619] IR MCE Keyboard/mouse protocol handler initialized [ 0.848747] IR XMP protocol handler initialized [ 0.848897] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: dm-devel@redhat.com [ 0.849250] xt_time: kernel timezone is -0000 [ 0.849371] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 0.849502] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 0.849682] IPVS: ipvs loaded. [ 0.849761] IPVS: [rr] scheduler registered. [ 0.849864] IPVS: [wrr] scheduler registered. [ 0.849967] IPVS: [lc] scheduler registered. [ 0.850070] IPVS: [wlc] scheduler registered. [ 0.850212] IPVS: [fo] scheduler registered. [ 0.850315] IPVS: [ovf] scheduler registered. [ 0.850420] IPVS: [lblc] scheduler registered. [ 0.850525] IPVS: [lblcr] scheduler registered. [ 0.850628] IPVS: [dh] scheduler registered. [ 0.850732] IPVS: [sh] scheduler registered. [ 0.850835] IPVS: [sed] scheduler registered. [ 0.850938] IPVS: [nq] scheduler registered. [ 0.851043] IPVS: ftp: loaded support on port[0] = 21 [ 0.851184] IPVS: [sip] pe registered. [ 0.851312] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully [ 0.851479] Initializing XFRM netlink socket [ 0.851597] NET: Registered protocol family 10 [ 0.851951] Segment Routing with IPv6 [ 0.852044] NET: Registered protocol family 17 [ 0.852198] 9pnet: Installing 9P2000 support [ 0.858927] NET: Registered protocol family 40 [ 0.859843] registered taskstats version 1 [ 0.860246] EXT4-fs (pmem0p1): DAX enabled. Warning: EXPERIMENTAL, use at your own risk [ 0.860498] EXT4-fs (pmem0p1): INFO: recovery required on readonly filesystem [ 0.860664] EXT4-fs (pmem0p1): write access will be enabled during recovery [ 0.865321] EXT4-fs (pmem0p1): recovery complete [ 0.865630] EXT4-fs (pmem0p1): mounted filesystem with ordered data mode. Opts: dax,data=ordered,errors=remount-ro [ 0.865890] VFS: Mounted root (ext4 filesystem) readonly on device 259:1. [ 0.866086] devtmpfs: mounted [ 0.866478] Freeing unused kernel image memory: 836K [ 0.869203] Write protecting the kernel read-only data: 16384k [ 0.869702] Freeing unused kernel image memory: 2016K [ 0.869953] Freeing unused kernel image memory: 1204K [ 0.870098] Run /usr/lib/systemd/systemd as init process [ 1.036741] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT -SELINUX +IMA -APPARMOR -SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=legacy) [ 1.037604] systemd[1]: No virtualization found in DMI [ 1.037866] systemd[1]: Virtualization found, CPUID=KVMKVMKVM [ 1.038160] systemd[1]: Found VM virtualization kvm [ 1.038350] systemd[1]: Detected virtualization kvm. [ 1.038660] systemd[1]: Detected architecture x86-64. [ 1.038981] systemd[1]: Mounting cgroup to /sys/fs/cgroup/pids of type cgroup with options pids. [ 1.039504] systemd[1]: Mounting cgroup to /sys/fs/cgroup/freezer of type cgroup with options freezer. [ 1.039929] systemd[1]: Mounting cgroup to /sys/fs/cgroup/cpu,cpuacct of type cgroup with options cpu,cpuacct. [ 1.045970] systemd[1]: Mounting cgroup to /sys/fs/cgroup/blkio of type cgroup with options blkio. [ 1.261365] systemd-fstab-generator[72]: Parsing /etc/fstab [ 1.274550] systemd-getty-generator[73]: Automatically adding serial getty for /dev/hvc0. [ 1.281359] systemd-getty-generator[73]: Automatically adding serial getty for /dev/hvc0. [ 1.294027] systemd-bless-boot-generator[69]: Skipping generator, not an EFI boot. [ 1.302830] systemd-gpt-auto-generator[74]: Failed to chase block device '/', ignoring: No such file or directory [ 1.311009] systemd-gpt-auto-generator[74]: pmem0p1: Root device /dev/pmem0. [ 1.380510] systemd-gpt-auto-generator[74]: No suitable partition table found, ignoring. [ 1.545804] systemd[76]: systemd-sysctl.service: Failed to connect stdout to the journal socket, ignoring: No such file or directory [ 1.546662] systemd[76]: systemd-sysctl.service: Executing: /usr/lib/systemd/systemd-sysctl [ 1.556533] systemd-sysctl[76]: Parsing /usr/lib/sysctl.d/50-coredump.conf [ 1.556866] systemd-sysctl[76]: Parsing /usr/lib/sysctl.d/50-default.conf [ 1.557820] systemd-sysctl[76]: Setting 'kernel/core_pattern' to '|/usr/lib/systemd/coredump-wrapper %E %P %u %g %s %t %c %h %e'. [ 1.560212] systemd-sysctl[76]: Setting 'kernel/sysrq' to '16'. [ 1.560547] systemd-sysctl[76]: Couldn't write '16' to 'kernel/sysrq', ignoring: No such file or directory [ 1.561754] systemd-sysctl[76]: Setting 'kernel/core_uses_pid' to '1'. [ 1.562689] systemd-sysctl[76]: Setting 'net/ipv4/conf/all/rp_filter' to '2'. [ 1.563814] systemd-sysctl[76]: Setting 'net/ipv4/conf/all/accept_source_route' to '0'. [ 1.564927] systemd-sysctl[76]: Setting 'net/ipv4/conf/all/promote_secondaries' to '1'. [ 1.566036] systemd-sysctl[76]: Setting 'net/core/default_qdisc' to 'fq'. [ 1.566903] systemd-sysctl: 4 output lines suppressed due to ratelimiting [ 1.577577] systemd[81]: kata-agent.service: Executing: /usr/bin/kata-agent [ 2.442148] scsi 0:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 2.442734] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 2.442920] sd 0:0:0:0: Power-on or device reset occurred [ 2.443873] sd 0:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB) [ 2.444226] sd 0:0:0:0: [sda] Write Protect is off [ 2.444450] sd 0:0:0:0: [sda] Mode Sense: 63 00 00 08 [ 2.444779] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 2.455063] sd 0:0:0:0: [sda] Attached SCSI disk [ 2.467250] pci 0000:00:02.0: PCI bridge to [bus 01] [ 2.467672] pci 0000:00:02.0: bridge window [io 0xc000-0xcfff] [ 2.468914] pci 0000:00:02.0: bridge window [mem 0xfe400000-0xfe5fffff] [ 2.469833] pci 0000:00:02.0: bridge window [mem 0xfe800000-0xfe9fffff 64bit pref] [ 2.474987] XFS (sda): Mounting V5 Filesystem [ 2.511690] XFS (sda): Ending clean mount ```

mcastelino commented 5 years ago

/cc @jodh-intel

grahamwhaley commented 5 years ago

@mcastelino - can you provide some details on what command you ran, and on what sort of system? It is very very rare I see a kata qemu boot take >3s - normally I'd be seeing <1.5s to get into the workload...

But, if fc can get into workload in <0.5s, then great, let's look at those differences!

mcastelino commented 5 years ago

@grahamwhaley https://github.com/mcastelino/fireclient/tree/topic/kata-is-slow

https://github.com/mcastelino/fireclient/commit/f2cd2bbbfea679b7d65d734800a266cb51b84403

mcastelino commented 5 years ago

Closing this as tests were being run in nested VMs. On a bare-metal system firecracker is much faster. Closing this issue.