Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Inconsistent first boot with Proxmox #9852

Open
Tracked by #9825
joeypiccola opened this issue Nov 30, 2024 · 22 comments · Fixed by siderolabs/go-blockdevice#120
Open
Tracked by #9825

Inconsistent first boot with Proxmox #9852

joeypiccola opened this issue Nov 30, 2024 · 22 comments · Fixed by siderolabs/go-blockdevice#120

Comments

@joeypiccola
Copy link

joeypiccola commented Nov 30, 2024

Bug Report

Description

I am building a Talos cluster (v1.8.3) on Proxmox (v8.2.2) with Terraform (v1.9.5). Sometimes, on first boot after provisioning, the machines boot to the expected Talos UI and others times they boot and throw the error error running phase 6 in initialize sequence: task 1/1: failed, unexpected EOF followed by failed to revert bootloader: unexpected EOF, then the system reboots and loops this error. I have completely destroyed the terraform managed infrastructure (i.e. Proxmox vm, disks etc) and retried with mixed results. Sometimes they boot as expected and other times they throw the previously mentioned error.

I've read in the following issues that I may need certain image extensions, so I have included i915-ucode, intel-ucode, and mei in my image factory generated Talos image, same error and boot loop behavior. I've confirmed the id generated with talos_image_factory_schematic matches the id I received from the Image Factory if performed manually.

Observations

I am leveraging a nocloud image so that I may set an IP and Gateway IP address. When running a ping to one of the machines, on first boot the machine does not respond. On second boot right before it enters the rebooting countdown the machine does respond. Only mentioning this as I am unsure if my problem is related to something with nocloud / cloudinit.

IaC

data "talos_image_factory_extensions_versions" "this" {
  talos_version = "v1.8.3"
  filters = {
    names = [
      "i915-ucode",       # see https://github.com/siderolabs/talos/issues/9776
      "intel-ucode",      # see https://github.com/siderolabs/talos/issues/9776
      "iscsi-tools",      # for longhorn
      "mei",              # see https://github.com/siderolabs/talos/issues/9776
      "qemu-guest-agent", # for proxmox
    ]
  }
}

resource "talos_image_factory_schematic" "this" {
  schematic = yamlencode(
    {
      customization = {
        systemExtensions = {
          officialExtensions = data.talos_image_factory_extensions_versions.this.extensions_info.*.name
        }
      }
    }
  )
}

resource "proxmox_virtual_environment_download_file" "talos_image" {
  content_type = "iso"
  datastore_id = "isos"
  node_name    = "pve"
  url          = format("https://factory.talos.dev/image/%s/%s/nocloud-amd64.iso", talos_image_factory_schematic.this.id, data.talos_image_factory_extensions_versions.this.talos_version)
}

resource "proxmox_virtual_environment_vm" "talos_cp" {
  name            = "talos-0"
  node_name       = "pve"
  scsi_hardware   = "virtio-scsi-pci"
  stop_on_destroy = true
  tags            = ["terraform", "linux", "kubernetes", "talos"]
  vm_id           = 210

  agent {
    enabled = true
  }
  startup {
    order      = "3"
    up_delay   = "60"
    down_delay = "60"
  }
  cpu {
    cores = 2
    type  = "x86-64-v2-AES"
  }
  memory {
    dedicated = 2048
    floating  = 2048 # set equal to dedicated to enable ballooning
  }
  disk {
    datastore_id = "truenas-vmware-datastore-0"
    file_id      = proxmox_virtual_environment_download_file.talos_image.id
    interface    = "scsi0"
    size         = 10
  }
  initialization {
    datastore_id = "truenas-vmware-datastore-0"
    dns {
      servers = ["10.0.3.24"]
    }
    ip_config {
      ipv4 {
        address = "10.0.3.151/24"
        gateway = "10.0.3.1"
      }
    }
  }
  network_device {
    bridge   = "vmbr1"
    firewall = true
    model    = "virtio"
    vlan_id  = "3"
  }
  operating_system {
    type = "l26"
  }
}

Logs

image

image

Environment

  • Talos version: v1.8.3
  • Kubernetes version: na
  • Platform: Proxmox v8.2.2 (Intel(R) Xeon(R) CPU D-1541)
@joeypiccola
Copy link
Author

Seems I did this to myself, copy/pasta 🤦🏻‍♂️ 😠 . To fix this, on the Terraform resource proxmox_virtual_environment_vm I changed

this:

scsi_hardware   = "virtio-scsi-pci"

to this:

scsi_hardware   = "virtio-scsi-single"

@joeypiccola
Copy link
Author

joeypiccola commented Dec 1, 2024

Reopening the issue, the behavior is definitely intermittent. Provisioned three identical VMs in proxmox.

  • one is ready
  • one has no ready status
  • one just loops

image

image

image

@joeypiccola joeypiccola reopened this Dec 1, 2024
@smira
Copy link
Member

smira commented Dec 2, 2024

This might be fixed by #9810.

You can inspect if any of the mounted disks contain a partition with a label META which is not coming from Talos.

@joeypiccola
Copy link
Author

joeypiccola commented Dec 3, 2024

Here is what I was able to get. Not seeing a partition with a label META at all 🤔 , at least not as seen in the docs Discovering Volumes.

➜ talosctl -n 10.0.5.151 disks --insecure
DEV          MODEL           SERIAL   TYPE      UUID   WWID   MODALIAS      NAME   SIZE     BUS_PATH                                                                  SUBSYSTEM          READ_ONLY   SYSTEM_DISK
/dev/loop0   -               -        UNKNOWN   -      -      -             -      139 kB   /virtual                                                                  /sys/class/block   *           
/dev/loop1   -               -        UNKNOWN   -      -      -             -      4.1 kB   /virtual                                                                  /sys/class/block   *           
/dev/loop2   -               -        UNKNOWN   -      -      -             -      684 kB   /virtual                                                                  /sys/class/block   *           
/dev/loop3   -               -        UNKNOWN   -      -      -             -      197 kB   /virtual                                                                  /sys/class/block   *           
/dev/loop4   -               -        UNKNOWN   -      -      -             -      2.6 MB   /virtual                                                                  /sys/class/block   *           
/dev/loop5   -               -        UNKNOWN   -      -      -             -      4.1 kB   /virtual                                                                  /sys/class/block   *           
/dev/loop6   -               -        UNKNOWN   -      -      -             -      4.1 kB   /virtual                                                                  /sys/class/block   *           
/dev/loop7   -               -        UNKNOWN   -      -      -             -      75 MB    /virtual                                                                  /sys/class/block   *           
/dev/sda     QEMU HARDDISK   -        HDD       -      -      scsi:t-0x00   -      11 GB    /pci0000:00/0000:00:05.0/0000:01:01.0/virtio3/host2/target2:0:0/2:0:0:0   /sys/class/block               
/dev/sr0     QEMU DVD-ROM    -        CD        -      -      scsi:t-0x05   -      4.2 MB   /pci0000:00/0000:00:01.1/ata2/host1/target1:0:0/1:0:0:0                   /sys/class/block               
➜ talosctl -n 10.0.5.151 get discoveredvolumes --insecure
NODE   NAMESPACE   TYPE               ID      VERSION   TYPE   SIZE     DISCOVERED   LABEL          PARTITIONLABEL
       runtime     DiscoveredVolume   loop0   1         disk   139 kB   squashfs                    
       runtime     DiscoveredVolume   loop2   1         disk   684 kB   squashfs                    
       runtime     DiscoveredVolume   loop3   1         disk   197 kB   squashfs                    
       runtime     DiscoveredVolume   loop4   1         disk   2.6 MB   squashfs                    
       runtime     DiscoveredVolume   loop7   1         disk   75 MB    squashfs                    
       runtime     DiscoveredVolume   sda     1         disk   11 GB    iso9660      TALOS_V1_8_3   
       runtime     DiscoveredVolume   sr0     1         disk   4.2 MB   iso9660      cidata
➜ talosctl -n 10.0.5.151 get volumestatus --insecure     
NODE   NAMESPACE   TYPE           ID      VERSION   PHASE     LOCATION   SIZE
       runtime     VolumeStatus   META    2         missing              
       runtime     VolumeStatus   STATE   2         missing              

@smira
Copy link
Member

smira commented Dec 3, 2024

Once it's there, it will fail to boot, so you won't see it on a successful run.

Anyways worth checking with Talos 1.9 which has a fix

@joeypiccola
Copy link
Author

Out of curiosity, is there a way to inspect disks when in the looping state? Whatever issue I'm hitting must be unique to me as I don't see a lot of others hitting this issue in Proxmox like myself. Simply looking to better understand so that I can attempt to fix this issue on my end. Thanks!

@smira
Copy link
Member

smira commented Dec 4, 2024

Try 1.9.0-beta.0 please.

When it fails, you should see Talos discover META when it shouldn't (before the initial install).

@joeypiccola
Copy link
Author

Same issue with v1.9.0-beta.0.

@smira
Copy link
Member

smira commented Dec 6, 2024

If that fails, can you please share full serial console logs of the boot process?

On Proxmox it should be easy, but by default Talos serial console logs are disabled, so you might need to add to the extra kernel args (e.g. via Image Factory): console=tty0 console=ttyS0

@joeypiccola
Copy link
Author

joeypiccola commented Dec 6, 2024

For others to follow:

  1. ensure your talos image includes the following extraKernelArgs.
 extraKernelArgs:
     - console=tty0
     - console=ttyS0
  1. On proxmox VM add serial Port.
    image
  2. On proxmox host write console data via serial port to file where 201 is the VM ID (press Ctrl+O to exit).
    root@pve:~# script -c "qm terminal 201" console.txt
[   21.476065] [talos] rebooting in 2 seconds
[   22.473161] [talos] rebooting in 1 seconds
[   23.468345] [talos] rebooting in 0 seconds
[   24.467391] [talos] waiting for sync...
[   24.471265] [talos] sync done
[   24.472273] sd 2:0:0:0: [sda] Synchronizing SCSI cache
[   24.474429] ACPI: PM: Preparing to enter system sleep state S5
[   24.475979] reboot: Restarting system
[   24.477175] reboot: machine restart
[    0.000000] Linux version 6.12.1-talos (@buildkitsandbox) (gcc (GCC) 14.2.0, GNU ld (GNU Binutils) 2.43.1) #1 SMP Mon Dec  2 11:37:05 UTC 2024
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz talos.platform=nocloud console=tty0 console=ttyS0 net.ifnames=0 talos.halt_if_installed=1 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha512
[    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-0x000000007ffd9fff] usable
[    0.000000] BIOS-e820: [mem 0x000000007ffda000-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] APIC: Static calls initialized
[    0.000000] SMBIOS 3.0.0 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.2-0-gea1b7a073390-prebuilt.qemu.org 04/01/2014
[    0.000000] DMI: Memory slots populated: 1/1
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000001] kvm-clock: using sched offset of 292732280669 cycles
[    0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000007] tsc: Detected 2099.998 MHz processor
[    0.001528] last_pfn = 0x7ffda max_arch_pfn = 0x400000000
[    0.001591] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
[    0.001594] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.012288] found SMP MP-table at [mem 0x000f5b70-0x000f5b7f]
[    0.012487] RAMDISK: [mem 0x2e93f000-0x33496fff]
[    0.012491] ACPI: Early table checksum verification disabled
[    0.012495] ACPI: RSDP 0x00000000000F5990 000014 (v00 BOCHS )
[    0.012501] ACPI: RSDT 0x000000007FFE2FBA 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012508] ACPI: FACP 0x000000007FFE2D9C 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012515] ACPI: DSDT 0x000000007FFDF040 003D5C (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012519] ACPI: FACS 0x000000007FFDF000 000040
[    0.012523] ACPI: APIC 0x000000007FFE2E10 000080 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012528] ACPI: SSDT 0x000000007FFE2E90 0000CA (v01 BOCHS  VMGENID  00000001 BXPC 00000001)
[    0.012532] ACPI: HPET 0x000000007FFE2F5A 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012536] ACPI: WAET 0x000000007FFE2F92 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012539] ACPI: Reserving FACP table memory at [mem 0x7ffe2d9c-0x7ffe2e0f]
[    0.012541] ACPI: Reserving DSDT table memory at [mem 0x7ffdf040-0x7ffe2d9b]
[    0.012542] ACPI: Reserving FACS table memory at [mem 0x7ffdf000-0x7ffdf03f]
[    0.012543] ACPI: Reserving APIC table memory at [mem 0x7ffe2e10-0x7ffe2e8f]
[    0.012544] ACPI: Reserving SSDT table memory at [mem 0x7ffe2e90-0x7ffe2f59]
[    0.012545] ACPI: Reserving HPET table memory at [mem 0x7ffe2f5a-0x7ffe2f91]
[    0.012546] ACPI: Reserving WAET table memory at [mem 0x7ffe2f92-0x7ffe2fb9]
[    0.012997] No NUMA configuration found
[    0.012998] Faking a node at [mem 0x0000000000000000-0x000000007ffd9fff]
[    0.013003] NODE_DATA(0) allocated [mem 0x7ffd5040-0x7ffd9fff]
[    0.013046] Zone ranges:
[    0.013046]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.013048]   DMA32    [mem 0x0000000001000000-0x000000007ffd9fff]
[    0.013050]   Normal   empty
[    0.013051]   Device   empty
[    0.013052] Movable zone start for each node
[    0.013053] Early memory node ranges
[    0.013053]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.013055]   node   0: [mem 0x0000000000100000-0x000000007ffd9fff]
[    0.013056] Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffd9fff]
[    0.013062] On node 0, zone DMA: 1 pages in unavailable ranges
[    0.013090] On node 0, zone DMA: 97 pages in unavailable ranges
[    0.017131] On node 0, zone DMA32: 38 pages in unavailable ranges
[    0.018795] ACPI: PM-Timer IO Port: 0x608
[    0.018814] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.018864] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.018868] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.018870] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.018872] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.018873] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.018874] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.018879] ACPI: Using ACPI (MADT) for SMP configuration information
[    0.018881] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.018891] CPU topo: Max. logical packages:   1
[    0.018892] CPU topo: Max. logical dies:       1
[    0.018893] CPU topo: Max. dies per package:   1
[    0.018898] CPU topo: Max. threads per core:   1
[    0.018900] CPU topo: Num. cores per package:     2
[    0.018900] CPU topo: Num. threads per package:   2
[    0.018901] CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs
[    0.018921] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
[    0.018939] [mem 0x80000000-0xfeffbfff] available for PCI devices
[    0.018941] Booting paravirtualized kernel on KVM
[    0.018943] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.030736] setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.031038] percpu: Embedded 81 pages/cpu s208464 r8192 d115120 u1048576
[    0.031088] Kernel command line: BOOT_IMAGE=/boot/vmlinuz talos.platform=nocloud console=tty0 console=ttyS0 net.ifnames=0 talos.halt_if_installed=1 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha512
[    0.031327] Unknown kernel command line parameters "BOOT_IMAGE=/boot/vmlinuz", will be passed to user space.
[    0.031587] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.031712] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    0.031791] Fallback order for Node 0: 0 
[    0.031794] Built 1 zonelists, mobility grouping on.  Total pages: 524152
[    0.031795] Policy zone: DMA32
[    0.032082] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
[    0.073495] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.074103] allocated 4194304 bytes of page_ext
[    0.074144] Kernel/User page tables isolation: enabled
[    0.074205] ftrace: allocating 76203 entries in 298 pages
[    0.089066] ftrace: allocated 298 pages with 4 groups
[    0.089405] rcu: Hierarchical RCU implementation.
[    0.089406] rcu: 	RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
[    0.089408] 	Rude variant of Tasks RCU enabled.
[    0.089408] 	Tracing variant of Tasks RCU enabled.
[    0.089409] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.089410] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.089420] RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
[    0.089422] RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
[    0.097157] NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16
[    0.097398] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.097528] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
[    0.107683] Console: colour VGA+ 80x25
[    0.107688] printk: legacy console [tty0] enabled
[    0.158778] printk: legacy console [ttyS0] enabled
[    0.323527] ACPI: Core revision 20240827
[    0.325518] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.328483] APIC: Switch to symmetric I/O mode setup
[    0.330324] x2apic enabled
[    0.331734] APIC: Switched APIC routing to: physical x2apic
[    0.335018] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.336964] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e452ea631d, max_idle_ns: 440795244572 ns
[    0.340145] Calibrating delay loop (skipped) preset value.. 4199.99 BogoMIPS (lpj=8399992)
[    0.344892] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.348143] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.350046] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.352708] Spectre V2 : Mitigation: Retpolines
[    0.354145] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.356709] Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
[    0.358738] Speculative Store Bypass: Vulnerable
[    0.360144] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    0.362083] MMIO Stale Data: Unknown: No mitigations
[    0.363645] x86/fpu: x87 FPU will use FXSAVE
[    0.398785] Freeing SMP alternatives memory: 64K
[    0.400148] pid_max: default: 32768 minimum: 301
[    0.402470] LSM: initializing lsm=lockdown,capability,yama,selinux,bpf,ima
[    0.405112] Yama: becoming mindful.
[    0.406296] SELinux:  Initializing.
[    0.409329] LSM support for eBPF active
[    0.410794] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.412743] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear)
[    0.524818] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0xf, model: 0x6b, stepping: 0x1)
[    0.527545] Performance Events: unsupported Netburst CPU model 107 no PMU driver, software events only.
[    0.528792] signal: max sigframe size: 1440
[    0.529955] rcu: Hierarchical SRCU implementation.
[    0.532144] rcu: 	Max phase no-delay instances is 1000.
[    0.533623] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
[    0.536163] smp: Bringing up secondary CPUs ...
[    0.537490] smpboot: x86: Booting SMP configuration:
[    0.538836] .... node  #0, CPUs:      #1
[    0.550142] smp: Brought up 1 node, 2 CPUs
[    0.553794] smpboot: Total of 2 processors activated (8399.99 BogoMIPS)
[    0.556773] Memory: 1902304K/2096608K available (28672K kernel code, 5586K rwdata, 16416K rodata, 5160K init, 1216K bss, 184288K reserved, 0K cma-reserved)
[    0.561242] devtmpfs: initialized
[    0.562028] x86/mm: Memory block size: 128MB
[    0.565144] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.568153] futex hash table entries: 512 (order: 3, 32768 bytes, linear)
[    0.576215] PM: RTC time: 18:59:57, date: 2024-12-06
[    0.578849] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.581039] audit: initializing netlink subsys (disabled)
[    0.582885] audit: type=2000 audit(1733511597.262:1): state=initialized audit_enabled=0 res=1
[    0.582885] thermal_sys: Registered thermal governor 'step_wise'
[    0.584742] thermal_sys: Registered thermal governor 'user_space'
[    0.586611] cpuidle: using governor menu
[    0.589934] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.592564] dca service started, version 1.12.1
[    0.593870] PCI: Using configuration type 1 for base access
[    0.595643] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.652183] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[    0.654047] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
[    0.657655] cryptd: max_cpu_qlen set to 1000
[    0.659321] ACPI: Added _OSI(Module Device)
[    0.660476] ACPI: Added _OSI(Processor Device)
[    0.661678] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.662918] ACPI: Added _OSI(Processor Aggregator Device)
[    0.665475] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.667874] ACPI: Interpreter enabled
[    0.668479] ACPI: PM: (supports S0 S3 S5)
[    0.669584] ACPI: Using IOAPIC for interrupt routing
[    0.670863] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.672744] PCI: Using E820 reservations for host bridge windows
[    0.674442] ACPI: Enabled 3 GPEs in block 00 to 0F
[    0.681499] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.683031] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI EDR HPX-Type3]
[    0.684696] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[    0.687141] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
[    0.689235] acpiphp: Slot [3] registered
[    0.692202] acpiphp: Slot [8] registered
[    0.693251] acpiphp: Slot [18] registered
[    0.694351] acpiphp: Slot [4] registered
[    0.695396] acpiphp: Slot [6] registered
[    0.696511] acpiphp: Slot [7] registered
[    0.697578] acpiphp: Slot [9] registered
[    0.698677] acpiphp: Slot [10] registered
[    0.699791] acpiphp: Slot [11] registered
[    0.700501] acpiphp: Slot [12] registered
[    0.701642] acpiphp: Slot [13] registered
[    0.702781] acpiphp: Slot [14] registered
[    0.704166] acpiphp: Slot [15] registered
[    0.705266] acpiphp: Slot [16] registered
[    0.706353] acpiphp: Slot [17] registered
[    0.707487] acpiphp: Slot [19] registered
[    0.708495] acpiphp: Slot [20] registered
[    0.709624] acpiphp: Slot [21] registered
[    0.710714] acpiphp: Slot [22] registered
[    0.712164] acpiphp: Slot [23] registered
[    0.713275] acpiphp: Slot [24] registered
[    0.714358] acpiphp: Slot [25] registered
[    0.715424] acpiphp: Slot [26] registered
[    0.716490] acpiphp: Slot [27] registered
[    0.717598] acpiphp: Slot [28] registered
[    0.718738] acpiphp: Slot [29] registered
[    0.719892] PCI host bridge to bus 0000:00
[    0.720488] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.722156] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.724144] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.726015] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window]
[    0.728144] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[    0.730063] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.731491] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
[    0.733253] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
[    0.736871] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
[    0.742511] pci 0000:00:01.1: BAR 4 [io  0xf0c0-0xf0cf]
[    0.745238] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk
[    0.746909] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk
[    0.748144] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk
[    0.750382] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk
[    0.752381] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
[    0.756889] pci 0000:00:01.2: BAR 4 [io  0xf080-0xf09f]
[    0.760495] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
[    0.763131] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.764731] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.766783] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
[    0.772672] pci 0000:00:02.0: BAR 0 [mem 0xfc000000-0xfcffffff pref]
[    0.777500] pci 0000:00:02.0: BAR 2 [mem 0xfea50000-0xfea50fff]
[    0.785414] pci 0000:00:02.0: ROM [mem 0xfea40000-0xfea4ffff pref]
[    0.787301] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.789543] pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint
[    0.793545] pci 0000:00:03.0: BAR 0 [io  0xf000-0xf03f]
[    0.798658] pci 0000:00:03.0: BAR 4 [mem 0xfd600000-0xfd603fff 64bit pref]
[    0.804681] pci 0000:00:05.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge
[    0.808769] pci 0000:00:05.0: BAR 0 [mem 0xfea51000-0xfea510ff 64bit]
[    0.811871] pci 0000:00:05.0: PCI bridge to [bus 01]
[    0.812545] pci 0000:00:05.0:   bridge window [io  0xe000-0xefff]
[    0.814782] pci 0000:00:05.0:   bridge window [mem 0xfe800000-0xfe9fffff]
[    0.817371] pci 0000:00:05.0:   bridge window [mem 0xfd400000-0xfd5fffff 64bit pref]
[    0.820854] pci 0000:00:08.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
[    0.824144] pci 0000:00:08.0: BAR 0 [io  0xf040-0xf07f]
[    0.827524] pci 0000:00:08.0: BAR 1 [mem 0xfea52000-0xfea52fff]
[    0.832146] pci 0000:00:08.0: BAR 4 [mem 0xfd604000-0xfd607fff 64bit pref]
[    0.838164] pci 0000:00:12.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
[    0.841281] pci 0000:00:12.0: BAR 0 [io  0xf0a0-0xf0bf]
[    0.843683] pci 0000:00:12.0: BAR 1 [mem 0xfea53000-0xfea53fff]
[    0.849305] pci 0000:00:12.0: BAR 4 [mem 0xfd608000-0xfd60bfff 64bit pref]
[    0.852046] pci 0000:00:12.0: ROM [mem 0xfea00000-0xfea3ffff pref]
[    0.853866] pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge
[    0.858905] pci 0000:00:1e.0: BAR 0 [mem 0xfea54000-0xfea540ff 64bit]
[    0.861359] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    0.862659] pci 0000:00:1e.0:   bridge window [io  0xd000-0xdfff]
[    0.864163] pci 0000:00:1e.0:   bridge window [mem 0xfe600000-0xfe7fffff]
[    0.866283] pci 0000:00:1e.0:   bridge window [mem 0xfd200000-0xfd3fffff 64bit pref]
[    0.868986] pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge
[    0.873231] pci 0000:00:1f.0: BAR 0 [mem 0xfea55000-0xfea550ff 64bit]
[    0.875723] pci 0000:00:1f.0: PCI bridge to [bus 03]
[    0.876543] pci 0000:00:1f.0:   bridge window [io  0xc000-0xcfff]
[    0.878055] pci 0000:00:1f.0:   bridge window [mem 0xfe400000-0xfe5fffff]
[    0.881074] pci 0000:00:1f.0:   bridge window [mem 0xfd000000-0xfd1fffff 64bit pref]
[    0.884161] pci_bus 0000:01: extended config space not accessible
[    0.885798] acpiphp: Slot [1] registered
[    0.886894] acpiphp: Slot [0] registered
[    0.887981] acpiphp: Slot [2] registered
[    0.888510] acpiphp: Slot [3-1] registered
[    0.889639] acpiphp: Slot [4-1] registered
[    0.890780] acpiphp: Slot [5] registered
[    0.892166] acpiphp: Slot [6-1] registered
[    0.893272] acpiphp: Slot [7-1] registered
[    0.894379] acpiphp: Slot [8-1] registered
[    0.895516] acpiphp: Slot [9-1] registered
[    0.896496] acpiphp: Slot [10-1] registered
[    0.897626] acpiphp: Slot [11-1] registered
[    0.898760] acpiphp: Slot [12-1] registered
[    0.900165] acpiphp: Slot [13-1] registered
[    0.901300] acpiphp: Slot [14-1] registered
[    0.902419] acpiphp: Slot [15-1] registered
[    0.903571] acpiphp: Slot [16-1] registered
[    0.904498] acpiphp: Slot [17-1] registered
[    0.905669] acpiphp: Slot [18-1] registered
[    0.906808] acpiphp: Slot [19-1] registered
[    0.908165] acpiphp: Slot [20-1] registered
[    0.909289] acpiphp: Slot [21-1] registered
[    0.910407] acpiphp: Slot [22-1] registered
[    0.911525] acpiphp: Slot [23-1] registered
[    0.912504] acpiphp: Slot [24-1] registered
[    0.913673] acpiphp: Slot [25-1] registered
[    0.914793] acpiphp: Slot [26-1] registered
[    0.916174] acpiphp: Slot [27-1] registered
[    0.917293] acpiphp: Slot [28-1] registered
[    0.918409] acpiphp: Slot [29-1] registered
[    0.919553] acpiphp: Slot [30] registered
[    0.920512] acpiphp: Slot [31] registered
[    0.921690] pci 0000:01:01.0: [1af4:1004] type 00 class 0x010000 conventional PCI endpoint
[    0.926357] pci 0000:01:01.0: BAR 0 [io  0xe000-0xe03f]
[    0.928876] pci 0000:01:01.0: BAR 1 [mem 0xfe800000-0xfe800fff]
[    0.934717] pci 0000:01:01.0: BAR 4 [mem 0xfd400000-0xfd403fff 64bit pref]
[    0.939364] pci 0000:00:05.0: PCI bridge to [bus 01]
[    0.941247] pci_bus 0000:02: extended config space not accessible
[    0.942761] acpiphp: Slot [0-1] registered
[    0.944507] acpiphp: Slot [1-1] registered
[    0.945596] acpiphp: Slot [2-1] registered
[    0.946686] acpiphp: Slot [3-2] registered
[    0.948170] acpiphp: Slot [4-2] registered
[    0.949283] acpiphp: Slot [5-1] registered
[    0.950402] acpiphp: Slot [6-2] registered
[    0.952492] acpiphp: Slot [7-2] registered
[    0.953614] acpiphp: Slot [8-2] registered
[    0.954733] acpiphp: Slot [9-2] registered
[    0.956167] acpiphp: Slot [10-2] registered
[    0.957273] acpiphp: Slot [11-2] registered
[    0.958356] acpiphp: Slot [12-2] registered
[    0.959421] acpiphp: Slot [13-2] registered
[    0.960510] acpiphp: Slot [14-2] registered
[    0.961676] acpiphp: Slot [15-2] registered
[    0.962815] acpiphp: Slot [16-2] registered
[    0.963904] acpiphp: Slot [17-2] registered
[    0.964491] acpiphp: Slot [18-2] registered
[    0.965595] acpiphp: Slot [19-2] registered
[    0.967333] acpiphp: Slot [20-2] registered
[    0.968517] acpiphp: Slot [21-2] registered
[    0.969689] acpiphp: Slot [22-2] registered
[    0.970772] acpiphp: Slot [23-2] registered
[    0.972484] acpiphp: Slot [24-2] registered
[    0.973558] acpiphp: Slot [25-2] registered
[    0.974699] acpiphp: Slot [26-2] registered
[    0.975811] acpiphp: Slot [27-2] registered
[    0.976478] acpiphp: Slot [28-2] registered
[    0.977623] acpiphp: Slot [29-2] registered
[    0.978715] acpiphp: Slot [30-1] registered
[    0.980186] acpiphp: Slot [31-1] registered
[    0.981545] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    0.983644] pci_bus 0000:03: extended config space not accessible
[    0.984628] acpiphp: Slot [0-2] registered
[    0.985708] acpiphp: Slot [1-2] registered
[    0.986762] acpiphp: Slot [2-2] registered
[    0.988486] acpiphp: Slot [3-3] registered
[    0.989593] acpiphp: Slot [4-3] registered
[    0.990714] acpiphp: Slot [5-2] registered
[    0.991828] acpiphp: Slot [6-3] registered
[    0.992487] acpiphp: Slot [7-3] registered
[    0.993674] acpiphp: Slot [8-3] registered
[    0.994813] acpiphp: Slot [9-3] registered
[    0.996168] acpiphp: Slot [10-3] registered
[    0.997241] acpiphp: Slot [11-3] registered
[    0.998493] acpiphp: Slot [12-3] registered
[    0.999589] acpiphp: Slot [13-3] registered
[    1.000492] acpiphp: Slot [14-3] registered
[    1.001585] acpiphp: Slot [15-3] registered
[    1.002720] acpiphp: Slot [16-3] registered
[    1.004166] acpiphp: Slot [17-3] registered
[    1.005255] acpiphp: Slot [18-3] registered
[    1.006332] acpiphp: Slot [19-3] registered
[    1.007406] acpiphp: Slot [20-3] registered
[    1.008499] acpiphp: Slot [21-3] registered
[    1.009624] acpiphp: Slot [22-3] registered
[    1.010713] acpiphp: Slot [23-3] registered
[    1.011857] acpiphp: Slot [24-3] registered
[    1.012491] acpiphp: Slot [25-3] registered
[    1.013660] acpiphp: Slot [26-3] registered
[    1.014759] acpiphp: Slot [27-3] registered
[    1.016169] acpiphp: Slot [28-3] registered
[    1.017233] acpiphp: Slot [29-3] registered
[    1.018291] acpiphp: Slot [30-2] registered
[    1.019385] acpiphp: Slot [31-2] registered
[    1.020744] pci 0000:00:1f.0: PCI bridge to [bus 03]
[    1.024443] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
[    1.026109] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
[    1.027746] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
[    1.028712] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
[    1.030307] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
[    1.033032] iommu: Default domain type: Translated
[    1.033402] iommu: DMA domain TLB invalidation policy: lazy mode
[    1.036635] SCSI subsystem initialized
[    1.037697] ACPI: bus type USB registered
[    1.037697] usbcore: registered new interface driver usbfs
[    1.040529] usbcore: registered new interface driver hub
[    1.041821] usbcore: registered new device driver usb
[    1.043064] pps_core: LinuxPPS API ver. 1 registered
[    1.044501] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    1.046788] PTP clock support registered
[    1.048192] EDAC MC: Ver: 3.0.0
[    1.049769] NET: Registered PF_ATMPVC protocol family
[    1.049769] NET: Registered PF_ATMSVC protocol family
[    1.049769] NetLabel: Initializing
[    1.050469] NetLabel:  domain hash size = 128
[    1.051567] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    1.056583] NetLabel:  unlabeled traffic allowed by default
[    1.057957] PCI: Using ACPI for IRQ routing
[    1.059397] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    1.059397] pci 0000:00:02.0: vgaarb: bridge control possible
[    1.059397] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    1.064147] vgaarb: loaded
[    1.065144] hpet: 3 channels of 0 reserved for per-cpu timers
[    1.065144] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    1.065756] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    1.072151] clocksource: Switched to clocksource kvm-clock
[    1.074528] VFS: Disk quotas dquot_6.6.0
[    1.075606] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.077485] pnp: PnP ACPI init
[    1.078922] pnp: PnP ACPI: found 5 devices
[    1.089018] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.091491] NET: Registered PF_INET protocol family
[    1.092959] IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    1.119997] tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear)
[    1.122267] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.124352] TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    1.126484] TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear)
[    1.128451] TCP: Hash tables configured (established 16384 bind 16384)
[    1.130153] UDP hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    1.131822] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear)
[    1.133753] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.135572] RPC: Registered named UNIX socket transport module.
[    1.137144] RPC: Registered udp transport module.
[    1.138284] RPC: Registered tcp transport module.
[    1.139468] RPC: Registered tcp-with-tls transport module.
[    1.140809] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.142369] NET: Registered PF_XDP protocol family
[    1.143623] pci 0000:00:05.0: PCI bridge to [bus 01]
[    1.144902] pci 0000:00:05.0:   bridge window [io  0xe000-0xefff]
[    1.147593] pci 0000:00:05.0:   bridge window [mem 0xfe800000-0xfe9fffff]
[    1.149939] pci 0000:00:05.0:   bridge window [mem 0xfd400000-0xfd5fffff 64bit pref]
[    1.153379] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    1.154638] pci 0000:00:1e.0:   bridge window [io  0xd000-0xdfff]
[    1.157232] pci 0000:00:1e.0:   bridge window [mem 0xfe600000-0xfe7fffff]
[    1.159737] pci 0000:00:1e.0:   bridge window [mem 0xfd200000-0xfd3fffff 64bit pref]
[    1.162601] pci 0000:00:1f.0: PCI bridge to [bus 03]
[    1.163889] pci 0000:00:1f.0:   bridge window [io  0xc000-0xcfff]
[    1.166352] pci 0000:00:1f.0:   bridge window [mem 0xfe400000-0xfe5fffff]
[    1.168830] pci 0000:00:1f.0:   bridge window [mem 0xfd000000-0xfd1fffff 64bit pref]
[    1.171579] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.173150] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    1.174612] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    1.176241] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window]
[    1.177898] pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window]
[    1.179594] pci_bus 0000:01: resource 0 [io  0xe000-0xefff]
[    1.180961] pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff]
[    1.182512] pci_bus 0000:01: resource 2 [mem 0xfd400000-0xfd5fffff 64bit pref]
[    1.184406] pci_bus 0000:02: resource 0 [io  0xd000-0xdfff]
[    1.185761] pci_bus 0000:02: resource 1 [mem 0xfe600000-0xfe7fffff]
[    1.187257] pci_bus 0000:02: resource 2 [mem 0xfd200000-0xfd3fffff 64bit pref]
[    1.189046] pci_bus 0000:03: resource 0 [io  0xc000-0xcfff]
[    1.190401] pci_bus 0000:03: resource 1 [mem 0xfe400000-0xfe5fffff]
[    1.192010] pci_bus 0000:03: resource 2 [mem 0xfd000000-0xfd1fffff 64bit pref]
[    1.193914] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.195319] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.211052] ACPI: \_SB_.LNKD: Enabled at IRQ 11
[    1.226705] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x690 took 29229 usecs
[    1.228696] PCI: CLS 0 bytes, default 64
[    1.229951] kvm_intel: VMX not supported by CPU 1
[    1.229955] Unpacking initramfs...
[    1.230871] kvm_amd: CPU 1 isn't AMD or Hygon
[    1.230875] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e452ea631d, max_idle_ns: 440795244572 ns
[    1.236738] Initialise system trusted keyrings
[    1.237917] Key type blacklist registered
[    1.239459] workingset: timestamp_bits=40 max_order=19 bucket_order=0
[    1.243693] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.251033] NFS: Registering the id_resolver key type
[    1.252335] Key type id_resolver registered
[    1.253424] Key type id_legacy registered
[    1.254517] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.256190] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.275455] Key type cifs.spnego registered
[    1.276605] Key type cifs.idmap registered
[    1.277893] fuse: init (API version 7.41)
[    1.279237] SGI XFS with ACLs, security attributes, scrub, repair, quota, no debug enabled
[    1.288901] ceph: loaded (mds proto 32)
[    1.290372] integrity: Platform Keyring initialized
[    1.306305] NET: Registered PF_ALG protocol family
[    1.307533] Key type asymmetric registered
[    1.308566] Asymmetric key parser 'x509' registered
[    1.309878] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.311819] io scheduler mq-deadline registered
[    1.312977] io scheduler kyber registered
[    1.314074] io scheduler bfq registered
[    1.315761] hv_vmbus: registering driver hyperv_fb
[    1.317016] IPMI message handler: version 39.2
[    1.318191] ipmi device interface
[    1.319202] ipmi_si: IPMI System Interface driver
[    1.320448] ipmi_si: Unable to find any System Interface(s)
[    1.321838] IPMI poweroff: Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot
[    1.324369] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    1.326344] ACPI: button: Power Button [PWRF]
[    1.330926] ioatdma: Intel(R) QuickData Technology Driver 5.00
[    1.332802] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.334618] 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    1.337254] Non-volatile memory driver v1.3
[    1.338368] Linux agpgart interface v0.103
[    1.339646] ACPI: bus type drm_connector registered
[    1.346010] Freeing initrd memory: 77152K
[    1.350141] loop: module loaded
[    1.352634] rbd: loaded (major 252)
[    1.353573] Guest personality initialized and is inactive
[    1.354944] VMCI host device registered (name=vmci, major=10, minor=126)
[    1.356546] Initialized host personality
[    1.357615] Loading iSCSI transport class v2.0-870.
[    1.360091] iscsi: registered transport (tcp)
[    1.361279] hv_vmbus: registering driver hv_storvsc
[    1.364307] scsi host0: ata_piix
[    1.365454] scsi host1: ata_piix
[    1.366394] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xf0c0 irq 14 lpm-pol 0
[    1.368397] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xf0c8 irq 15 lpm-pol 0
[    1.375481] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    1.377400] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <[email protected]>. All Rights Reserved.
[    1.380090] tun: Universal TUN/TAP device driver, 1.6
[    1.381568] hv_vmbus: registering driver hv_netvsc
[    1.382878] usbcore: registered new interface driver cdc_acm
[    1.384253] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.386314] usbcore: registered new interface driver uas
[    1.387653] usbcore: registered new interface driver usb-storage
[    1.389174] usbcore: registered new interface driver usbserial_generic
[    1.390750] usbserial: USB Serial support registered for generic
[    1.392251] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    1.395187] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.396440] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.397768] hv_vmbus: registering driver hyperv_keyboard
[    1.399286] mousedev: PS/2 mouse device common for all mice
[    1.400802] rtc_cmos 00:04: RTC can wake from S4
[    1.402852] rtc_cmos 00:04: registered as rtc0
[    1.402908] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    1.403836] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
[    1.409155] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    1.412344] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: [email protected]
[    1.415148] intel_pstate: CPU model not supported
[    1.416388] sdhci: Secure Digital Host Controller Interface driver
[    1.418041] sdhci: Copyright(c) Pierre Ossman
[    1.423639] hid: raw HID events driver (C) Jiri Kosina
[    1.424994] usbcore: registered new interface driver usbhid
[    1.426389] usbhid: USB HID core driver
[    1.427775] hv_utils: Registering HyperV Utility Driver
[    1.429076] hv_vmbus: registering driver hv_utils
[    1.430247] hv_vmbus: registering driver hv_balloon
[    1.431522] NET: Registered PF_LLC protocol family
[    1.432767] GACT probability NOT on
[    1.433939] Mirror/redirect action on
[    1.434933] Simple TC action Loaded
[    1.436050] netem: version 1.3
[    1.436947] u32 classifier
[    1.437699]     input device check on
[    1.438677]     Actions configured
[    1.440260] xt_time: kernel timezone is -0000
[    1.441368] IPVS: Registered protocols (TCP, UDP)
[    1.442838] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[    1.444848] IPVS: ipvs loaded.
[    1.445690] IPVS: [rr] scheduler registered.
[    1.446793] IPVS: [wrr] scheduler registered.
[    1.447886] IPVS: [lc] scheduler registered.
[    1.448997] IPVS: [sh] scheduler registered.
[    1.450234] ipip: IPv4 and MPLS over IPv4 tunneling driver
[    1.451722] gre: GRE over IPv4 demultiplexor driver
[    1.453025] Initializing XFRM netlink socket
[    1.454310] NET: Registered PF_INET6 protocol family
[    1.456206] Segment Routing with IPv6
[    1.457208] In-situ OAM (IOAM) with IPv6
[    1.458292] mip6: Mobile IPv6
[    1.459177] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.460905] NET: Registered PF_PACKET protocol family
[    1.462232] Bridge firewalling registered
[    1.463414] NET: Registered PF_X25 protocol family
[    1.464670] X25: Linux Version 0.2
[    1.465664] RPC: Registered rdma transport module.
[    1.466900] RPC: Registered rdma backchannel transport module.
[    1.468405] l2tp_core: L2TP core driver, V2.0
[    1.469545] NET: Registered PF_PHONET protocol family
[    1.470908] 8021q: 802.1Q VLAN Support v1.8
[    1.475052] DCCP: Activated CCID 2 (TCP-like)
[    1.476237] DCCP: Activated CCID 3 (TCP-Friendly Rate Control)
[    1.477690] DCCP is deprecated and scheduled to be removed in 2025, please contact the netdev mailing list
[    1.480193] sctp: Hash tables configured (bind 256/256)
[    1.481911] NET: Registered PF_RDS protocol family
[    1.483187] Key type dns_resolver registered
[    1.484248] Key type ceph registered
[    1.485288] libceph: loaded (mon/osd proto 15/24)
[    1.486462] openvswitch: Open vSwitch switching datapath
[    1.488187] NET: Registered PF_VSOCK protocol family
[    1.489440] mpls_gso: MPLS GSO support
[    1.490845] IPI shorthand broadcast: enabled
[    1.496282] sched_clock: Marking stable (1260004844, 234735033)->(1497997317, -3257440)
[    1.501138] registered taskstats version 1
[    1.502349] Loading compiled-in X.509 certificates
[    1.504458] Loaded X.509 cert 'Sidero Labs, Inc.: Build time throw-away kernel key: 73787c11f20c5c5eaf11c2efce7961745b791bd9'
[    1.517675] Demotion targets for Node 0: null
[    1.518869] ima: No TPM chip found, activating TPM-bypass!
[    1.520197] ima: Allocated hash algorithm: sha512
[    1.521353] ima: No architecture policies found
[    1.522835] PM:   Magic number: 4:839:998
[    1.523970] serial 00:00: hash matches
[    1.525018] printk: legacy console [netcon0] enabled
[    1.526214] netconsole: network logging started
[    1.527184] ata2: found unknown device (class 0)
[    1.529263] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[    1.535264] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[    1.546556] clk: Disabling unused clocks
[    1.563862] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    1.565198] cdrom: Uniform CD-ROM driver Revision: 3.20
[    1.588001] sr 1:0:0:0: Attached scsi generic sg0 type 5
[    1.591760] Freeing unused kernel image (initmem) memory: 5160K
[    1.593103] Write protecting the kernel read-only data: 47104k
[    1.595000] Freeing unused kernel image (rodata/data gap) memory: 2016K
[    1.598551] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.599795] x86/mm: Checking user space page tables
[    1.601100] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.602378] Run /init as init process
[    1.607906] [talos] [initramfs] TPM device is not available
[    1.609040] [talos] [initramfs] TPM device is not available, skipping PCR extension
[    1.610531] [talos] [initramfs] booting Talos v1.9.0-beta.0
[    1.611645] [talos] [initramfs] CPU: QEMU Virtual CPU version 2.5+, 2 core(s), 1 thread(s) per core
[    1.613420] [talos] [initramfs] x86_64 microarchitecture level: 2
[    1.614590] [talos] [initramfs] mounting the rootfs
[    1.615916] [talos] [initramfs] enabling system extension schematic e88202574e1c65f86d35d171ab7f08eddbe6c8e78cb45b9cb0470d6b0c394876
[    1.618111] [talos] [initramfs] enabling system extension qemu-guest-agent 9.1.2
[    1.619586] [talos] [initramfs] enabling system extension iscsi-tools v0.1.6
[    1.621091] loop0: detected capacity change from 0 to 8
[    1.635234] loop1: detected capacity change from 0 to 1336
[    1.650867] loop2: detected capacity change from 0 to 5232
[    1.666872] loop3: detected capacity change from 0 to 143568
[    1.687060] [talos] [initramfs] entering the rootfs
[    1.688092] [talos] [initramfs] moving mounts to the new rootfs
[    1.689537] [talos] [initramfs] changing working directory into /root
[    1.690818] [talos] [initramfs] moving /root to /
[    1.691840] [talos] [initramfs] changing root directory
[    1.692904] [talos] [initramfs] cleaning up initramfs
[    1.699222] [talos] [initramfs] selinux: disabled, not loading policy
[    1.700610] [talos] [initramfs] TPM device is not available, skipping PCR extension
[    1.702173] [talos] [initramfs] executing /sbin/init
[    1.833591] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    3.286839] random: crng init done
[    3.478260] [talos] machined logger initialized
[    3.484511] [talos] platform information {"component": "early-startup", "mode": "cloud"}
[    3.487421] audit: type=1807 audit(1733511600.166:2): action=dont_measure fsmagic=0x9fa0 res=1
[    3.487781] ima: policy update completed
[    3.488996] audit: type=1807 audit(1733511600.166:3): action=dont_measure fsmagic=0x62656572 res=1
[    3.492998] audit: type=1807 audit(1733511600.166:4): action=dont_measure fsmagic=0x64626720 res=1
[    3.495272] audit: type=1807 audit(1733511600.166:5): action=dont_measure fsmagic=0x1021994 res=1
[    3.497561] audit: type=1807 audit(1733511600.166:6): action=dont_measure fsmagic=0x1cd1 res=1
[    3.499765] audit: type=1807 audit(1733511600.166:7): action=dont_measure fsmagic=0x42494e4d res=1
[    3.502070] audit: type=1807 audit(1733511600.166:8): action=dont_measure fsmagic=0x73636673 res=1
[    3.504394] audit: type=1807 audit(1733511600.166:9): action=dont_measure fsmagic=0xf97cff8c res=1
[    3.506661] audit: type=1807 audit(1733511600.166:10): action=dont_measure fsmagic=0x43415d53 res=1
[    3.515035] [talos] initializing cgroups {"component": "early-startup", "root": "/"}
[    3.541934] [talos] early startup done {"component": "early-startup", "duration": "57.440566ms"}
[    3.544964] [talos] initialize sequence: 10 phase(s)
[    3.546284] [talos] phase systemRequirements (1/10): 1 tasks(s)
[    3.563488] [talos] service[ext-iscsid](Starting): Starting service
[    3.565220] [talos] service[ext-iscsid](Waiting): Waiting for service "containerd" to be "up", service "cri" to be "up", service "ext-tgtd" to be "up", network
[    3.570875] [talos] service[ext-qemu-guest-agent](Starting): Starting service
[    3.572922] [talos] service[ext-qemu-guest-agent](Waiting): Waiting for service "containerd" to be "up", file "/system/run/machined/machine.sock" to exist, file "/dev/virtio-ports/org.qemu.guest_agent.0" to exist
[    3.578731] [talos] service[ext-tgtd](Starting): Starting service
[    3.580417] [talos] service[ext-tgtd](Waiting): Waiting for service "containerd" to be "up", service "cri" to be "up", network
[    3.595826] [talos] pre-created iptables-nft table 'mangle'/'KUBE-IPTABLES-HINT' {"component": "controller-runtime", "controller": "network.NfTablesChainController"}
[    3.602377] [talos] task enforceKSPPRequirements (1/1): starting
[    3.604856] [talos] nftables chains updated {"component": "controller-runtime", "controller": "network.NfTablesChainController", "chains": []}
[    3.608684] [talos] setting time servers {"component": "controller-runtime", "controller": "network.TimeServerSpecController", "addresses": ["time.cloudflare.com"]}
[    3.617513] [talos] setting time servers {"component": "controller-runtime", "controller": "network.TimeServerSpecController", "addresses": ["time.cloudflare.com"]}
[    3.623812] [talos] setting resolvers {"component": "controller-runtime", "controller": "network.ResolverSpecController", "resolvers": ["1.1.1.1", "8.8.8.8"], "searchDomains": []}
[    3.633812] [talos] setting resolvers {"component": "controller-runtime", "controller": "network.ResolverSpecController", "resolvers": ["1.1.1.1", "8.8.8.8"], "searchDomains": []}
[    3.640051] [talos] waiting for devices to be ready...
[    3.641175] [talos] task enforceKSPPRequirements (1/1): done, 92.582811ms
[    3.642488] [talos] phase systemRequirements (1/10): done, 96.20292ms
[    3.643743] [talos] phase earlyServices (2/10): 5 tasks(s)
[    3.644931] [talos] task startContainerd (5/5): starting
[    3.646037] [talos] service[containerd](Starting): Starting service
[    3.647294] [talos] assigned address {"component": "controller-runtime", "controller": "network.AddressSpecController", "address": "127.0.0.1/8", "link": "lo"}
[    3.649974] [talos] task startMachined (2/5): starting
[    3.651027] [talos] task startUdevd (1/5): starting
[    3.652014] [talos] task startAuditd (3/5): starting
[    3.653137] [talos] task startSyslogd (4/5): starting
[    3.654151] [talos] service[containerd](Preparing): Running pre state
[    3.655495] [talos] service[syslogd](Starting): Starting service
[    3.656733] [talos] service[syslogd](Waiting): Waiting for service "machined" to be "up"
[    3.658351] [talos] TPM device is not available, skipping PCR extension
[    3.659670] [talos] service[containerd](Preparing): Creating service runner
[    3.661033] [talos] task startSyslogd (4/5): done, 10.38054ms
[    3.662195] [talos] service[udevd](Starting): Starting service
[    3.663400] [talos] service[udevd](Preparing): Running pre state
[    3.667551] [talos] service[auditd](Starting): Starting service
[    3.669595] [talos] service[auditd](Preparing): Running pre state
[    3.670909] [talos] service[auditd](Preparing): Creating service runner
[    3.672933] [talos] service[machined](Starting): Starting service
[    3.674999] [talos] service[machined](Preparing): Running pre state
[    3.676224] [talos] service[machined](Preparing): Creating service runner
[    3.677545] [talos] service[auditd](Running): Service started as goroutine
[    3.679823] [talos] task startAuditd (3/5): done, 33.279485ms
[    3.681492] [talos] service[machined](Running): Service started as goroutine
[    3.683445] [talos] service[containerd](Running): Process Process(["/bin/containerd" "--address" "/system/run/containerd/containerd.sock" "--state" "/system/run/containerd" "--root" "/system/var/lib/containerd"]) started with PID 1115
[    3.705769] [talos] service[udevd](Preparing): Creating service runner
[    3.722873] [talos] service[udevd](Running): Process Process(["/sbin/systemd-udevd" "--resolve-names=never"]) started with PID 1119
[    4.093595] ACPI: \_SB_.LNKC: Enabled at IRQ 10
[    4.170293] ACPI: \_SB_.LNKB: Enabled at IRQ 10
[    4.189210] ACPI: \_SB_.LNKA: Enabled at IRQ 11
[    4.209552] virtio_scsi virtio3: 2/0/0 default/read/poll queues
[    4.212404] scsi host2: Virtio SCSI HBA
[    4.220802] scsi 2:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    4.226452] sd 2:0:0:0: Power-on or device reset occurred
[    4.226456] sd 2:0:0:0: Attached scsi generic sg1 type 0
[    4.227464] sd 2:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB)
[    4.231999] sd 2:0:0:0: [sda] Write Protect is off
[    4.233257] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.569526] [talos] service[ext-iscsid](Waiting): Waiting for service "containerd" to be "up", service "cri" to be registered, service "ext-tgtd" to be "up", network
[    4.595832] [talos] service[ext-tgtd](Waiting): Waiting for service "containerd" to be "up", service "cri" to be registered, network
[    4.603730] GPT:Primary header thinks Alt. header is not at the end of the disk.
[    4.605321] GPT:210459 != 20971519
[    4.606077] GPT:Alternate GPT header not at the end of the disk.
[    4.607267] GPT:210459 != 20971519
[    4.608008] GPT: Use GNU Parted to correct GPT errors.
[    4.609036]  sda: sda1 sda2 sda3 sda4
[    4.610229] sd 2:0:0:0: [sda] Attached SCSI disk
[    4.669767] [talos] service[containerd](Running): Health check successful
[    4.671335] [talos] task startContainerd (5/5): done, 1.026400776s
[    4.679963] [talos] service[auditd](Running): Health check successful
[    4.684131] [talos] service[machined](Running): Health check successful
[    4.685498] [talos] task startMachined (2/5): done, 1.038993747s
[    4.688153] [talos] service[syslogd](Preparing): Running pre state
[    4.689427] [talos] service[syslogd](Preparing): Creating service runner
[    4.690806] [talos] service[syslogd](Running): Service started as goroutine
[    5.188555] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "META", "phase": "waiting -> ready"}
[    5.569226] [talos] service[ext-iscsid](Waiting): Waiting for service "cri" to be registered, service "ext-tgtd" to be "up", network
[    5.579152] [talos] service[ext-qemu-guest-agent](Waiting): Waiting for file "/dev/virtio-ports/org.qemu.guest_agent.0" to exist
[    5.596065] [talos] service[ext-tgtd](Waiting): Waiting for service "cri" to be registered, network
[    5.692071] [talos] service[syslogd](Running): Health check successful
[   13.644433] Free page reporting enabled
[   13.759022] [talos] service[udevd](Running): Health check successful
[   13.762400] [talos] task startUdevd (1/5): done, 10.115873306s
[   13.764286] [talos] phase earlyServices (2/10): done, 10.120542445s
[   13.765640] [talos] phase usb (3/10): 1 tasks(s)
[   13.767722] [talos] task waitForUSB (1/1): starting
[   13.768953] [talos] task waitForUSB (1/1): waiting 1 second(s) for USB storage
[   13.785397] 8021q: adding VLAN 0 to HW filter on device eth0
[   13.799423] [talos] found config disk (cidata) at /dev/sr0
[   13.804879] [talos] fetching meta config from: cidata/meta-data
[   13.806546] [talos] fetching network config from: cidata/network-config
[   13.808359] [talos] fetching machine config from: cidata/user-data
[   13.811143] [talos] setting resolvers {"component": "controller-runtime", "controller": "network.ResolverSpecController", "resolvers": ["10.0.3.24"], "searchDomains": []}
[   13.815597] [talos] setting hostname {"component": "controller-runtime", "controller": "network.HostnameSpecController", "hostname": "control-1", "domainname": ""}
[   13.820531] [talos] setting hostname {"component": "controller-runtime", "controller": "network.HostnameSpecController", "hostname": "control-1", "domainname": ""}
[   13.824250] [talos] assigned address {"component": "controller-runtime", "controller": "network.AddressSpecController", "address": "10.0.5.151/24", "link": "eth0"}
[   13.880769] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "STATE", "phase": "waiting -> missing"}
[   14.407411] [talos] created route {"component": "controller-runtime", "controller": "network.RouteSpecController", "destination": "default", "gateway": "10.0.5.1", "table": "main", "link": "eth0", "priority": 1024, "family": "inet4"}
[   14.570012] [talos] service[ext-iscsid](Waiting): Waiting for service "cri" to be registered, service "ext-tgtd" to be "up"
[   14.579317] [talos] service[ext-qemu-guest-agent](Preparing): Running pre state
[   14.585993] [talos] service[ext-qemu-guest-agent](Preparing): Creating service runner
[   14.595522] [talos] service[ext-tgtd](Waiting): Waiting for service "cri" to be registered
[   14.771237] [talos] task waitForUSB (1/1): done, 1.003513633s
[   14.772576] [talos] phase usb (3/10): done, 1.006934689s
[   14.773710] [talos] phase meta (4/10): 1 tasks(s)
[   14.774776] [talos] task reloadMeta (1/1): starting
[   14.801328] [talos] service[ext-qemu-guest-agent](Running): Started task ext-qemu-guest-agent (PID 2020) for container ext-qemu-guest-agent
[   14.807036] [talos] adjusting time (slew) by 238.690826ms via 162.159.200.123, state TIME_OK, status STA_PLL | STA_NANO {"component": "controller-runtime", "controller": "time.SyncController"}
[   14.881690] [talos] META: failed to load: unexpected EOF
[   14.882905] [talos] task reloadMeta (1/1): failed: unexpected EOF
[   14.884169] [talos] phase meta (4/10): failed
[   14.885109] [talos] initialize sequence: failed
[   14.886132] [talos] service[ext-qemu-guest-agent](Stopping): Sending SIGTERM to task ext-qemu-guest-agent (PID 2020, container ext-qemu-guest-agent)
[   14.888943] [talos] service[udevd](Stopping): Sending SIGTERM to Process(["/sbin/systemd-udevd" "--resolve-names=never"])
[   14.891219] [talos] service[syslogd](Stopping): Service stopping
[   14.892484] [talos] service[auditd](Stopping): Service stopping
[   14.893703] [talos] service[ext-iscsid](Failed): Condition failed: context canceled
[   14.895260] kauditd_printk_skb: 28 callbacks suppressed
[   14.895267] audit: type=1305 audit(1733511611.570:45): op=set audit_enabled=0 old=1 auid=4294967295 ses=4294967295 subj=kernel res=1
[   14.906765] [talos] service[ext-tgtd](Failed): Condition failed: context canceled
[   14.910689] [talos] service[syslogd](Finished): Service finished successfully
[   14.916104] [talos] service[auditd](Finished): Service finished successfully
[   14.917552] [talos] service[machined](Stopping): Service stopping
[   14.921927] [talos] service[machined](Finished): Service finished successfully
[   14.923656] [talos] service[udevd](Finished): Service finished successfully
[   15.062131] [talos] service[ext-qemu-guest-agent](Finished): Service finished successfully
[   15.064001] [talos] service[containerd](Stopping): Sending SIGTERM to Process(["/bin/containerd" "--address" "/system/run/containerd/containerd.sock" "--state" "/system/run/containerd" "--root" "/system/var/lib/containerd"])
[   15.069836] [talos] service[containerd](Finished): Service finished successfully
[   15.073905] [talos] manager finished {"component": "dns-resolve-cache", "error": "context canceled"}
[   15.090878] [talos] controller runtime finished
[   15.094015] [talos] error running phase 4 in initialize sequence: task 1/1: failed, unexpected EOF
[   15.203606] [talos] failed to revert bootloader: unexpected EOF
[   15.205071] [talos] rebooting in 10 seconds
[   16.180910] [talos] rebooting in 9 seconds
[   17.156824] [talos] rebooting in 8 seconds
[   18.135925] [talos] rebooting in 7 seconds
[   19.120219] [talos] rebooting in 6 seconds

@smira
Copy link
Member

smira commented Dec 9, 2024

I'm totally lost right now as to how this can be.

The disk in question is (most probably):

[    4.209552] virtio_scsi virtio3: 2/0/0 default/read/poll queues
[    4.212404] scsi host2: Virtio SCSI HBA
[    4.220802] scsi 2:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    4.226452] sd 2:0:0:0: Power-on or device reset occurred
[    4.226456] sd 2:0:0:0: Attached scsi generic sg1 type 0
[    4.227464] sd 2:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB)
[    4.231999] sd 2:0:0:0: [sda] Write Protect is off
[    4.233257] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.603730] GPT:Primary header thinks Alt. header is not at the end of the disk.
[    4.605321] GPT:210459 != 20971519
[    4.606077] GPT:Alternate GPT header not at the end of the disk.
[    4.607267] GPT:210459 != 20971519
[    4.608008] GPT: Use GNU Parted to correct GPT errors.
[    4.609036]  sda: sda1 sda2 sda3 sda4
[    4.610229] sd 2:0:0:0: [sda] Attached SCSI disk

Looks like it's some kind of a disk image with GPT being smaller than the whole disk. Is this Talos disk image?

Talos finds the META there (idk if this is expected?):

[    5.188555] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "META", "phase": "waiting -> ready"}

But after that something else goes terribly wrong (need to look more to understand):

[   14.881690] [talos] META: failed to load: unexpected EOF

smira added a commit to smira/talos that referenced this issue Dec 16, 2024
Doesn't fix anything, but the hope is that it would help
with siderolabs#9852 and siderolabs#9786.

Signed-off-by: Andrey Smirnov <[email protected]>
smira added a commit to smira/talos that referenced this issue Dec 17, 2024
Doesn't fix anything, but the hope is that it would help
with siderolabs#9852 and siderolabs#9786.

Signed-off-by: Andrey Smirnov <[email protected]>
(cherry picked from commit b15917e)
@joeypiccola
Copy link
Author

joeypiccola commented Dec 18, 2024

Some more details, in my Terraform config that I use to build the nodes, I use cloud-init to set the hostname and IP config. When using cloud-init, Proxmox leverages a cloud-init disk. Additionally, I specify an EFI disk (though not required). That said, the nodes have three disks (the one I specify for talos disk-0, cloud-init cloudinit, and efi disk-1). These can be seen below.

image

In an attempt to make progress, I removed the cloud-init config and EFI disk and as I result the nodes started booting successfully. However, as I refined the IaC and after a few rebuilds (all successful one after the next with no changes to the IaC) the nodes began boot looping. At this point I suspect something with my infrastructure 🤷🏻 .

@smira, I noticed you added some debugging. How can I leverage this?

@smira
Copy link
Member

smira commented Dec 18, 2024

I noticed you added some debugging. How can I leverage this?

You can simply pull the console logs. Boot with console=ttyS0 and capture serial console logs, Proxmox should have it readily available.

@joeypiccola
Copy link
Author

Would this be with the latest v1.9.0 release?

@smira
Copy link
Member

smira commented Dec 18, 2024

Would this be with the latest v1.9.0 release?

yes!

@joeypiccola
Copy link
Author

Ok, took a while for the issue to come back. Below is the console output from v1.9.0. Thanks for taking a look.

starting serial terminal on interface serial0 (press Ctrl+O to exit)
[    0.000000] Linux version 6.12.5-talos (@buildkitsandbox) (gcc (GCC) 14.2.0, GNU ld (GNU Binutils) 2.43.1) #1 SMP Mon Dec 16 16:52:31 UTC 2024
[    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz talos.platform=nocloud console=tty0 console=ttyS0 net.ifnames=0 talos.halt_if_installed=1 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha512
[    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-0x00000000bffd9fff] usable
[    0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000033fffffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] APIC: Static calls initialized
[    0.000000] SMBIOS 3.0.0 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.2-0-gea1b7a073390-prebuilt.qemu.org 04/01/2014
[    0.000000] DMI: Memory slots populated: 1/1
[    0.000000] Hypervisor detected: KVM
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000001] kvm-clock: using sched offset of 8609394872 cycles
[    0.000003] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000007] tsc: Detected 2099.998 MHz processor
[    0.001265] last_pfn = 0x340000 max_arch_pfn = 0x400000000
[    0.001299] MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs
[    0.001302] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.001347] last_pfn = 0xbffda max_arch_pfn = 0x400000000
[    0.011998] found SMP MP-table at [mem 0x000f5b70-0x000f5b7f]
[    0.012669] RAMDISK: [mem 0x2e8e9000-0x3346bfff]
[    0.012673] ACPI: Early table checksum verification disabled
[    0.012677] ACPI: RSDP 0x00000000000F5960 000014 (v00 BOCHS )
[    0.012683] ACPI: RSDT 0x00000000BFFE3076 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012691] ACPI: FACP 0x00000000BFFE2E48 000074 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012698] ACPI: DSDT 0x00000000BFFDF040 003E08 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012702] ACPI: FACS 0x00000000BFFDF000 000040
[    0.012707] ACPI: APIC 0x00000000BFFE2EBC 000090 (v03 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012711] ACPI: SSDT 0x00000000BFFE2F4C 0000CA (v01 BOCHS  VMGENID  00000001 BXPC 00000001)
[    0.012715] ACPI: HPET 0x00000000BFFE3016 000038 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012719] ACPI: WAET 0x00000000BFFE304E 000028 (v01 BOCHS  BXPC     00000001 BXPC 00000001)
[    0.012723] ACPI: Reserving FACP table memory at [mem 0xbffe2e48-0xbffe2ebb]
[    0.012724] ACPI: Reserving DSDT table memory at [mem 0xbffdf040-0xbffe2e47]
[    0.012726] ACPI: Reserving FACS table memory at [mem 0xbffdf000-0xbffdf03f]
[    0.012727] ACPI: Reserving APIC table memory at [mem 0xbffe2ebc-0xbffe2f4b]
[    0.012728] ACPI: Reserving SSDT table memory at [mem 0xbffe2f4c-0xbffe3015]
[    0.012728] ACPI: Reserving HPET table memory at [mem 0xbffe3016-0xbffe304d]
[    0.012729] ACPI: Reserving WAET table memory at [mem 0xbffe304e-0xbffe3075]
[    0.013159] No NUMA configuration found
[    0.013160] Faking a node at [mem 0x0000000000000000-0x000000033fffffff]
[    0.013165] NODE_DATA(0) allocated [mem 0x33fff3040-0x33fff7fff]
[    0.013200] Zone ranges:
[    0.013201]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.013203]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.013205]   Normal   [mem 0x0000000100000000-0x000000033fffffff]
[    0.013206]   Device   empty
[    0.013207] Movable zone start for each node
[    0.013208] Early memory node ranges
[    0.013209]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.013210]   node   0: [mem 0x0000000000100000-0x00000000bffd9fff]
[    0.013212]   node   0: [mem 0x0000000100000000-0x000000033fffffff]
[    0.013214] Initmem setup node 0 [mem 0x0000000000001000-0x000000033fffffff]
[    0.013542] On node 0, zone DMA: 1 pages in unavailable ranges
[    0.013562] On node 0, zone DMA: 97 pages in unavailable ranges
[    0.055611] On node 0, zone Normal: 38 pages in unavailable ranges
[    0.057141] ACPI: PM-Timer IO Port: 0x608
[    0.057161] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.057207] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.057211] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.057214] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.057215] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.057217] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.057218] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.057224] ACPI: Using ACPI (MADT) for SMP configuration information
[    0.057226] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.057237] CPU topo: Max. logical packages:   1
[    0.057238] CPU topo: Max. logical dies:       1
[    0.057239] CPU topo: Max. dies per package:   1
[    0.057245] CPU topo: Max. threads per core:   1
[    0.057246] CPU topo: Num. cores per package:     4
[    0.057246] CPU topo: Num. threads per package:   4
[    0.057247] CPU topo: Allowing 4 present CPUs plus 0 hotplug CPUs
[    0.057269] kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write()
[    0.057289] [mem 0xc0000000-0xfeffbfff] available for PCI devices
[    0.057291] Booting paravirtualized kernel on KVM
[    0.057293] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns
[    0.069621] setup_percpu: NR_CPUS:512 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[    0.070180] percpu: Embedded 81 pages/cpu s208528 r8192 d115056 u524288
[    0.070214] Kernel command line: BOOT_IMAGE=/boot/vmlinuz talos.platform=nocloud console=tty0 console=ttyS0 net.ifnames=0 talos.halt_if_installed=1 init_on_alloc=1 slab_nomerge pti=on consoleblank=0 nvme_core.io_timeout=4294967295 printk.devkmsg=on ima_template=ima-ng ima_appraise=fix ima_hash=sha512
[    0.070457] Unknown kernel command line parameters "BOOT_IMAGE=/boot/vmlinuz", will be passed to user space.
[    0.073937] Dentry cache hash table entries: 2097152 (order: 12, 16777216 bytes, linear)
[    0.075608] Inode-cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
[    0.075736] Fallback order for Node 0: 0 
[    0.075740] Built 1 zonelists, mobility grouping on.  Total pages: 3145592
[    0.075741] Policy zone: Normal
[    0.076219] mem auto-init: stack:all(zero), heap alloc:on, heap free:off
[    0.076225] software IO TLB: area num 4.
[    0.169763] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.175306] allocated 25165824 bytes of page_ext
[    0.175349] Kernel/User page tables isolation: enabled
[    0.175442] ftrace: allocating 76225 entries in 298 pages
[    0.190623] ftrace: allocated 298 pages with 4 groups
[    0.191573] rcu: Hierarchical RCU implementation.
[    0.191574] rcu: 	RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4.
[    0.191576] 	Rude variant of Tasks RCU enabled.
[    0.191577] 	Tracing variant of Tasks RCU enabled.
[    0.191577] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.191578] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.191589] RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[    0.191591] RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4.
[    0.199439] NR_IRQS: 33024, nr_irqs: 456, preallocated irqs: 16
[    0.199668] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.199784] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____)
[    0.210164] Console: colour VGA+ 80x25
[    0.210169] printk: legacy console [tty0] enabled
[    0.257712] printk: legacy console [ttyS0] enabled
[    0.390237] ACPI: Core revision 20240827
[    0.392328] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[    0.395193] APIC: Switch to symmetric I/O mode setup
[    0.397069] x2apic enabled
[    0.398456] APIC: Switched APIC routing to: physical x2apic
[    0.401693] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.403679] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x1e452ea631d, max_idle_ns: 440795244572 ns
[    0.406213] Calibrating delay loop (skipped) preset value.. 4199.99 BogoMIPS (lpj=8399992)
[    0.410966] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.412649] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.414602] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
[    0.417296] Spectre V2 : Mitigation: Retpolines
[    0.418543] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[    0.421136] Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT
[    0.422635] Speculative Store Bypass: Vulnerable
[    0.423816] MDS: Vulnerable: Clear CPU buffers attempted, no microcode
[    0.426211] MMIO Stale Data: Unknown: No mitigations
[    0.427515] x86/fpu: x87 FPU will use FXSAVE
[    0.464476] Freeing SMP alternatives memory: 64K
[    0.466001] pid_max: default: 32768 minimum: 301
[    0.467416] LSM: initializing lsm=lockdown,capability,yama,selinux,bpf,ima
[    0.470773] Yama: becoming mindful.
[    0.471975] SELinux:  Initializing.
[    0.475075] LSM support for eBPF active
[    0.476948] Mount-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.478759] Mountpoint-cache hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.591189] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0xf, model: 0x6b, stepping: 0x1)
[    0.594208] Performance Events: unsupported Netburst CPU model 107 no PMU driver, software events only.
[    0.594864] signal: max sigframe size: 1440
[    0.596292] rcu: Hierarchical SRCU implementation.
[    0.598564] rcu: 	Max phase no-delay instances is 1000.
[    0.600278] Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
[    0.603068] smp: Bringing up secondary CPUs ...
[    0.604670] smpboot: x86: Booting SMP configuration:
[    0.606567] .... node  #0, CPUs:      #1 #2 #3
[    0.646306] smp: Brought up 1 node, 4 CPUs
[    0.648985] smpboot: Total of 4 processors activated (16799.98 BogoMIPS)
[    0.651572] Memory: 12113512K/12582368K available (28672K kernel code, 5587K rwdata, 16424K rodata, 5156K init, 1216K bss, 436808K reserved, 0K cma-reserved)
[    0.658288] devtmpfs: initialized
[    0.662284] x86/mm: Memory block size: 128MB
[    0.667183] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.670228] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.673092] PM: RTC time: 05:12:35, date: 2024-12-24
[    0.675756] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.678550] audit: initializing netlink subsys (disabled)
[    0.680281] audit: type=2000 audit(1735017155.767:1): state=initialized audit_enabled=0 res=1
[    0.680281] thermal_sys: Registered thermal governor 'step_wise'
[    0.682222] thermal_sys: Registered thermal governor 'user_space'
[    0.684090] cpuidle: using governor menu
[    0.687776] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.690457] dca service started, version 1.12.1
[    0.691991] PCI: Using configuration type 1 for base access
[    0.694020] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.698580] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
[    0.700293] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
[    0.703703] cryptd: max_cpu_qlen set to 1000
[    0.706470] ACPI: Added _OSI(Module Device)
[    0.707593] ACPI: Added _OSI(Processor Device)
[    0.707593] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.707593] ACPI: Added _OSI(Processor Aggregator Device)
[    0.710274] ACPI: 2 ACPI AML tables successfully acquired and loaded
[    0.715213] ACPI: Interpreter enabled
[    0.715457] ACPI: PM: (supports S0 S3 S5)
[    0.718563] ACPI: Using IOAPIC for interrupt routing
[    0.719793] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.721996] PCI: Using E820 reservations for host bridge windows
[    0.722752] ACPI: Enabled 3 GPEs in block 00 to 0F
[    0.730146] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.730612] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI EDR HPX-Type3]
[    0.732707] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI]
[    0.734871] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge
[    0.738696] acpiphp: Slot [3] registered
[    0.739768] acpiphp: Slot [8] registered
[    0.740813] acpiphp: Slot [18] registered
[    0.742590] acpiphp: Slot [4] registered
[    0.743603] acpiphp: Slot [6] registered
[    0.744653] acpiphp: Slot [7] registered
[    0.746234] acpiphp: Slot [9] registered
[    0.747256] acpiphp: Slot [10] registered
[    0.748457] acpiphp: Slot [11] registered
[    0.749860] acpiphp: Slot [12] registered
[    0.750594] acpiphp: Slot [13] registered
[    0.751672] acpiphp: Slot [14] registered
[    0.752767] acpiphp: Slot [15] registered
[    0.754553] acpiphp: Slot [16] registered
[    0.755621] acpiphp: Slot [17] registered
[    0.756692] acpiphp: Slot [19] registered
[    0.757855] acpiphp: Slot [20] registered
[    0.758547] acpiphp: Slot [21] registered
[    0.759651] acpiphp: Slot [22] registered
[    0.760771] acpiphp: Slot [23] registered
[    0.762560] acpiphp: Slot [24] registered
[    0.763626] acpiphp: Slot [25] registered
[    0.764677] acpiphp: Slot [26] registered
[    0.765770] acpiphp: Slot [27] registered
[    0.766687] acpiphp: Slot [28] registered
[    0.767843] acpiphp: Slot [29] registered
[    0.768937] PCI host bridge to bus 0000:00
[    0.770216] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.771880] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.773643] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    0.774755] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window]
[    0.776673] pci_bus 0000:00: root bus resource [mem 0x340000000-0x3bfffffff window]
[    0.778749] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.780214] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint
[    0.783330] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 conventional PCI endpoint
[    0.786716] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 conventional PCI endpoint
[    0.793013] pci 0000:00:01.1: BAR 4 [io  0xf0c0-0xf0cf]
[    0.795660] pci 0000:00:01.1: BAR 0 [io  0x01f0-0x01f7]: legacy IDE quirk
[    0.797725] pci 0000:00:01.1: BAR 1 [io  0x03f6]: legacy IDE quirk
[    0.798636] pci 0000:00:01.1: BAR 2 [io  0x0170-0x0177]: legacy IDE quirk
[    0.800435] pci 0000:00:01.1: BAR 3 [io  0x0376]: legacy IDE quirk
[    0.802881] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 conventional PCI endpoint
[    0.808063] pci 0000:00:01.2: BAR 4 [io  0xf080-0xf09f]
[    0.811126] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint
[    0.813769] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    0.814789] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.817219] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 conventional PCI endpoint
[    0.822856] pci 0000:00:02.0: BAR 0 [mem 0xfc000000-0xfcffffff pref]
[    0.827833] pci 0000:00:02.0: BAR 2 [mem 0xfea50000-0xfea50fff]
[    0.836755] pci 0000:00:02.0: ROM [mem 0xfea40000-0xfea4ffff pref]
[    0.838820] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[    0.841418] pci 0000:00:03.0: [1af4:1002] type 00 class 0x00ff00 conventional PCI endpoint
[    0.843831] pci 0000:00:03.0: BAR 0 [io  0xf000-0xf03f]
[    0.849850] pci 0000:00:03.0: BAR 4 [mem 0xfd600000-0xfd603fff 64bit pref]
[    0.852810] pci 0000:00:05.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge
[    0.858892] pci 0000:00:05.0: BAR 0 [mem 0xfea51000-0xfea510ff 64bit]
[    0.861938] pci 0000:00:05.0: PCI bridge to [bus 01]
[    0.862611] pci 0000:00:05.0:   bridge window [io  0xe000-0xefff]
[    0.864096] pci 0000:00:05.0:   bridge window [mem 0xfe800000-0xfe9fffff]
[    0.867032] pci 0000:00:05.0:   bridge window [mem 0xfd400000-0xfd5fffff 64bit pref]
[    0.870956] pci 0000:00:08.0: [1af4:1003] type 00 class 0x078000 conventional PCI endpoint
[    0.874213] pci 0000:00:08.0: BAR 0 [io  0xf040-0xf07f]
[    0.877645] pci 0000:00:08.0: BAR 1 [mem 0xfea52000-0xfea52fff]
[    0.887754] pci 0000:00:08.0: BAR 4 [mem 0xfd604000-0xfd607fff 64bit pref]
[    0.896346] pci 0000:00:12.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint
[    0.900074] pci 0000:00:12.0: BAR 0 [io  0xf0a0-0xf0bf]
[    0.903308] pci 0000:00:12.0: BAR 1 [mem 0xfea53000-0xfea53fff]
[    0.909283] pci 0000:00:12.0: BAR 4 [mem 0xfd608000-0xfd60bfff 64bit pref]
[    0.912002] pci 0000:00:12.0: ROM [mem 0xfea00000-0xfea3ffff pref]
[    0.915970] pci 0000:00:1e.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge
[    0.920200] pci 0000:00:1e.0: BAR 0 [mem 0xfea54000-0xfea540ff 64bit]
[    0.923040] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    0.925084] pci 0000:00:1e.0:   bridge window [io  0xd000-0xdfff]
[    0.926642] pci 0000:00:1e.0:   bridge window [mem 0xfe600000-0xfe7fffff]
[    0.929227] pci 0000:00:1e.0:   bridge window [mem 0xfd200000-0xfd3fffff 64bit pref]
[    0.931822] pci 0000:00:1f.0: [1b36:0001] type 01 class 0x060400 conventional PCI bridge
[    0.936238] pci 0000:00:1f.0: BAR 0 [mem 0xfea55000-0xfea550ff 64bit]
[    0.939403] pci 0000:00:1f.0: PCI bridge to [bus 03]
[    0.940704] pci 0000:00:1f.0:   bridge window [io  0xc000-0xcfff]
[    0.942658] pci 0000:00:1f.0:   bridge window [mem 0xfe400000-0xfe5fffff]
[    0.945867] pci 0000:00:1f.0:   bridge window [mem 0xfd000000-0xfd1fffff 64bit pref]
[    0.947836] pci_bus 0000:01: extended config space not accessible
[    0.950683] acpiphp: Slot [1] registered
[    0.951966] acpiphp: Slot [0] registered
[    0.954248] acpiphp: Slot [2] registered
[    0.955677] acpiphp: Slot [3-1] registered
[    0.957042] acpiphp: Slot [4-1] registered
[    0.958250] acpiphp: Slot [5] registered
[    0.959597] acpiphp: Slot [6-1] registered
[    0.960918] acpiphp: Slot [7-1] registered
[    0.962568] acpiphp: Slot [8-1] registered
[    0.963937] acpiphp: Slot [9-1] registered
[    0.965090] acpiphp: Slot [10-1] registered
[    0.966225] acpiphp: Slot [11-1] registered
[    0.967502] acpiphp: Slot [12-1] registered
[    0.968900] acpiphp: Slot [13-1] registered
[    0.970240] acpiphp: Slot [14-1] registered
[    0.971619] acpiphp: Slot [15-1] registered
[    0.973032] acpiphp: Slot [16-1] registered
[    0.974242] acpiphp: Slot [17-1] registered
[    0.975632] acpiphp: Slot [18-1] registered
[    0.977046] acpiphp: Slot [19-1] registered
[    0.978570] acpiphp: Slot [20-1] registered
[    0.979957] acpiphp: Slot [21-1] registered
[    0.981341] acpiphp: Slot [22-1] registered
[    0.982572] acpiphp: Slot [23-1] registered
[    0.983858] acpiphp: Slot [24-1] registered
[    0.985145] acpiphp: Slot [25-1] registered
[    0.986239] acpiphp: Slot [26-1] registered
[    0.987505] acpiphp: Slot [27-1] registered
[    0.988755] acpiphp: Slot [28-1] registered
[    0.990253] acpiphp: Slot [29-1] registered
[    0.991623] acpiphp: Slot [30] registered
[    0.992867] acpiphp: Slot [31] registered
[    0.994367] pci 0000:01:01.0: [1af4:1004] type 00 class 0x010000 conventional PCI endpoint
[    0.998216] pci 0000:01:01.0: BAR 0 [io  0xe000-0xe03f]
[    1.001458] pci 0000:01:01.0: BAR 1 [mem 0xfe800000-0xfe800fff]
[    1.008526] pci 0000:01:01.0: BAR 4 [mem 0xfd400000-0xfd403fff 64bit pref]
[    1.014318] pci 0000:00:05.0: PCI bridge to [bus 01]
[    1.017319] pci_bus 0000:02: extended config space not accessible
[    1.022299] acpiphp: Slot [0-1] registered
[    1.023624] acpiphp: Slot [1-1] registered
[    1.026622] acpiphp: Slot [2-1] registered
[    1.027975] acpiphp: Slot [3-2] registered
[    1.029447] acpiphp: Slot [4-2] registered
[    1.030623] acpiphp: Slot [5-1] registered
[    1.032006] acpiphp: Slot [6-2] registered
[    1.033417] acpiphp: Slot [7-2] registered
[    1.034598] acpiphp: Slot [8-2] registered
[    1.035907] acpiphp: Slot [9-2] registered
[    1.038249] acpiphp: Slot [10-2] registered
[    1.039618] acpiphp: Slot [11-2] registered
[    1.041037] acpiphp: Slot [12-2] registered
[    1.042260] acpiphp: Slot [13-2] registered
[    1.043741] acpiphp: Slot [14-2] registered
[    1.045154] acpiphp: Slot [15-2] registered
[    1.046567] acpiphp: Slot [16-2] registered
[    1.048024] acpiphp: Slot [17-2] registered
[    1.049428] acpiphp: Slot [18-2] registered
[    1.050588] acpiphp: Slot [19-2] registered
[    1.051953] acpiphp: Slot [20-2] registered
[    1.053270] acpiphp: Slot [21-2] registered
[    1.054582] acpiphp: Slot [22-2] registered
[    1.055873] acpiphp: Slot [23-2] registered
[    1.057138] acpiphp: Slot [24-2] registered
[    1.058606] acpiphp: Slot [25-2] registered
[    1.059875] acpiphp: Slot [26-2] registered
[    1.061017] acpiphp: Slot [27-2] registered
[    1.062246] acpiphp: Slot [28-2] registered
[    1.063475] acpiphp: Slot [29-2] registered
[    1.064607] acpiphp: Slot [30-1] registered
[    1.065737] acpiphp: Slot [31-1] registered
[    1.066875] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    1.070702] pci_bus 0000:03: extended config space not accessible
[    1.072502] acpiphp: Slot [0-2] registered
[    1.074255] acpiphp: Slot [1-2] registered
[    1.075547] acpiphp: Slot [2-2] registered
[    1.076725] acpiphp: Slot [3-3] registered
[    1.077953] acpiphp: Slot [4-3] registered
[    1.078586] acpiphp: Slot [5-2] registered
[    1.079775] acpiphp: Slot [6-3] registered
[    1.080950] acpiphp: Slot [7-3] registered
[    1.082570] acpiphp: Slot [8-3] registered
[    1.083686] acpiphp: Slot [9-3] registered
[    1.084769] acpiphp: Slot [10-3] registered
[    1.085913] acpiphp: Slot [11-3] registered
[    1.086573] acpiphp: Slot [12-3] registered
[    1.087742] acpiphp: Slot [13-3] registered
[    1.089113] acpiphp: Slot [14-3] registered
[    1.090595] acpiphp: Slot [15-3] registered
[    1.091873] acpiphp: Slot [16-3] registered
[    1.093292] acpiphp: Slot [17-3] registered
[    1.094573] acpiphp: Slot [18-3] registered
[    1.095721] acpiphp: Slot [19-3] registered
[    1.097043] acpiphp: Slot [20-3] registered
[    1.098570] acpiphp: Slot [21-3] registered
[    1.099967] acpiphp: Slot [22-3] registered
[    1.101352] acpiphp: Slot [23-3] registered
[    1.102565] acpiphp: Slot [24-3] registered
[    1.103933] acpiphp: Slot [25-3] registered
[    1.105308] acpiphp: Slot [26-3] registered
[    1.106576] acpiphp: Slot [27-3] registered
[    1.107977] acpiphp: Slot [28-3] registered
[    1.110237] acpiphp: Slot [29-3] registered
[    1.111646] acpiphp: Slot [30-2] registered
[    1.113018] acpiphp: Slot [31-2] registered
[    1.114500] pci 0000:00:1f.0: PCI bridge to [bus 03]
[    1.118765] ACPI: PCI: Interrupt link LNKA configured for IRQ 10
[    1.120775] ACPI: PCI: Interrupt link LNKB configured for IRQ 10
[    1.122734] ACPI: PCI: Interrupt link LNKC configured for IRQ 11
[    1.124335] ACPI: PCI: Interrupt link LNKD configured for IRQ 11
[    1.126225] ACPI: PCI: Interrupt link LNKS configured for IRQ 9
[    1.128612] iommu: Default domain type: Translated
[    1.128612] iommu: DMA domain TLB invalidation policy: lazy mode
[    1.130917] SCSI subsystem initialized
[    1.131956] ACPI: bus type USB registered
[    1.134241] usbcore: registered new interface driver usbfs
[    1.135837] usbcore: registered new interface driver hub
[    1.137161] usbcore: registered new device driver usb
[    1.138646] pps_core: LinuxPPS API ver. 1 registered
[    1.139939] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[    1.142827] PTP clock support registered
[    1.144150] EDAC MC: Ver: 3.0.0
[    1.146347] NET: Registered PF_ATMPVC protocol family
[    1.147870] NET: Registered PF_ATMSVC protocol family
[    1.149412] NetLabel: Initializing
[    1.150504] NetLabel:  domain hash size = 128
[    1.151798] NetLabel:  protocols = UNLABELED CIPSOv4 CALIPSO
[    1.153477] NetLabel:  unlabeled traffic allowed by default
[    1.154608] PCI: Using ACPI for IRQ routing
[    1.156193] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    1.156193] pci 0000:00:02.0: vgaarb: bridge control possible
[    1.157769] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[    1.158217] vgaarb: loaded
[    1.159325] hpet: 3 channels of 0 reserved for per-cpu timers
[    1.160977] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    1.162596] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    1.172430] clocksource: Switched to clocksource kvm-clock
[    1.174058] VFS: Disk quotas dquot_6.6.0
[    1.174129] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    1.176039] pnp: PnP ACPI init
[    1.177354] pnp: PnP ACPI: found 5 devices
[    1.187663] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[    1.190024] NET: Registered PF_INET protocol family
[    1.191811] IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    1.220022] tcp_listen_portaddr_hash hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    1.222599] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    1.225340] TCP established hash table entries: 131072 (order: 8, 1048576 bytes, linear)
[    1.228844] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
[    1.231690] TCP: Hash tables configured (established 131072 bind 65536)
[    1.233769] UDP hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    1.235686] UDP-Lite hash table entries: 8192 (order: 6, 262144 bytes, linear)
[    1.237966] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    1.240430] RPC: Registered named UNIX socket transport module.
[    1.242139] RPC: Registered udp transport module.
[    1.243586] RPC: Registered tcp transport module.
[    1.245041] RPC: Registered tcp-with-tls transport module.
[    1.246665] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    1.248614] NET: Registered PF_XDP protocol family
[    1.250119] pci 0000:00:05.0: PCI bridge to [bus 01]
[    1.251645] pci 0000:00:05.0:   bridge window [io  0xe000-0xefff]
[    1.255010] pci 0000:00:05.0:   bridge window [mem 0xfe800000-0xfe9fffff]
[    1.257975] pci 0000:00:05.0:   bridge window [mem 0xfd400000-0xfd5fffff 64bit pref]
[    1.261674] pci 0000:00:1e.0: PCI bridge to [bus 02]
[    1.263091] pci 0000:00:1e.0:   bridge window [io  0xd000-0xdfff]
[    1.266219] pci 0000:00:1e.0:   bridge window [mem 0xfe600000-0xfe7fffff]
[    1.269051] pci 0000:00:1e.0:   bridge window [mem 0xfd200000-0xfd3fffff 64bit pref]
[    1.272932] pci 0000:00:1f.0: PCI bridge to [bus 03]
[    1.274740] pci 0000:00:1f.0:   bridge window [io  0xc000-0xcfff]
[    1.277070] pci 0000:00:1f.0:   bridge window [mem 0xfe400000-0xfe5fffff]
[    1.279432] pci 0000:00:1f.0:   bridge window [mem 0xfd000000-0xfd1fffff 64bit pref]
[    1.282327] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    1.283889] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    1.286029] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    1.288148] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window]
[    1.290107] pci_bus 0000:00: resource 8 [mem 0x340000000-0x3bfffffff window]
[    1.292042] pci_bus 0000:01: resource 0 [io  0xe000-0xefff]
[    1.293739] pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff]
[    1.295618] pci_bus 0000:01: resource 2 [mem 0xfd400000-0xfd5fffff 64bit pref]
[    1.297847] pci_bus 0000:02: resource 0 [io  0xd000-0xdfff]
[    1.299573] pci_bus 0000:02: resource 1 [mem 0xfe600000-0xfe7fffff]
[    1.301427] pci_bus 0000:02: resource 2 [mem 0xfd200000-0xfd3fffff 64bit pref]
[    1.303705] pci_bus 0000:03: resource 0 [io  0xc000-0xcfff]
[    1.305388] pci_bus 0000:03: resource 1 [mem 0xfe400000-0xfe5fffff]
[    1.307276] pci_bus 0000:03: resource 2 [mem 0xfd000000-0xfd1fffff 64bit pref]
[    1.309594] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    1.312048] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    1.328599] ACPI: \_SB_.LNKD: Enabled at IRQ 11
[    1.344122] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x690 took 29546 usecs
[    1.346512] PCI: CLS 0 bytes, default 64
[    1.347851] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    1.347990] Unpacking initramfs...
[    1.349273] software IO TLB: mapped [mem 0x00000000bbfda000-0x00000000bffda000] (64MB)
[    1.352827] kvm_intel: VMX not supported by CPU 3
[    1.354251] kvm_amd: CPU 3 isn't AMD or Hygon
[    1.355643] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x1e452ea631d, max_idle_ns: 440795244572 ns
[    1.360441] Initialise system trusted keyrings
[    1.361931] Key type blacklist registered
[    1.363342] workingset: timestamp_bits=40 max_order=22 bucket_order=0
[    1.368048] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.370352] NFS: Registering the id_resolver key type
[    1.371917] Key type id_resolver registered
[    1.377857] Key type id_legacy registered
[    1.379156] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.381103] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.384523] Key type cifs.spnego registered
[    1.385798] Key type cifs.idmap registered
[    1.387214] fuse: init (API version 7.41)
[    1.388799] SGI XFS with ACLs, security attributes, scrub, repair, quota, no debug enabled
[    1.393693] ceph: loaded (mds proto 32)
[    1.395640] integrity: Platform Keyring initialized
[    1.412107] NET: Registered PF_ALG protocol family
[    1.413525] Key type asymmetric registered
[    1.414735] Asymmetric key parser 'x509' registered
[    1.416187] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[    1.418411] io scheduler mq-deadline registered
[    1.419709] io scheduler kyber registered
[    1.420947] io scheduler bfq registered
[    1.422797] hv_vmbus: registering driver hyperv_fb
[    1.424170] IPMI message handler: version 39.2
[    1.425442] ipmi device interface
[    1.426714] ipmi_si: IPMI System Interface driver
[    1.428246] ipmi_si: Unable to find any System Interface(s)
[    1.429858] IPMI poweroff: Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot
[    1.432872] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    1.435399] ACPI: button: Power Button [PWRF]
[    1.437335] ioatdma: Intel(R) QuickData Technology Driver 5.00
[    1.439744] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    1.441871] 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    1.444805] Non-volatile memory driver v1.3
[    1.446123] Linux agpgart interface v0.103
[    1.447579] ACPI: bus type drm_connector registered
[    1.459907] Freeing initrd memory: 77324K
[    1.463673] loop: module loaded
[    1.464849] rbd: loaded (major 252)
[    1.465819] Guest personality initialized and is inactive
[    1.467232] VMCI host device registered (name=vmci, major=10, minor=126)
[    1.468903] Initialized host personality
[    1.470069] Loading iSCSI transport class v2.0-870.
[    1.471653] iscsi: registered transport (tcp)
[    1.472821] hv_vmbus: registering driver hv_storvsc
[    1.480127] scsi host0: ata_piix
[    1.481616] scsi host1: ata_piix
[    1.482862] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xf0c0 irq 14 lpm-pol 0
[    1.485250] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xf0c8 irq 15 lpm-pol 0
[    1.488597] wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information.
[    1.491023] wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld <[email protected]>. All Rights Reserved.
[    1.493802] tun: Universal TUN/TAP device driver, 1.6
[    1.495347] hv_vmbus: registering driver hv_netvsc
[    1.497511] usbcore: registered new interface driver cdc_acm
[    1.499991] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[    1.503604] usbcore: registered new interface driver uas
[    1.505452] usbcore: registered new interface driver usb-storage
[    1.508387] usbcore: registered new interface driver usbserial_generic
[    1.511145] usbserial: USB Serial support registered for generic
[    1.513736] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    1.518156] serio: i8042 KBD port at 0x60,0x64 irq 1
[    1.520200] serio: i8042 AUX port at 0x60,0x64 irq 12
[    1.522422] hv_vmbus: registering driver hyperv_keyboard
[    1.524673] mousedev: PS/2 mouse device common for all mice
[    1.526616] rtc_cmos 00:04: RTC can wake from S4
[    1.528774] rtc_cmos 00:04: registered as rtc0
[    1.529018] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    1.529938] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram, hpet irqs
[    1.534990] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
[    1.538122] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: [email protected]
[    1.542078] intel_pstate: CPU model not supported
[    1.544321] sdhci: Secure Digital Host Controller Interface driver
[    1.546399] sdhci: Copyright(c) Pierre Ossman
[    1.548274] hid: raw HID events driver (C) Jiri Kosina
[    1.550119] usbcore: registered new interface driver usbhid
[    1.551999] usbhid: USB HID core driver
[    1.553826] hv_utils: Registering HyperV Utility Driver
[    1.555625] hv_vmbus: registering driver hv_utils
[    1.557232] hv_vmbus: registering driver hv_balloon
[    1.558997] NET: Registered PF_LLC protocol family
[    1.560692] GACT probability NOT on
[    1.561936] Mirror/redirect action on
[    1.563321] Simple TC action Loaded
[    1.564783] netem: version 1.3
[    1.566010] u32 classifier
[    1.567088]     input device check on
[    1.568404]     Actions configured
[    1.571686] xt_time: kernel timezone is -0000
[    1.573254] IPVS: Registered protocols (TCP, UDP)
[    1.574936] IPVS: Connection hash table configured (size=4096, memory=32Kbytes)
[    1.577590] IPVS: ipvs loaded.
[    1.578749] IPVS: [rr] scheduler registered.
[    1.580313] IPVS: [wrr] scheduler registered.
[    1.581853] IPVS: [lc] scheduler registered.
[    1.583360] IPVS: [sh] scheduler registered.
[    1.584963] ipip: IPv4 and MPLS over IPv4 tunneling driver
[    1.587022] gre: GRE over IPv4 demultiplexor driver
[    1.588775] Initializing XFRM netlink socket
[    1.590816] NET: Registered PF_INET6 protocol family
[    1.593577] Segment Routing with IPv6
[    1.594956] In-situ OAM (IOAM) with IPv6
[    1.596523] mip6: Mobile IPv6
[    1.597759] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.600147] NET: Registered PF_PACKET protocol family
[    1.602001] Bridge firewalling registered
[    1.603644] NET: Registered PF_X25 protocol family
[    1.605329] X25: Linux Version 0.2
[    1.606665] RPC: Registered rdma transport module.
[    1.608947] RPC: Registered rdma backchannel transport module.
[    1.611051] l2tp_core: L2TP core driver, V2.0
[    1.612637] NET: Registered PF_PHONET protocol family
[    1.613961] 8021q: 802.1Q VLAN Support v1.8
[    1.618607] DCCP: Activated CCID 2 (TCP-like)
[    1.619799] DCCP: Activated CCID 3 (TCP-Friendly Rate Control)
[    1.621332] DCCP is deprecated and scheduled to be removed in 2025, please contact the netdev mailing list
[    1.623918] sctp: Hash tables configured (bind 256/256)
[    1.625905] NET: Registered PF_RDS protocol family
[    1.627180] Key type dns_resolver registered
[    1.628332] Key type ceph registered
[    1.629519] libceph: loaded (mon/osd proto 15/24)
[    1.630760] openvswitch: Open vSwitch switching datapath
[    1.632611] NET: Registered PF_VSOCK protocol family
[    1.634256] mpls_gso: MPLS GSO support
[    1.635980] IPI shorthand broadcast: enabled
[    1.642123] sched_clock: Marking stable (1440003582, 198532546)->(1646768917, -8232789)
[    1.644673] registered taskstats version 1
[    1.646096] Loading compiled-in X.509 certificates
[    1.648514] Loaded X.509 cert 'Sidero Labs, Inc.: Build time throw-away kernel key: 386b66cf94a8c8b58c9317af3abd284234026ea8'
[    1.658359] Demotion targets for Node 0: null
[    1.659794] ima: No TPM chip found, activating TPM-bypass!
[    1.661372] ima: Allocated hash algorithm: sha512
[    1.662654] ima: No architecture policies found
[    1.664702] PM:   Magic number: 4:537:214
[    1.665965] acpi device:65: hash matches
[    1.667164] printk: legacy console [netcon0] enabled
[    1.668645] netconsole: network logging started
[    1.690151] clk: Disabling unused clocks
[    1.694250] Freeing unused kernel image (initmem) memory: 5156K
[    1.695780] Write protecting the kernel read-only data: 47104k
[    1.697787] Freeing unused kernel image (rodata/data gap) memory: 2008K
[    1.702182] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.703571] x86/mm: Checking user space page tables
[    1.704983] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[    1.706328] Run /init as init process
[    1.712147] [talos] [initramfs] TPM device is not available
[    1.713304] [talos] [initramfs] TPM device is not available, skipping PCR extension
[    1.714902] [talos] [initramfs] booting Talos v1.9.0
[    1.715928] [talos] [initramfs] CPU: QEMU Virtual CPU version 2.5+, 4 core(s), 1 thread(s) per core
[    1.717746] [talos] [initramfs] x86_64 microarchitecture level: 2
[    1.718980] [talos] [initramfs] mounting the rootfs
[    1.720299] [talos] [initramfs] enabling system extension schematic e88202574e1c65f86d35d171ab7f08eddbe6c8e78cb45b9cb0470d6b0c394876
[    1.722596] [talos] [initramfs] enabling system extension qemu-guest-agent 9.1.2
[    1.724108] [talos] [initramfs] enabling system extension iscsi-tools v0.1.6
[    1.725640] loop0: detected capacity change from 0 to 8
[    1.750912] loop1: detected capacity change from 0 to 1336
[    1.766772] loop2: detected capacity change from 0 to 5232
[    1.786777] loop3: detected capacity change from 0 to 143920
[    1.815174] [talos] [initramfs] entering the rootfs
[    1.816381] [talos] [initramfs] moving mounts to the new rootfs
[    1.817972] [talos] [initramfs] changing working directory into /root
[    1.819335] [talos] [initramfs] moving /root to /
[    1.820303] [talos] [initramfs] changing root directory
[    1.825922] [talos] [initramfs] cleaning up initramfs
[    1.827807] [talos] [initramfs] selinux: disabled, not loading policy
[    1.829157] [talos] [initramfs] TPM device is not available, skipping PCR extension
[    1.830734] [talos] [initramfs] executing /sbin/init
[    1.956263] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    3.034617] random: crng init done
[    3.198401] [talos] machined logger initialized
[    3.200327] [talos] platform information {"component": "early-startup", "mode": "cloud"}
[    3.202900] audit: type=1807 audit(1735017158.291:2): action=dont_measure fsmagic=0x9fa0 res=1
[    3.203039] ima: policy update completed
[    3.204111] audit: type=1807 audit(1735017158.291:3): action=dont_measure fsmagic=0x62656572 res=1
[    3.207328] audit: type=1807 audit(1735017158.291:4): action=dont_measure fsmagic=0x64626720 res=1
[    3.209133] audit: type=1807 audit(1735017158.291:5): action=dont_measure fsmagic=0x1021994 res=1
[    3.210892] audit: type=1807 audit(1735017158.291:6): action=dont_measure fsmagic=0x1cd1 res=1
[    3.212648] audit: type=1807 audit(1735017158.291:7): action=dont_measure fsmagic=0x42494e4d res=1
[    3.214409] audit: type=1807 audit(1735017158.291:8): action=dont_measure fsmagic=0x73636673 res=1
[    3.216229] audit: type=1807 audit(1735017158.291:9): action=dont_measure fsmagic=0xf97cff8c res=1
[    3.217952] audit: type=1807 audit(1735017158.291:10): action=dont_measure fsmagic=0x43415d53 res=1
[    3.226762] [talos] initializing cgroups {"component": "early-startup", "root": "/"}
[    3.249105] [talos] early startup done {"component": "early-startup", "duration": "48.830058ms"}
[    3.252029] [talos] initialize sequence: 10 phase(s)
[    3.253084] [talos] phase systemRequirements (1/10): 1 tasks(s)
[    3.259206] [talos] task enforceKSPPRequirements (1/1): starting
[    3.266895] [talos] pre-created iptables-nft table 'mangle'/'KUBE-IPTABLES-HINT' {"component": "controller-runtime", "controller": "network.NfTablesChainController"}
[    3.274642] [talos] nftables chains updated {"component": "controller-runtime", "controller": "network.NfTablesChainController", "chains": []}
[    3.277111] [talos] setting time servers {"component": "controller-runtime", "controller": "network.TimeServerSpecController", "addresses": ["time.cloudflare.com"]}
[    3.280154] [talos] setting time servers {"component": "controller-runtime", "controller": "network.TimeServerSpecController", "addresses": ["time.cloudflare.com"]}
[    3.285239] [talos] waiting for devices to be ready...
[    3.290519] [talos] assigned address {"component": "controller-runtime", "controller": "network.AddressSpecController", "address": "127.0.0.1/8", "link": "lo"}
[    3.293369] [talos] service[ext-iscsid](Starting): Starting service
[    3.295058] [talos] service[ext-iscsid](Waiting): Waiting for service "containerd" to be "up", service "cri" to be "up", service "ext-tgtd" to be "up", network
[    3.301952] [talos] service[ext-qemu-guest-agent](Starting): Starting service
[    3.303537] [talos] service[ext-qemu-guest-agent](Waiting): Waiting for service "containerd" to be "up", file "/system/run/machined/machine.sock" to exist, file "/dev/virtio-ports/org.qemu.guest_agent.0" to exist
[    3.307110] [talos] setting resolvers {"component": "controller-runtime", "controller": "network.ResolverSpecController", "resolvers": ["1.1.1.1", "8.8.8.8"], "searchDomains": []}
[    3.310200] [talos] task enforceKSPPRequirements (1/1): done, 46.568547ms
[    3.311587] [talos] service[ext-tgtd](Starting): Starting service
[    3.312783] [talos] setting resolvers {"component": "controller-runtime", "controller": "network.ResolverSpecController", "resolvers": ["1.1.1.1", "8.8.8.8"], "searchDomains": []}
[    3.315792] [talos] phase systemRequirements (1/10): done, 58.594853ms
[    3.317060] [talos] service[ext-tgtd](Waiting): Waiting for service "containerd" to be "up", service "cri" to be "up", network
[    3.319712] [talos] phase earlyServices (2/10): 5 tasks(s)
[    3.321085] [talos] task startContainerd (5/5): starting
[    3.322430] [talos] service[containerd](Starting): Starting service
[    3.324004] [talos] task startUdevd (1/5): starting
[    3.325220] [talos] task startAuditd (3/5): starting
[    3.326460] [talos] task startMachined (2/5): starting
[    3.327755] [talos] task startSyslogd (4/5): starting
[    3.329038] [talos] service[containerd](Preparing): Running pre state
[    3.330640] [talos] service[syslogd](Starting): Starting service
[    3.332117] [talos] service[syslogd](Waiting): Waiting for service "machined" to be "up"
[    3.334011] [talos] service[containerd](Preparing): Creating service runner
[    3.335590] [talos] TPM device is not available, skipping PCR extension
[    3.336885] [talos] task startSyslogd (4/5): done, 11.065974ms
[    3.338070] [talos] service[auditd](Starting): Starting service
[    3.339278] [talos] service[auditd](Preparing): Running pre state
[    3.340520] [talos] service[auditd](Preparing): Creating service runner
[    3.341815] [talos] task startAuditd (3/5): done, 18.196208ms
[    3.342969] [talos] service[udevd](Starting): Starting service
[    3.344113] [talos] service[auditd](Running): Service started as goroutine
[    3.345533] [talos] service[udevd](Preparing): Running pre state
[    3.347001] [talos] service[machined](Starting): Starting service
[    3.348711] [talos] service[machined](Preparing): Running pre state
[    3.350417] [talos] service[machined](Preparing): Creating service runner
[    3.352308] [talos] service[machined](Running): Service started as goroutine
[    3.366041] [talos] service[udevd](Preparing): Creating service runner
[    3.368190] [talos] service[containerd](Running): Process Process(["/bin/containerd" "--address" "/system/run/containerd/containerd.sock" "--state" "/system/run/containerd" "--root" "/system/var/lib/containerd"]) started with PID 1135
[    3.372086] [talos] service[udevd](Running): Process Process(["/sbin/systemd-udevd" "--resolve-names=never"]) started with PID 1139
[    3.739501] ACPI: \_SB_.LNKC: Enabled at IRQ 10
[    3.794272] ACPI: \_SB_.LNKB: Enabled at IRQ 10
[    3.812503] ACPI: \_SB_.LNKA: Enabled at IRQ 11
[    3.830404] virtio_scsi virtio3: 4/0/0 default/read/poll queues
[    3.833318] scsi host2: Virtio SCSI HBA
[    3.835344] scsi 2:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[    3.837887] sd 2:0:0:0: Attached scsi generic sg0 type 0
[    3.837908] sd 2:0:0:0: Power-on or device reset occurred
[    3.840872] sd 2:0:0:0: [sda] 104857600 512-byte logical blocks: (53.7 GB/50.0 GiB)
[    3.842853] sd 2:0:0:0: [sda] Write Protect is off
[    3.844131] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    4.300322] [talos] service[ext-iscsid](Waiting): Waiting for service "containerd" to be "up", service "cri" to be registered, service "ext-tgtd" to be "up", network
[    4.320018] [talos] service[ext-tgtd](Waiting): Waiting for service "containerd" to be "up", service "cri" to be registered, network
[    4.338092] [talos] service[containerd](Running): Health check successful
[    4.339634] [talos] task startContainerd (5/5): done, 1.018542674s
[    4.342435] [talos] service[auditd](Running): Health check successful
[    4.354973] [talos] service[machined](Running): Health check successful
[    4.356894] [talos] service[syslogd](Preparing): Running pre state
[    4.358312] [talos] service[syslogd](Preparing): Creating service runner
[    4.359478] GPT:Primary header thinks Alt. header is not at the end of the disk.
[    4.359676] [talos] task startMachined (2/5): done, 1.035798989s
[    4.360796] GPT:210815 != 104857599
[    4.361605] [talos] service[syslogd](Running): Service started as goroutine
[    4.362106] GPT:Alternate GPT header not at the end of the disk.
[    4.366056] GPT:210815 != 104857599
[    4.366998] GPT: Use GNU Parted to correct GPT errors.
[    4.368178]  sda: sda1 sda2 sda3 sda4
[    4.369592] sd 2:0:0:0: [sda] Attached SCSI disk
[    4.947300] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "META", "phase": "waiting -> ready", "location": "/dev/sda4", "parentLocation": "/dev/sda"}
[    5.052837] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "STATE", "phase": "waiting -> failed", "error": "error probing disk: open /dev/sda5: no such file or directory", "location": "/dev/sda5", "parentLocation": "/dev/sda"}
[    5.159960] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "STATE", "phase": "failed -> failed", "error": "error probing disk: open /dev/sda5: no such file or directory", "location": "/dev/sda5", "parentLocation": "/dev/sda"}
[    5.300252] [talos] service[ext-iscsid](Waiting): Waiting for service "cri" to be registered, service "ext-tgtd" to be "up", network
[    5.307800] [talos] service[ext-qemu-guest-agent](Waiting): Waiting for file "/dev/virtio-ports/org.qemu.guest_agent.0" to exist
[    5.319860] [talos] service[ext-tgtd](Waiting): Waiting for service "cri" to be registered, network
[    5.360881] [talos] service[syslogd](Running): Health check successful
[   11.301525] Free page reporting enabled
[   11.307772] [talos] service[ext-qemu-guest-agent](Preparing): Running pre state
[   11.310301] [talos] service[ext-qemu-guest-agent](Preparing): Creating service runner
[   11.370852] [talos] service[udevd](Running): Health check successful
[   11.372667] [talos] task startUdevd (1/5): done, 8.051553202s
[   11.374320] [talos] phase earlyServices (2/10): done, 8.057191551s
[   11.376360] [talos] waiting for devices to be ready...
[   11.378661] [talos] phase usb (3/10): 1 tasks(s)
[   11.381041] [talos] task waitForUSB (1/1): starting
[   11.382447] [talos] task waitForUSB (1/1): waiting 1 second(s) for USB storage
[   11.387141] 8021q: adding VLAN 0 to HW filter on device eth0
[   11.418935] [talos] setting resolvers {"component": "controller-runtime", "controller": "network.ResolverSpecController", "resolvers": ["10.0.3.24", "10.0.3.22"], "searchDomains": []}
[   11.424399] [talos] assigned address {"component": "controller-runtime", "controller": "network.AddressSpecController", "address": "10.0.5.201/24", "link": "eth0"}
[   11.476179] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "STATE", "phase": "failed -> failed", "error": "error probing disk: open /dev/sda5: no such file or directory", "location": "/dev/sda5", "parentLocation": "/dev/sda"}
[   11.593598] [talos] volume status {"component": "controller-runtime", "controller": "block.VolumeManagerController", "volume": "STATE", "phase": "failed -> failed", "error": "error probing disk: open /dev/sda5: no such file or directory", "location": "/dev/sda5", "parentLocation": "/dev/sda"}
[   11.609717] [talos] service[ext-qemu-guest-agent](Running): Started task ext-qemu-guest-agent (PID 2135) for container ext-qemu-guest-agent
[   11.993217] [talos] created route {"component": "controller-runtime", "controller": "network.RouteSpecController", "destination": "default", "gateway": "10.0.5.1", "table": "main", "link": "eth0", "priority": 1024, "family": "inet4"}
[   12.386074] [talos] task waitForUSB (1/1): done, 1.005027964s
[   12.387719] [talos] phase usb (3/10): done, 1.009741881s
[   12.389248] [talos] phase meta (4/10): 1 tasks(s)
[   12.390711] [talos] task reloadMeta (1/1): starting
[   12.398878] [talos] adjusting time (slew) by 207.628061ms via 162.159.200.123, state TIME_OK, status STA_PLL | STA_NANO {"component": "controller-runtime", "controller": "time.SyncController"}
[   12.493461] [talos] META: loading from /dev/sda4
[   12.496186] [talos] META: failed to load: failed to load Talos adv: failed to load second block: unexpected EOF
[   12.498849] [talos] task reloadMeta (1/1): failed: failed to load Talos adv: failed to load second block: unexpected EOF
[   12.501474] [talos] phase meta (4/10): failed
[   12.502801] [talos] initialize sequence: failed
[   12.504296] [talos] service[ext-qemu-guest-agent](Stopping): Sending SIGTERM to task ext-qemu-guest-agent (PID 2135, container ext-qemu-guest-agent)
[   12.504570] kauditd_printk_skb: 30 callbacks suppressed
[   12.504574] audit: type=1305 audit(1735017167.591:47): op=set audit_enabled=0 old=1 auid=4294967295 ses=4294967295 subj=kernel res=1
[   12.507688] [talos] service[syslogd](Stopping): Service stopping
[   12.507846] audit: type=1300 audit(1735017167.591:47): arch=c000003e syscall=44 success=yes exit=60 a0=17 a1=c00064b3c0 a2=3c a3=0 items=0 ppid=0 pid=1 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="init" exe="/sbin/init" subj=kernel key=(null)
[   12.509834] [talos] service[syslogd](Finished): Service finished successfully
[   12.511459] audit: type=1327 audit(1735017167.591:47): proctitle="/sbin/init"
[   12.515722] [talos] service[udevd](Stopping): Sending SIGTERM to Process(["/sbin/systemd-udevd" "--resolve-names=never"])
[   12.528477] [talos] service[auditd](Stopping): Service stopping
[   12.530414] [talos] service[ext-iscsid](Failed): Condition failed: context canceled
[   12.533072] [talos] service[machined](Stopping): Service stopping
[   12.534970] [talos] service[auditd](Finished): Service finished successfully
[   12.537177] [talos] service[ext-tgtd](Failed): Condition failed: context canceled
[   12.539964] [talos] service[machined](Finished): Service finished successfully
[   12.571143] [talos] service[udevd](Finished): Service finished successfully
[   12.668851] [talos] service[ext-qemu-guest-agent](Finished): Service finished successfully
[   12.671593] [talos] service[containerd](Stopping): Sending SIGTERM to Process(["/bin/containerd" "--address" "/system/run/containerd/containerd.sock" "--state" "/system/run/containerd" "--root" "/system/var/lib/containerd"])
[   12.680047] [talos] service[containerd](Finished): Service finished successfully
[   12.684202] [talos] manager finished {"component": "dns-resolve-cache", "error": "context canceled"}
[   12.687088] [talos] controller runtime finished
[   12.690553] [talos] error running phase 4 in initialize sequence: task 1/1: failed, failed to load Talos adv: failed to load second block: unexpected EOF
[   12.796396] [talos] META: loading from /dev/sda4
[   12.801124] [talos] failed to revert bootloader: failed to load Talos adv: failed to load second block: unexpected EOF
[   12.804316] [talos] rebooting in 10 seconds
[   13.783973] [talos] rebooting in 9 seconds
[   14.763776] [talos] rebooting in 8 seconds
[   15.746978] [talos] rebooting in 7 seconds
[   16.731975] [talos] rebooting in 6 seconds
[   17.718937] [talos] rebooting in 5 seconds
[   18.708662] [talos] rebooting in 4 seconds
[   19.699191] [talos] rebooting in 3 seconds

@smira
Copy link
Member

smira commented Dec 24, 2024

Thanks for capturing this... My guess is that sda is not wiped and it contains something very strange, the primary and backup GPT headers don't agree at all.

So if you keep trying to install Talos on non-empty disks, and disks wiped in a bad way, too many fun things might happen.

I'll take a look if we can mitigate that, but correctly wiping the disk on your side would be a solution.

@smira
Copy link
Member

smira commented Dec 24, 2024

So from the log it's clear that GPT contains a smaller-than-expected GPT, but Talos (I need to verify that) falls back to a backup GPT (at the end of the disk), and that backup GPT is from Talos itself. And then there's a disagreement between a kernel probed partition structure and what Talos assumes is a partition structure.

@smira
Copy link
Member

smira commented Dec 24, 2024

I kept trying to reproducing this with any kind of broken GPT, but I still can't. If you have a chance to dump fully this sda disk when it's broken, that would help a lot.

smira added a commit to smira/go-blockdevice that referenced this issue Dec 24, 2024
1. Push GPT discovery last in the chain - if the GPT image is overridden
   with a smaller filesystem image, it might detect GPT before actual
   filesystem.

2. Fix GPT secondary header signature calculation - it might be not the
   end of the disk.

3. Add more tests for wipe by signatures and detection.

Related to, but doesn't fix siderolabs/talos#9852

Signed-off-by: Andrey Smirnov <[email protected]>
smira added a commit to smira/go-blockdevice that referenced this issue Dec 24, 2024
1. Push GPT discovery last in the chain - if the GPT image is overridden
   with a smaller filesystem image, it might detect GPT before actual
   filesystem.

2. Fix GPT secondary header signature calculation - it might be not the
   end of the disk.

3. Add more tests for wipe by signatures and detection.

Related to, but doesn't fix siderolabs/talos#9852

Signed-off-by: Andrey Smirnov <[email protected]>
@smira smira reopened this Dec 25, 2024
@smira
Copy link
Member

smira commented Dec 25, 2024

This wasn't supposed to be a fix yet.

@joeypiccola
Copy link
Author

I kept trying to reproducing this with any kind of broken GPT, but I still can't. If you have a chance to dump fully this sda disk when it's broken, that would help a lot.

Can you clarify on this or provide a direction for how I'd do this? I don't need proxmox specific directions, just trying to understand what you're asking. Thanks!

@smira
Copy link
Member

smira commented Jan 8, 2025

Can you clarify on this or provide a direction for how I'd do this? I don't need proxmox specific directions, just trying to understand what you're asking. Thanks!

I mean if you could do something like dd if=/dev/sda of=somefile bs=1M and then compress the image and attach it here. (I guess not from Talos though, but exactly same contents which make Talos fail).

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

Successfully merging a pull request may close this issue.

2 participants