Nov 6 00:20:24.253782 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Nov 5 22:12:28 -00 2025 Nov 6 00:20:24.253812 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=59ca0b9e28689480cec05e5a7a50ffb2fd81e743a9e2986eb3bceb3b87f6702e Nov 6 00:20:24.253822 kernel: BIOS-provided physical RAM map: Nov 6 00:20:24.253829 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 6 00:20:24.253835 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 6 00:20:24.253842 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 6 00:20:24.253850 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 6 00:20:24.253860 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 6 00:20:24.253867 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 6 00:20:24.253873 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 6 00:20:24.253881 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 6 00:20:24.253887 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 6 00:20:24.253894 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 6 00:20:24.253901 kernel: NX (Execute Disable) protection: active Nov 6 00:20:24.253912 kernel: APIC: Static calls initialized Nov 6 00:20:24.253920 kernel: efi: EFI v2.7 by Microsoft Nov 6 00:20:24.253928 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee6da98 RNG=0x3ffd1018 Nov 6 00:20:24.253935 kernel: random: crng init done Nov 6 00:20:24.253943 kernel: secureboot: Secure boot disabled Nov 6 00:20:24.253951 kernel: SMBIOS 3.1.0 present. Nov 6 00:20:24.253958 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 6 00:20:24.253966 kernel: DMI: Memory slots populated: 3/3 Nov 6 00:20:24.253973 kernel: Hypervisor detected: Microsoft Hyper-V Nov 6 00:20:24.253981 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 6 00:20:24.253991 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 6 00:20:24.253998 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 6 00:20:24.254006 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 6 00:20:24.254014 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 6 00:20:24.254022 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 6 00:20:24.254030 kernel: tsc: Detected 2593.992 MHz processor Nov 6 00:20:24.254038 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 6 00:20:24.254046 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 6 00:20:24.254054 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 6 00:20:24.254064 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 6 00:20:24.254071 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 6 00:20:24.254079 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 6 00:20:24.254087 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 6 00:20:24.254094 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 6 00:20:24.254102 kernel: Using GB pages for direct mapping Nov 6 00:20:24.254110 kernel: ACPI: Early table checksum verification disabled Nov 6 00:20:24.254121 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 6 00:20:24.254132 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254144 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254152 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 6 00:20:24.254160 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 6 00:20:24.254168 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254177 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254187 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254196 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254204 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254212 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254220 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 00:20:24.254228 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 6 00:20:24.254236 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 6 00:20:24.254244 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 6 00:20:24.254253 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 6 00:20:24.254263 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 6 00:20:24.254271 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 6 00:20:24.254279 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 6 00:20:24.254287 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 6 00:20:24.254295 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 6 00:20:24.254303 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 6 00:20:24.254312 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 6 00:20:24.254320 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 6 00:20:24.254330 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 6 00:20:24.254338 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 6 00:20:24.254347 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 6 00:20:24.254355 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 6 00:20:24.254363 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 6 00:20:24.254371 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 6 00:20:24.254379 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 6 00:20:24.254388 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 6 00:20:24.254396 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 6 00:20:24.254406 kernel: Zone ranges: Nov 6 00:20:24.254415 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 6 00:20:24.254423 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 6 00:20:24.254431 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 6 00:20:24.254439 kernel: Device empty Nov 6 00:20:24.254447 kernel: Movable zone start for each node Nov 6 00:20:24.254455 kernel: Early memory node ranges Nov 6 00:20:24.254464 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 6 00:20:24.254482 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 6 00:20:24.254493 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 6 00:20:24.254502 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 6 00:20:24.254510 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 6 00:20:24.254518 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 6 00:20:24.254526 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 6 00:20:24.254534 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 6 00:20:24.254542 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 6 00:20:24.254550 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 6 00:20:24.254558 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 6 00:20:24.254569 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 6 00:20:24.254577 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 6 00:20:24.254585 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 6 00:20:24.254594 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 6 00:20:24.254602 kernel: CPU topo: Max. logical packages: 1 Nov 6 00:20:24.254610 kernel: CPU topo: Max. logical dies: 1 Nov 6 00:20:24.254618 kernel: CPU topo: Max. dies per package: 1 Nov 6 00:20:24.254626 kernel: CPU topo: Max. threads per core: 1 Nov 6 00:20:24.254634 kernel: CPU topo: Num. cores per package: 6 Nov 6 00:20:24.254644 kernel: CPU topo: Num. threads per package: 6 Nov 6 00:20:24.254653 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 6 00:20:24.254661 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 6 00:20:24.254669 kernel: Booting paravirtualized kernel on Hyper-V Nov 6 00:20:24.254677 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 6 00:20:24.254685 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 6 00:20:24.254694 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 6 00:20:24.254702 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 6 00:20:24.254710 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 6 00:20:24.254721 kernel: Hyper-V: PV spinlocks enabled Nov 6 00:20:24.254729 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 6 00:20:24.254738 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=59ca0b9e28689480cec05e5a7a50ffb2fd81e743a9e2986eb3bceb3b87f6702e Nov 6 00:20:24.254747 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 6 00:20:24.254755 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 6 00:20:24.254763 kernel: Fallback order for Node 0: 0 Nov 6 00:20:24.254772 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 6 00:20:24.254780 kernel: Policy zone: Normal Nov 6 00:20:24.254790 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 6 00:20:24.254806 kernel: software IO TLB: area num 8. Nov 6 00:20:24.254815 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 6 00:20:24.254826 kernel: Kernel/User page tables isolation: enabled Nov 6 00:20:24.254835 kernel: ftrace: allocating 40021 entries in 157 pages Nov 6 00:20:24.254843 kernel: ftrace: allocated 157 pages with 5 groups Nov 6 00:20:24.254852 kernel: Dynamic Preempt: voluntary Nov 6 00:20:24.254860 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 6 00:20:24.254870 kernel: rcu: RCU event tracing is enabled. Nov 6 00:20:24.254879 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 6 00:20:24.254887 kernel: Trampoline variant of Tasks RCU enabled. Nov 6 00:20:24.254898 kernel: Rude variant of Tasks RCU enabled. Nov 6 00:20:24.254907 kernel: Tracing variant of Tasks RCU enabled. Nov 6 00:20:24.254916 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 6 00:20:24.254925 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 6 00:20:24.254933 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 6 00:20:24.254942 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 6 00:20:24.254953 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 6 00:20:24.254962 kernel: Using NULL legacy PIC Nov 6 00:20:24.254971 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 6 00:20:24.254979 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 6 00:20:24.254988 kernel: Console: colour dummy device 80x25 Nov 6 00:20:24.254997 kernel: printk: legacy console [tty1] enabled Nov 6 00:20:24.255005 kernel: printk: legacy console [ttyS0] enabled Nov 6 00:20:24.255014 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 6 00:20:24.255022 kernel: ACPI: Core revision 20240827 Nov 6 00:20:24.255033 kernel: Failed to register legacy timer interrupt Nov 6 00:20:24.255042 kernel: APIC: Switch to symmetric I/O mode setup Nov 6 00:20:24.255050 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 6 00:20:24.255059 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 6 00:20:24.255068 kernel: Hyper-V: Using IPI hypercalls Nov 6 00:20:24.255076 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 6 00:20:24.255085 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 6 00:20:24.255093 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 6 00:20:24.255102 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 6 00:20:24.255113 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 6 00:20:24.255122 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 6 00:20:24.255130 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 6 00:20:24.255139 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 6 00:20:24.255147 kernel: APIC: read() replaced with hv_apic_read() Nov 6 00:20:24.255156 kernel: APIC: write() replaced with hv_apic_write() Nov 6 00:20:24.255164 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 6 00:20:24.255173 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 6 00:20:24.255182 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Nov 6 00:20:24.255193 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 6 00:20:24.255202 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 6 00:20:24.255210 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 6 00:20:24.255219 kernel: Spectre V2 : Mitigation: Retpolines Nov 6 00:20:24.255227 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 6 00:20:24.255236 kernel: Speculative Store Bypass: Vulnerable Nov 6 00:20:24.255244 kernel: MDS: Mitigation: Clear CPU buffers Nov 6 00:20:24.255253 kernel: TAA: Mitigation: Clear CPU buffers Nov 6 00:20:24.255261 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 6 00:20:24.255269 kernel: active return thunk: its_return_thunk Nov 6 00:20:24.255278 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 6 00:20:24.255289 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 6 00:20:24.255297 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 6 00:20:24.255305 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 6 00:20:24.255314 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 6 00:20:24.255322 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 6 00:20:24.255331 kernel: Freeing SMP alternatives memory: 32K Nov 6 00:20:24.255339 kernel: pid_max: default: 32768 minimum: 301 Nov 6 00:20:24.255348 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 6 00:20:24.255356 kernel: landlock: Up and running. Nov 6 00:20:24.255365 kernel: SELinux: Initializing. Nov 6 00:20:24.255373 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 6 00:20:24.255384 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 6 00:20:24.255392 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 6 00:20:24.255401 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 6 00:20:24.255410 kernel: signal: max sigframe size: 1776 Nov 6 00:20:24.255418 kernel: rcu: Hierarchical SRCU implementation. Nov 6 00:20:24.255427 kernel: rcu: Max phase no-delay instances is 400. Nov 6 00:20:24.255436 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 6 00:20:24.255445 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 6 00:20:24.255453 kernel: smp: Bringing up secondary CPUs ... Nov 6 00:20:24.255465 kernel: smpboot: x86: Booting SMP configuration: Nov 6 00:20:24.255481 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 6 00:20:24.255490 kernel: smp: Brought up 1 node, 6 CPUs Nov 6 00:20:24.255498 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Nov 6 00:20:24.255507 kernel: Memory: 115332948K/117439364K available (14336K kernel code, 2436K rwdata, 26048K rodata, 45548K init, 1180K bss, 2096140K reserved, 0K cma-reserved) Nov 6 00:20:24.255516 kernel: devtmpfs: initialized Nov 6 00:20:24.255525 kernel: x86/mm: Memory block size: 1024MB Nov 6 00:20:24.255533 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 6 00:20:24.255542 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 6 00:20:24.255554 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 6 00:20:24.255562 kernel: pinctrl core: initialized pinctrl subsystem Nov 6 00:20:24.255571 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 6 00:20:24.255580 kernel: audit: initializing netlink subsys (disabled) Nov 6 00:20:24.255595 kernel: audit: type=2000 audit(1762388420.028:1): state=initialized audit_enabled=0 res=1 Nov 6 00:20:24.255604 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 6 00:20:24.255613 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 6 00:20:24.255622 kernel: cpuidle: using governor menu Nov 6 00:20:24.255630 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 6 00:20:24.255642 kernel: dca service started, version 1.12.1 Nov 6 00:20:24.255651 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 6 00:20:24.255660 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 6 00:20:24.255668 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 6 00:20:24.255677 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 6 00:20:24.255686 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 6 00:20:24.255694 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 6 00:20:24.255703 kernel: ACPI: Added _OSI(Module Device) Nov 6 00:20:24.255712 kernel: ACPI: Added _OSI(Processor Device) Nov 6 00:20:24.255723 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 6 00:20:24.255740 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 6 00:20:24.255750 kernel: ACPI: Interpreter enabled Nov 6 00:20:24.255759 kernel: ACPI: PM: (supports S0 S5) Nov 6 00:20:24.255767 kernel: ACPI: Using IOAPIC for interrupt routing Nov 6 00:20:24.255776 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 6 00:20:24.255785 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 6 00:20:24.255793 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 6 00:20:24.255802 kernel: iommu: Default domain type: Translated Nov 6 00:20:24.255814 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 6 00:20:24.255822 kernel: efivars: Registered efivars operations Nov 6 00:20:24.255831 kernel: PCI: Using ACPI for IRQ routing Nov 6 00:20:24.255840 kernel: PCI: System does not support PCI Nov 6 00:20:24.255848 kernel: vgaarb: loaded Nov 6 00:20:24.255857 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 6 00:20:24.255866 kernel: VFS: Disk quotas dquot_6.6.0 Nov 6 00:20:24.255874 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 6 00:20:24.255883 kernel: pnp: PnP ACPI init Nov 6 00:20:24.255894 kernel: pnp: PnP ACPI: found 3 devices Nov 6 00:20:24.255903 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 6 00:20:24.255912 kernel: NET: Registered PF_INET protocol family Nov 6 00:20:24.255920 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 6 00:20:24.255929 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 6 00:20:24.255938 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 6 00:20:24.255946 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 6 00:20:24.255955 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 6 00:20:24.255964 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 6 00:20:24.255975 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 6 00:20:24.255983 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 6 00:20:24.255992 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 6 00:20:24.256001 kernel: NET: Registered PF_XDP protocol family Nov 6 00:20:24.256009 kernel: PCI: CLS 0 bytes, default 64 Nov 6 00:20:24.256018 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 6 00:20:24.256027 kernel: software IO TLB: mapped [mem 0x000000003ad78000-0x000000003ed78000] (64MB) Nov 6 00:20:24.256035 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 6 00:20:24.256044 kernel: Initialise system trusted keyrings Nov 6 00:20:24.256055 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 6 00:20:24.256064 kernel: Key type asymmetric registered Nov 6 00:20:24.256072 kernel: Asymmetric key parser 'x509' registered Nov 6 00:20:24.256080 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 6 00:20:24.256092 kernel: io scheduler mq-deadline registered Nov 6 00:20:24.256101 kernel: io scheduler kyber registered Nov 6 00:20:24.256110 kernel: io scheduler bfq registered Nov 6 00:20:24.256118 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 6 00:20:24.256127 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 6 00:20:24.256138 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 6 00:20:24.256147 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 6 00:20:24.256155 kernel: i8042: PNP: No PS/2 controller found. Nov 6 00:20:24.256305 kernel: rtc_cmos 00:02: registered as rtc0 Nov 6 00:20:24.256409 kernel: rtc_cmos 00:02: setting system clock to 2025-11-06T00:20:23 UTC (1762388423) Nov 6 00:20:24.256600 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 6 00:20:24.256617 kernel: intel_pstate: CPU model not supported Nov 6 00:20:24.256631 kernel: efifb: probing for efifb Nov 6 00:20:24.256640 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 6 00:20:24.256649 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 6 00:20:24.256658 kernel: efifb: scrolling: redraw Nov 6 00:20:24.256666 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 6 00:20:24.256675 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 00:20:24.256684 kernel: fb0: EFI VGA frame buffer device Nov 6 00:20:24.256692 kernel: pstore: Using crash dump compression: deflate Nov 6 00:20:24.256701 kernel: pstore: Registered efi_pstore as persistent store backend Nov 6 00:20:24.256712 kernel: NET: Registered PF_INET6 protocol family Nov 6 00:20:24.256721 kernel: Segment Routing with IPv6 Nov 6 00:20:24.256729 kernel: In-situ OAM (IOAM) with IPv6 Nov 6 00:20:24.256738 kernel: NET: Registered PF_PACKET protocol family Nov 6 00:20:24.256747 kernel: Key type dns_resolver registered Nov 6 00:20:24.256755 kernel: IPI shorthand broadcast: enabled Nov 6 00:20:24.256764 kernel: sched_clock: Marking stable (3742007200, 118091700)->(4327712600, -467613700) Nov 6 00:20:24.256772 kernel: registered taskstats version 1 Nov 6 00:20:24.256781 kernel: Loading compiled-in X.509 certificates Nov 6 00:20:24.256793 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: f906521ec29cbf079ae365554bad8eb8ed6ecb31' Nov 6 00:20:24.256810 kernel: Demotion targets for Node 0: null Nov 6 00:20:24.256824 kernel: Key type .fscrypt registered Nov 6 00:20:24.256833 kernel: Key type fscrypt-provisioning registered Nov 6 00:20:24.256842 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 6 00:20:24.256851 kernel: ima: Allocated hash algorithm: sha1 Nov 6 00:20:24.256859 kernel: ima: No architecture policies found Nov 6 00:20:24.256868 kernel: clk: Disabling unused clocks Nov 6 00:20:24.256876 kernel: Warning: unable to open an initial console. Nov 6 00:20:24.256885 kernel: Freeing unused kernel image (initmem) memory: 45548K Nov 6 00:20:24.256897 kernel: Write protecting the kernel read-only data: 40960k Nov 6 00:20:24.256906 kernel: Freeing unused kernel image (rodata/data gap) memory: 576K Nov 6 00:20:24.256914 kernel: Run /init as init process Nov 6 00:20:24.256923 kernel: with arguments: Nov 6 00:20:24.256932 kernel: /init Nov 6 00:20:24.256940 kernel: with environment: Nov 6 00:20:24.256949 kernel: HOME=/ Nov 6 00:20:24.256957 kernel: TERM=linux Nov 6 00:20:24.256967 systemd[1]: Successfully made /usr/ read-only. Nov 6 00:20:24.256981 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 00:20:24.256991 systemd[1]: Detected virtualization microsoft. Nov 6 00:20:24.257000 systemd[1]: Detected architecture x86-64. Nov 6 00:20:24.257009 systemd[1]: Running in initrd. Nov 6 00:20:24.257018 systemd[1]: No hostname configured, using default hostname. Nov 6 00:20:24.257027 systemd[1]: Hostname set to . Nov 6 00:20:24.257036 systemd[1]: Initializing machine ID from random generator. Nov 6 00:20:24.257048 systemd[1]: Queued start job for default target initrd.target. Nov 6 00:20:24.257057 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 00:20:24.257066 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 00:20:24.257076 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 6 00:20:24.257086 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 00:20:24.257095 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 6 00:20:24.257105 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 6 00:20:24.257118 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 6 00:20:24.257127 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 6 00:20:24.257137 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 00:20:24.257146 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 00:20:24.257155 systemd[1]: Reached target paths.target - Path Units. Nov 6 00:20:24.257164 systemd[1]: Reached target slices.target - Slice Units. Nov 6 00:20:24.257173 systemd[1]: Reached target swap.target - Swaps. Nov 6 00:20:24.257182 systemd[1]: Reached target timers.target - Timer Units. Nov 6 00:20:24.257194 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 00:20:24.257218 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 00:20:24.257227 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 6 00:20:24.257236 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 6 00:20:24.257246 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 00:20:24.257255 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 00:20:24.257264 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 00:20:24.257273 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 00:20:24.257283 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 6 00:20:24.257295 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 00:20:24.257304 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 6 00:20:24.257314 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 6 00:20:24.257323 systemd[1]: Starting systemd-fsck-usr.service... Nov 6 00:20:24.257333 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 00:20:24.257342 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 00:20:24.257351 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:20:24.257383 systemd-journald[213]: Collecting audit messages is disabled. Nov 6 00:20:24.257409 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 6 00:20:24.257422 systemd-journald[213]: Journal started Nov 6 00:20:24.257443 systemd-journald[213]: Runtime Journal (/run/log/journal/3b0fd3f5048b460488ea992189f65fc5) is 8M, max 2.2G, 2.1G free. Nov 6 00:20:24.256865 systemd-modules-load[215]: Inserted module 'overlay' Nov 6 00:20:24.271852 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 00:20:24.272332 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 00:20:24.272852 systemd[1]: Finished systemd-fsck-usr.service. Nov 6 00:20:24.276032 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 00:20:24.277575 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 00:20:24.315964 systemd-tmpfiles[225]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 6 00:20:24.363074 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 6 00:20:24.363104 kernel: Bridge firewalling registered Nov 6 00:20:24.321936 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 00:20:24.331623 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:20:24.332308 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 00:20:24.334571 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 00:20:24.335298 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 00:20:24.353740 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 00:20:24.369897 systemd-modules-load[215]: Inserted module 'br_netfilter' Nov 6 00:20:24.376527 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 00:20:24.382594 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 00:20:24.399894 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 6 00:20:24.401291 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 00:20:24.450282 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 00:20:24.454665 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 00:20:24.479585 dracut-cmdline[249]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=59ca0b9e28689480cec05e5a7a50ffb2fd81e743a9e2986eb3bceb3b87f6702e Nov 6 00:20:24.501168 systemd-resolved[260]: Positive Trust Anchors: Nov 6 00:20:24.501179 systemd-resolved[260]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 00:20:24.501209 systemd-resolved[260]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 6 00:20:24.503867 systemd-resolved[260]: Defaulting to hostname 'linux'. Nov 6 00:20:24.504891 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 00:20:24.507312 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 00:20:24.655502 kernel: SCSI subsystem initialized Nov 6 00:20:24.665493 kernel: Loading iSCSI transport class v2.0-870. Nov 6 00:20:24.678494 kernel: iscsi: registered transport (tcp) Nov 6 00:20:24.701466 kernel: iscsi: registered transport (qla4xxx) Nov 6 00:20:24.701512 kernel: QLogic iSCSI HBA Driver Nov 6 00:20:24.721636 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 00:20:24.753041 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 00:20:24.755752 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 00:20:24.819372 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 6 00:20:24.822191 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 6 00:20:24.904503 kernel: raid6: avx2x4 gen() 30714 MB/s Nov 6 00:20:24.923497 kernel: raid6: avx2x2 gen() 30842 MB/s Nov 6 00:20:24.944995 kernel: raid6: avx2x1 gen() 26816 MB/s Nov 6 00:20:24.945065 kernel: raid6: using algorithm avx2x2 gen() 30842 MB/s Nov 6 00:20:24.968127 kernel: raid6: .... xor() 18237 MB/s, rmw enabled Nov 6 00:20:24.968214 kernel: raid6: using avx2x2 recovery algorithm Nov 6 00:20:24.991504 kernel: xor: automatically using best checksumming function avx Nov 6 00:20:25.156504 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 6 00:20:25.164682 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 6 00:20:25.167029 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 00:20:25.215292 systemd-udevd[465]: Using default interface naming scheme 'v255'. Nov 6 00:20:25.221346 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 00:20:25.223956 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 6 00:20:25.272530 dracut-pre-trigger[473]: rd.md=0: removing MD RAID activation Nov 6 00:20:25.302770 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 00:20:25.305758 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 00:20:25.381332 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 00:20:25.385217 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 6 00:20:25.425649 kernel: cryptd: max_cpu_qlen set to 1000 Nov 6 00:20:25.434497 kernel: hv_vmbus: Vmbus version:4.0 Nov 6 00:20:25.443575 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 00:20:25.451562 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:20:25.463158 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:20:25.478806 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 6 00:20:25.478846 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 6 00:20:25.473831 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:20:25.487673 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 00:20:25.530024 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 6 00:20:25.530057 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 6 00:20:25.530069 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Nov 6 00:20:25.530082 kernel: AES CTR mode by8 optimization enabled Nov 6 00:20:25.537603 kernel: hv_vmbus: registering driver hv_pci Nov 6 00:20:25.537641 kernel: hv_vmbus: registering driver hid_hyperv Nov 6 00:20:25.537656 kernel: PTP clock support registered Nov 6 00:20:25.551491 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 6 00:20:25.561770 kernel: hv_vmbus: registering driver hv_netvsc Nov 6 00:20:25.561798 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 6 00:20:25.561811 kernel: hv_vmbus: registering driver hv_storvsc Nov 6 00:20:25.563440 kernel: hv_utils: Registering HyperV Utility Driver Nov 6 00:20:25.563483 kernel: hv_vmbus: registering driver hv_utils Nov 6 00:20:25.563498 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 6 00:20:25.563655 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 6 00:20:25.563787 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 6 00:20:25.563940 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 6 00:20:25.564063 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 6 00:20:25.568980 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 6 00:20:25.569017 kernel: hv_utils: Heartbeat IC version 3.0 Nov 6 00:20:25.569029 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 6 00:20:25.569048 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 6 00:20:25.569067 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 6 00:20:25.572496 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 6 00:20:25.572539 kernel: hv_utils: Shutdown IC version 3.2 Nov 6 00:20:25.605378 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 6 00:20:25.605663 kernel: hv_utils: TimeSync IC version 4.0 Nov 6 00:20:25.605682 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 6 00:20:25.608522 kernel: scsi host1: storvsc_host_t Nov 6 00:20:26.273811 systemd-resolved[260]: Clock change detected. Flushing caches. Nov 6 00:20:26.346632 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 6 00:20:26.346879 kernel: scsi host0: storvsc_host_t Nov 6 00:20:26.347042 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 6 00:20:26.347213 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 6 00:20:26.347474 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 6 00:20:26.347665 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 6 00:20:26.354888 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:20:26.370271 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 6 00:20:26.370495 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 6 00:20:26.370690 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 6 00:20:26.370838 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Nov 6 00:20:26.370969 kernel: sd 0:0:0:1: [sdb] Write Protect is off Nov 6 00:20:26.381003 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 6 00:20:26.381219 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 6 00:20:26.381367 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 6 00:20:26.386244 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 6 00:20:26.391612 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 6 00:20:26.396603 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:26.396774 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:26.414761 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#7 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:26.421613 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#268 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:26.431278 kernel: sdb: sdb1 Nov 6 00:20:26.431306 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Nov 6 00:20:26.440860 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 00:20:26.440888 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 6 00:20:26.449315 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 6 00:20:26.449510 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 6 00:20:26.450596 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 6 00:20:26.999007 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 00:20:27.048030 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 6 00:20:27.066622 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 6 00:20:27.089724 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 6 00:20:27.090837 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 6 00:20:27.111494 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 6 00:20:27.173606 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#49 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:27.198619 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 00:20:28.218415 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#517 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:28.234538 disk-uuid[701]: The operation has completed successfully. Nov 6 00:20:28.239228 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 00:20:29.511562 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 6 00:20:29.513666 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 6 00:20:29.513776 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 6 00:20:29.523783 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 00:20:29.531692 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 00:20:29.541244 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 00:20:29.560813 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 6 00:20:29.570808 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 6 00:20:29.604199 sh[868]: Success Nov 6 00:20:29.609414 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 6 00:20:29.643599 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 6 00:20:29.643672 kernel: device-mapper: uevent: version 1.0.3 Nov 6 00:20:29.648319 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 6 00:20:29.663606 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Nov 6 00:20:29.942752 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 6 00:20:29.953097 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 6 00:20:29.976504 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 6 00:20:30.009608 kernel: BTRFS: device fsid 85d805c5-984c-4a6a-aaeb-49fff3689175 devid 1 transid 38 /dev/mapper/usr (254:0) scanned by mount (893) Nov 6 00:20:30.018953 kernel: BTRFS info (device dm-0): first mount of filesystem 85d805c5-984c-4a6a-aaeb-49fff3689175 Nov 6 00:20:30.019009 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:20:30.304739 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 6 00:20:30.304823 kernel: BTRFS info (device dm-0): enabling free space tree Nov 6 00:20:30.391515 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 6 00:20:30.394841 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 6 00:20:30.400804 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 6 00:20:30.401769 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 6 00:20:30.428661 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 6 00:20:30.475430 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (916) Nov 6 00:20:30.475486 kernel: BTRFS info (device sda6): first mount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:20:30.479810 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:20:30.509798 kernel: BTRFS info (device sda6): turning on async discard Nov 6 00:20:30.509874 kernel: BTRFS info (device sda6): enabling free space tree Nov 6 00:20:30.521617 kernel: BTRFS info (device sda6): last unmount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:20:30.522160 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 6 00:20:30.525625 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 6 00:20:30.615369 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 00:20:30.619255 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 00:20:30.676752 systemd-networkd[1063]: lo: Link UP Nov 6 00:20:30.676764 systemd-networkd[1063]: lo: Gained carrier Nov 6 00:20:30.677790 systemd-networkd[1063]: Enumeration completed Nov 6 00:20:30.677892 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 00:20:30.678155 systemd-networkd[1063]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:20:30.678159 systemd-networkd[1063]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 00:20:30.679325 systemd-networkd[1063]: eth0: Link UP Nov 6 00:20:30.679522 systemd-networkd[1063]: eth0: Gained carrier Nov 6 00:20:30.679532 systemd-networkd[1063]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:20:30.684555 systemd[1]: Reached target network.target - Network. Nov 6 00:20:30.759661 systemd-networkd[1063]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 6 00:20:32.174733 systemd-networkd[1063]: eth0: Gained IPv6LL Nov 6 00:20:32.315211 ignition[991]: Ignition 2.22.0 Nov 6 00:20:32.315230 ignition[991]: Stage: fetch-offline Nov 6 00:20:32.315440 ignition[991]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:32.315457 ignition[991]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:32.315699 ignition[991]: parsed url from cmdline: "" Nov 6 00:20:32.315709 ignition[991]: no config URL provided Nov 6 00:20:32.315717 ignition[991]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 00:20:32.315729 ignition[991]: no config at "/usr/lib/ignition/user.ign" Nov 6 00:20:32.315736 ignition[991]: failed to fetch config: resource requires networking Nov 6 00:20:32.343146 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 00:20:32.315947 ignition[991]: Ignition finished successfully Nov 6 00:20:32.364179 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 6 00:20:32.420026 ignition[1077]: Ignition 2.22.0 Nov 6 00:20:32.420041 ignition[1077]: Stage: fetch Nov 6 00:20:32.420269 ignition[1077]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:32.420279 ignition[1077]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:32.420377 ignition[1077]: parsed url from cmdline: "" Nov 6 00:20:32.420381 ignition[1077]: no config URL provided Nov 6 00:20:32.420387 ignition[1077]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 00:20:32.420396 ignition[1077]: no config at "/usr/lib/ignition/user.ign" Nov 6 00:20:32.420414 ignition[1077]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 6 00:20:32.536645 ignition[1077]: GET result: OK Nov 6 00:20:32.536714 ignition[1077]: config has been read from IMDS userdata Nov 6 00:20:32.536732 ignition[1077]: parsing config with SHA512: ff6ca897f908f55db6fb7186a4a257dbf5a831857150096c3f3126805cd5acfd74ba3f0949e8ef836301bdf5c2fbdf130163ddf4cd9a480c9da4b3644a99c6a6 Nov 6 00:20:32.542027 unknown[1077]: fetched base config from "system" Nov 6 00:20:32.542239 ignition[1077]: fetch: fetch complete Nov 6 00:20:32.542040 unknown[1077]: fetched base config from "system" Nov 6 00:20:32.542248 ignition[1077]: fetch: fetch passed Nov 6 00:20:32.542046 unknown[1077]: fetched user config from "azure" Nov 6 00:20:32.542294 ignition[1077]: Ignition finished successfully Nov 6 00:20:32.546297 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 6 00:20:32.550616 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 6 00:20:32.611173 ignition[1086]: Ignition 2.22.0 Nov 6 00:20:32.611188 ignition[1086]: Stage: kargs Nov 6 00:20:32.611450 ignition[1086]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:32.611463 ignition[1086]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:32.612014 ignition[1086]: kargs: kargs passed Nov 6 00:20:32.612064 ignition[1086]: Ignition finished successfully Nov 6 00:20:32.625367 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 6 00:20:32.628383 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 6 00:20:32.672029 ignition[1095]: Ignition 2.22.0 Nov 6 00:20:32.672046 ignition[1095]: Stage: disks Nov 6 00:20:32.672402 ignition[1095]: no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:32.672416 ignition[1095]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:32.681802 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 6 00:20:32.673181 ignition[1095]: disks: disks passed Nov 6 00:20:32.688845 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 6 00:20:32.673233 ignition[1095]: Ignition finished successfully Nov 6 00:20:32.698390 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 6 00:20:32.708440 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 00:20:32.715533 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 00:20:32.724316 systemd[1]: Reached target basic.target - Basic System. Nov 6 00:20:32.732252 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 6 00:20:32.829487 systemd-fsck[1107]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Nov 6 00:20:32.842464 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 6 00:20:32.844238 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 6 00:20:33.257611 kernel: EXT4-fs (sda9): mounted filesystem 25ee01aa-0270-4de7-b5da-d8936d968d16 r/w with ordered data mode. Quota mode: none. Nov 6 00:20:33.258674 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 6 00:20:33.260764 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 6 00:20:33.299900 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 00:20:33.303240 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 6 00:20:33.324273 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 6 00:20:33.325956 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 6 00:20:33.325989 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 00:20:33.361793 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1121) Nov 6 00:20:33.361822 kernel: BTRFS info (device sda6): first mount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:20:33.337680 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 6 00:20:33.395427 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:20:33.395460 kernel: BTRFS info (device sda6): turning on async discard Nov 6 00:20:33.395474 kernel: BTRFS info (device sda6): enabling free space tree Nov 6 00:20:33.376496 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 6 00:20:33.389290 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 00:20:34.064056 coreos-metadata[1123]: Nov 06 00:20:34.064 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 00:20:34.072520 coreos-metadata[1123]: Nov 06 00:20:34.072 INFO Fetch successful Nov 6 00:20:34.072520 coreos-metadata[1123]: Nov 06 00:20:34.072 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 6 00:20:34.086415 coreos-metadata[1123]: Nov 06 00:20:34.086 INFO Fetch successful Nov 6 00:20:34.107801 coreos-metadata[1123]: Nov 06 00:20:34.107 INFO wrote hostname ci-4459.1.0-n-5184a1554a to /sysroot/etc/hostname Nov 6 00:20:34.116328 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 00:20:34.352595 initrd-setup-root[1151]: cut: /sysroot/etc/passwd: No such file or directory Nov 6 00:20:34.408651 initrd-setup-root[1158]: cut: /sysroot/etc/group: No such file or directory Nov 6 00:20:34.504225 initrd-setup-root[1165]: cut: /sysroot/etc/shadow: No such file or directory Nov 6 00:20:34.545041 initrd-setup-root[1172]: cut: /sysroot/etc/gshadow: No such file or directory Nov 6 00:20:35.643049 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 6 00:20:35.646496 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 6 00:20:35.674172 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 6 00:20:35.676620 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 6 00:20:35.701524 kernel: BTRFS info (device sda6): last unmount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:20:35.750912 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 6 00:20:35.765525 ignition[1240]: INFO : Ignition 2.22.0 Nov 6 00:20:35.765525 ignition[1240]: INFO : Stage: mount Nov 6 00:20:35.772165 ignition[1240]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:35.772165 ignition[1240]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:35.772165 ignition[1240]: INFO : mount: mount passed Nov 6 00:20:35.772165 ignition[1240]: INFO : Ignition finished successfully Nov 6 00:20:35.775982 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 6 00:20:35.780159 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 6 00:20:35.827305 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 00:20:35.857612 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1255) Nov 6 00:20:35.857685 kernel: BTRFS info (device sda6): first mount of filesystem ca2bb832-66d5-4dca-a6d2-cbf7440d9381 Nov 6 00:20:35.867656 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 00:20:35.884818 kernel: BTRFS info (device sda6): turning on async discard Nov 6 00:20:35.884877 kernel: BTRFS info (device sda6): enabling free space tree Nov 6 00:20:35.887159 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 00:20:35.923453 ignition[1272]: INFO : Ignition 2.22.0 Nov 6 00:20:35.923453 ignition[1272]: INFO : Stage: files Nov 6 00:20:35.930978 ignition[1272]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:35.930978 ignition[1272]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:35.930978 ignition[1272]: DEBUG : files: compiled without relabeling support, skipping Nov 6 00:20:35.930978 ignition[1272]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 6 00:20:35.930978 ignition[1272]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 6 00:20:36.025834 ignition[1272]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 6 00:20:36.035542 ignition[1272]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 6 00:20:36.035542 ignition[1272]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 6 00:20:36.026536 unknown[1272]: wrote ssh authorized keys file for user: core Nov 6 00:20:36.092567 ignition[1272]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 00:20:36.100513 ignition[1272]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 00:20:36.100513 ignition[1272]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 6 00:20:36.100513 ignition[1272]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 6 00:20:36.100513 ignition[1272]: INFO : files: files passed Nov 6 00:20:36.100513 ignition[1272]: INFO : Ignition finished successfully Nov 6 00:20:36.109478 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 6 00:20:36.118167 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 6 00:20:36.151329 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 6 00:20:36.154688 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 6 00:20:36.154792 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 6 00:20:36.180743 initrd-setup-root-after-ignition[1305]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 00:20:36.180743 initrd-setup-root-after-ignition[1305]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 6 00:20:36.194167 initrd-setup-root-after-ignition[1309]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 00:20:36.194406 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 00:20:36.197211 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 6 00:20:36.221834 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 6 00:20:36.282568 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 6 00:20:36.282697 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 6 00:20:36.285130 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 6 00:20:36.297639 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 6 00:20:36.309796 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 6 00:20:36.310646 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 6 00:20:36.357115 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 00:20:36.362847 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 6 00:20:36.405809 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 6 00:20:36.415981 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 00:20:36.417654 systemd[1]: Stopped target timers.target - Timer Units. Nov 6 00:20:36.426656 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 6 00:20:36.426802 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 00:20:36.445276 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 6 00:20:36.452507 systemd[1]: Stopped target basic.target - Basic System. Nov 6 00:20:36.460902 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 6 00:20:36.468616 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 00:20:36.477652 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 6 00:20:36.487192 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 6 00:20:36.495703 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 6 00:20:36.511491 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 00:20:36.520746 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 6 00:20:36.530148 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 6 00:20:36.538390 systemd[1]: Stopped target swap.target - Swaps. Nov 6 00:20:36.545804 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 6 00:20:36.545961 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 6 00:20:36.557304 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 6 00:20:36.566287 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 00:20:36.583114 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 6 00:20:36.590431 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 00:20:36.606071 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 6 00:20:36.606629 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 6 00:20:36.621670 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 6 00:20:36.621839 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 00:20:36.632056 systemd[1]: ignition-files.service: Deactivated successfully. Nov 6 00:20:36.632244 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 6 00:20:36.641701 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 6 00:20:36.641843 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 00:20:36.656973 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 6 00:20:36.662354 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 6 00:20:36.675055 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 6 00:20:36.675245 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 00:20:36.685049 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 6 00:20:36.715233 ignition[1329]: INFO : Ignition 2.22.0 Nov 6 00:20:36.715233 ignition[1329]: INFO : Stage: umount Nov 6 00:20:36.715233 ignition[1329]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 00:20:36.715233 ignition[1329]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 00:20:36.715233 ignition[1329]: INFO : umount: umount passed Nov 6 00:20:36.715233 ignition[1329]: INFO : Ignition finished successfully Nov 6 00:20:36.685190 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 00:20:36.713980 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 6 00:20:36.714088 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 6 00:20:36.716533 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 6 00:20:36.716638 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 6 00:20:36.730103 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 6 00:20:36.730567 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 6 00:20:36.730637 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 6 00:20:36.736677 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 6 00:20:36.736743 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 6 00:20:36.744877 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 6 00:20:36.744931 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 6 00:20:36.755392 systemd[1]: Stopped target network.target - Network. Nov 6 00:20:36.763227 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 6 00:20:36.763308 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 00:20:36.771446 systemd[1]: Stopped target paths.target - Path Units. Nov 6 00:20:36.780118 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 6 00:20:36.789709 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 00:20:36.793247 systemd[1]: Stopped target slices.target - Slice Units. Nov 6 00:20:36.802491 systemd[1]: Stopped target sockets.target - Socket Units. Nov 6 00:20:36.809563 systemd[1]: iscsid.socket: Deactivated successfully. Nov 6 00:20:36.809649 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 00:20:36.820553 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 6 00:20:36.820623 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 00:20:36.831653 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 6 00:20:36.831743 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 6 00:20:36.840749 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 6 00:20:36.840813 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 6 00:20:36.850841 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 6 00:20:36.874504 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 6 00:20:36.883490 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 6 00:20:36.883614 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 6 00:20:36.897370 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 6 00:20:36.897670 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 6 00:20:36.897776 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 6 00:20:36.903391 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 6 00:20:36.903539 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 6 00:20:36.928802 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 6 00:20:36.928879 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 00:20:36.970342 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 6 00:20:36.970693 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 6 00:20:36.970818 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 6 00:20:36.982809 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 6 00:20:36.983505 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 6 00:20:36.987146 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 6 00:20:36.987201 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 6 00:20:37.000154 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 6 00:20:37.002921 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 6 00:20:37.002980 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 00:20:37.016667 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 6 00:20:37.016728 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 6 00:20:37.033660 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 6 00:20:37.033732 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 6 00:20:37.035971 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 00:20:37.048911 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 6 00:20:37.079098 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 6 00:20:37.079216 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 6 00:20:37.092705 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 6 00:20:37.092940 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 00:20:37.096368 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 6 00:20:37.096423 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 6 00:20:37.106309 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 6 00:20:37.106346 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 00:20:37.114609 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 6 00:20:37.114665 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 6 00:20:37.133732 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 6 00:20:37.133800 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 6 00:20:37.160139 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 00:20:37.160224 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 00:20:37.185514 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 6 00:20:37.196107 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 6 00:20:37.196176 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 00:20:37.207149 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 6 00:20:37.207220 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 00:20:37.225151 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 6 00:20:37.225218 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 00:20:37.240688 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 6 00:20:37.240761 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 00:20:37.242875 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 00:20:37.242937 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:20:37.264246 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 6 00:20:37.264353 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 6 00:20:37.274839 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 6 00:20:37.284158 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 6 00:20:37.372725 systemd[1]: Switching root. Nov 6 00:20:37.468487 systemd-journald[213]: Journal stopped Nov 6 00:20:42.440876 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Nov 6 00:20:42.440911 kernel: SELinux: policy capability network_peer_controls=1 Nov 6 00:20:42.440924 kernel: SELinux: policy capability open_perms=1 Nov 6 00:20:42.440934 kernel: SELinux: policy capability extended_socket_class=1 Nov 6 00:20:42.440943 kernel: SELinux: policy capability always_check_network=0 Nov 6 00:20:42.440952 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 6 00:20:42.440963 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 6 00:20:42.440975 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 6 00:20:42.440985 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 6 00:20:42.440994 kernel: SELinux: policy capability userspace_initial_context=0 Nov 6 00:20:42.441003 kernel: audit: type=1403 audit(1762388438.458:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 6 00:20:42.441014 systemd[1]: Successfully loaded SELinux policy in 242.859ms. Nov 6 00:20:42.441026 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.676ms. Nov 6 00:20:42.441037 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 00:20:42.441051 systemd[1]: Detected virtualization microsoft. Nov 6 00:20:42.441062 systemd[1]: Detected architecture x86-64. Nov 6 00:20:42.441072 systemd[1]: Detected first boot. Nov 6 00:20:42.441083 systemd[1]: Hostname set to . Nov 6 00:20:42.441094 systemd[1]: Initializing machine ID from random generator. Nov 6 00:20:42.441112 zram_generator::config[1374]: No configuration found. Nov 6 00:20:42.441124 kernel: Guest personality initialized and is inactive Nov 6 00:20:42.441134 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Nov 6 00:20:42.441143 kernel: Initialized host personality Nov 6 00:20:42.441153 kernel: NET: Registered PF_VSOCK protocol family Nov 6 00:20:42.441163 systemd[1]: Populated /etc with preset unit settings. Nov 6 00:20:42.441175 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 6 00:20:42.441189 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 6 00:20:42.441199 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 6 00:20:42.441209 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 6 00:20:42.441220 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 6 00:20:42.441232 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 6 00:20:42.441242 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 6 00:20:42.441253 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 6 00:20:42.441266 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 6 00:20:42.441278 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 6 00:20:42.441288 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 6 00:20:42.441299 systemd[1]: Created slice user.slice - User and Session Slice. Nov 6 00:20:42.441310 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 00:20:42.441321 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 00:20:42.441332 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 6 00:20:42.441349 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 6 00:20:42.441361 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 6 00:20:42.441375 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 00:20:42.441387 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 6 00:20:42.441398 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 00:20:42.441409 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 00:20:42.441420 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 6 00:20:42.441431 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 6 00:20:42.441442 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 6 00:20:42.441456 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 6 00:20:42.441524 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 00:20:42.441539 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 00:20:42.441550 systemd[1]: Reached target slices.target - Slice Units. Nov 6 00:20:42.441561 systemd[1]: Reached target swap.target - Swaps. Nov 6 00:20:42.441572 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 6 00:20:42.441593 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 6 00:20:42.441610 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 6 00:20:42.441622 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 00:20:42.441633 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 00:20:42.441644 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 00:20:42.441655 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 6 00:20:42.441671 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 6 00:20:42.441686 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 6 00:20:42.441697 systemd[1]: Mounting media.mount - External Media Directory... Nov 6 00:20:42.441709 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:20:42.441720 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 6 00:20:42.441732 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 6 00:20:42.441743 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 6 00:20:42.441755 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 6 00:20:42.441767 systemd[1]: Reached target machines.target - Containers. Nov 6 00:20:42.441781 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 6 00:20:42.441793 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:20:42.441804 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 00:20:42.441815 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 6 00:20:42.441827 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:20:42.441838 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 00:20:42.441849 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:20:42.441860 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 6 00:20:42.441874 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:20:42.441886 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 6 00:20:42.441897 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 6 00:20:42.441908 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 6 00:20:42.441923 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 6 00:20:42.441934 systemd[1]: Stopped systemd-fsck-usr.service. Nov 6 00:20:42.441946 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 00:20:42.441957 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 00:20:42.441968 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 00:20:42.441983 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 00:20:42.441995 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 6 00:20:42.442006 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 6 00:20:42.442017 kernel: loop: module loaded Nov 6 00:20:42.442028 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 00:20:42.442039 systemd[1]: verity-setup.service: Deactivated successfully. Nov 6 00:20:42.442050 systemd[1]: Stopped verity-setup.service. Nov 6 00:20:42.442062 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:20:42.442075 kernel: fuse: init (API version 7.41) Nov 6 00:20:42.442086 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 6 00:20:42.442124 systemd-journald[1467]: Collecting audit messages is disabled. Nov 6 00:20:42.442150 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 6 00:20:42.442166 systemd-journald[1467]: Journal started Nov 6 00:20:42.442188 systemd-journald[1467]: Runtime Journal (/run/log/journal/e7474935f7dc4dd7b869c8ba83208d1e) is 8M, max 2.2G, 2.1G free. Nov 6 00:20:41.588233 systemd[1]: Queued start job for default target multi-user.target. Nov 6 00:20:41.617368 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 6 00:20:41.618089 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 6 00:20:42.455156 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 00:20:42.459643 systemd[1]: Mounted media.mount - External Media Directory. Nov 6 00:20:42.464128 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 6 00:20:42.469413 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 6 00:20:42.474961 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 6 00:20:42.479524 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 6 00:20:42.485729 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 00:20:42.491688 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 6 00:20:42.491901 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 6 00:20:42.497652 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:20:42.497841 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:20:42.503393 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:20:42.503599 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:20:42.509094 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 6 00:20:42.509288 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 6 00:20:42.522739 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:20:42.522948 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:20:42.534296 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 00:20:42.540296 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 00:20:42.546261 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 6 00:20:42.552290 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 6 00:20:42.558392 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 00:20:42.572657 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 00:20:42.579075 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 6 00:20:42.594921 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 6 00:20:42.600402 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 6 00:20:42.600447 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 00:20:42.606206 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 6 00:20:42.610600 kernel: ACPI: bus type drm_connector registered Nov 6 00:20:42.615722 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 6 00:20:42.620573 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:20:42.653447 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 6 00:20:42.676928 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 6 00:20:42.682029 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 00:20:42.683036 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 6 00:20:42.688171 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 00:20:42.689259 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 00:20:42.694729 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 6 00:20:42.700886 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 00:20:42.708469 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 00:20:42.708693 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 00:20:42.713730 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 6 00:20:42.721436 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 6 00:20:42.727351 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 6 00:20:42.731597 systemd-journald[1467]: Time spent on flushing to /var/log/journal/e7474935f7dc4dd7b869c8ba83208d1e is 15.274ms for 948 entries. Nov 6 00:20:42.731597 systemd-journald[1467]: System Journal (/var/log/journal/e7474935f7dc4dd7b869c8ba83208d1e) is 8M, max 2.6G, 2.6G free. Nov 6 00:20:42.777228 systemd-journald[1467]: Received client request to flush runtime journal. Nov 6 00:20:42.738361 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 6 00:20:42.744676 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 6 00:20:42.762957 systemd-tmpfiles[1518]: ACLs are not supported, ignoring. Nov 6 00:20:42.762972 systemd-tmpfiles[1518]: ACLs are not supported, ignoring. Nov 6 00:20:42.767533 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 00:20:42.773696 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 00:20:42.779040 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 6 00:20:42.787282 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 6 00:20:42.812610 kernel: loop0: detected capacity change from 0 to 27936 Nov 6 00:20:42.877732 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 6 00:20:42.878448 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 6 00:20:42.960814 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 6 00:20:42.966823 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 00:20:43.005030 systemd-tmpfiles[1535]: ACLs are not supported, ignoring. Nov 6 00:20:43.005052 systemd-tmpfiles[1535]: ACLs are not supported, ignoring. Nov 6 00:20:43.013310 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 00:20:43.560612 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 6 00:20:43.660971 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 6 00:20:43.670008 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 00:20:43.717661 systemd-udevd[1541]: Using default interface naming scheme 'v255'. Nov 6 00:20:43.762619 kernel: loop1: detected capacity change from 0 to 110984 Nov 6 00:20:43.932989 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 00:20:43.943244 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 00:20:43.969425 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 6 00:20:44.060634 kernel: hv_vmbus: registering driver hyperv_fb Nov 6 00:20:44.062980 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 6 00:20:44.079019 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 6 00:20:44.079106 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 6 00:20:44.079130 kernel: mousedev: PS/2 mouse device common for all mice Nov 6 00:20:44.083705 kernel: Console: switching to colour dummy device 80x25 Nov 6 00:20:44.091620 kernel: hv_vmbus: registering driver hv_balloon Nov 6 00:20:44.091759 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 00:20:44.091816 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 6 00:20:44.122821 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:20:44.135224 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 6 00:20:44.145406 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 00:20:44.145642 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:20:44.151631 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 00:20:44.160541 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 00:20:44.200774 kernel: EDAC MC: Ver: 3.0.0 Nov 6 00:20:44.249814 systemd-networkd[1566]: lo: Link UP Nov 6 00:20:44.249824 systemd-networkd[1566]: lo: Gained carrier Nov 6 00:20:44.251391 systemd-networkd[1566]: Enumeration completed Nov 6 00:20:44.251529 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 00:20:44.251918 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:20:44.251929 systemd-networkd[1566]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 00:20:44.253168 systemd-networkd[1566]: eth0: Link UP Nov 6 00:20:44.253178 systemd-networkd[1566]: eth0: Gained carrier Nov 6 00:20:44.253193 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:20:44.259209 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 6 00:20:44.266903 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 6 00:20:44.292658 systemd-networkd[1566]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 6 00:20:44.306085 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 6 00:20:44.471155 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 00:20:44.478123 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 6 00:20:44.490610 kernel: loop2: detected capacity change from 0 to 128016 Nov 6 00:20:44.573927 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 6 00:20:44.620828 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 00:20:45.014609 kernel: loop3: detected capacity change from 0 to 27936 Nov 6 00:20:45.040608 kernel: loop4: detected capacity change from 0 to 110984 Nov 6 00:20:45.057603 kernel: loop5: detected capacity change from 0 to 128016 Nov 6 00:20:45.073641 (sd-merge)[1701]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Nov 6 00:20:45.074165 (sd-merge)[1701]: Merged extensions into '/usr'. Nov 6 00:20:45.078028 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 6 00:20:45.085639 systemd[1]: Starting ensure-sysext.service... Nov 6 00:20:45.089604 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 00:20:45.120642 systemd[1]: Reload requested from client PID 1703 ('systemctl') (unit ensure-sysext.service)... Nov 6 00:20:45.120660 systemd[1]: Reloading... Nov 6 00:20:45.161778 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 6 00:20:45.161823 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 6 00:20:45.162105 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 6 00:20:45.162386 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 6 00:20:45.163297 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 6 00:20:45.163575 systemd-tmpfiles[1704]: ACLs are not supported, ignoring. Nov 6 00:20:45.163652 systemd-tmpfiles[1704]: ACLs are not supported, ignoring. Nov 6 00:20:45.167614 zram_generator::config[1735]: No configuration found. Nov 6 00:20:45.168562 systemd-tmpfiles[1704]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 00:20:45.168610 systemd-tmpfiles[1704]: Skipping /boot Nov 6 00:20:45.177634 systemd-tmpfiles[1704]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 00:20:45.177652 systemd-tmpfiles[1704]: Skipping /boot Nov 6 00:20:45.453328 systemd[1]: Reloading finished in 332 ms. Nov 6 00:20:45.501856 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 00:20:45.520291 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:20:45.521668 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 6 00:20:45.547642 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 6 00:20:45.552894 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:20:45.554118 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:20:45.559858 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:20:45.566025 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:20:45.570514 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:20:45.570666 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 00:20:45.571854 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 6 00:20:45.578742 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 00:20:45.585187 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 6 00:20:45.591199 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:20:45.594707 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:20:45.594908 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:20:45.600107 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:20:45.600300 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:20:45.605927 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:20:45.606111 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:20:45.618467 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 6 00:20:45.630973 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:20:45.631256 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 00:20:45.632492 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 00:20:45.660506 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 00:20:45.669808 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 00:20:45.688005 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 00:20:45.693853 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 00:20:45.694030 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 00:20:45.694224 systemd[1]: Reached target time-set.target - System Time Set. Nov 6 00:20:45.700560 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 00:20:45.703071 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 6 00:20:45.711828 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 00:20:45.712042 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 00:20:45.716651 systemd-resolved[1807]: Positive Trust Anchors: Nov 6 00:20:45.716666 systemd-resolved[1807]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 00:20:45.716702 systemd-resolved[1807]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 6 00:20:45.727042 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 00:20:45.727263 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 00:20:45.732198 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 00:20:45.732391 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 00:20:45.740726 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 00:20:45.740952 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 00:20:45.748802 systemd[1]: Finished ensure-sysext.service. Nov 6 00:20:45.752546 augenrules[1835]: No rules Nov 6 00:20:45.753312 systemd[1]: audit-rules.service: Deactivated successfully. Nov 6 00:20:45.753541 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 6 00:20:45.760405 systemd-resolved[1807]: Using system hostname 'ci-4459.1.0-n-5184a1554a'. Nov 6 00:20:45.760590 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 00:20:45.760689 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 00:20:45.762724 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 00:20:45.767471 systemd[1]: Reached target network.target - Network. Nov 6 00:20:45.771349 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 00:20:46.126733 systemd-networkd[1566]: eth0: Gained IPv6LL Nov 6 00:20:46.129566 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 6 00:20:46.139011 systemd[1]: Reached target network-online.target - Network is Online. Nov 6 00:20:46.903714 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 6 00:20:46.911178 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 6 00:20:50.172060 ldconfig[1511]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 00:20:50.193941 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 6 00:20:50.203040 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 6 00:20:50.237241 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 6 00:20:50.242211 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 00:20:50.246778 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 6 00:20:50.252536 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 6 00:20:50.257875 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 6 00:20:50.264972 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 6 00:20:50.269660 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 6 00:20:50.275081 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 6 00:20:50.282176 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 6 00:20:50.282234 systemd[1]: Reached target paths.target - Path Units. Nov 6 00:20:50.286321 systemd[1]: Reached target timers.target - Timer Units. Nov 6 00:20:50.304610 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 6 00:20:50.310789 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 6 00:20:50.317051 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 6 00:20:50.322831 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 6 00:20:50.328539 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 6 00:20:50.335432 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 6 00:20:50.340422 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 6 00:20:50.346524 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 6 00:20:50.354854 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 00:20:50.359038 systemd[1]: Reached target basic.target - Basic System. Nov 6 00:20:50.363045 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 6 00:20:50.363087 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 6 00:20:50.387487 systemd[1]: Starting chronyd.service - NTP client/server... Nov 6 00:20:50.408503 systemd[1]: Starting containerd.service - containerd container runtime... Nov 6 00:20:50.414134 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 6 00:20:50.419638 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 6 00:20:50.424388 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 6 00:20:50.430166 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 6 00:20:50.435702 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 6 00:20:50.440033 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 6 00:20:50.441105 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 6 00:20:50.445569 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Nov 6 00:20:50.446650 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 6 00:20:50.453521 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 6 00:20:50.454789 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 6 00:20:50.456546 jq[1859]: false Nov 6 00:20:50.459937 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 6 00:20:50.469127 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 6 00:20:50.476334 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 6 00:20:50.478490 extend-filesystems[1860]: Found /dev/sda6 Nov 6 00:20:50.480715 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 6 00:20:50.484164 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 6 00:20:50.484959 chronyd[1851]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 6 00:20:50.489453 KVP[1865]: KVP starting; pid is:1865 Nov 6 00:20:50.491829 KVP[1865]: KVP LIC Version: 3.1 Nov 6 00:20:50.493602 kernel: hv_utils: KVP IC version 4.0 Nov 6 00:20:50.494858 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 6 00:20:50.495439 systemd[1]: Starting update-engine.service - Update Engine... Nov 6 00:20:50.500534 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 6 00:20:50.507447 extend-filesystems[1860]: Found /dev/sda9 Nov 6 00:20:50.510727 extend-filesystems[1860]: Checking size of /dev/sda9 Nov 6 00:20:50.517606 jq[1880]: true Nov 6 00:20:50.507703 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 6 00:20:50.516325 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 6 00:20:50.516543 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 6 00:20:50.516843 systemd[1]: motdgen.service: Deactivated successfully. Nov 6 00:20:50.517029 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 6 00:20:50.524010 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 6 00:20:50.524273 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 6 00:20:50.535223 extend-filesystems[1860]: Old size kept for /dev/sda9 Nov 6 00:20:50.537741 chronyd[1851]: Timezone right/UTC failed leap second check, ignoring Nov 6 00:20:50.537918 chronyd[1851]: Loaded seccomp filter (level 2) Nov 6 00:20:50.538978 systemd[1]: Started chronyd.service - NTP client/server. Nov 6 00:20:50.543482 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 6 00:20:50.548976 (ntainerd)[1888]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 6 00:20:50.549928 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 6 00:20:50.554575 jq[1886]: true Nov 6 00:20:50.554990 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Refreshing passwd entry cache Nov 6 00:20:50.554992 oslogin_cache_refresh[1861]: Refreshing passwd entry cache Nov 6 00:20:50.563195 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Failure getting users, quitting Nov 6 00:20:50.563195 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 6 00:20:50.563183 oslogin_cache_refresh[1861]: Failure getting users, quitting Nov 6 00:20:50.563361 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Refreshing group entry cache Nov 6 00:20:50.563201 oslogin_cache_refresh[1861]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 6 00:20:50.563252 oslogin_cache_refresh[1861]: Refreshing group entry cache Nov 6 00:20:50.576805 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Failure getting groups, quitting Nov 6 00:20:50.576805 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 6 00:20:50.576796 oslogin_cache_refresh[1861]: Failure getting groups, quitting Nov 6 00:20:50.576810 oslogin_cache_refresh[1861]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 6 00:20:50.578763 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 6 00:20:50.579076 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 6 00:20:50.592157 systemd-logind[1877]: New seat seat0. Nov 6 00:20:50.593719 systemd-logind[1877]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 6 00:20:50.593905 systemd[1]: Started systemd-logind.service - User Login Management. Nov 6 00:20:50.627603 update_engine[1878]: I20251106 00:20:50.627288 1878 main.cc:92] Flatcar Update Engine starting Nov 6 00:20:50.679069 bash[1931]: Updated "/home/core/.ssh/authorized_keys" Nov 6 00:20:50.680760 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 6 00:20:50.686912 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 6 00:20:50.732664 setup-nvidia[1867]: Downloading Flatcar Container Linux Developer Container for version: 4459.1.0+nightly-20251105-2100 Nov 6 00:20:50.732852 dbus-daemon[1854]: [system] SELinux support is enabled Nov 6 00:20:50.733035 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 6 00:20:50.735611 update_engine[1878]: I20251106 00:20:50.735545 1878 update_check_scheduler.cc:74] Next update check in 4m23s Nov 6 00:20:50.739948 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 6 00:20:50.739984 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 6 00:20:50.740789 dbus-daemon[1854]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 6 00:20:50.745667 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 6 00:20:50.745690 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 6 00:20:50.751241 systemd[1]: Started update-engine.service - Update Engine. Nov 6 00:20:50.757796 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 6 00:20:50.799115 coreos-metadata[1853]: Nov 06 00:20:50.799 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 00:20:50.802118 coreos-metadata[1853]: Nov 06 00:20:50.802 INFO Fetch successful Nov 6 00:20:50.802282 coreos-metadata[1853]: Nov 06 00:20:50.802 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 6 00:20:50.806900 coreos-metadata[1853]: Nov 06 00:20:50.806 INFO Fetch successful Nov 6 00:20:50.807211 coreos-metadata[1853]: Nov 06 00:20:50.807 INFO Fetching http://168.63.129.16/machine/bb65b9a0-bfbf-4f0e-b648-f0d263174703/024b278a%2Dfe93%2D4040%2Db9b3%2D8d2bf17781ae.%5Fci%2D4459.1.0%2Dn%2D5184a1554a?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 6 00:20:50.809184 coreos-metadata[1853]: Nov 06 00:20:50.808 INFO Fetch successful Nov 6 00:20:50.809184 coreos-metadata[1853]: Nov 06 00:20:50.809 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 6 00:20:50.817189 coreos-metadata[1853]: Nov 06 00:20:50.817 INFO Fetch successful Nov 6 00:20:50.857929 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 6 00:20:50.863475 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 6 00:20:50.925383 sshd_keygen[1885]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 6 00:20:50.947145 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 6 00:20:50.953194 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 6 00:20:50.967941 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 6 00:20:50.973337 systemd[1]: issuegen.service: Deactivated successfully. Nov 6 00:20:50.973544 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 6 00:20:50.974760 locksmithd[1998]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 6 00:20:50.980896 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 6 00:20:50.991424 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 6 00:20:51.011739 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 6 00:20:51.034631 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 6 00:20:51.058108 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 6 00:20:51.064319 systemd[1]: Reached target getty.target - Login Prompts. Nov 6 00:20:51.392618 setup-nvidia[2036]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 6 00:20:51.393285 setup-nvidia[2036]: Dload Upload Total Spent Left Speed Nov 6 00:20:51.871358 containerd[1888]: time="2025-11-06T00:20:51Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 6 00:20:51.872008 containerd[1888]: time="2025-11-06T00:20:51.871971300Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 6 00:20:51.879776 containerd[1888]: time="2025-11-06T00:20:51.879739200Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" Nov 6 00:20:51.879776 containerd[1888]: time="2025-11-06T00:20:51.879768600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 6 00:20:51.879863 containerd[1888]: time="2025-11-06T00:20:51.879786600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 6 00:20:51.879947 containerd[1888]: time="2025-11-06T00:20:51.879923900Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 6 00:20:51.879988 containerd[1888]: time="2025-11-06T00:20:51.879946600Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 6 00:20:51.879988 containerd[1888]: time="2025-11-06T00:20:51.879969500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880055 containerd[1888]: time="2025-11-06T00:20:51.880032500Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880055 containerd[1888]: time="2025-11-06T00:20:51.880051500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880383 containerd[1888]: time="2025-11-06T00:20:51.880353000Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880480 containerd[1888]: time="2025-11-06T00:20:51.880455700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880570 containerd[1888]: time="2025-11-06T00:20:51.880546900Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880840 containerd[1888]: time="2025-11-06T00:20:51.880729500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 6 00:20:51.880959 containerd[1888]: time="2025-11-06T00:20:51.880930300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 6 00:20:51.881303 containerd[1888]: time="2025-11-06T00:20:51.881194400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 6 00:20:51.881303 containerd[1888]: time="2025-11-06T00:20:51.881237100Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 6 00:20:51.881303 containerd[1888]: time="2025-11-06T00:20:51.881250500Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 6 00:20:51.881303 containerd[1888]: time="2025-11-06T00:20:51.881282600Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 6 00:20:51.881509 containerd[1888]: time="2025-11-06T00:20:51.881481400Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 6 00:20:51.881582 containerd[1888]: time="2025-11-06T00:20:51.881558800Z" level=info msg="metadata content store policy set" policy=shared Nov 6 00:20:51.900278 containerd[1888]: time="2025-11-06T00:20:51.900236300Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 6 00:20:51.900330 containerd[1888]: time="2025-11-06T00:20:51.900282700Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 6 00:20:51.900330 containerd[1888]: time="2025-11-06T00:20:51.900299400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 6 00:20:51.900330 containerd[1888]: time="2025-11-06T00:20:51.900311900Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 6 00:20:51.900330 containerd[1888]: time="2025-11-06T00:20:51.900326500Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900339500Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900351300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900363100Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900373800Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900382900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900392100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 6 00:20:51.900423 containerd[1888]: time="2025-11-06T00:20:51.900403700Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 6 00:20:51.900572 containerd[1888]: time="2025-11-06T00:20:51.900523400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 6 00:20:51.900572 containerd[1888]: time="2025-11-06T00:20:51.900546200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 6 00:20:51.900572 containerd[1888]: time="2025-11-06T00:20:51.900560200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900596600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900612100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900622700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900633300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900643300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900654600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 6 00:20:51.900665 containerd[1888]: time="2025-11-06T00:20:51.900664200Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 6 00:20:51.900809 containerd[1888]: time="2025-11-06T00:20:51.900675900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 6 00:20:51.900809 containerd[1888]: time="2025-11-06T00:20:51.900734900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 6 00:20:51.900809 containerd[1888]: time="2025-11-06T00:20:51.900781800Z" level=info msg="Start snapshots syncer" Nov 6 00:20:51.900809 containerd[1888]: time="2025-11-06T00:20:51.900804800Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 6 00:20:51.901096 containerd[1888]: time="2025-11-06T00:20:51.901052100Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 6 00:20:51.901216 containerd[1888]: time="2025-11-06T00:20:51.901116200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 6 00:20:51.901216 containerd[1888]: time="2025-11-06T00:20:51.901186100Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 6 00:20:51.901310 containerd[1888]: time="2025-11-06T00:20:51.901285800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 6 00:20:51.901345 containerd[1888]: time="2025-11-06T00:20:51.901314000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 6 00:20:51.901345 containerd[1888]: time="2025-11-06T00:20:51.901326800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 6 00:20:51.901345 containerd[1888]: time="2025-11-06T00:20:51.901338200Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 6 00:20:51.901416 containerd[1888]: time="2025-11-06T00:20:51.901348700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 6 00:20:51.901416 containerd[1888]: time="2025-11-06T00:20:51.901358800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 6 00:20:51.901416 containerd[1888]: time="2025-11-06T00:20:51.901369000Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 6 00:20:51.901416 containerd[1888]: time="2025-11-06T00:20:51.901388400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 6 00:20:51.901416 containerd[1888]: time="2025-11-06T00:20:51.901398700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 6 00:20:51.901416 containerd[1888]: time="2025-11-06T00:20:51.901408900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901435300Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901448600Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901456800Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901467500Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901475100Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901484200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901493900Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901510800Z" level=info msg="runtime interface created" Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901516200Z" level=info msg="created NRI interface" Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901524700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 6 00:20:51.901544 containerd[1888]: time="2025-11-06T00:20:51.901535200Z" level=info msg="Connect containerd service" Nov 6 00:20:51.901790 containerd[1888]: time="2025-11-06T00:20:51.901558200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 6 00:20:51.902232 containerd[1888]: time="2025-11-06T00:20:51.902203400Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 6 00:20:52.508965 containerd[1888]: time="2025-11-06T00:20:52.508902000Z" level=info msg="Start subscribing containerd event" Nov 6 00:20:52.508965 containerd[1888]: time="2025-11-06T00:20:52.508962600Z" level=info msg="Start recovering state" Nov 6 00:20:52.509144 containerd[1888]: time="2025-11-06T00:20:52.509097700Z" level=info msg="Start event monitor" Nov 6 00:20:52.509144 containerd[1888]: time="2025-11-06T00:20:52.509107600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 6 00:20:52.509144 containerd[1888]: time="2025-11-06T00:20:52.509120600Z" level=info msg="Start cni network conf syncer for default" Nov 6 00:20:52.509144 containerd[1888]: time="2025-11-06T00:20:52.509141700Z" level=info msg="Start streaming server" Nov 6 00:20:52.509246 containerd[1888]: time="2025-11-06T00:20:52.509154700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 6 00:20:52.509246 containerd[1888]: time="2025-11-06T00:20:52.509163800Z" level=info msg="runtime interface starting up..." Nov 6 00:20:52.509246 containerd[1888]: time="2025-11-06T00:20:52.509170100Z" level=info msg="starting plugins..." Nov 6 00:20:52.509246 containerd[1888]: time="2025-11-06T00:20:52.509187600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 6 00:20:52.509246 containerd[1888]: time="2025-11-06T00:20:52.509166500Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 6 00:20:52.509395 containerd[1888]: time="2025-11-06T00:20:52.509330400Z" level=info msg="containerd successfully booted in 0.638491s" Nov 6 00:20:52.509456 systemd[1]: Started containerd.service - containerd container runtime. Nov 6 00:20:53.383135 waagent[2027]: 2025-11-06T00:20:53.383036Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 6 00:20:53.388120 waagent[2027]: 2025-11-06T00:20:53.388057Z INFO Daemon Daemon OS: flatcar 4459.1.0 Nov 6 00:20:53.391904 waagent[2027]: 2025-11-06T00:20:53.391840Z INFO Daemon Daemon Python: 3.11.13 Nov 6 00:20:53.395692 waagent[2027]: 2025-11-06T00:20:53.395513Z INFO Daemon Daemon Run daemon Nov 6 00:20:53.399256 waagent[2027]: 2025-11-06T00:20:53.399195Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.1.0' Nov 6 00:20:53.409437 waagent[2027]: 2025-11-06T00:20:53.409333Z INFO Daemon Daemon Using waagent for provisioning Nov 6 00:20:53.413943 waagent[2027]: 2025-11-06T00:20:53.413891Z INFO Daemon Daemon Activate resource disk Nov 6 00:20:53.418086 waagent[2027]: 2025-11-06T00:20:53.418031Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 6 00:20:53.428415 waagent[2027]: 2025-11-06T00:20:53.428316Z INFO Daemon Daemon Found device: sdb Nov 6 00:20:53.439564 waagent[2027]: 2025-11-06T00:20:53.439491Z INFO Daemon Daemon Examining partition table Nov 6 00:20:53.480710 waagent[2027]: 2025-11-06T00:20:53.480632Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 6 00:20:53.566033 waagent[2027]: 2025-11-06T00:20:53.565883Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 6 00:20:53.572134 waagent[2027]: 2025-11-06T00:20:53.572065Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 6 00:20:54.065094 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#297 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:54.079845 kernel: sdb: sdb1 Nov 6 00:20:54.079958 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#24 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:54.109959 kernel: sdb: sdb1 Nov 6 00:20:54.113097 waagent[2027]: 2025-11-06T00:20:54.113003Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 6 00:20:54.134628 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#32 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 6 00:20:54.146606 kernel: sdb: sdb1 Nov 6 00:20:54.146923 waagent[2027]: 2025-11-06T00:20:54.146859Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 6 00:20:58.817929 login[2029]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 6 00:20:58.923284 login[2030]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 6 00:20:58.933173 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 6 00:20:58.934892 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 6 00:20:58.941489 systemd-logind[1877]: New session 2 of user core. Nov 6 00:20:59.077311 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 6 00:20:59.080430 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 6 00:20:59.122418 (systemd)[2131]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 6 00:20:59.133258 systemd-logind[1877]: New session c1 of user core. Nov 6 00:20:59.818344 login[2029]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 6 00:20:59.823531 systemd-logind[1877]: New session 1 of user core. Nov 6 00:20:59.888984 waagent[2027]: 2025-11-06T00:20:59.888911Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 6 00:21:00.034030 systemd[2131]: Queued start job for default target default.target. Nov 6 00:21:00.050648 kernel: EXT4-fs (sdb1): mounted filesystem eb894c90-f5bc-4247-8456-3b1e69997a38 r/w with ordered data mode. Quota mode: none. Nov 6 00:21:00.050942 waagent[2027]: 2025-11-06T00:21:00.050850Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 6 00:21:00.076844 systemd[2131]: Created slice app.slice - User Application Slice. Nov 6 00:21:00.089832 waagent[2027]: 2025-11-06T00:21:00.053495Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 00:21:00.089832 waagent[2027]: 2025-11-06T00:21:00.055275Z INFO Daemon Daemon Running default provisioning handler Nov 6 00:21:00.089832 waagent[2027]: 2025-11-06T00:21:00.065110Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 6 00:21:00.089832 waagent[2027]: 2025-11-06T00:21:00.067468Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 6 00:21:00.089832 waagent[2027]: 2025-11-06T00:21:00.069301Z INFO Daemon Daemon cloud-init is enabled: False Nov 6 00:21:00.089832 waagent[2027]: 2025-11-06T00:21:00.070795Z INFO Daemon Daemon Copying ovf-env.xml Nov 6 00:21:00.076886 systemd[2131]: Reached target paths.target - Paths. Nov 6 00:21:00.076939 systemd[2131]: Reached target timers.target - Timers. Nov 6 00:21:00.078189 systemd[2131]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 00:21:00.088629 systemd[2131]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 00:21:00.088793 systemd[2131]: Reached target sockets.target - Sockets. Nov 6 00:21:00.088845 systemd[2131]: Reached target basic.target - Basic System. Nov 6 00:21:00.088898 systemd[2131]: Reached target default.target - Main User Target. Nov 6 00:21:00.088930 systemd[2131]: Startup finished in 943ms. Nov 6 00:21:00.088951 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 6 00:21:00.090239 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 6 00:21:00.091017 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 6 00:21:00.249120 waagent[2027]: 2025-11-06T00:21:00.249031Z INFO Daemon Daemon Successfully mounted dvd Nov 6 00:21:00.263247 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 6 00:21:00.264293 waagent[2027]: 2025-11-06T00:21:00.264229Z INFO Daemon Daemon Detect protocol endpoint Nov 6 00:21:00.266046 waagent[2027]: 2025-11-06T00:21:00.265997Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 00:21:00.269916 waagent[2027]: 2025-11-06T00:21:00.269864Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 6 00:21:00.270726 waagent[2027]: 2025-11-06T00:21:00.270689Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 6 00:21:00.271764 waagent[2027]: 2025-11-06T00:21:00.271660Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 6 00:21:00.272403 waagent[2027]: 2025-11-06T00:21:00.272363Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 6 00:21:00.283449 waagent[2027]: 2025-11-06T00:21:00.283403Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 6 00:21:00.285136 waagent[2027]: 2025-11-06T00:21:00.285105Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 6 00:21:00.286752 waagent[2027]: 2025-11-06T00:21:00.286506Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 6 00:21:00.375924 waagent[2027]: 2025-11-06T00:21:00.375775Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 6 00:21:00.377655 waagent[2027]: 2025-11-06T00:21:00.377605Z INFO Daemon Daemon Forcing an update of the goal state. Nov 6 00:21:00.396911 waagent[2027]: 2025-11-06T00:21:00.396842Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 00:21:00.435200 waagent[2027]: 2025-11-06T00:21:00.435141Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 6 00:21:00.437361 waagent[2027]: 2025-11-06T00:21:00.437318Z INFO Daemon Nov 6 00:21:00.438498 waagent[2027]: 2025-11-06T00:21:00.438458Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 50c1561f-620a-44b3-84d0-92b26ea314aa eTag: 13796837508848210218 source: Fabric] Nov 6 00:21:00.439592 waagent[2027]: 2025-11-06T00:21:00.439548Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 6 00:21:00.440495 waagent[2027]: 2025-11-06T00:21:00.440454Z INFO Daemon Nov 6 00:21:00.441811 waagent[2027]: 2025-11-06T00:21:00.441762Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 6 00:21:00.446160 waagent[2027]: 2025-11-06T00:21:00.446119Z INFO Daemon Daemon Downloading artifacts profile blob Nov 6 00:21:00.581265 waagent[2027]: 2025-11-06T00:21:00.581182Z INFO Daemon Downloaded certificate {'thumbprint': '69B83A37802E2781697E7EBDE5113E728D31E1E2', 'hasPrivateKey': True} Nov 6 00:21:00.583545 waagent[2027]: 2025-11-06T00:21:00.583495Z INFO Daemon Fetch goal state completed Nov 6 00:21:00.617906 waagent[2027]: 2025-11-06T00:21:00.617824Z INFO Daemon Daemon Starting provisioning Nov 6 00:21:00.619594 waagent[2027]: 2025-11-06T00:21:00.619531Z INFO Daemon Daemon Handle ovf-env.xml. Nov 6 00:21:00.620664 waagent[2027]: 2025-11-06T00:21:00.620315Z INFO Daemon Daemon Set hostname [ci-4459.1.0-n-5184a1554a] Nov 6 00:21:00.624264 waagent[2027]: 2025-11-06T00:21:00.624223Z INFO Daemon Daemon Publish hostname [ci-4459.1.0-n-5184a1554a] Nov 6 00:21:00.626059 waagent[2027]: 2025-11-06T00:21:00.625974Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 6 00:21:00.627695 waagent[2027]: 2025-11-06T00:21:00.627646Z INFO Daemon Daemon Primary interface is [eth0] Nov 6 00:21:00.658153 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 00:21:00.658163 systemd-networkd[1566]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 00:21:00.658195 systemd-networkd[1566]: eth0: DHCP lease lost Nov 6 00:21:00.658940 waagent[2027]: 2025-11-06T00:21:00.658862Z INFO Daemon Daemon Create user account if not exists Nov 6 00:21:00.670555 waagent[2027]: 2025-11-06T00:21:00.660557Z INFO Daemon Daemon User core already exists, skip useradd Nov 6 00:21:00.670555 waagent[2027]: 2025-11-06T00:21:00.661179Z INFO Daemon Daemon Configure sudoer Nov 6 00:21:00.670555 waagent[2027]: 2025-11-06T00:21:00.667929Z INFO Daemon Daemon Configure sshd Nov 6 00:21:00.681095 waagent[2027]: 2025-11-06T00:21:00.681008Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 6 00:21:00.682948 waagent[2027]: 2025-11-06T00:21:00.682863Z INFO Daemon Daemon Deploy ssh public key. Nov 6 00:21:00.728569 systemd-networkd[1566]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 6 00:21:01.812985 waagent[2027]: 2025-11-06T00:21:01.812913Z INFO Daemon Daemon Provisioning complete Nov 6 00:21:01.825202 waagent[2027]: 2025-11-06T00:21:01.825141Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 6 00:21:01.827794 waagent[2027]: 2025-11-06T00:21:01.827745Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 6 00:21:01.829378 waagent[2027]: 2025-11-06T00:21:01.829328Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 6 00:21:02.037940 waagent[2189]: 2025-11-06T00:21:02.037845Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 6 00:21:02.038296 waagent[2189]: 2025-11-06T00:21:02.037997Z INFO ExtHandler ExtHandler OS: flatcar 4459.1.0 Nov 6 00:21:02.038296 waagent[2189]: 2025-11-06T00:21:02.038067Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 6 00:21:02.038296 waagent[2189]: 2025-11-06T00:21:02.038136Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 6 00:21:02.106660 waagent[2189]: 2025-11-06T00:21:02.106511Z INFO ExtHandler ExtHandler Distro: flatcar-4459.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 6 00:21:02.106834 waagent[2189]: 2025-11-06T00:21:02.106793Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 00:21:02.106918 waagent[2189]: 2025-11-06T00:21:02.106883Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 00:21:02.115291 waagent[2189]: 2025-11-06T00:21:02.115225Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 00:21:02.120218 waagent[2189]: 2025-11-06T00:21:02.120168Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 6 00:21:02.120692 waagent[2189]: 2025-11-06T00:21:02.120646Z INFO ExtHandler Nov 6 00:21:02.120770 waagent[2189]: 2025-11-06T00:21:02.120737Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: c1470faf-4ec9-454d-a4b5-b252fe2d5313 eTag: 13796837508848210218 source: Fabric] Nov 6 00:21:02.121094 waagent[2189]: 2025-11-06T00:21:02.121051Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 6 00:21:02.121746 waagent[2189]: 2025-11-06T00:21:02.121705Z INFO ExtHandler Nov 6 00:21:02.121818 waagent[2189]: 2025-11-06T00:21:02.121786Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 6 00:21:02.125514 waagent[2189]: 2025-11-06T00:21:02.125471Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 6 00:21:02.190800 waagent[2189]: 2025-11-06T00:21:02.190722Z INFO ExtHandler Downloaded certificate {'thumbprint': '69B83A37802E2781697E7EBDE5113E728D31E1E2', 'hasPrivateKey': True} Nov 6 00:21:02.191337 waagent[2189]: 2025-11-06T00:21:02.191291Z INFO ExtHandler Fetch goal state completed Nov 6 00:21:02.203310 waagent[2189]: 2025-11-06T00:21:02.203256Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 6 00:21:02.208457 waagent[2189]: 2025-11-06T00:21:02.208407Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2189 Nov 6 00:21:02.208606 waagent[2189]: 2025-11-06T00:21:02.208555Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 6 00:21:02.208948 waagent[2189]: 2025-11-06T00:21:02.208912Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 6 00:21:02.210584 waagent[2189]: 2025-11-06T00:21:02.210539Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] Nov 6 00:21:02.211014 waagent[2189]: 2025-11-06T00:21:02.210977Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 6 00:21:02.211166 waagent[2189]: 2025-11-06T00:21:02.211131Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 6 00:21:02.211781 waagent[2189]: 2025-11-06T00:21:02.211743Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 6 00:21:02.332095 waagent[2189]: 2025-11-06T00:21:02.332034Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 6 00:21:02.332298 waagent[2189]: 2025-11-06T00:21:02.332260Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 6 00:21:02.342558 waagent[2189]: 2025-11-06T00:21:02.342519Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 6 00:21:02.354182 systemd[1]: Reload requested from client PID 2204 ('systemctl') (unit waagent.service)... Nov 6 00:21:02.354204 systemd[1]: Reloading... Nov 6 00:21:02.422623 zram_generator::config[2244]: No configuration found. Nov 6 00:21:02.738492 systemd[1]: Reloading finished in 383 ms. Nov 6 00:21:02.760024 waagent[2189]: 2025-11-06T00:21:02.759948Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 6 00:21:02.760135 waagent[2189]: 2025-11-06T00:21:02.760101Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 6 00:21:03.455437 waagent[2189]: 2025-11-06T00:21:03.455342Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 6 00:21:03.455843 waagent[2189]: 2025-11-06T00:21:03.455728Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 6 00:21:03.456621 waagent[2189]: 2025-11-06T00:21:03.456553Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 6 00:21:03.456987 waagent[2189]: 2025-11-06T00:21:03.456924Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 00:21:03.457212 waagent[2189]: 2025-11-06T00:21:03.457167Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 6 00:21:03.457363 waagent[2189]: 2025-11-06T00:21:03.457322Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 00:21:03.457540 waagent[2189]: 2025-11-06T00:21:03.457493Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 00:21:03.457940 waagent[2189]: 2025-11-06T00:21:03.457890Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 6 00:21:03.458268 waagent[2189]: 2025-11-06T00:21:03.458223Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 00:21:03.458356 waagent[2189]: 2025-11-06T00:21:03.458304Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 6 00:21:03.458441 waagent[2189]: 2025-11-06T00:21:03.458393Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 6 00:21:03.459058 waagent[2189]: 2025-11-06T00:21:03.459007Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 6 00:21:03.459058 waagent[2189]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 6 00:21:03.459058 waagent[2189]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Nov 6 00:21:03.459058 waagent[2189]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 6 00:21:03.459058 waagent[2189]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 6 00:21:03.459058 waagent[2189]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 00:21:03.459058 waagent[2189]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 00:21:03.459336 waagent[2189]: 2025-11-06T00:21:03.459287Z INFO EnvHandler ExtHandler Configure routes Nov 6 00:21:03.459497 waagent[2189]: 2025-11-06T00:21:03.459443Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 6 00:21:03.459748 waagent[2189]: 2025-11-06T00:21:03.459697Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 6 00:21:03.460306 waagent[2189]: 2025-11-06T00:21:03.460260Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 6 00:21:03.460427 waagent[2189]: 2025-11-06T00:21:03.460382Z INFO EnvHandler ExtHandler Gateway:None Nov 6 00:21:03.461205 waagent[2189]: 2025-11-06T00:21:03.461159Z INFO EnvHandler ExtHandler Routes:None Nov 6 00:21:03.467208 waagent[2189]: 2025-11-06T00:21:03.467155Z INFO ExtHandler ExtHandler Nov 6 00:21:03.467603 waagent[2189]: 2025-11-06T00:21:03.467547Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 8a2f17ca-66df-4ff2-a94a-c4ef0f4031f2 correlation 418322cc-3398-4cd6-8f57-778f59320aa1 created: 2025-11-06T00:19:36.384125Z] Nov 6 00:21:03.469189 waagent[2189]: 2025-11-06T00:21:03.469143Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 6 00:21:03.472591 waagent[2189]: 2025-11-06T00:21:03.472522Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 5 ms] Nov 6 00:21:03.575496 waagent[2189]: 2025-11-06T00:21:03.571624Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 6 00:21:03.575496 waagent[2189]: Try `iptables -h' or 'iptables --help' for more information.) Nov 6 00:21:03.575496 waagent[2189]: 2025-11-06T00:21:03.572118Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: E96C8186-4D63-4299-90F1-05CA522BA8BD;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 6 00:21:03.640964 waagent[2189]: 2025-11-06T00:21:03.640882Z INFO MonitorHandler ExtHandler Network interfaces: Nov 6 00:21:03.640964 waagent[2189]: Executing ['ip', '-a', '-o', 'link']: Nov 6 00:21:03.640964 waagent[2189]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 6 00:21:03.640964 waagent[2189]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:75:e8:bb brd ff:ff:ff:ff:ff:ff Nov 6 00:21:03.640964 waagent[2189]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 6 00:21:03.640964 waagent[2189]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 6 00:21:03.640964 waagent[2189]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 6 00:21:03.640964 waagent[2189]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 6 00:21:03.640964 waagent[2189]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 6 00:21:03.640964 waagent[2189]: 2: eth0 inet6 fe80::7e1e:52ff:fe75:e8bb/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 6 00:21:03.924301 waagent[2189]: 2025-11-06T00:21:03.923772Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 6 00:21:03.924301 waagent[2189]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 00:21:03.924301 waagent[2189]: pkts bytes target prot opt in out source destination Nov 6 00:21:03.924301 waagent[2189]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 00:21:03.924301 waagent[2189]: pkts bytes target prot opt in out source destination Nov 6 00:21:03.924301 waagent[2189]: Chain OUTPUT (policy ACCEPT 14 packets, 728 bytes) Nov 6 00:21:03.924301 waagent[2189]: pkts bytes target prot opt in out source destination Nov 6 00:21:03.924301 waagent[2189]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 00:21:03.924301 waagent[2189]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 00:21:03.924301 waagent[2189]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 00:21:03.929280 waagent[2189]: 2025-11-06T00:21:03.929227Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 6 00:21:03.929280 waagent[2189]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 00:21:03.929280 waagent[2189]: pkts bytes target prot opt in out source destination Nov 6 00:21:03.929280 waagent[2189]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 00:21:03.929280 waagent[2189]: pkts bytes target prot opt in out source destination Nov 6 00:21:03.929280 waagent[2189]: Chain OUTPUT (policy ACCEPT 18 packets, 936 bytes) Nov 6 00:21:03.929280 waagent[2189]: pkts bytes target prot opt in out source destination Nov 6 00:21:03.929280 waagent[2189]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 00:21:03.929280 waagent[2189]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 00:21:03.929280 waagent[2189]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 00:21:03.929561 waagent[2189]: 2025-11-06T00:21:03.929516Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 6 00:21:03.929718 waagent[2189]: 2025-11-06T00:21:03.929686Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 6 00:21:14.323738 chronyd[1851]: Selected source PHC0 Nov 6 00:21:16.558326 setup-nvidia[2036]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 530M 0 400k 0 0 275k 0 0:32:50 0:00:01 0:32:49 275k\u000d 3 530M 3 20.0M 0 0 7996k 0 0:01:07 0:00:02 0:01:05 7996k\u000d 8 530M 8 45.1M 0 0 12.7M 0 0:00:41 0:00:03 0:00:38 12.7M\u000d 12 530M 12 65.1M 0 0 14.3M 0 0:00:36 0:00:04 0:00:32 14.3M\u000d 15 530M 15 82.6M 0 0 15.0M 0 0:00:35 0:00:05 0:00:30 16.5M\u000d 19 530M 19 105M 0 0 16.3M 0 0:00:32 0:00:06 0:00:26 20.9M\u000d 24 530M 24 128M 0 0 17.3M 0 0:00:30 0:00:07 0:00:23 22.3M\u000d 27 530M 27 147M 0 0 17.3M 0 0:00:30 0:00:08 0:00:22 20.6M\u000d 31 530M 31 167M 0 0 17.5M 0 0:00:30 0:00:09 0:00:21 20.4M\u000d 35 530M 35 187M 0 0 17.8M 0 0:00:29 0:00:10 0:00:19 21.0M\u000d 39 530M 39 209M 0 0 18.3M 0 0:00:28 0:00:11 0:00:17 20.9M\u000d 43 530M 43 232M 0 0 18.5M 0 0:00:28 0:00:12 0:00:16 20.4M\u000d 47 530M 47 254M 0 0 18.9M 0 0:00:28 0:00:13 0:00:15 21.6M\u000d 52 530M 52 278M 0 0 19.2M 0 0:00:27 0:00:14 0:00:13 22.5M\u000d 56 530M 56 301M 0 0 19.4M 0 0:00:27 0:00:15 0:00:12 22.8M\u000d 61 530M 61 325M 0 0 19.7M 0 0:00:26 0:00:16 0:00:10 22.9M\u000d 65 530M 65 348M 0 0 19.9M 0 0:00:26 0:00:17 0:00:09 23.4M\u000d 70 530M 70 372M 0 0 20.1M 0 0:00:26 0:00:18 0:00:08 23.5M\u000d 74 530M 74 395M 0 0 20.3M 0 0:00:26 0:00:19 0:00:07 23.3M\u000d 78 530M 78 418M 0 0 20.4M 0 0:00:25 0:00:20 0:00:05 23.4M\u000d 83 530M 83 442M 0 0 20.5M 0 0:00:25 0:00:21 0:00:04 23.3M\u000d 87 530M 87 465M 0 0 20.7M 0 0:00:25 0:00:22 0:00:03 23.3M\u000d 92 530M 92 489M 0 0 20.8M 0 0:00:25 0:00:23 0:00:02 23.2M\u000d 96 530M 96 512M 0 0 20.9M 0 0:00:25 0:00:24 0:00:01 23.4M\u000d100 530M 100 530M 0 0 21.0M 0 0:00:25 0:00:25 --:--:-- 23.7M Nov 6 00:21:22.740021 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 6 00:21:22.746796 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:55510.service - OpenSSH per-connection server daemon (10.200.16.10:55510). Nov 6 00:21:23.541761 sshd[2347]: Accepted publickey for core from 10.200.16.10 port 55510 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:23.543209 sshd-session[2347]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:23.549988 systemd-logind[1877]: New session 3 of user core. Nov 6 00:21:23.556778 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 6 00:21:24.111668 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:55514.service - OpenSSH per-connection server daemon (10.200.16.10:55514). Nov 6 00:21:24.770698 sshd[2353]: Accepted publickey for core from 10.200.16.10 port 55514 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:24.772066 sshd-session[2353]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:24.779919 systemd-logind[1877]: New session 4 of user core. Nov 6 00:21:24.789830 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 6 00:21:25.223605 sshd[2356]: Connection closed by 10.200.16.10 port 55514 Nov 6 00:21:25.224769 sshd-session[2353]: pam_unix(sshd:session): session closed for user core Nov 6 00:21:25.230025 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:55514.service: Deactivated successfully. Nov 6 00:21:25.232065 systemd-logind[1877]: Session 4 logged out. Waiting for processes to exit. Nov 6 00:21:25.235049 systemd[1]: session-4.scope: Deactivated successfully. Nov 6 00:21:25.237042 systemd-logind[1877]: Removed session 4. Nov 6 00:21:25.338866 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:55520.service - OpenSSH per-connection server daemon (10.200.16.10:55520). Nov 6 00:21:25.999530 sshd[2362]: Accepted publickey for core from 10.200.16.10 port 55520 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:27.027896 sshd-session[2362]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:27.036365 systemd-logind[1877]: New session 5 of user core. Nov 6 00:21:27.043837 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 6 00:21:27.421732 sshd[2365]: Connection closed by 10.200.16.10 port 55520 Nov 6 00:21:27.422066 sshd-session[2362]: pam_unix(sshd:session): session closed for user core Nov 6 00:21:27.427716 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:55520.service: Deactivated successfully. Nov 6 00:21:27.431383 systemd[1]: session-5.scope: Deactivated successfully. Nov 6 00:21:27.432765 systemd-logind[1877]: Session 5 logged out. Waiting for processes to exit. Nov 6 00:21:27.436101 systemd-logind[1877]: Removed session 5. Nov 6 00:21:29.660900 setup-nvidia[1867]: Downloading NVIDIA 535.261.03 Driver Nov 6 00:21:29.665291 setup-nvidia[2371]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 6 00:21:29.665569 setup-nvidia[2371]: Dload Upload Total Spent Left Speed Nov 6 00:21:32.193075 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 6 00:21:32.939850 setup-nvidia[2371]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 49152 0 0 92706 0 1:01:26 --:--:-- 1:01:26 92739\u000d 20 325M 20 67.6M 0 0 57.6M 0 0:00:05 0:00:01 0:00:04 57.6M\u000d 53 325M 53 173M 0 0 79.9M 0 0:00:04 0:00:02 0:00:02 79.8M\u000d 95 325M 95 309M 0 0 97.5M 0 0:00:03 0:00:03 --:--:-- 97.5M\u000d100 325M 100 325M 0 0 99.5M 0 0:00:03 0:00:03 --:--:-- 99.5M Nov 6 00:21:33.019723 setup-nvidia[1867]: Extract the NVIDIA Driver Installer 535.261.03 Nov 6 00:21:33.019723 setup-nvidia[1867]: /opt/nvidia/workdir/nvidia-workdir / Nov 6 00:21:33.069596 setup-nvidia[2374]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Nov 6 00:21:33.388596 setup-nvidia[2374]: Verifying archive integrity... OK Nov 6 00:21:34.198068 setup-nvidia[2374]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Nov 6 00:21:35.517796 update_engine[1878]: I20251106 00:21:35.517712 1878 update_attempter.cc:509] Updating boot flags... Nov 6 00:21:36.406714 setup-nvidia[2400]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 6 00:21:36.505532 setup-nvidia[1867]: / Nov 6 00:21:36.505532 setup-nvidia[1867]: Spawn system-nspawn container to install the NVIDIA drivers Nov 6 00:21:36.667326 sudo[2523]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.1.0+nightly-20251105-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Nov 6 00:21:36.669178 sudo[2523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 6 00:21:36.675064 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 6 00:21:36.679787 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 6 00:21:36.717396 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 6 00:21:36.721664 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 6 00:21:36.742404 (systemd)[2526]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 6 00:21:36.745052 systemd-logind[1877]: New session c3 of user root. Nov 6 00:21:36.933044 systemd[2526]: Queued start job for default target default.target. Nov 6 00:21:36.949909 systemd[2526]: Created slice app.slice - User Application Slice. Nov 6 00:21:36.949943 systemd[2526]: Reached target paths.target - Paths. Nov 6 00:21:36.949995 systemd[2526]: Reached target timers.target - Timers. Nov 6 00:21:36.951229 systemd[2526]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 00:21:36.963315 systemd[2526]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 00:21:36.963449 systemd[2526]: Reached target sockets.target - Sockets. Nov 6 00:21:36.963504 systemd[2526]: Reached target basic.target - Basic System. Nov 6 00:21:36.963551 systemd[2526]: Reached target default.target - Main User Target. Nov 6 00:21:36.963599 systemd[2526]: Startup finished in 207ms. Nov 6 00:21:36.963728 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 6 00:21:36.967140 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 6 00:21:37.075608 kernel: loop6: detected capacity change from 0 to 12644352 Nov 6 00:21:37.196611 kernel: loop6: p9 Nov 6 00:21:37.325227 setup-nvidia[2535]: ░ Spawning container flatcardevelopercontainer-4459.1.0nightly-20251105-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.1.0+nightly-20251105-2100.bin. Nov 6 00:21:37.534179 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:49782.service - OpenSSH per-connection server daemon (10.200.16.10:49782). Nov 6 00:21:37.735784 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Nov 6 00:21:37.735916 kernel: EXT4-fs (loop6p9): mounted filesystem 1e39b452-972d-46d5-b7a7-0dccbb4f0913 ro without journal. Quota mode: none. Nov 6 00:21:38.190760 sshd[2539]: Accepted publickey for core from 10.200.16.10 port 49782 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:38.192036 sshd-session[2539]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:38.209821 systemd-logind[1877]: New session 6 of user core. Nov 6 00:21:38.213772 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 6 00:21:38.638252 sshd[2544]: Connection closed by 10.200.16.10 port 49782 Nov 6 00:21:38.638664 sshd-session[2539]: pam_unix(sshd:session): session closed for user core Nov 6 00:21:38.642396 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:49782.service: Deactivated successfully. Nov 6 00:21:38.644271 systemd[1]: session-6.scope: Deactivated successfully. Nov 6 00:21:38.645244 systemd-logind[1877]: Session 6 logged out. Waiting for processes to exit. Nov 6 00:21:38.646397 systemd-logind[1877]: Removed session 6. Nov 6 00:21:38.765249 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:49794.service - OpenSSH per-connection server daemon (10.200.16.10:49794). Nov 6 00:21:39.420161 sshd[2550]: Accepted publickey for core from 10.200.16.10 port 49794 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:39.421471 sshd-session[2550]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:39.427033 systemd-logind[1877]: New session 7 of user core. Nov 6 00:21:39.446760 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 6 00:21:39.867335 sshd[2553]: Connection closed by 10.200.16.10 port 49794 Nov 6 00:21:39.867756 sshd-session[2550]: pam_unix(sshd:session): session closed for user core Nov 6 00:21:39.871478 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:49794.service: Deactivated successfully. Nov 6 00:21:39.873100 systemd[1]: session-7.scope: Deactivated successfully. Nov 6 00:21:39.873992 systemd-logind[1877]: Session 7 logged out. Waiting for processes to exit. Nov 6 00:21:39.875302 systemd-logind[1877]: Removed session 7. Nov 6 00:21:49.986129 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:59620.service - OpenSSH per-connection server daemon (10.200.16.10:59620). Nov 6 00:21:50.658841 sshd[2615]: Accepted publickey for core from 10.200.16.10 port 59620 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:50.660093 sshd-session[2615]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:50.664397 systemd-logind[1877]: New session 8 of user core. Nov 6 00:21:50.685853 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 6 00:21:51.106530 sshd[2618]: Connection closed by 10.200.16.10 port 59620 Nov 6 00:21:51.106939 sshd-session[2615]: pam_unix(sshd:session): session closed for user core Nov 6 00:21:51.110910 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:59620.service: Deactivated successfully. Nov 6 00:21:51.112743 systemd[1]: session-8.scope: Deactivated successfully. Nov 6 00:21:51.113497 systemd-logind[1877]: Session 8 logged out. Waiting for processes to exit. Nov 6 00:21:51.114430 systemd-logind[1877]: Removed session 8. Nov 6 00:21:51.230023 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:59632.service - OpenSSH per-connection server daemon (10.200.16.10:59632). Nov 6 00:21:51.895765 sshd[2624]: Accepted publickey for core from 10.200.16.10 port 59632 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:21:51.897153 sshd-session[2624]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:21:51.902333 systemd-logind[1877]: New session 9 of user core. Nov 6 00:21:51.923139 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 6 00:21:52.485256 sshd[2627]: Connection closed by 10.200.16.10 port 59632 Nov 6 00:21:52.485368 sshd-session[2624]: pam_unix(sshd:session): session closed for user core Nov 6 00:21:52.489278 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:59632.service: Deactivated successfully. Nov 6 00:21:52.491860 systemd[1]: session-9.scope: Deactivated successfully. Nov 6 00:21:52.492570 systemd-logind[1877]: Session 9 logged out. Waiting for processes to exit. Nov 6 00:21:52.493684 systemd-logind[1877]: Removed session 9. Nov 6 00:22:02.469924 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:57976.service - OpenSSH per-connection server daemon (10.200.16.10:57976). Nov 6 00:22:03.102919 sshd[3354]: Accepted publickey for core from 10.200.16.10 port 57976 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:03.104190 sshd-session[3354]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:03.109032 systemd-logind[1877]: New session 10 of user core. Nov 6 00:22:03.131834 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 6 00:22:03.549599 sshd[3357]: Connection closed by 10.200.16.10 port 57976 Nov 6 00:22:03.549989 sshd-session[3354]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:03.553095 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:57976.service: Deactivated successfully. Nov 6 00:22:03.554968 systemd[1]: session-10.scope: Deactivated successfully. Nov 6 00:22:03.556319 systemd-logind[1877]: Session 10 logged out. Waiting for processes to exit. Nov 6 00:22:03.557376 systemd-logind[1877]: Removed session 10. Nov 6 00:22:03.674038 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:57978.service - OpenSSH per-connection server daemon (10.200.16.10:57978). Nov 6 00:22:04.308765 sshd[3363]: Accepted publickey for core from 10.200.16.10 port 57978 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:04.310131 sshd-session[3363]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:04.314725 systemd-logind[1877]: New session 11 of user core. Nov 6 00:22:04.322761 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 6 00:22:04.754694 sshd[3366]: Connection closed by 10.200.16.10 port 57978 Nov 6 00:22:04.755070 sshd-session[3363]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:04.758658 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:57978.service: Deactivated successfully. Nov 6 00:22:04.760284 systemd[1]: session-11.scope: Deactivated successfully. Nov 6 00:22:04.760979 systemd-logind[1877]: Session 11 logged out. Waiting for processes to exit. Nov 6 00:22:04.761903 systemd-logind[1877]: Removed session 11. Nov 6 00:22:14.878856 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:51932.service - OpenSSH per-connection server daemon (10.200.16.10:51932). Nov 6 00:22:15.528702 sshd[3598]: Accepted publickey for core from 10.200.16.10 port 51932 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:15.529994 sshd-session[3598]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:15.554290 systemd-logind[1877]: New session 12 of user core. Nov 6 00:22:15.563807 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 6 00:22:15.995185 sshd[3929]: Connection closed by 10.200.16.10 port 51932 Nov 6 00:22:15.991943 sshd-session[3598]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:15.996442 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:51932.service: Deactivated successfully. Nov 6 00:22:15.998504 systemd[1]: session-12.scope: Deactivated successfully. Nov 6 00:22:15.999375 systemd-logind[1877]: Session 12 logged out. Waiting for processes to exit. Nov 6 00:22:16.000529 systemd-logind[1877]: Removed session 12. Nov 6 00:22:16.118817 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:51936.service - OpenSSH per-connection server daemon (10.200.16.10:51936). Nov 6 00:22:16.761148 sshd[4192]: Accepted publickey for core from 10.200.16.10 port 51936 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:16.767236 sshd-session[4192]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:16.772545 systemd-logind[1877]: New session 13 of user core. Nov 6 00:22:16.783935 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 6 00:22:17.210520 sshd[4527]: Connection closed by 10.200.16.10 port 51936 Nov 6 00:22:17.210885 sshd-session[4192]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:17.214726 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:51936.service: Deactivated successfully. Nov 6 00:22:17.216326 systemd[1]: session-13.scope: Deactivated successfully. Nov 6 00:22:17.217090 systemd-logind[1877]: Session 13 logged out. Waiting for processes to exit. Nov 6 00:22:17.218097 systemd-logind[1877]: Removed session 13. Nov 6 00:22:27.329807 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:37288.service - OpenSSH per-connection server daemon (10.200.16.10:37288). Nov 6 00:22:27.981141 sshd[8591]: Accepted publickey for core from 10.200.16.10 port 37288 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:27.983102 sshd-session[8591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:27.991275 systemd-logind[1877]: New session 14 of user core. Nov 6 00:22:27.999733 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 6 00:22:28.426053 sshd[8852]: Connection closed by 10.200.16.10 port 37288 Nov 6 00:22:28.429664 sshd-session[8591]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:28.433160 systemd-logind[1877]: Session 14 logged out. Waiting for processes to exit. Nov 6 00:22:28.433290 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:37288.service: Deactivated successfully. Nov 6 00:22:28.435108 systemd[1]: session-14.scope: Deactivated successfully. Nov 6 00:22:28.438836 systemd-logind[1877]: Removed session 14. Nov 6 00:22:28.556712 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:37298.service - OpenSSH per-connection server daemon (10.200.16.10:37298). Nov 6 00:22:29.213471 sshd[9088]: Accepted publickey for core from 10.200.16.10 port 37298 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:29.214872 sshd-session[9088]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:29.222541 systemd-logind[1877]: New session 15 of user core. Nov 6 00:22:29.229230 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 6 00:22:29.677758 sshd[9192]: Connection closed by 10.200.16.10 port 37298 Nov 6 00:22:29.678768 sshd-session[9088]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:29.685931 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:37298.service: Deactivated successfully. Nov 6 00:22:29.686655 systemd-logind[1877]: Session 15 logged out. Waiting for processes to exit. Nov 6 00:22:29.688680 systemd[1]: session-15.scope: Deactivated successfully. Nov 6 00:22:29.691964 systemd-logind[1877]: Removed session 15. Nov 6 00:22:39.800853 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:34744.service - OpenSSH per-connection server daemon (10.200.16.10:34744). Nov 6 00:22:40.457702 sshd[12916]: Accepted publickey for core from 10.200.16.10 port 34744 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:40.459111 sshd-session[12916]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:40.464948 systemd-logind[1877]: New session 16 of user core. Nov 6 00:22:40.472557 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 6 00:22:40.912457 sshd[13159]: Connection closed by 10.200.16.10 port 34744 Nov 6 00:22:40.912557 sshd-session[12916]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:40.916712 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:34744.service: Deactivated successfully. Nov 6 00:22:40.918566 systemd[1]: session-16.scope: Deactivated successfully. Nov 6 00:22:40.919003 systemd-logind[1877]: Session 16 logged out. Waiting for processes to exit. Nov 6 00:22:40.922963 systemd-logind[1877]: Removed session 16. Nov 6 00:22:41.028480 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:48248.service - OpenSSH per-connection server daemon (10.200.16.10:48248). Nov 6 00:22:41.668450 sshd[13374]: Accepted publickey for core from 10.200.16.10 port 48248 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:41.670279 sshd-session[13374]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:41.677046 systemd-logind[1877]: New session 17 of user core. Nov 6 00:22:41.689845 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 6 00:22:42.126862 sshd[13597]: Connection closed by 10.200.16.10 port 48248 Nov 6 00:22:42.127225 sshd-session[13374]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:42.131353 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:48248.service: Deactivated successfully. Nov 6 00:22:42.133008 systemd[1]: session-17.scope: Deactivated successfully. Nov 6 00:22:42.133786 systemd-logind[1877]: Session 17 logged out. Waiting for processes to exit. Nov 6 00:22:42.135183 systemd-logind[1877]: Removed session 17. Nov 6 00:22:52.239266 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:33864.service - OpenSSH per-connection server daemon (10.200.16.10:33864). Nov 6 00:22:52.901166 sshd[14415]: Accepted publickey for core from 10.200.16.10 port 33864 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:52.905774 sshd-session[14415]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:52.917669 systemd-logind[1877]: New session 18 of user core. Nov 6 00:22:52.920795 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 6 00:22:53.354247 sshd[14435]: Connection closed by 10.200.16.10 port 33864 Nov 6 00:22:53.354636 sshd-session[14415]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:53.358009 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:33864.service: Deactivated successfully. Nov 6 00:22:53.359929 systemd[1]: session-18.scope: Deactivated successfully. Nov 6 00:22:53.363052 systemd-logind[1877]: Session 18 logged out. Waiting for processes to exit. Nov 6 00:22:53.364696 systemd-logind[1877]: Removed session 18. Nov 6 00:22:53.481879 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:33870.service - OpenSSH per-connection server daemon (10.200.16.10:33870). Nov 6 00:22:54.139423 sshd[14459]: Accepted publickey for core from 10.200.16.10 port 33870 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:22:54.141224 sshd-session[14459]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:22:54.153261 systemd-logind[1877]: New session 19 of user core. Nov 6 00:22:54.157753 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 6 00:22:54.611431 sshd[14486]: Connection closed by 10.200.16.10 port 33870 Nov 6 00:22:54.612036 sshd-session[14459]: pam_unix(sshd:session): session closed for user core Nov 6 00:22:54.616060 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:33870.service: Deactivated successfully. Nov 6 00:22:54.617959 systemd[1]: session-19.scope: Deactivated successfully. Nov 6 00:22:54.618907 systemd-logind[1877]: Session 19 logged out. Waiting for processes to exit. Nov 6 00:22:54.622415 systemd-logind[1877]: Removed session 19. Nov 6 00:23:04.725760 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:40528.service - OpenSSH per-connection server daemon (10.200.16.10:40528). Nov 6 00:23:05.405719 sshd[14810]: Accepted publickey for core from 10.200.16.10 port 40528 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:05.407966 sshd-session[14810]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:05.419726 systemd-logind[1877]: New session 20 of user core. Nov 6 00:23:05.428961 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 6 00:23:05.852292 sshd[14831]: Connection closed by 10.200.16.10 port 40528 Nov 6 00:23:05.852708 sshd-session[14810]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:05.856831 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:40528.service: Deactivated successfully. Nov 6 00:23:05.858539 systemd[1]: session-20.scope: Deactivated successfully. Nov 6 00:23:05.862156 systemd-logind[1877]: Session 20 logged out. Waiting for processes to exit. Nov 6 00:23:05.866853 systemd-logind[1877]: Removed session 20. Nov 6 00:23:05.967405 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:40544.service - OpenSSH per-connection server daemon (10.200.16.10:40544). Nov 6 00:23:06.623027 sshd[14843]: Accepted publickey for core from 10.200.16.10 port 40544 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:06.624443 sshd-session[14843]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:06.639658 systemd-logind[1877]: New session 21 of user core. Nov 6 00:23:06.646829 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 6 00:23:07.076683 sshd[14864]: Connection closed by 10.200.16.10 port 40544 Nov 6 00:23:07.077057 sshd-session[14843]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:07.081212 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:40544.service: Deactivated successfully. Nov 6 00:23:07.082973 systemd[1]: session-21.scope: Deactivated successfully. Nov 6 00:23:07.083347 systemd-logind[1877]: Session 21 logged out. Waiting for processes to exit. Nov 6 00:23:07.087033 systemd-logind[1877]: Removed session 21. Nov 6 00:23:17.201408 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:57798.service - OpenSSH per-connection server daemon (10.200.16.10:57798). Nov 6 00:23:17.842791 sshd[15215]: Accepted publickey for core from 10.200.16.10 port 57798 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:17.844197 sshd-session[15215]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:17.848556 systemd-logind[1877]: New session 22 of user core. Nov 6 00:23:17.865844 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 6 00:23:18.288655 sshd[15218]: Connection closed by 10.200.16.10 port 57798 Nov 6 00:23:18.289019 sshd-session[15215]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:18.292287 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:57798.service: Deactivated successfully. Nov 6 00:23:18.293983 systemd[1]: session-22.scope: Deactivated successfully. Nov 6 00:23:18.295342 systemd-logind[1877]: Session 22 logged out. Waiting for processes to exit. Nov 6 00:23:18.296649 systemd-logind[1877]: Removed session 22. Nov 6 00:23:18.409449 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:57800.service - OpenSSH per-connection server daemon (10.200.16.10:57800). Nov 6 00:23:19.048046 sshd[15224]: Accepted publickey for core from 10.200.16.10 port 57800 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:19.049478 sshd-session[15224]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:19.056940 systemd-logind[1877]: New session 23 of user core. Nov 6 00:23:19.070742 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 6 00:23:19.510115 sshd[15227]: Connection closed by 10.200.16.10 port 57800 Nov 6 00:23:19.510459 sshd-session[15224]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:19.514088 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:57800.service: Deactivated successfully. Nov 6 00:23:19.515729 systemd[1]: session-23.scope: Deactivated successfully. Nov 6 00:23:19.516442 systemd-logind[1877]: Session 23 logged out. Waiting for processes to exit. Nov 6 00:23:19.517423 systemd-logind[1877]: Removed session 23. Nov 6 00:23:26.320844 setup-nvidia[2535]: \u000d Nov 6 00:23:26.320844 setup-nvidia[2535]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 6 00:23:26.320844 setup-nvidia[2535]: frequently when this kernel module was built against the wrong or Nov 6 00:23:26.320844 setup-nvidia[2535]: improperly configured kernel sources, with a version of gcc that differs Nov 6 00:23:26.320844 setup-nvidia[2535]: from the one used to build the target kernel, or if another driver, such Nov 6 00:23:26.320844 setup-nvidia[2535]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 6 00:23:26.320844 setup-nvidia[2535]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 6 00:23:26.320844 setup-nvidia[2535]: installed in this system is supported by this NVIDIA Linux graphics Nov 6 00:23:26.320844 setup-nvidia[2535]: driver release. Nov 6 00:23:26.320844 setup-nvidia[2535]: \u000d Nov 6 00:23:26.320844 setup-nvidia[2535]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 6 00:23:26.320844 setup-nvidia[2535]: messages' at the end of the file Nov 6 00:23:26.320844 setup-nvidia[2535]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Nov 6 00:23:26.320844 setup-nvidia[2535]: information. Nov 6 00:23:26.320844 setup-nvidia[2535]: \u000d Nov 6 00:23:26.337975 setup-nvidia[2535]: dmesg: read kernel buffer failed: Operation not permitted Nov 6 00:23:26.357740 setup-nvidia[2535]: \u000d Nov 6 00:23:26.357740 setup-nvidia[2535]: ERROR: Installation has failed. Please see the file Nov 6 00:23:26.357740 setup-nvidia[2535]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Nov 6 00:23:26.357740 setup-nvidia[2535]: details. You may find suggestions on fixing installation problems in Nov 6 00:23:26.357740 setup-nvidia[2535]: the README available on the Linux driver download page at Nov 6 00:23:26.357740 setup-nvidia[2535]: www.nvidia.com. Nov 6 00:23:26.357740 setup-nvidia[2535]: \u000d Nov 6 00:23:26.358291 setup-nvidia[2535]: Last 50 lines of nvidia-installer.log: Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 6 00:23:26.359214 setup-nvidia[2535]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Nov 6 00:23:26.359214 setup-nvidia[2535]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Nov 6 00:23:26.359214 setup-nvidia[2535]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Nov 6 00:23:26.359214 setup-nvidia[2535]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Nov 6 00:23:26.359214 setup-nvidia[2535]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Nov 6 00:23:26.359214 setup-nvidia[2535]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Nov 6 00:23:26.359214 setup-nvidia[2535]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Nov 6 00:23:26.359214 setup-nvidia[2535]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 6 00:23:26.359214 setup-nvidia[2535]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 6 00:23:26.359214 setup-nvidia[2535]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 6 00:23:26.359214 setup-nvidia[2535]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 6 00:23:26.359920 setup-nvidia[2535]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 6 00:23:26.359920 setup-nvidia[2535]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 6 00:23:26.359920 setup-nvidia[2535]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 6 00:23:26.359920 setup-nvidia[2535]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 6 00:23:26.359920 setup-nvidia[2535]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Nov 6 00:23:26.359920 setup-nvidia[2535]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Nov 6 00:23:26.359920 setup-nvidia[2535]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Nov 6 00:23:26.359920 setup-nvidia[2535]: -> done. Nov 6 00:23:26.359920 setup-nvidia[2535]: -> Kernel module compilation complete. Nov 6 00:23:26.359920 setup-nvidia[2535]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 6 00:23:26.359920 setup-nvidia[2535]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Nov 6 00:23:26.359920 setup-nvidia[2535]: \u000d Nov 6 00:23:26.359920 setup-nvidia[2535]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Nov 6 00:23:26.359920 setup-nvidia[2535]: -> Kernel module load error: Operation not permitted Nov 6 00:23:26.359920 setup-nvidia[2535]: -> Kernel messages: Nov 6 00:23:26.359920 setup-nvidia[2535]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 6 00:23:29.628375 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:52476.service - OpenSSH per-connection server daemon (10.200.16.10:52476). Nov 6 00:23:30.292024 sshd[15413]: Accepted publickey for core from 10.200.16.10 port 52476 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:30.293317 sshd-session[15413]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:30.297738 systemd-logind[1877]: New session 24 of user core. Nov 6 00:23:30.311715 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 6 00:23:30.742904 sshd[15416]: Connection closed by 10.200.16.10 port 52476 Nov 6 00:23:30.743291 sshd-session[15413]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:30.746927 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:52476.service: Deactivated successfully. Nov 6 00:23:30.748784 systemd[1]: session-24.scope: Deactivated successfully. Nov 6 00:23:30.749604 systemd-logind[1877]: Session 24 logged out. Waiting for processes to exit. Nov 6 00:23:30.750557 systemd-logind[1877]: Removed session 24. Nov 6 00:23:30.859551 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:56772.service - OpenSSH per-connection server daemon (10.200.16.10:56772). Nov 6 00:23:31.499246 sshd[15422]: Accepted publickey for core from 10.200.16.10 port 56772 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:31.500497 sshd-session[15422]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:31.505188 systemd-logind[1877]: New session 25 of user core. Nov 6 00:23:31.516740 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 6 00:23:31.950225 sshd[15425]: Connection closed by 10.200.16.10 port 56772 Nov 6 00:23:31.950614 sshd-session[15422]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:31.954075 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:56772.service: Deactivated successfully. Nov 6 00:23:31.955707 systemd[1]: session-25.scope: Deactivated successfully. Nov 6 00:23:31.956369 systemd-logind[1877]: Session 25 logged out. Waiting for processes to exit. Nov 6 00:23:31.957347 systemd-logind[1877]: Removed session 25. Nov 6 00:23:42.068079 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:44712.service - OpenSSH per-connection server daemon (10.200.16.10:44712). Nov 6 00:23:42.726476 sshd[15432]: Accepted publickey for core from 10.200.16.10 port 44712 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:42.727799 sshd-session[15432]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:42.732269 systemd-logind[1877]: New session 26 of user core. Nov 6 00:23:42.740742 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 6 00:23:42.811610 kernel: EXT4-fs (loop6p9): unmounting filesystem 1e39b452-972d-46d5-b7a7-0dccbb4f0913. Nov 6 00:23:42.812243 setup-nvidia[2535]: Container flatcardevelopercontainer-4459.1.0nightly-20251105-2100.bin exited successfully. Nov 6 00:23:42.814447 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.1.0nightly\x2d20251105\x2d2100.bin.mount: Deactivated successfully. Nov 6 00:23:42.888624 sudo[2523]: pam_unix(sudo:session): session closed for user root Nov 6 00:23:42.890867 systemd[1]: session-c2.scope: Deactivated successfully. Nov 6 00:23:42.891199 systemd[1]: session-c2.scope: Consumed 5min 37.308s CPU time, 889.3M memory peak. Nov 6 00:23:43.181636 sshd[15435]: Connection closed by 10.200.16.10 port 44712 Nov 6 00:23:43.181942 sshd-session[15432]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:43.185658 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:44712.service: Deactivated successfully. Nov 6 00:23:43.187533 systemd[1]: session-26.scope: Deactivated successfully. Nov 6 00:23:43.188409 systemd-logind[1877]: Session 26 logged out. Waiting for processes to exit. Nov 6 00:23:43.191200 systemd-logind[1877]: Removed session 26. Nov 6 00:23:43.297558 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:44720.service - OpenSSH per-connection server daemon (10.200.16.10:44720). Nov 6 00:23:43.322555 setup-nvidia[1867]: /opt/nvidia / Nov 6 00:23:43.323758 setup-nvidia[1867]: / Nov 6 00:23:43.334650 kernel: IPMI message handler: version 39.2 Nov 6 00:23:43.340599 kernel: ipmi device interface Nov 6 00:23:43.353720 setup-nvidia[1867]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Nov 6 00:23:43.411613 kernel: nvidia: loading out-of-tree module taints kernel. Nov 6 00:23:43.411760 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 6 00:23:43.411785 kernel: Disabling lock debugging due to kernel taint Nov 6 00:23:43.419145 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 6 00:23:43.419223 kernel: nvidia: module license taints kernel. Nov 6 00:23:43.829797 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 6 00:23:43.947076 sshd[15452]: Accepted publickey for core from 10.200.16.10 port 44720 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:43.948456 sshd-session[15452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:43.952937 systemd-logind[1877]: New session 27 of user core. Nov 6 00:23:44.250858 kernel: Nov 6 00:23:44.251002 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 6 00:23:44.260826 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 6 00:23:44.462608 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Nov 6 00:23:44.492617 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Nov 6 00:23:44.556625 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 6 00:23:44.601649 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 6 00:23:44.601775 setup-nvidia[1867]: / Nov 6 00:23:44.623571 sshd[15471]: Connection closed by 10.200.16.10 port 44720 Nov 6 00:23:44.623974 sshd-session[15452]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:44.627629 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:44720.service: Deactivated successfully. Nov 6 00:23:44.629225 systemd[1]: session-27.scope: Deactivated successfully. Nov 6 00:23:44.629942 systemd-logind[1877]: Session 27 logged out. Waiting for processes to exit. Nov 6 00:23:44.631121 systemd-logind[1877]: Removed session 27. Nov 6 00:23:44.636731 setup-nvidia[15480]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 00:23:46.700570 setup-nvidia[15500]: Thu Nov 6 00:23:46 2025 Nov 6 00:23:46.700570 setup-nvidia[15500]: +---------------------------------------------------------------------------------------+ Nov 6 00:23:46.700570 setup-nvidia[15500]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Nov 6 00:23:46.700570 setup-nvidia[15500]: |-----------------------------------------+----------------------+----------------------+ Nov 6 00:23:46.700570 setup-nvidia[15500]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 6 00:23:46.700570 setup-nvidia[15500]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 6 00:23:46.700570 setup-nvidia[15500]: | | | MIG M. | Nov 6 00:23:46.700570 setup-nvidia[15500]: |=========================================+======================+======================| Nov 6 00:23:46.704289 setup-nvidia[15500]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 6 00:23:46.704289 setup-nvidia[15500]: | N/A 25C P0 35W / 250W | 0MiB / 16384MiB | 0% Default | Nov 6 00:23:46.704289 setup-nvidia[15500]: | | | N/A | Nov 6 00:23:46.704289 setup-nvidia[15500]: +-----------------------------------------+----------------------+----------------------+ Nov 6 00:23:46.704289 setup-nvidia[15500]: Nov 6 00:23:46.704289 setup-nvidia[15500]: +---------------------------------------------------------------------------------------+ Nov 6 00:23:46.704289 setup-nvidia[15500]: | Processes: | Nov 6 00:23:46.704289 setup-nvidia[15500]: | GPU GI CI PID Type Process name GPU Memory | Nov 6 00:23:46.704289 setup-nvidia[15500]: | ID ID Usage | Nov 6 00:23:46.704289 setup-nvidia[15500]: |=======================================================================================| Nov 6 00:23:46.704554 setup-nvidia[15500]: | No running processes found | Nov 6 00:23:46.704554 setup-nvidia[15500]: +---------------------------------------------------------------------------------------+ Nov 6 00:23:47.106177 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 6 00:23:47.108541 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 6 00:23:47.108970 systemd[1]: Startup finished in 1.092s (firmware) + 20.669s (loader) + 3.875s (kernel) + 13.832s (initrd) + 3min 8.891s (userspace) = 3min 48.361s. Nov 6 00:23:52.944654 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 6 00:23:52.945434 systemd[2526]: Activating special unit exit.target... Nov 6 00:23:52.945523 systemd[2526]: Stopped target default.target - Main User Target. Nov 6 00:23:52.945567 systemd[2526]: Stopped target basic.target - Basic System. Nov 6 00:23:52.945614 systemd[2526]: Stopped target paths.target - Paths. Nov 6 00:23:52.945642 systemd[2526]: Stopped target sockets.target - Sockets. Nov 6 00:23:52.945668 systemd[2526]: Stopped target timers.target - Timers. Nov 6 00:23:52.946012 systemd[2526]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 6 00:23:52.946225 systemd[2526]: Removed slice app.slice - User Application Slice. Nov 6 00:23:52.946257 systemd[2526]: Reached target shutdown.target - Shutdown. Nov 6 00:23:52.946397 systemd[2526]: Finished systemd-exit.service - Exit the Session. Nov 6 00:23:52.946434 systemd[2526]: Reached target exit.target - Exit the Session. Nov 6 00:23:52.970283 (sd-pam)[2527]: pam_unix(systemd-user:session): session closed for user root Nov 6 00:23:52.971728 systemd[1]: user@0.service: Deactivated successfully. Nov 6 00:23:52.972272 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 6 00:23:52.974953 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 6 00:23:53.004181 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 6 00:23:53.005154 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 6 00:23:53.005344 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 6 00:23:53.006982 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 6 00:23:53.007077 systemd[1]: user-0.slice: Consumed 5min 37.537s CPU time, 895.5M memory peak. Nov 6 00:23:53.007520 systemd-logind[1877]: Removed session c3. Nov 6 00:23:54.749775 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:42698.service - OpenSSH per-connection server daemon (10.200.16.10:42698). Nov 6 00:23:55.383394 sshd[15511]: Accepted publickey for core from 10.200.16.10 port 42698 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:55.384729 sshd-session[15511]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:55.389154 systemd-logind[1877]: New session 28 of user core. Nov 6 00:23:55.404764 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 6 00:23:55.838876 sshd[15514]: Connection closed by 10.200.16.10 port 42698 Nov 6 00:23:55.839272 sshd-session[15511]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:55.843111 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:42698.service: Deactivated successfully. Nov 6 00:23:55.844823 systemd[1]: session-28.scope: Deactivated successfully. Nov 6 00:23:55.845623 systemd-logind[1877]: Session 28 logged out. Waiting for processes to exit. Nov 6 00:23:55.846806 systemd-logind[1877]: Removed session 28. Nov 6 00:23:55.956139 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:42712.service - OpenSSH per-connection server daemon (10.200.16.10:42712). Nov 6 00:23:56.599946 sshd[15520]: Accepted publickey for core from 10.200.16.10 port 42712 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:56.601295 sshd-session[15520]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:56.607331 systemd-logind[1877]: New session 29 of user core. Nov 6 00:23:56.618760 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 6 00:23:57.044770 sshd[15523]: Connection closed by 10.200.16.10 port 42712 Nov 6 00:23:57.045197 sshd-session[15520]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:57.049169 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:42712.service: Deactivated successfully. Nov 6 00:23:57.050812 systemd[1]: session-29.scope: Deactivated successfully. Nov 6 00:23:57.051597 systemd-logind[1877]: Session 29 logged out. Waiting for processes to exit. Nov 6 00:23:57.052691 systemd-logind[1877]: Removed session 29. Nov 6 00:23:57.160346 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:42728.service - OpenSSH per-connection server daemon (10.200.16.10:42728). Nov 6 00:23:57.795427 sshd[15530]: Accepted publickey for core from 10.200.16.10 port 42728 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:57.796729 sshd-session[15530]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:57.801352 systemd-logind[1877]: New session 30 of user core. Nov 6 00:23:57.816726 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 6 00:23:58.245392 sshd[15535]: Connection closed by 10.200.16.10 port 42728 Nov 6 00:23:58.245797 sshd-session[15530]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:58.249507 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:42728.service: Deactivated successfully. Nov 6 00:23:58.251185 systemd[1]: session-30.scope: Deactivated successfully. Nov 6 00:23:58.259212 systemd-logind[1877]: Session 30 logged out. Waiting for processes to exit. Nov 6 00:23:58.277327 systemd-logind[1877]: Removed session 30. Nov 6 00:23:58.369651 systemd[1]: Started sshd@28-10.200.8.12:22-10.200.16.10:42742.service - OpenSSH per-connection server daemon (10.200.16.10:42742). Nov 6 00:23:59.023321 sshd[15541]: Accepted publickey for core from 10.200.16.10 port 42742 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:23:59.024634 sshd-session[15541]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:23:59.034036 systemd-logind[1877]: New session 31 of user core. Nov 6 00:23:59.047830 systemd[1]: Started session-31.scope - Session 31 of User core. Nov 6 00:23:59.483403 sshd[15544]: Connection closed by 10.200.16.10 port 42742 Nov 6 00:23:59.483811 sshd-session[15541]: pam_unix(sshd:session): session closed for user core Nov 6 00:23:59.486942 systemd[1]: sshd@28-10.200.8.12:22-10.200.16.10:42742.service: Deactivated successfully. Nov 6 00:23:59.488788 systemd[1]: session-31.scope: Deactivated successfully. Nov 6 00:23:59.490032 systemd-logind[1877]: Session 31 logged out. Waiting for processes to exit. Nov 6 00:23:59.491100 systemd-logind[1877]: Removed session 31. Nov 6 00:23:59.602975 systemd[1]: Started sshd@29-10.200.8.12:22-10.200.16.10:42746.service - OpenSSH per-connection server daemon (10.200.16.10:42746). Nov 6 00:24:00.259468 sshd[15550]: Accepted publickey for core from 10.200.16.10 port 42746 ssh2: RSA SHA256:BRCLy0/gdh06tBBZnpBOLBP6uCVHXmhFqXrcHgpC+6M Nov 6 00:24:00.260816 sshd-session[15550]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 00:24:00.267931 systemd-logind[1877]: New session 32 of user core. Nov 6 00:24:00.280008 systemd[1]: Started session-32.scope - Session 32 of User core. Nov 6 00:24:02.368449 sshd[15553]: Connection closed by 10.200.16.10 port 42746 Nov 6 00:24:02.368966 sshd-session[15550]: pam_unix(sshd:session): session closed for user core Nov 6 00:24:02.372768 systemd[1]: sshd@29-10.200.8.12:22-10.200.16.10:42746.service: Deactivated successfully. Nov 6 00:24:02.374391 systemd[1]: session-32.scope: Deactivated successfully. Nov 6 00:24:02.374606 systemd[1]: session-32.scope: Consumed 1.596s CPU time, 14.2M memory peak. Nov 6 00:24:02.375116 systemd-logind[1877]: Session 32 logged out. Waiting for processes to exit. Nov 6 00:24:02.383508 systemd-logind[1877]: Removed session 32.