search

cloud-hypervisor / cloud-hypervisor

A Virtual Machine Monitor for modern Cloud workloads. Features include CPU, memory and device hotplug, support for running Windows and Linux guests, device offload with vhost-user and a minimal compact footprint. Written in Rust with a strong focus on security.
https://www.cloudhypervisor.org
4.07k stars 453 forks source link

ch-socket resize --memory hangs when vm running with --memory hotplug_method=virtio-mem #4001

Closed dengxuehua closed 2 years ago

dengxuehua commented 2 years ago

Describe the bug The ch-socket hangs on executing resize --memory if vm using --memory hotplug_method=virtio-mem.

To Reproduce Launch a VM with focal (Ubuntu 20.04) cloud image (link) , hypervisor-fw and --memory hotplug_method=virtio-mem. Run command line ./ch-remote --api-socket=/tmp/ch-socket resize --memory 3G.

Version

cloud-hypervisor v22.1(upstream version) Built with default feature (e.g. using cargo build --release)

VM configuration

What command line did you run (or JSON config data):

target/release/cloud-hypervisor \    
    --kernel hypervisor-fw  \         
    --cmdline "console=ttyS0 console=hvc0 root=/dev/vda1 rw" \         
    --disk path=focal-server-cloudimg-amd64.raw \     
    --memory size=1024M,hotplug_size=8192M,hotplug_method=virtio-mem \     
    --net "tap=,mac=,ip=,mask=" \    
    --api-socket=/tmp/ch-socket \     
    --log-file /tmp/clh.log -v

Guest OS version details:

ubuntu@ubuntu:~$ uname -a
Linux ubuntu 5.4.0-99-generic #112-Ubuntu SMP Thu Feb 3 13:50:55 UTC 2022 x86_64 x86_64 x86_64 GNU/Linux
ubuntu@ubuntu:~$ lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description:    Ubuntu 20.04.3 LTS
Release:    20.04
Codename:   focal

Host OS version details:

ubuntu@ubuntu:~$ uname  -a
Linux ubuntu 5.4.0-104-generic #118-Ubuntu SMP Wed Mar 2 19:02:41 UTC 2022 x86_64 x86_64 x86_64 GNU/Linux

Logs

Output of `cloud-hypervisor -v` from either standard error or via `--log-file`: 

cloud-hypervisor: 571.152µs:  INFO:vmm/src/lib.rs:1578 -- API request event: VmCreate(Mutex { data: VmConfig { cpus: CpusConfig { boot_vcpus: 1, max_vcpus: 1, topology: None, kvm_hyperv: false, max_phys_bits: 46, affinity: None }, memory: MemoryConfig { size: 1073741824, mergeable: false, hotplug_method: VirtioMem, hotplug_size: Some(8589934592), hotplugged_size: None, shared: false, hugepages: false, hugepage_size: None, prefault: false, zones: None }, kernel: Some(KernelConfig { path: "/opt/hypervisor-fw" }), initramfs: None, cmdline: CmdlineConfig { args: "console=ttyS0 console=hvc0 root=/dev/vda1 rw" }, disks: Some([DiskConfig { path: Some("/test/cloud-hypervisor/focal-server-cloudimg-amd64-pmem.raw"), readonly: false, direct: false, iommu: false, num_queues: 1, queue_size: 128, vhost_user: false, vhost_socket: None, poll_queue: true, rate_limiter_config: None, id: None, disable_io_uring: false, pci_segment: 0 }]), net: Some([NetConfig { tap: None, ip: 192.168.249.1, mask: 255.255.255.0, mac: MacAddr { bytes: [46, 216, 183, 90, 251, 2] }, host_mac: None, iommu: false, num_queues: 2, queue_size: 256, vhost_user: false, vhost_socket: None, vhost_mode: Client, id: None, fds: None, rate_limiter_config: None, pci_segment: 0 }]), rng: RngConfig { src: "/dev/urandom", iommu: false }, balloon: None, fs: None, pmem: None, serial: ConsoleConfig { file: None, mode: Null, iommu: false }, console: ConsoleConfig { file: None, mode: Tty, iommu: false }, devices: None, user_devices: None, vsock: None, iommu: false, sgx_epc: None, numa: None, watchdog: false, platform: None }, poisoned: false, .. }, Sender { .. })
cloud-hypervisor: 1.365663ms:  INFO:vmm/src/lib.rs:1578 -- API request event: VmBoot(Sender { .. })
cloud-hypervisor: 2.439064ms:  INFO:vmm/src/memory_manager.rs:1475 -- Creating userspace mapping: 0 -> 7f2cf0000000 40000000, slot 0
cloud-hypervisor: 4.981577ms:  INFO:vmm/src/memory_manager.rs:1509 -- Created userspace mapping: 0 -> 7f2cf0000000 40000000
cloud-hypervisor: 5.020712ms:  INFO:vmm/src/memory_manager.rs:1475 -- Creating userspace mapping: 100000000 -> 7f2af0000000 200000000, slot 1
cloud-hypervisor: 26.970075ms:  INFO:vmm/src/memory_manager.rs:1509 -- Created userspace mapping: 100000000 -> 7f2af0000000 200000000
cloud-hypervisor: 27.017366ms:  INFO:vmm/src/vm.rs:567 -- Booting VM from config: Mutex { data: VmConfig { cpus: CpusConfig { boot_vcpus: 1, max_vcpus: 1, topology: None, kvm_hyperv: false, max_phys_bits: 46, affinity: None }, memory: MemoryConfig { size: 1073741824, mergeable: false, hotplug_method: VirtioMem, hotplug_size: Some(8589934592), hotplugged_size: None, shared: false, hugepages: false, hugepage_size: None, prefault: false, zones: None }, kernel: Some(KernelConfig { path: "/opt/hypervisor-fw" }), initramfs: None, cmdline: CmdlineConfig { args: "console=ttyS0 console=hvc0 root=/dev/vda1 rw" }, disks: Some([DiskConfig { path: Some("/test/cloud-hypervisor/focal-server-cloudimg-amd64-pmem.raw"), readonly: false, direct: false, iommu: false, num_queues: 1, queue_size: 128, vhost_user: false, vhost_socket: None, poll_queue: true, rate_limiter_config: None, id: None, disable_io_uring: false, pci_segment: 0 }]), net: Some([NetConfig { tap: None, ip: 192.168.249.1, mask: 255.255.255.0, mac: MacAddr { bytes: [46, 216, 183, 90, 251, 2] }, host_mac: None, iommu: false, num_queues: 2, queue_size: 256, vhost_user: false, vhost_socket: None, vhost_mode: Client, id: None, fds: None, rate_limiter_config: None, pci_segment: 0 }]), rng: RngConfig { src: "/dev/urandom", iommu: false }, balloon: None, fs: None, pmem: None, serial: ConsoleConfig { file: None, mode: Null, iommu: false }, console: ConsoleConfig { file: None, mode: Tty, iommu: false }, devices: None, user_devices: None, vsock: None, iommu: false, sgx_epc: None, numa: None, watchdog: false, platform: None }, poisoned: false, .. }
cloud-hypervisor: 27.805055ms:  INFO:vmm/src/pci_segment.rs:92 -- Adding PCI segment: id=0, PCI MMIO config address: 0xe8000000, device area [0x300000000-0x3ffeffffffff
cloud-hypervisor: 28.031983ms:  INFO:vmm/src/device_manager.rs:1959 -- Creating virtio-block device: DiskConfig { path: Some("/test/cloud-hypervisor/focal-server-cloudimg-amd64-pmem.raw"), readonly: false, direct: false, iommu: false, num_queues: 1, queue_size: 128, vhost_user: false, vhost_socket: None, poll_queue: true, rate_limiter_config: None, id: Some("_disk0"), disable_io_uring: false, pci_segment: 0 }
cloud-hypervisor: 28.28076ms:  INFO:block_util/src/lib.rs:532 -- io_uring not supported: failed to create io_uring instance: Function not implemented (os error 38)
cloud-hypervisor: 28.320344ms:  INFO:vmm/src/device_manager.rs:2044 -- Using synchronous RAW disk file
cloud-hypervisor: 28.350111ms:  INFO:virtio-devices/src/block.rs:445 -- Disk topology: DiskTopology { logical_block_size: 512, physical_block_size: 512, minimum_io_size: 512, optimal_io_size: 0 }
cloud-hypervisor: 28.412051ms:  INFO:vmm/src/device_manager.rs:2128 -- Creating virtio-net device: NetConfig { tap: None, ip: 192.168.249.1, mask: 255.255.255.0, mac: MacAddr { bytes: [46, 216, 183, 90, 251, 2] }, host_mac: None, iommu: false, num_queues: 2, queue_size: 256, vhost_user: false, vhost_socket: None, vhost_mode: Client, id: Some("_net1"), fds: None, rate_limiter_config: None, pci_segment: 0 }
cloud-hypervisor: 29.410972ms:  INFO:vmm/src/device_manager.rs:2274 -- Creating virtio-rng device: RngConfig { src: "/dev/urandom", iommu: false }
cloud-hypervisor: 29.495672ms:  INFO:vmm/src/device_manager.rs:2739 -- Creating virtio-mem device: id = mem0
cloud-hypervisor: 29.817016ms:  INFO:vmm/src/vm.rs:2024 -- Booting VM
cloud-hypervisor: 29.843921ms:  INFO:vmm/src/vm.rs:1040 -- Loading kernel
cloud-hypervisor: 30.005796ms:  INFO:vmm/src/vm.rs:1100 -- Kernel loaded: entry_addr = 0x11205e
cloud-hypervisor: 30.033137ms:  INFO:vmm/src/cpu.rs:707 -- Request to create new vCPUs: desired = 1, max = 1, allocated = 0, present = 0
cloud-hypervisor: 30.069013ms:  INFO:vmm/src/cpu.rs:673 -- Creating vCPU: cpu_id = 0
cloud-hypervisor: 30.806711ms:  INFO:vmm/src/cpu.rs:297 -- Configuring vCPU: cpu_id = 0
cloud-hypervisor: 31.211174ms:  INFO:vmm/src/acpi.rs:773 -- Generated ACPI tables: took 331µs
cloud-hypervisor: 31.241753ms:  INFO:vmm/src/vm.rs:2006 -- Created ACPI tables: rsdp_addr = 0xa0000
cloud-hypervisor: 31.268446ms:  INFO:vmm/src/vm.rs:1111 -- Configuring system
cloud-hypervisor: 31.311759ms:  INFO:vmm/src/cpu.rs:1021 -- Starting vCPUs: desired = 1, allocated = 1, present = 0
cloud-hypervisor: 31.376157ms:  INFO:vmm/src/cpu.rs:810 -- Starting vCPU: cpu_id = 0
cloud-hypervisor: 38.975502ms:  INFO:virtio-devices/src/transport/pci_device.rs:1045 -- _virtio-pci-_disk0: Needs activation; writing to activate event fd
cloud-hypervisor: 39.017776ms:  INFO:virtio-devices/src/transport/pci_device.rs:1050 -- _virtio-pci-_disk0: Needs activation; returning barrier
cloud-hypervisor: 39.049724ms:  INFO:vmm/src/vm.rs:453 -- Waiting for barrier
cloud-hypervisor: 39.022867ms:  INFO:vmm/src/lib.rs:1563 -- Trying to activate pending virtio devices: count = 1
cloud-hypervisor: 39.11992ms:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_net1: Device does not need activation
cloud-hypervisor: 39.147405ms:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_console: Device does not need activation
cloud-hypervisor: 39.192996ms:  INFO:virtio-devices/src/block.rs:526 -- Changing cache mode to writethrough
cloud-hypervisor: 39.300825ms:  INFO:virtio-devices/src/transport/pci_device.rs:693 -- _virtio-pci-_disk0: Waiting for barrier
cloud-hypervisor: 39.33233ms:  INFO:virtio-devices/src/transport/pci_device.rs:695 -- _virtio-pci-_disk0: Barrier released
cloud-hypervisor: 39.361854ms:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_rng: Device does not need activation
cloud-hypervisor: 39.391409ms:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-mem0: Device does not need activation
cloud-hypervisor: 39.336814ms:  INFO:vmm/src/vm.rs:455 -- Barrier released
cloud-hypervisor: 6.800038126s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x87
cloud-hypervisor: 6.820256997s:  INFO:virtio-devices/src/device.rs:364 -- Resuming virtio-block
cloud-hypervisor: 6.820326586s: <_disk0_q0> INFO:virtio-devices/src/epoll_helper.rs:135 -- KILL_EVENT received, stopping epoll loop
cloud-hypervisor: 6.849274626s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x2f9
cloud-hypervisor: 6.849320952s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x2f9
cloud-hypervisor: 6.849356494s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x2f9
cloud-hypervisor: 6.849390818s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x2f9
cloud-hypervisor: 6.849425935s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x2f9
cloud-hypervisor: 6.849461431s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x2f9
cloud-hypervisor: 6.849545291s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x3e9
cloud-hypervisor: 6.849581232s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x3e9
cloud-hypervisor: 6.849616099s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x3e9
cloud-hypervisor: 6.849651376s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x3e9
cloud-hypervisor: 6.849685447s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x3e9
cloud-hypervisor: 6.849720074s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x3e9
cloud-hypervisor: 6.849811292s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x2e9
cloud-hypervisor: 6.849847977s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x2e9
cloud-hypervisor: 6.849883157s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x2e9
cloud-hypervisor: 6.84991719s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x2e9
cloud-hypervisor: 6.849952328s:  WARN:vmm/src/vm.rs:476 -- Guest PIO read to unregistered address 0x2e9
cloud-hypervisor: 6.849987242s:  WARN:vmm/src/vm.rs:501 -- Guest PIO write to unregistered address 0x2e9
cloud-hypervisor: 6.852666102s:  INFO:virtio-devices/src/transport/pci_device.rs:1045 -- _virtio-pci-_console: Needs activation; writing to activate event fd
cloud-hypervisor: 6.852705483s:  INFO:virtio-devices/src/transport/pci_device.rs:1050 -- _virtio-pci-_console: Needs activation; returning barrier
cloud-hypervisor: 6.852745219s:  INFO:vmm/src/vm.rs:453 -- Waiting for barrier
cloud-hypervisor: 6.852727066s:  INFO:vmm/src/lib.rs:1563 -- Trying to activate pending virtio devices: count = 1
cloud-hypervisor: 6.852829337s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_net1: Device does not need activation
cloud-hypervisor: 6.852964567s:  INFO:virtio-devices/src/transport/pci_device.rs:693 -- _virtio-pci-_console: Waiting for barrier
cloud-hypervisor: 6.85299679s:  INFO:virtio-devices/src/transport/pci_device.rs:695 -- _virtio-pci-_console: Barrier released
cloud-hypervisor: 6.853027191s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_disk0: Device does not need activation
cloud-hypervisor: 6.853059559s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_rng: Device does not need activation
cloud-hypervisor: 6.853098433s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-mem0: Device does not need activation
cloud-hypervisor: 6.853003606s:  INFO:vmm/src/vm.rs:455 -- Barrier released
cloud-hypervisor: 7.144127705s:  INFO:virtio-devices/src/transport/pci_device.rs:1045 -- _virtio-pci-_disk0: Needs activation; writing to activate event fd
cloud-hypervisor: 7.144174424s:  INFO:virtio-devices/src/transport/pci_device.rs:1050 -- _virtio-pci-_disk0: Needs activation; returning barrier
cloud-hypervisor: 7.144218302s:  INFO:vmm/src/vm.rs:453 -- Waiting for barrier
cloud-hypervisor: 7.144182088s:  INFO:vmm/src/lib.rs:1563 -- Trying to activate pending virtio devices: count = 1
cloud-hypervisor: 7.144285907s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_net1: Device does not need activation
cloud-hypervisor: 7.144312886s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_console: Device does not need activation
cloud-hypervisor: 7.144362465s:  INFO:virtio-devices/src/block.rs:526 -- Changing cache mode to writeback
cloud-hypervisor: 7.144467062s:  INFO:virtio-devices/src/transport/pci_device.rs:693 -- _virtio-pci-_disk0: Waiting for barrier
cloud-hypervisor: 7.144498315s:  INFO:virtio-devices/src/transport/pci_device.rs:695 -- _virtio-pci-_disk0: Barrier released
cloud-hypervisor: 7.144528824s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_rng: Device does not need activation
cloud-hypervisor: 7.144559171s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-mem0: Device does not need activation
cloud-hypervisor: 7.14450524s:  INFO:vmm/src/vm.rs:455 -- Barrier released
cloud-hypervisor: 7.19000548s:  INFO:virtio-devices/src/transport/pci_device.rs:1045 -- _virtio-pci-_net1: Needs activation; writing to activate event fd
cloud-hypervisor: 7.190048388s:  INFO:virtio-devices/src/transport/pci_device.rs:1050 -- _virtio-pci-_net1: Needs activation; returning barrier
cloud-hypervisor: 7.190086717s:  INFO:vmm/src/vm.rs:453 -- Waiting for barrier
cloud-hypervisor: 7.190055538s:  INFO:vmm/src/lib.rs:1563 -- Trying to activate pending virtio devices: count = 1
cloud-hypervisor: 7.19036698s:  INFO:virtio-devices/src/transport/pci_device.rs:693 -- _virtio-pci-_net1: Waiting for barrier
cloud-hypervisor: 7.190401891s:  INFO:virtio-devices/src/transport/pci_device.rs:695 -- _virtio-pci-_net1: Barrier released
cloud-hypervisor: 7.190434833s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_console: Device does not need activation
cloud-hypervisor: 7.19046513s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_disk0: Device does not need activation
cloud-hypervisor: 7.190494894s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_rng: Device does not need activation
cloud-hypervisor: 7.190527323s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-mem0: Device does not need activation
cloud-hypervisor: 7.190412432s:  INFO:vmm/src/vm.rs:455 -- Barrier released
cloud-hypervisor: 7.190623813s: <_net1_ctrl> INFO:net_util/src/ctrl_queue.rs:99 -- Number of MQ pairs requested: 1
cloud-hypervisor: 9.441731906s:  INFO:virtio-devices/src/transport/pci_device.rs:1045 -- _virtio-pci-_rng: Needs activation; writing to activate event fd
cloud-hypervisor: 9.441784521s:  INFO:virtio-devices/src/transport/pci_device.rs:1050 -- _virtio-pci-_rng: Needs activation; returning barrier
cloud-hypervisor: 9.441817297s:  INFO:vmm/src/vm.rs:453 -- Waiting for barrier
cloud-hypervisor: 9.441796866s:  INFO:vmm/src/lib.rs:1563 -- Trying to activate pending virtio devices: count = 1
cloud-hypervisor: 9.441894913s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_net1: Device does not need activation
cloud-hypervisor: 9.441923527s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_console: Device does not need activation
cloud-hypervisor: 9.441950721s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-_disk0: Device does not need activation
cloud-hypervisor: 9.44207483s:  INFO:virtio-devices/src/transport/pci_device.rs:693 -- _virtio-pci-_rng: Waiting for barrier
cloud-hypervisor: 9.442115432s:  INFO:virtio-devices/src/transport/pci_device.rs:695 -- _virtio-pci-_rng: Barrier released
cloud-hypervisor: 9.442152927s:  INFO:virtio-devices/src/transport/pci_device.rs:697 -- _virtio-pci-mem0: Device does not need activation
cloud-hypervisor: 9.442123059s:  INFO:vmm/src/vm.rs:455 -- Barrier released
cloud-hypervisor: 3377.803414269s:  INFO:vmm/src/lib.rs:1578 -- API request event: VmResize(VmResizeData { desired_vcpus: None, desired_ram: Some(3221225472), desired_balloon: None }, Sender { .. })
Linux kernel output: 

[    0.000000] Linux version 5.4.0-99-generic (buildd@lgw01-amd64-007) (gcc version 9.3.0 (Ubuntu 9.3.0-17ubuntu1~20.04)) #112-Ubuntu SMP Thu Feb 3 13:50:55 UTC 2022 (Ubuntu 5.4.0-99.112-generic 5.4.162)
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-5.4.0-99-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Hygon HygonGenuine
[    0.000000]   Centaur CentaurHauls
[    0.000000]   zhaoxin   Shanghai  
[    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: Supporting XSAVE feature 0x200: 'Protection Keys User registers'
[    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: xstate_offset[9]: 2560, xstate_sizes[9]:    8
[    0.000000] x86/fpu: Enabled xstate features 0x2ff, context size is 2568 bytes, using 'compacted' format.
[    0.000000] BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000000013ffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000140000-0x000000003fffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] efi: EFI v2.80 by 
[    0.000000] efi:  ACPI 2.0=0xa0000 
[    0.000000] secureboot: Secure boot disabled
[    0.000000] DMI not present or invalid.
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 2be01001, primary cpu clock
[    0.000000] kvm-clock: using sched offset of 6293192181 cycles
[    0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000006] tsc: Detected 2294.608 MHz processor
[    0.000231] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000233] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000236] last_pfn = 0x40000 max_arch_pfn = 0x400000000
[    0.000268] MTRR default type: write-back
[    0.000269] MTRR fixed ranges disabled:
[    0.000271]   00000-FFFFF uncachable
[    0.000271] MTRR variable ranges enabled:
[    0.000272]   0 disabled
[    0.000273]   1 disabled
[    0.000273]   2 disabled
[    0.000274]   3 disabled
[    0.000274]   4 disabled
[    0.000275]   5 disabled
[    0.000275]   6 disabled
[    0.000275]   7 disabled
[    0.000284] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.000462] found SMP MP-table at [mem 0x000f0090-0x000f009f]
[    0.000540] check: Scanning 1 areas for low memory corruption
[    0.000611] Using GB pages for direct mapping
[    0.000741] secureboot: Secure boot disabled
[    0.000742] RAMDISK: [mem 0x13680000-0x1509dfff]
[    0.000745] ACPI: Early table checksum verification disabled
[    0.000750] ACPI: RSDP 0x00000000000A0000 000024 (v02 CLOUDH)
[    0.000754] ACPI: XSDT 0x00000000000A1907 00003C (v01 CLOUDH CHXSDT   00000001 CLDH 00000000)
[    0.000762] ACPI: FACP 0x00000000000A176D 000114 (v06 CLOUDH CHFACP   00000001 CLDH 00000000)
[    0.000769] ACPI: DSDT 0x00000000000A0024 001749 (v06 CLOUDH CHDSDT   00000001 CLDH 00000000)
[    0.000774] ACPI: APIC 0x00000000000A1881 00004A (v05 CLOUDH CHMADT   00000001 CLDH 00000000)
[    0.000778] ACPI: MCFG 0x00000000000A18CB 00003C (v01 CLOUDH CHMCFG   00000001 CLDH 00000000)
[    0.000782] ACPI: Reserving FACP table memory at [mem 0xa176d-0xa1880]
[    0.000783] ACPI: Reserving DSDT table memory at [mem 0xa0024-0xa176c]
[    0.000784] ACPI: Reserving APIC table memory at [mem 0xa1881-0xa18ca]
[    0.000785] ACPI: Reserving MCFG table memory at [mem 0xa18cb-0xa1906]
[    0.000806] ACPI: Local APIC address 0xfee00000
[    0.001375] No NUMA configuration found
[    0.001376] Faking a node at [mem 0x0000000000000000-0x000000003fffffff]
[    0.001387] NODE_DATA(0) allocated [mem 0x3ffd5000-0x3fffffff]
[    0.001744] Zone ranges:
[    0.001746]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.001747]   DMA32    [mem 0x0000000001000000-0x000000003fffffff]
[    0.001749]   Normal   empty
[    0.001750]   Device   empty
[    0.001751] Movable zone start for each node
[    0.001754] Early memory node ranges
[    0.001755]   node   0: [mem 0x0000000000001000-0x000000000009ffff]
[    0.001756]   node   0: [mem 0x0000000000140000-0x000000003fffffff]
[    0.001760] Zeroed struct page in unavailable ranges: 161 pages
[    0.001762] Initmem setup node 0 [mem 0x0000000000001000-0x000000003fffffff]
[    0.001764] On node 0 totalpages: 261983
[    0.001765]   DMA zone: 64 pages used for memmap
[    0.001766]   DMA zone: 3396 pages reserved
[    0.001766]   DMA zone: 3935 pages, LIFO batch:0
[    0.001853]   DMA32 zone: 4032 pages used for memmap
[    0.001853]   DMA32 zone: 258048 pages, LIFO batch:63
[    0.007240] ACPI: PM-Timer IO Port: 0xb008
[    0.007245] ACPI: Local APIC address 0xfee00000
[    0.007359] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.007364] ACPI: INT_SRC_OVR (bus 0 bus_irq 4 global_irq 4 dfl dfl)
[    0.007368] Using ACPI (MADT) for SMP configuration information
[    0.007370] TSC deadline timer available
[    0.007372] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    0.007397] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.007400] PM: Registered nosave memory: [mem 0x000a0000-0x000fffff]
[    0.007402] PM: Registered nosave memory: [mem 0x00100000-0x0013ffff]
[    0.007405] [mem 0x40000000-0xffffffff] available for PCI devices
[    0.007408] Booting paravirtualized kernel on KVM
[    0.007412] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.007421] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[    0.007774] percpu: Embedded 60 pages/cpu s208896 r8192 d28672 u2097152
[    0.007778] pcpu-alloc: s208896 r8192 d28672 u2097152 alloc=1*2097152
[    0.007779] pcpu-alloc: [0] 0 
[    0.007801] setup async PF for cpu 0
[    0.007806] kvm-stealtime: cpu 0, msr 3ea32040
[    0.007813] Built 1 zonelists, mobility grouping on.  Total pages: 254491
[    0.007814] Policy zone: DMA32
[    0.007815] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-5.4.0-99-generic root=LABEL=cloudimg-rootfs ro console=tty1 console=ttyS0
[    0.008384] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.008565] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.008612] mem auto-init: stack:off, heap alloc:on, heap free:off
[    0.008615] Calgary: detecting Calgary via BIOS EBDA area
[    0.008616] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.011580] Memory: 703652K/1047932K available (14339K kernel code, 2400K rwdata, 9500K rodata, 2752K init, 4948K bss, 344280K reserved, 0K cma-reserved)
[    0.011589] random: get_random_u64 called from kmem_cache_open+0x2d/0x410 with crng_init=0
[    0.011723] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.011738] ftrace: allocating 44643 entries in 175 pages
[    0.030554] rcu: Hierarchical RCU implementation.
[    0.030556] rcu:     RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1.
[    0.030557]  Tasks RCU enabled.
[    0.030558] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.030559] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[    0.033737] NR_IRQS: 524544, nr_irqs: 256, preallocated irqs: 0
[    0.033812] random: crng done (trusting CPU's manufacturer)
[    0.033839] Console: colour dummy device 80x25
[    0.033996] printk: console [tty1] enabled
[    0.115153] printk: console [ttyS0] enabled
[    0.115720] ACPI: Core revision 20190816
[    0.116239] APIC: Switch to symmetric I/O mode setup
[    0.117672] x2apic enabled
[    0.120021] Switched APIC routing to physical x2apic.
[    0.120646] KVM setup pv IPIs
[    0.121055] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns
[    0.122377] Calibrating delay loop (skipped) preset value.. 4589.21 BogoMIPS (lpj=9178432)
[    0.123426] pid_max: default: 32768 minimum: 301
[    0.126374] LSM: Security Framework initializing
[    0.126374] Yama: becoming mindful.
[    0.126374] AppArmor: AppArmor initialized
[    0.126374] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.126374] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear)
[    0.126374] *** VALIDATE tmpfs ***
[    0.126374] *** VALIDATE proc ***
[    0.126374] *** VALIDATE cgroup1 ***
[    0.126374] *** VALIDATE cgroup2 ***
[    0.126374] x86/cpu: User Mode Instruction Prevention (UMIP) activated
[    0.126374] Last level iTLB entries: 4KB 64, 2MB 8, 4MB 8
[    0.126374] Last level dTLB entries: 4KB 64, 2MB 0, 4MB 0, 1GB 4
[    0.126374] unchecked MSR access error: RDMSR from 0x122 at rIP: 0xffffffff8ee733c8 (native_read_msr+0x8/0x40)
[    0.126374] Call Trace:
[    0.126374]  tsx_disable+0x19/0x40
[    0.126374]  tsx_init+0xe4/0x139
[    0.126374]  identify_boot_cpu+0x98/0x9a
[    0.126374]  check_bugs+0x2a/0x8a4
[    0.126374]  start_kernel+0x535/0x56a
[    0.126374]  x86_64_start_reservations+0x24/0x26
[    0.126374]  x86_64_start_kernel+0x75/0x79
[    0.126374]  secondary_startup_64+0xa4/0xb0
[    0.126374] unchecked MSR access error: WRMSR to 0x122 (tried to write 0x0000000000000003) at rIP: 0xffffffff8ee735b4 (native_write_msr+0x4/0x30)
[    0.126374] Call Trace:
[    0.126374]  ? tsx_disable+0x2f/0x40
[    0.126374]  tsx_init+0xe4/0x139
[    0.126374]  identify_boot_cpu+0x98/0x9a
[    0.126374]  check_bugs+0x2a/0x8a4
[    0.126374]  start_kernel+0x535/0x56a
[    0.126374]  x86_64_start_reservations+0x24/0x26
[    0.126374]  x86_64_start_kernel+0x75/0x79
[    0.126374]  secondary_startup_64+0xa4/0xb0
[    0.126374] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.126374] Spectre V2 : Mitigation: Enhanced IBRS
[    0.126374] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.126374] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[    0.126374] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[    0.126374] TAA: Mitigation: TSX disabled
[    0.126374] Freeing SMP alternatives memory: 40K
[    0.126374] smpboot: CPU0: Intel(R) Xeon(R) Gold 5218 CPU @ 2.30GHz (family: 0x6, model: 0x55, stepping: 0x7)
[    0.126374] Performance Events: Skylake events, Intel PMU driver.
[    0.126374] ... version:                2
[    0.126374] ... bit width:              48
[    0.126374] ... generic registers:      4
[    0.126374] ... value mask:             0000ffffffffffff
[    0.126380] ... max period:             000000007fffffff
[    0.127044] ... fixed-purpose events:   3
[    0.127552] ... event mask:             000000070000000f
[    0.128286] rcu: Hierarchical SRCU implementation.
[    0.129789] smp: Bringing up secondary CPUs ...
[    0.130360] smp: Brought up 1 node, 1 CPU
[    0.130380] smpboot: Max logical packages: 1
[    0.130913] smpboot: Total of 1 processors activated (4589.21 BogoMIPS)
[    0.131926] devtmpfs: initialized
[    0.132391] x86/mm: Memory block size: 128MB
[    0.133205] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.134389] futex hash table entries: 256 (order: 2, 16384 bytes, linear)
[    0.135269] pinctrl core: initialized pinctrl subsystem
[    0.136196] PM: RTC time: 02:29:52, date: 2022-04-21
[    0.136942] NET: Registered protocol family 16
[    0.137552] audit: initializing netlink subsys (disabled)
[    0.138373] EISA bus registered
[    0.138387] audit: type=2000 audit(1650508192.572:1): state=initialized audit_enabled=0 res=1
[    0.139443] cpuidle: using governor ladder
[    0.139959] cpuidle: using governor menu
[    0.140507] KVM setup pv remote TLB flush
[    0.141018] ACPI: bus type PCI registered
[    0.141532] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.142436] PCI: MMCONFIG for domain 0000 [bus 00-00] at [mem 0xe8000000-0xe80fffff] (base 0xe8000000)
[    0.143586] PCI: not using MMCONFIG
[    0.144035] PCI: Using configuration type 1 for base access
[    0.146493] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[    0.147338] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[    0.149243] fbcon: Taking over console
[    0.149736] ACPI: Added _OSI(Module Device)
[    0.150272] ACPI: Added _OSI(Processor Device)
[    0.150380] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.150978] ACPI: Added _OSI(Processor Aggregator Device)
[    0.151666] ACPI: Added _OSI(Linux-Dell-Video)
[    0.152220] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[    0.152883] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[    0.154303] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.154812] ACPI: Interpreter enabled
[    0.155280] ACPI: (supports S0 S5)
[    0.155704] ACPI: Using IOAPIC for interrupt routing
[    0.156330] PCI: MMCONFIG for domain 0000 [bus 00-00] at [mem 0xe8000000-0xe80fffff] (base 0xe8000000)
[    0.157576] PCI: MMCONFIG at [mem 0xe8000000-0xe80fffff] reserved in ACPI motherboard resources
[    0.158426] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.163429] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00])
[    0.164162] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3]
[    0.165263] acpi PNP0A08:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.166348] acpiphp: Slot [0] registered
[    0.166424] acpiphp: Slot [1] registered
[    0.166933] acpiphp: Slot [2] registered
[    0.167449] acpiphp: Slot [3] registered
[    0.167956] acpiphp: Slot [4] registered
[    0.168475] acpiphp: Slot [5] registered
[    0.168986] acpiphp: Slot [6] registered
[    0.169508] acpiphp: Slot [7] registered
[    0.170023] acpiphp: Slot [8] registered
[    0.170399] acpiphp: Slot [9] registered
[    0.170914] acpiphp: Slot [10] registered
[    0.171449] acpiphp: Slot [11] registered
[    0.171977] acpiphp: Slot [12] registered
[    0.172509] acpiphp: Slot [13] registered
[    0.173042] acpiphp: Slot [14] registered
[    0.173564] acpiphp: Slot [15] registered
[    0.174096] acpiphp: Slot [16] registered
[    0.174400] acpiphp: Slot [17] registered
[    0.174929] acpiphp: Slot [18] registered
[    0.175448] acpiphp: Slot [19] registered
[    0.175974] acpiphp: Slot [20] registered
[    0.176486] acpiphp: Slot [21] registered
[    0.177005] acpiphp: Slot [22] registered
[    0.177527] acpiphp: Slot [23] registered
[    0.178051] acpiphp: Slot [24] registered
[    0.178400] acpiphp: Slot [25] registered
[    0.178928] acpiphp: Slot [26] registered
[    0.179449] acpiphp: Slot [27] registered
[    0.179976] acpiphp: Slot [28] registered
[    0.180503] acpiphp: Slot [29] registered
[    0.181037] acpiphp: Slot [30] registered
[    0.181566] acpiphp: Slot [31] registered
[    0.182126] PCI host bridge to bus 0000:00
[    0.182379] pci_bus 0000:00: root bus resource [mem 0xe8000000-0xe80fffff]
[    0.183230] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xe7ffffff window]
[    0.184153] pci_bus 0000:00: root bus resource [mem 0x300000000-0x3ffeffffffff window]
[    0.185119] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.185944] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.186379] pci_bus 0000:00: root bus resource [bus 00]
[    0.187093] pci 0000:00:00.0: [8086:0d57] type 00 class 0x060000
[    0.188876] pci 0000:00:01.0: [1af4:1043] type 00 class 0xffff00
[    0.189872] pci 0000:00:01.0: reg 0x10: [mem 0x3ffefff80000-0x3ffeffffffff 64bit]
[    0.191845] pci 0000:00:02.0: [1af4:1042] type 00 class 0x018000
[    0.192778] pci 0000:00:02.0: reg 0x10: [mem 0xe7f80000-0xe7ffffff]
[    0.195042] pci 0000:00:03.0: [1af4:1041] type 00 class 0x020000
[    0.196031] pci 0000:00:03.0: reg 0x10: [mem 0x3ffefff00000-0x3ffefff7ffff 64bit]
[    0.198422] pci 0000:00:04.0: [1af4:1044] type 00 class 0xffff00
[    0.199419] pci 0000:00:04.0: reg 0x10: [mem 0x3ffeffe80000-0x3ffeffefffff 64bit]
[    0.201786] pci 0000:00:05.0: [1af4:1058] type 00 class 0xffff00
[    0.202621] pci 0000:00:05.0: reg 0x10: [mem 0x3ffeffe00000-0x3ffeffe7ffff 64bit]
[    0.212203] pci_bus 0000:00: on NUMA node 0
[    0.212918] iommu: Default domain type: Translated 
[    0.213698] SCSI subsystem initialized
[    0.214199] libata version 3.00 loaded.
[    0.214228] vgaarb: loaded
[    0.214396] ACPI: bus type USB registered
[    0.214913] usbcore: registered new interface driver usbfs
[    0.215600] usbcore: registered new interface driver hub
[    0.216257] usbcore: registered new device driver usb
[    0.216903] pps_core: LinuxPPS API ver. 1 registered
[    0.217520] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 
[    0.218384] PTP clock support registered
[    0.218915] EDAC MC: Ver: 3.0.0
[    0.219571] Registered efivars operations
[    0.220142] PCI: Using ACPI for IRQ routing
[    0.220674] PCI: pci_cache_line_size set to 64 bytes
[    0.220908] NetLabel: Initializing
[    0.221346] NetLabel:  domain hash size = 128
[    0.221901] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    0.222411] NetLabel:  unlabeled traffic allowed by default
[    0.223219] clocksource: Switched to clocksource kvm-clock
[    0.242657] *** VALIDATE bpf ***
[    0.243152] VFS: Disk quotas dquot_6.6.0
[    0.243669] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.244554] *** VALIDATE ramfs ***
[    0.244998] *** VALIDATE hugetlbfs ***
[    0.245686] AppArmor: AppArmor Filesystem Enabled
[    0.246299] pnp: PnP ACPI init
[    0.246800] system 00:00: [mem 0xe8000000-0xe80fffff] has been reserved
[    0.247627] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[    0.247659] pnp 00:01: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.248139] pnp: PnP ACPI: found 2 devices
[    0.250507] thermal_sys: Registered thermal governor 'fair_share'
[    0.250508] thermal_sys: Registered thermal governor 'bang_bang'
[    0.251273] thermal_sys: Registered thermal governor 'step_wise'
[    0.252017] thermal_sys: Registered thermal governor 'user_space'
[    0.252750] thermal_sys: Registered thermal governor 'power_allocator'
[    0.258091] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    0.260036] pci_bus 0000:00: resource 4 [mem 0xe8000000-0xe80fffff]
[    0.260804] pci_bus 0000:00: resource 5 [mem 0xc0000000-0xe7ffffff window]
[    0.261642] pci_bus 0000:00: resource 6 [mem 0x300000000-0x3ffeffffffff window]
[    0.262540] pci_bus 0000:00: resource 7 [io  0x0000-0x0cf7 window]
[    0.263296] pci_bus 0000:00: resource 8 [io  0x0d00-0xffff window]
[    0.264118] NET: Registered protocol family 2
[    0.264845] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    0.266020] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear)
[    0.267156] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.268287] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    0.269198] TCP: Hash tables configured (established 8192 bind 8192)
[    0.270195] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.271053] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    0.272272] NET: Registered protocol family 1
[    0.272828] NET: Registered protocol family 44
[    0.273456] PCI: CLS 0 bytes, default 64
[    0.274042] Trying to unpack rootfs image as initramfs...
[    0.454299] Freeing initrd memory: 26744K
[    0.454919] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x21134f58f0d, max_idle_ns: 440795217993 ns
[    0.456136] clocksource: Switched to clocksource tsc
[    0.456793] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.457854] check: Scanning for low memory corruption every 60 seconds
[    0.459081] Initialise system trusted keyrings
[    0.459650] Key type blacklist registered
[    0.460223] workingset: timestamp_bits=36 max_order=18 bucket_order=0
[    0.462622] zbud: loaded
[    0.463311] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.464199] fuse: init (API version 7.31)
[    0.464721] *** VALIDATE fuse ***
[    0.465150] *** VALIDATE fuse ***
[    0.465666] Platform Keyring initialized
[    0.469002] Key type asymmetric registered
[    0.469519] Asymmetric key parser 'x509' registered
[    0.470144] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 244)
[    0.471104] io scheduler mq-deadline registered
[    0.471785] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    0.472665] intel_idle: Please enable MWAIT in BIOS SETUP
[    0.473112] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[    0.474166] ACPI: Power Button [PWRB]
[    0.474839] virtio-pci 0000:00:01.0: enabling device (0000 -> 0002)
[    0.476446] virtio-pci 0000:00:02.0: enabling device (0000 -> 0002)
[    0.478229] virtio-pci 0000:00:03.0: enabling device (0000 -> 0002)
[    0.479809] virtio-pci 0000:00:04.0: enabling device (0000 -> 0002)
[    0.481347] virtio-pci 0000:00:05.0: enabling device (0000 -> 0002)
[    0.482914] Serial: 8250/16550 driver, 32 ports, IRQ sharing enabled
[    0.505907] 00:01: ttyS0 at I/O 0x3f8 (irq = 24, base_baud = 115200) is a U6_16550A
[    0.511884] Linux agpgart interface v0.103
[    0.606414] loop: module loaded
[    0.607014] libphy: Fixed MDIO Bus: probed
[    0.607527] tun: Universal TUN/TAP device driver, 1.6
[    0.608178] PPP generic driver version 2.4.2
[    0.608757] VFIO - User Level meta-driver version: 0.3
[    0.609445] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.610275] ehci-pci: EHCI PCI platform driver
[    0.610860] ehci-platform: EHCI generic platform driver
[    0.611512] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.612286] ohci-pci: OHCI PCI platform driver
[    0.612854] ohci-platform: OHCI generic platform driver
[    0.613510] uhci_hcd: USB Universal Host Controller Interface driver
[    0.614347] i8042: PNP: No PS/2 controller found.
[    0.614999] mousedev: PS/2 mouse device common for all mice
[    0.615846] rtc_cmos rtc_cmos: only 24-hr supported
[    0.616464] i2c /dev entries driver
[    0.616964] device-mapper: uevent: version 1.0.3
[    0.617604] device-mapper: ioctl: 4.41.0-ioctl (2019-09-16) initialised: dm-devel@redhat.com
[    0.618689] platform eisa.0: Probing EISA bus 0
[    0.619264] platform eisa.0: EISA: Cannot allocate resource for mainboard
[    0.620110] platform eisa.0: Cannot allocate resource for EISA slot 1
[    0.620920] platform eisa.0: Cannot allocate resource for EISA slot 2
[    0.621722] platform eisa.0: Cannot allocate resource for EISA slot 3
[    0.622536] platform eisa.0: Cannot allocate resource for EISA slot 4
[    0.623346] platform eisa.0: Cannot allocate resource for EISA slot 5
[    0.624146] platform eisa.0: Cannot allocate resource for EISA slot 6
[    0.624950] platform eisa.0: Cannot allocate resource for EISA slot 7
[    0.625740] platform eisa.0: Cannot allocate resource for EISA slot 8
[    0.626542] platform eisa.0: EISA: Detected 0 cards
[    0.627154] intel_pstate: CPU model not supported
[    0.627744] ledtrig-cpu: registered to indicate activity on CPUs
[    0.628481] EFI Variables Facility v0.08 2004-May-17
[    0.629115] efivars: get_next_variable: status=8000000000000003
[    0.629877] drop_monitor: Initializing network drop monitor service
[    0.630836] NET: Registered protocol family 10
[    0.637065] Segment Routing with IPv6
[    0.637563] NET: Registered protocol family 17
[    0.638171] Key type dns_resolver registered
[    0.638900] RAS: Correctable Errors collector initialized.
[    0.639580] IPI shorthand broadcast: enabled
[    0.640127] sched_clock: Marking stable (550104522, 88608366)->(685605304, -46892416)
[    0.641161] registered taskstats version 1
[    0.641710] Loading compiled-in X.509 certificates
[    0.643106] Loaded X.509 cert 'Build time autogenerated kernel key: 3e9c0893400760cb433d1bad37690f1fbf53b04b'
[    0.644972] Loaded X.509 cert 'Canonical Ltd. Live Patch Signing: 14df34d1a87cf37625abec039ef2bf521249b969'
[    0.646848] Loaded X.509 cert 'Canonical Ltd. Kernel Module Signing: 88f752e560a1e0737e31163a466ad7b70a850c19'
[    0.648063] blacklist: Loading compiled-in revocation X.509 certificates
[    0.648901] Loaded X.509 cert 'Canonical Ltd. Secure Boot Signing: 61482aa2830d0ab2ad5af10b7250da9033ddcef0'
[    0.650112] zswap: loaded using pool lzo/zbud
[    0.650736] Key type ._fscrypt registered
[    0.651248] Key type .fscrypt registered
[    0.656779] Key type big_key registered
[    0.659802] Key type encrypted registered
[    0.660319] AppArmor: AppArmor sha1 policy hashing enabled
[    0.661032] Couldn't get size: Unknown error code (0x8000000000000003)
[    0.661837] MODSIGN: Couldn't get UEFI db list
[    0.662438] Couldn't get size: Unknown error code (0x8000000000000003)
[    0.663241] Couldn't get UEFI dbx list
[    0.663717] Couldn't get size: Unknown error code (0x8000000000000003)
[    0.664511] Couldn't get UEFI MokListXRT
[    0.665006] Couldn't get size: Unknown error code (0x8000000000000003)
[    0.665800] Couldn't get UEFI MokListRT
[    0.666282] ima: No TPM chip found, activating TPM-bypass!
[    0.666987] ima: Allocated hash algorithm: sha1
[    0.667559] ima: No architecture policies found
[    0.668131] evm: Initialising EVM extended attributes:
[    0.668766] evm: security.selinux
[    0.669184] evm: security.SMACK64
[    0.669606] evm: security.SMACK64EXEC
[    0.670075] evm: security.SMACK64TRANSMUTE
[    0.670596] evm: security.SMACK64MMAP
[    0.671067] evm: security.apparmor
[    0.671496] evm: security.ima
[    0.671880] evm: security.capability
[    0.672334] evm: HMAC attrs: 0x1
[    0.673022] PM:   Magic number: 2:700:460
[    0.673589] hctosys: unable to open rtc device (rtc0)
[    0.675792] Freeing unused decrypted memory: 2040K
[    0.677084] Freeing unused kernel image memory: 2752K
[    0.678394] Write protecting the kernel read-only data: 26624k
[    0.679731] Freeing unused kernel image memory: 2008K
[    0.680574] Freeing unused kernel image memory: 740K
[    0.694509] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    0.695317] Run /init as init process
[    0.800776] virtio_blk virtio1: [vda] 4612096 512-byte logical blocks (2.36 GB/2.20 GiB)
[    0.816960]  vda: vda1 vda14 vda15
[    0.823911] cryptd: max_cpu_qlen set to 1000
[    0.863499] AVX2 version of gcm_enc/dec engaged.
[    0.864086] AES CTR mode by8 optimization enabled
[    0.877967] virtio_net virtio2 ens3: renamed from eth0
[    2.074385] raid6: avx512x4 gen() 25745 MB/s
[    2.122382] raid6: avx512x4 xor() 15301 MB/s
[    2.170382] raid6: avx512x2 gen() 24142 MB/s
[    2.218380] raid6: avx512x2 xor() 14101 MB/s
[    2.266381] raid6: avx512x1 gen() 18432 MB/s
[    2.314383] raid6: avx512x1 xor()  9858 MB/s
[    2.362383] raid6: avx2x4   gen() 16817 MB/s
[    2.410381] raid6: avx2x4   xor() 10567 MB/s
[    2.458383] raid6: avx2x2   gen() 13299 MB/s
[    2.506382] raid6: avx2x2   xor()  9485 MB/s
[    2.554383] raid6: avx2x1   gen() 12059 MB/s
[    2.602381] raid6: avx2x1   xor()  7398 MB/s
[    2.650386] raid6: sse2x4   gen()  7795 MB/s
[    2.698382] raid6: sse2x4   xor()  4875 MB/s
[    2.746386] raid6: sse2x2   gen()  6740 MB/s
[    2.794384] raid6: sse2x2   xor()  4383 MB/s
[    2.842382] raid6: sse2x1   gen()  5683 MB/s
[    2.890383] raid6: sse2x1   xor()  3639 MB/s
[    2.891040] raid6: using algorithm avx512x4 gen() 25745 MB/s
[    2.891763] raid6: .... xor() 15301 MB/s, rmw enabled
[    2.892390] raid6: using avx512x2 recovery algorithm
[    2.894169] xor: automatically using best checksumming function   avx       
[    2.896137] async_tx: api initialized (async)
[    2.955105] Btrfs loaded, crc32c=crc32c-intel
[    2.975269] EXT4-fs (vda1): INFO: recovery required on readonly filesystem
[    2.976134] EXT4-fs (vda1): write access will be enabled during recovery
[    2.982048] EXT4-fs (vda1): recovery complete
[    2.982929] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)
[    3.091809] systemd[1]: Inserted module 'autofs4'
[    3.100912] efivars: get_next_variable: status=8000000000000003
[    3.102862] systemd[1]: systemd 245.4-4ubuntu3.15 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=hybrid)
[    3.105582] systemd[1]: Detected virtualization kvm.
[    3.106207] systemd[1]: Detected architecture x86-64.
[    3.109834] systemd[1]: Set hostname to .
[    3.307874] systemd[1]: Created slice system-modprobe.slice.
[    3.309860] systemd[1]: Created slice system-serial\x2dgetty.slice.
[    3.311894] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    3.313967] systemd[1]: Created slice User and Session Slice.
[    3.315613] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.317835] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    3.320057] systemd[1]: Reached target User and Group Name Lookups.
[    3.321720] systemd[1]: Reached target Slices.
[    3.323019] systemd[1]: Reached target Swap.
[    3.324339] systemd[1]: Listening on Device-mapper event daemon FIFOs.
[    3.326130] systemd[1]: Listening on LVM2 poll daemon socket.
[    3.327754] systemd[1]: Listening on multipathd control socket.
[    3.329419] systemd[1]: Listening on Syslog Socket.
[    3.330931] systemd[1]: Listening on fsck to fsckd communication Socket.
[    3.332701] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    3.334596] systemd[1]: Listening on Journal Audit Socket.
[    3.336177] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.337843] systemd[1]: Listening on Journal Socket.
[    3.339365] systemd[1]: Listening on Network Service Netlink Socket.
[    3.341131] systemd[1]: Listening on udev Control Socket.
[    3.342697] systemd[1]: Listening on udev Kernel Socket.
[    3.345181] systemd[1]: Mounting Huge Pages File System...
[    3.347679] systemd[1]: Mounting POSIX Message Queue File System...
[    3.355746] systemd[1]: Mounting Kernel Debug File System...
[    3.360514] systemd[1]: Mounting Kernel Trace File System...
[    3.365678] systemd[1]: Starting Journal Service...
[    3.368128] systemd[1]: Starting Set the console keyboard layout...
[    3.376736] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    3.383314] systemd[1]: Starting Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
[    3.391767] systemd[1]: Starting Load Kernel Module drm...
[    3.393929] systemd[1]: Condition check resulted in OpenVSwitch configuration for cleanup being skipped.
[    3.398449] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    3.402759] systemd[1]: Starting File System Check on Root Device...
[    3.411438] systemd[1]: Starting Load Kernel Modules...
[    3.418628] systemd[1]: Starting udev Coldplug all Devices...
[    3.424959] systemd[1]: Starting Uncomplicated firewall...
[    3.431999] systemd[1]: Mounted Huge Pages File System.
[    3.435456] systemd[1]: Mounted POSIX Message Queue File System.
[    3.439026] systemd[1]: Mounted Kernel Debug File System.
[    3.442611] systemd[1]: Mounted Kernel Trace File System.
[    3.447031] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    3.455453] systemd[1]: Finished Uncomplicated firewall.
[    3.468266] systemd[1]: Started File System Check Daemon to report status.
[    3.480685] systemd[1]: Finished Load Kernel Modules.
[    3.487334] systemd[1]: Mounting FUSE Control File System...
[    3.490471] systemd[1]: Mounting Kernel Configuration File System...
[    3.498691] systemd[1]: Starting Apply Kernel Variables...
[    3.508837] systemd[1]: Mounted Kernel Configuration File System.
[    3.511773] systemd[1]: Mounted FUSE Control File System.
[    3.539694] systemd[1]: Finished Apply Kernel Variables.
[    3.541007] systemd[1]: modprobe@drm.service: Succeeded.
[    3.541308] systemd[1]: Finished Load Kernel Module drm.
[    3.553722] systemd[1]: Finished File System Check on Root Device.
[    3.555932] systemd[1]: Starting Remount Root and Kernel File Systems...
[    3.578411] EXT4-fs (vda1): re-mounted. Opts: (null)
[    3.580538] systemd[1]: Finished Remount Root and Kernel File Systems.
[    3.582534] systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
[    3.582574] systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped.
[    3.583491] systemd[1]: Starting Load/Save Random Seed...
[    3.588457] systemd[1]: Starting Create System Users...
[    3.591202] systemd[1]: Finished Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
[    3.609196] systemd[1]: Finished Load/Save Random Seed.
[    3.620706] systemd[1]: Finished Create System Users.
[    3.622634] systemd[1]: Starting Create Static Device Nodes in /dev...
[    3.652000] systemd[1]: Finished Create Static Device Nodes in /dev.
[    3.653982] systemd[1]: Starting udev Kernel Device Manager...
[    3.702426] systemd[1]: Finished udev Coldplug all Devices.
[    3.704324] systemd[1]: Starting udev Wait for Complete Device Initialization...
[    3.706688] systemd[1]: Started Journal Service.
[    3.725200] systemd-journald[331]: Received client request to flush runtime journal.
[    3.727346] systemd-journald[331]: File /var/log/journal/7c855105149a42b88534452855f023f3/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    4.654580] alua: device handler registered
[    4.655621] emc: device handler registered
[    4.656709] rdac: device handler registered
[    4.921083] audit: type=1400 audit(1650508197.354:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=468 comm="apparmor_parser"
[    4.921086] audit: type=1400 audit(1650508197.354:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=468 comm="apparmor_parser"
[    4.921088] audit: type=1400 audit(1650508197.354:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=468 comm="apparmor_parser"
[    4.936104] audit: type=1400 audit(1650508197.370:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=469 comm="apparmor_parser"
[    4.936107] audit: type=1400 audit(1650508197.370:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=469 comm="apparmor_parser"
[    4.936109] audit: type=1400 audit(1650508197.370:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=469 comm="apparmor_parser"
[    4.936110] audit: type=1400 audit(1650508197.370:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/{,usr/}sbin/dhclient" pid=469 comm="apparmor_parser"
[    4.948051] audit: type=1400 audit(1650508197.382:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/sbin/tcpdump" pid=470 comm="apparmor_parser"
[    4.951867] audit: type=1400 audit(1650508197.386:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=471 comm="apparmor_parser"
[    9.451827] kauditd_printk_skb: 19 callbacks suppressed
[    9.451829] audit: type=1400 audit(1650508201.886:30): apparmor="DENIED" operation="open" profile="snap.lxd.activate" name="/proc/675/attr/current" pid=675 comm="cat" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
[    9.453643] audit: type=1400 audit(1650508201.886:31): apparmor="DENIED" operation="exec" profile="snap.lxd.activate" name="/usr/bin/aa-exec" pid=676 comm="daemon.activate" requested_mask="x" denied_mask="x" fsuid=0 ouid=0
[    9.454158] audit: type=1400 audit(1650508201.886:32): apparmor="DENIED" operation="exec" profile="snap.lxd.activate" name="/usr/bin/aa-exec" pid=676 comm="daemon.activate" requested_mask="x" denied_mask="x" fsuid=0 ouid=0
[  519.012545] systemd-journald[331]: File /var/log/journal/7c855105149a42b88534452855f023f3/user-1000.journal corrupted or uncleanly shut down, renaming and replacing.
sboeuf commented 2 years ago

Well I think the reason it doesn't work for you is because you're using a guest kernel that's too old. virtio-mem support has been introduced in Linux 5.8, therefore it's not part of the guest you're running in your test.

dengxuehua commented 2 years ago

@sboeuf Thanks for your answer.