Jan 23 01:02:46.211091 kernel: Linux version 6.12.66-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 Thu Jan 22 22:22:03 -00 2026 Jan 23 01:02:46.211120 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=e8d7116310bea9a494780b8becdce41e7cc03ed509d8e2363e08981a47b3edc6 Jan 23 01:02:46.211131 kernel: BIOS-provided physical RAM map: Jan 23 01:02:46.211137 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jan 23 01:02:46.211144 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jan 23 01:02:46.211151 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jan 23 01:02:46.211159 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jan 23 01:02:46.211169 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jan 23 01:02:46.211176 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jan 23 01:02:46.211183 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jan 23 01:02:46.211190 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jan 23 01:02:46.211197 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jan 23 01:02:46.211203 kernel: printk: legacy bootconsole [earlyser0] enabled Jan 23 01:02:46.211210 kernel: NX (Execute Disable) protection: active Jan 23 01:02:46.211221 kernel: APIC: Static calls initialized Jan 23 01:02:46.211229 kernel: efi: EFI v2.7 by Microsoft Jan 23 01:02:46.211237 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee5fa98 RNG=0x3ffd1018 Jan 23 01:02:46.211245 kernel: random: crng init done Jan 23 01:02:46.211253 kernel: secureboot: Secure boot disabled Jan 23 01:02:46.211260 kernel: SMBIOS 3.1.0 present. Jan 23 01:02:46.211268 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jan 23 01:02:46.211275 kernel: DMI: Memory slots populated: 3/3 Jan 23 01:02:46.211283 kernel: Hypervisor detected: Microsoft Hyper-V Jan 23 01:02:46.211295 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jan 23 01:02:46.211306 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jan 23 01:02:46.211320 kernel: Hyper-V: Using hypercall for remote TLB flush Jan 23 01:02:46.211328 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 01:02:46.211341 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jan 23 01:02:46.211358 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jan 23 01:02:46.211371 kernel: tsc: Detected 2593.992 MHz processor Jan 23 01:02:46.211384 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jan 23 01:02:46.211400 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jan 23 01:02:46.211408 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jan 23 01:02:46.211424 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jan 23 01:02:46.211432 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jan 23 01:02:46.211439 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jan 23 01:02:46.211451 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jan 23 01:02:46.211464 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jan 23 01:02:46.211477 kernel: Using GB pages for direct mapping Jan 23 01:02:46.211485 kernel: ACPI: Early table checksum verification disabled Jan 23 01:02:46.211497 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jan 23 01:02:46.211508 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211516 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211525 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jan 23 01:02:46.211533 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jan 23 01:02:46.211541 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211549 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211560 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211568 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211576 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211585 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211593 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 23 01:02:46.211601 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jan 23 01:02:46.211609 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jan 23 01:02:46.211617 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jan 23 01:02:46.211625 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jan 23 01:02:46.211636 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jan 23 01:02:46.211644 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jan 23 01:02:46.211661 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jan 23 01:02:46.211669 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jan 23 01:02:46.211677 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jan 23 01:02:46.211685 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jan 23 01:02:46.211693 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jan 23 01:02:46.211701 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jan 23 01:02:46.211712 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jan 23 01:02:46.211721 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jan 23 01:02:46.211729 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jan 23 01:02:46.211737 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jan 23 01:02:46.211745 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jan 23 01:02:46.211753 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jan 23 01:02:46.211772 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jan 23 01:02:46.211782 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jan 23 01:02:46.211790 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jan 23 01:02:46.211802 kernel: Zone ranges: Jan 23 01:02:46.211810 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jan 23 01:02:46.211818 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jan 23 01:02:46.211826 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jan 23 01:02:46.211834 kernel: Device empty Jan 23 01:02:46.211842 kernel: Movable zone start for each node Jan 23 01:02:46.211850 kernel: Early memory node ranges Jan 23 01:02:46.211859 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jan 23 01:02:46.211867 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jan 23 01:02:46.211877 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jan 23 01:02:46.211885 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jan 23 01:02:46.211893 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jan 23 01:02:46.211902 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jan 23 01:02:46.211910 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jan 23 01:02:46.211918 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jan 23 01:02:46.211926 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jan 23 01:02:46.211934 kernel: ACPI: PM-Timer IO Port: 0x408 Jan 23 01:02:46.211942 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jan 23 01:02:46.211952 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jan 23 01:02:46.211961 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jan 23 01:02:46.211969 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jan 23 01:02:46.211977 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jan 23 01:02:46.211985 kernel: CPU topo: Max. logical packages: 1 Jan 23 01:02:46.211993 kernel: CPU topo: Max. logical dies: 1 Jan 23 01:02:46.212001 kernel: CPU topo: Max. dies per package: 1 Jan 23 01:02:46.212009 kernel: CPU topo: Max. threads per core: 1 Jan 23 01:02:46.212017 kernel: CPU topo: Num. cores per package: 6 Jan 23 01:02:46.212028 kernel: CPU topo: Num. threads per package: 6 Jan 23 01:02:46.212036 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jan 23 01:02:46.212044 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jan 23 01:02:46.212052 kernel: Booting paravirtualized kernel on Hyper-V Jan 23 01:02:46.212060 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jan 23 01:02:46.212068 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jan 23 01:02:46.212076 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jan 23 01:02:46.212085 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jan 23 01:02:46.212093 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jan 23 01:02:46.212103 kernel: Hyper-V: PV spinlocks enabled Jan 23 01:02:46.212111 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jan 23 01:02:46.212121 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=e8d7116310bea9a494780b8becdce41e7cc03ed509d8e2363e08981a47b3edc6 Jan 23 01:02:46.212129 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jan 23 01:02:46.212137 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jan 23 01:02:46.212149 kernel: Fallback order for Node 0: 0 Jan 23 01:02:46.212159 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jan 23 01:02:46.212172 kernel: Policy zone: Normal Jan 23 01:02:46.212188 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 23 01:02:46.212210 kernel: software IO TLB: area num 8. Jan 23 01:02:46.212219 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jan 23 01:02:46.212230 kernel: Kernel/User page tables isolation: enabled Jan 23 01:02:46.212239 kernel: ftrace: allocating 40097 entries in 157 pages Jan 23 01:02:46.212248 kernel: ftrace: allocated 157 pages with 5 groups Jan 23 01:02:46.212256 kernel: Dynamic Preempt: voluntary Jan 23 01:02:46.212265 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 23 01:02:46.212274 kernel: rcu: RCU event tracing is enabled. Jan 23 01:02:46.212283 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jan 23 01:02:46.212292 kernel: Trampoline variant of Tasks RCU enabled. Jan 23 01:02:46.212303 kernel: Rude variant of Tasks RCU enabled. Jan 23 01:02:46.212312 kernel: Tracing variant of Tasks RCU enabled. Jan 23 01:02:46.212320 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 23 01:02:46.212329 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jan 23 01:02:46.212338 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 01:02:46.212346 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 01:02:46.212358 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jan 23 01:02:46.212366 kernel: Using NULL legacy PIC Jan 23 01:02:46.212382 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jan 23 01:02:46.212396 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 23 01:02:46.212405 kernel: Console: colour dummy device 80x25 Jan 23 01:02:46.212413 kernel: printk: legacy console [tty1] enabled Jan 23 01:02:46.212424 kernel: printk: legacy console [ttyS0] enabled Jan 23 01:02:46.212433 kernel: printk: legacy bootconsole [earlyser0] disabled Jan 23 01:02:46.212442 kernel: ACPI: Core revision 20240827 Jan 23 01:02:46.212454 kernel: Failed to register legacy timer interrupt Jan 23 01:02:46.212462 kernel: APIC: Switch to symmetric I/O mode setup Jan 23 01:02:46.212471 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jan 23 01:02:46.212480 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jan 23 01:02:46.212488 kernel: Hyper-V: Using IPI hypercalls Jan 23 01:02:46.212497 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jan 23 01:02:46.212505 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jan 23 01:02:46.212514 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jan 23 01:02:46.212572 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jan 23 01:02:46.212586 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jan 23 01:02:46.212595 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jan 23 01:02:46.212604 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jan 23 01:02:46.212612 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jan 23 01:02:46.212621 kernel: APIC: read() replaced with hv_apic_read() Jan 23 01:02:46.212630 kernel: APIC: write() replaced with hv_apic_write() Jan 23 01:02:46.212638 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jan 23 01:02:46.212647 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jan 23 01:02:46.212656 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Jan 23 01:02:46.212667 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jan 23 01:02:46.212676 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jan 23 01:02:46.212685 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jan 23 01:02:46.212693 kernel: Spectre V2 : Mitigation: Retpolines Jan 23 01:02:46.212701 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jan 23 01:02:46.212710 kernel: Speculative Store Bypass: Vulnerable Jan 23 01:02:46.212718 kernel: MDS: Mitigation: Clear CPU buffers Jan 23 01:02:46.212727 kernel: TAA: Mitigation: Clear CPU buffers Jan 23 01:02:46.212735 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jan 23 01:02:46.212743 kernel: active return thunk: its_return_thunk Jan 23 01:02:46.212752 kernel: ITS: Mitigation: Aligned branch/return thunks Jan 23 01:02:46.212776 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jan 23 01:02:46.212785 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jan 23 01:02:46.212794 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jan 23 01:02:46.212802 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jan 23 01:02:46.212811 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jan 23 01:02:46.212820 kernel: Freeing SMP alternatives memory: 32K Jan 23 01:02:46.212828 kernel: pid_max: default: 32768 minimum: 301 Jan 23 01:02:46.212836 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jan 23 01:02:46.212845 kernel: landlock: Up and running. Jan 23 01:02:46.212853 kernel: SELinux: Initializing. Jan 23 01:02:46.212862 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 01:02:46.212874 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jan 23 01:02:46.212882 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jan 23 01:02:46.212891 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jan 23 01:02:46.212900 kernel: signal: max sigframe size: 1776 Jan 23 01:02:46.212908 kernel: rcu: Hierarchical SRCU implementation. Jan 23 01:02:46.212918 kernel: rcu: Max phase no-delay instances is 400. Jan 23 01:02:46.212926 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jan 23 01:02:46.212935 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jan 23 01:02:46.212944 kernel: smp: Bringing up secondary CPUs ... Jan 23 01:02:46.212955 kernel: smpboot: x86: Booting SMP configuration: Jan 23 01:02:46.212963 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jan 23 01:02:46.212972 kernel: smp: Brought up 1 node, 6 CPUs Jan 23 01:02:46.212980 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Jan 23 01:02:46.212990 kernel: Memory: 115330896K/117439364K available (14336K kernel code, 2445K rwdata, 26064K rodata, 46196K init, 2564K bss, 2098192K reserved, 0K cma-reserved) Jan 23 01:02:46.212999 kernel: devtmpfs: initialized Jan 23 01:02:46.213007 kernel: x86/mm: Memory block size: 1024MB Jan 23 01:02:46.213016 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jan 23 01:02:46.213025 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 23 01:02:46.213036 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jan 23 01:02:46.213044 kernel: pinctrl core: initialized pinctrl subsystem Jan 23 01:02:46.213053 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 23 01:02:46.213062 kernel: audit: initializing netlink subsys (disabled) Jan 23 01:02:46.213070 kernel: audit: type=2000 audit(1769130162.028:1): state=initialized audit_enabled=0 res=1 Jan 23 01:02:46.213079 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 23 01:02:46.213087 kernel: thermal_sys: Registered thermal governor 'user_space' Jan 23 01:02:46.213096 kernel: cpuidle: using governor menu Jan 23 01:02:46.213104 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 23 01:02:46.213116 kernel: dca service started, version 1.12.1 Jan 23 01:02:46.213124 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jan 23 01:02:46.213133 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jan 23 01:02:46.213141 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 23 01:02:46.213150 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jan 23 01:02:46.213159 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 23 01:02:46.213167 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jan 23 01:02:46.213176 kernel: ACPI: Added _OSI(Module Device) Jan 23 01:02:46.213184 kernel: ACPI: Added _OSI(Processor Device) Jan 23 01:02:46.213195 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 23 01:02:46.213204 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jan 23 01:02:46.213212 kernel: ACPI: Interpreter enabled Jan 23 01:02:46.213221 kernel: ACPI: PM: (supports S0 S5) Jan 23 01:02:46.213229 kernel: ACPI: Using IOAPIC for interrupt routing Jan 23 01:02:46.213238 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jan 23 01:02:46.213247 kernel: PCI: Ignoring E820 reservations for host bridge windows Jan 23 01:02:46.213255 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jan 23 01:02:46.213264 kernel: iommu: Default domain type: Translated Jan 23 01:02:46.213275 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jan 23 01:02:46.213284 kernel: efivars: Registered efivars operations Jan 23 01:02:46.213292 kernel: PCI: Using ACPI for IRQ routing Jan 23 01:02:46.213301 kernel: PCI: System does not support PCI Jan 23 01:02:46.213309 kernel: vgaarb: loaded Jan 23 01:02:46.213318 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jan 23 01:02:46.213327 kernel: VFS: Disk quotas dquot_6.6.0 Jan 23 01:02:46.213335 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 23 01:02:46.213343 kernel: pnp: PnP ACPI init Jan 23 01:02:46.213354 kernel: pnp: PnP ACPI: found 3 devices Jan 23 01:02:46.213363 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jan 23 01:02:46.213372 kernel: NET: Registered PF_INET protocol family Jan 23 01:02:46.213381 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.213389 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jan 23 01:02:46.213398 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 23 01:02:46.213407 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jan 23 01:02:46.213415 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.213424 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jan 23 01:02:46.213435 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.213444 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jan 23 01:02:46.213452 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 23 01:02:46.213461 kernel: NET: Registered PF_XDP protocol family Jan 23 01:02:46.213469 kernel: PCI: CLS 0 bytes, default 64 Jan 23 01:02:46.213478 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jan 23 01:02:46.213494 kernel: software IO TLB: mapped [mem 0x000000003ae5f000-0x000000003ee5f000] (64MB) Jan 23 01:02:46.213503 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jan 23 01:02:46.213512 kernel: Initialise system trusted keyrings Jan 23 01:02:46.213524 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jan 23 01:02:46.213533 kernel: Key type asymmetric registered Jan 23 01:02:46.213541 kernel: Asymmetric key parser 'x509' registered Jan 23 01:02:46.213550 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jan 23 01:02:46.213558 kernel: io scheduler mq-deadline registered Jan 23 01:02:46.213567 kernel: io scheduler kyber registered Jan 23 01:02:46.213575 kernel: io scheduler bfq registered Jan 23 01:02:46.213584 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jan 23 01:02:46.213593 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 23 01:02:46.213604 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jan 23 01:02:46.213613 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jan 23 01:02:46.213621 kernel: i8042: PNP: No PS/2 controller found. Jan 23 01:02:46.213796 kernel: rtc_cmos 00:02: registered as rtc0 Jan 23 01:02:46.213907 kernel: rtc_cmos 00:02: setting system clock to 2026-01-23T01:02:45 UTC (1769130165) Jan 23 01:02:46.214006 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jan 23 01:02:46.214019 kernel: intel_pstate: CPU model not supported Jan 23 01:02:46.214033 kernel: efifb: probing for efifb Jan 23 01:02:46.214042 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jan 23 01:02:46.214051 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jan 23 01:02:46.214059 kernel: efifb: scrolling: redraw Jan 23 01:02:46.214068 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jan 23 01:02:46.214077 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 01:02:46.214085 kernel: fb0: EFI VGA frame buffer device Jan 23 01:02:46.214094 kernel: pstore: Using crash dump compression: deflate Jan 23 01:02:46.214102 kernel: pstore: Registered efi_pstore as persistent store backend Jan 23 01:02:46.214114 kernel: NET: Registered PF_INET6 protocol family Jan 23 01:02:46.214122 kernel: Segment Routing with IPv6 Jan 23 01:02:46.214131 kernel: In-situ OAM (IOAM) with IPv6 Jan 23 01:02:46.214139 kernel: NET: Registered PF_PACKET protocol family Jan 23 01:02:46.214148 kernel: Key type dns_resolver registered Jan 23 01:02:46.214156 kernel: IPI shorthand broadcast: enabled Jan 23 01:02:46.214165 kernel: sched_clock: Marking stable (3573006000, 110200400)->(4012018000, -328811600) Jan 23 01:02:46.214174 kernel: registered taskstats version 1 Jan 23 01:02:46.214182 kernel: Loading compiled-in X.509 certificates Jan 23 01:02:46.214191 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.66-flatcar: ed54f39d0282729985c39b8ffa9938cacff38d8a' Jan 23 01:02:46.214202 kernel: Demotion targets for Node 0: null Jan 23 01:02:46.214210 kernel: Key type .fscrypt registered Jan 23 01:02:46.214219 kernel: Key type fscrypt-provisioning registered Jan 23 01:02:46.214227 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 23 01:02:46.214236 kernel: ima: Allocated hash algorithm: sha1 Jan 23 01:02:46.214244 kernel: ima: No architecture policies found Jan 23 01:02:46.214253 kernel: clk: Disabling unused clocks Jan 23 01:02:46.214261 kernel: Warning: unable to open an initial console. Jan 23 01:02:46.214270 kernel: Freeing unused kernel image (initmem) memory: 46196K Jan 23 01:02:46.214281 kernel: Write protecting the kernel read-only data: 40960k Jan 23 01:02:46.214289 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Jan 23 01:02:46.214298 kernel: Run /init as init process Jan 23 01:02:46.214307 kernel: with arguments: Jan 23 01:02:46.214315 kernel: /init Jan 23 01:02:46.214323 kernel: with environment: Jan 23 01:02:46.214332 kernel: HOME=/ Jan 23 01:02:46.214340 kernel: TERM=linux Jan 23 01:02:46.214350 systemd[1]: Successfully made /usr/ read-only. Jan 23 01:02:46.214364 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) Jan 23 01:02:46.214374 systemd[1]: Detected virtualization microsoft. Jan 23 01:02:46.214383 systemd[1]: Detected architecture x86-64. Jan 23 01:02:46.214391 systemd[1]: Running in initrd. Jan 23 01:02:46.214400 systemd[1]: No hostname configured, using default hostname. Jan 23 01:02:46.214409 systemd[1]: Hostname set to . Jan 23 01:02:46.214418 systemd[1]: Initializing machine ID from random generator. Jan 23 01:02:46.214430 systemd[1]: Queued start job for default target initrd.target. Jan 23 01:02:46.214439 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 01:02:46.214448 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 01:02:46.214458 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 23 01:02:46.214467 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 01:02:46.214477 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 23 01:02:46.214487 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 23 01:02:46.214499 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jan 23 01:02:46.214509 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jan 23 01:02:46.214518 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 01:02:46.214527 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 01:02:46.214536 systemd[1]: Reached target paths.target - Path Units. Jan 23 01:02:46.214545 systemd[1]: Reached target slices.target - Slice Units. Jan 23 01:02:46.214555 systemd[1]: Reached target swap.target - Swaps. Jan 23 01:02:46.214564 systemd[1]: Reached target timers.target - Timer Units. Jan 23 01:02:46.214575 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 01:02:46.214585 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 01:02:46.214594 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 23 01:02:46.214603 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jan 23 01:02:46.214612 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 01:02:46.214621 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 01:02:46.214630 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 01:02:46.214640 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 01:02:46.214649 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 23 01:02:46.214660 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 01:02:46.214670 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 23 01:02:46.214679 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). Jan 23 01:02:46.214688 systemd[1]: Starting systemd-fsck-usr.service... Jan 23 01:02:46.214697 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 01:02:46.214706 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 01:02:46.214716 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:46.214745 systemd-journald[214]: Collecting audit messages is disabled. Jan 23 01:02:46.214792 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 23 01:02:46.214807 systemd-journald[214]: Journal started Jan 23 01:02:46.214827 systemd-journald[214]: Runtime Journal (/run/log/journal/3c4d08af4ac14c5f8849606ebd6c66b7) is 8M, max 2.2G, 2.1G free. Jan 23 01:02:46.212419 systemd-modules-load[216]: Inserted module 'overlay' Jan 23 01:02:46.222793 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 01:02:46.223144 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 01:02:46.223668 systemd[1]: Finished systemd-fsck-usr.service. Jan 23 01:02:46.225942 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 23 01:02:46.226961 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 01:02:46.269747 systemd-tmpfiles[225]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jan 23 01:02:46.276673 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 23 01:02:46.278418 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:46.289322 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 01:02:46.307660 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 23 01:02:46.326781 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jan 23 01:02:46.330978 systemd-modules-load[216]: Inserted module 'br_netfilter' Jan 23 01:02:46.334328 kernel: Bridge firewalling registered Jan 23 01:02:46.337382 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 01:02:46.339043 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 01:02:46.340698 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 01:02:46.350466 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 01:02:46.365348 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 01:02:46.366943 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 23 01:02:46.392302 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 01:02:46.395022 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 01:02:46.408526 dracut-cmdline[254]: 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=e8d7116310bea9a494780b8becdce41e7cc03ed509d8e2363e08981a47b3edc6 Jan 23 01:02:46.459455 systemd-resolved[264]: Positive Trust Anchors: Jan 23 01:02:46.459468 systemd-resolved[264]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 01:02:46.459500 systemd-resolved[264]: 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 Jan 23 01:02:46.461989 systemd-resolved[264]: Defaulting to hostname 'linux'. Jan 23 01:02:46.462972 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 01:02:46.472319 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 01:02:46.535785 kernel: SCSI subsystem initialized Jan 23 01:02:46.546784 kernel: Loading iSCSI transport class v2.0-870. Jan 23 01:02:46.558782 kernel: iscsi: registered transport (tcp) Jan 23 01:02:46.581960 kernel: iscsi: registered transport (qla4xxx) Jan 23 01:02:46.581986 kernel: QLogic iSCSI HBA Driver Jan 23 01:02:46.600510 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 01:02:46.627226 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 01:02:46.629710 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 01:02:46.682664 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 23 01:02:46.685260 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 23 01:02:46.757783 kernel: raid6: avx2x4 gen() 30792 MB/s Jan 23 01:02:46.776776 kernel: raid6: avx2x2 gen() 30985 MB/s Jan 23 01:02:46.797965 kernel: raid6: avx2x1 gen() 28001 MB/s Jan 23 01:02:46.797991 kernel: raid6: using algorithm avx2x2 gen() 30985 MB/s Jan 23 01:02:46.821020 kernel: raid6: .... xor() 18522 MB/s, rmw enabled Jan 23 01:02:46.821056 kernel: raid6: using avx2x2 recovery algorithm Jan 23 01:02:46.843783 kernel: xor: automatically using best checksumming function avx Jan 23 01:02:47.007786 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 23 01:02:47.014406 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 23 01:02:47.024072 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 01:02:47.052395 systemd-udevd[467]: Using default interface naming scheme 'v255'. Jan 23 01:02:47.058578 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 01:02:47.068857 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 23 01:02:47.098394 dracut-pre-trigger[478]: rd.md=0: removing MD RAID activation Jan 23 01:02:47.125862 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 01:02:47.127739 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 01:02:47.209213 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 01:02:47.219629 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 23 01:02:47.239808 kernel: cryptd: max_cpu_qlen set to 1000 Jan 23 01:02:47.255793 kernel: AES CTR mode by8 optimization enabled Jan 23 01:02:47.272949 kernel: hv_vmbus: Vmbus version:4.0 Jan 23 01:02:47.272843 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:02:47.272904 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:47.310748 kernel: pps_core: LinuxPPS API ver. 1 registered Jan 23 01:02:47.310790 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 23 01:02:47.310809 kernel: hv_vmbus: registering driver hyperv_keyboard Jan 23 01:02:47.310836 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 Jan 23 01:02:47.281230 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:47.296388 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:02:47.310933 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:02:47.329641 kernel: PTP clock support registered Jan 23 01:02:47.329671 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 23 01:02:47.336895 kernel: hv_vmbus: registering driver hv_pci Jan 23 01:02:47.336928 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jan 23 01:02:47.341390 kernel: hv_vmbus: registering driver hid_hyperv Jan 23 01:02:47.341465 kernel: hv_vmbus: registering driver hv_storvsc Jan 23 01:02:47.341535 kernel: hv_vmbus: registering driver hv_netvsc Jan 23 01:02:47.341579 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jan 23 01:02:47.341626 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jan 23 01:02:47.342041 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jan 23 01:02:47.342471 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jan 23 01:02:47.342878 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jan 23 01:02:47.343113 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jan 23 01:02:47.347778 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jan 23 01:02:47.347845 kernel: hv_utils: Registering HyperV Utility Driver Jan 23 01:02:47.347907 kernel: hv_vmbus: registering driver hv_utils Jan 23 01:02:47.351831 kernel: scsi host1: storvsc_host_t Jan 23 01:02:47.351938 kernel: scsi host0: storvsc_host_t Jan 23 01:02:47.352029 kernel: hv_utils: Heartbeat IC version 3.0 Jan 23 01:02:47.352089 kernel: hv_utils: Shutdown IC version 3.2 Jan 23 01:02:47.352825 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 01:02:47.352900 kernel: hv_utils: TimeSync IC version 4.0 Jan 23 01:02:47.353780 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 23 01:02:48.049667 systemd-resolved[264]: Clock change detected. Flushing caches. Jan 23 01:02:48.117086 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jan 23 01:02:48.117287 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jan 23 01:02:48.117315 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jan 23 01:02:48.117334 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jan 23 01:02:48.123505 kernel: pci 0001:00:00.0: Enabling HDA controller Jan 23 01:02:48.124328 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:48.137945 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jan 23 01:02:48.138135 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jan 23 01:02:48.138290 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jan 23 01:02:48.138425 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jan 23 01:02:48.138594 kernel: sd 0:0:0:0: [sda] Write Protect is off Jan 23 01:02:48.141486 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jan 23 01:02:48.141625 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jan 23 01:02:48.141773 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jan 23 01:02:48.141904 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jan 23 01:02:48.142038 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jan 23 01:02:48.148407 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jan 23 01:02:48.148610 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jan 23 01:02:48.165052 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 01:02:48.165229 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 23 01:02:48.165391 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#578 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.186389 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#585 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.194143 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#541 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.201473 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#548 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.210692 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:48.210728 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jan 23 01:02:48.215805 kernel: sdb: sdb1 Jan 23 01:02:48.215836 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jan 23 01:02:48.217474 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jan 23 01:02:48.222410 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jan 23 01:02:48.224484 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jan 23 01:02:48.683122 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jan 23 01:02:48.704582 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jan 23 01:02:48.716924 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 01:02:48.731442 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jan 23 01:02:48.732872 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jan 23 01:02:48.752321 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 23 01:02:48.794472 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#231 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:48.819480 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:49.835584 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#517 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:02:49.850220 disk-uuid[705]: The operation has completed successfully. Jan 23 01:02:49.853900 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 23 01:02:50.650259 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 23 01:02:50.650377 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 23 01:02:50.653584 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jan 23 01:02:50.683642 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 23 01:02:50.685019 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 01:02:50.697049 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 01:02:50.707276 sh[869]: Success Jan 23 01:02:50.706500 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 01:02:50.708657 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 23 01:02:50.733575 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 23 01:02:50.752868 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 23 01:02:50.752892 kernel: device-mapper: uevent: version 1.0.3 Jan 23 01:02:50.752905 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jan 23 01:02:50.764474 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jan 23 01:02:51.019123 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jan 23 01:02:51.022437 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jan 23 01:02:51.043148 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jan 23 01:02:51.061483 kernel: BTRFS: device fsid f8eb2396-46b8-49a3-a8e7-cd8ad10a3ce4 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (896) Jan 23 01:02:51.070360 kernel: BTRFS info (device dm-0): first mount of filesystem f8eb2396-46b8-49a3-a8e7-cd8ad10a3ce4 Jan 23 01:02:51.070385 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:51.443924 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 23 01:02:51.444000 kernel: BTRFS info (device dm-0): enabling free space tree Jan 23 01:02:51.480523 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jan 23 01:02:51.482566 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jan 23 01:02:51.488576 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 23 01:02:51.495769 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 23 01:02:51.507519 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 23 01:02:51.556474 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (928) Jan 23 01:02:51.565561 kernel: BTRFS info (device sda6): first mount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:51.565592 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:51.588405 kernel: BTRFS info (device sda6): turning on async discard Jan 23 01:02:51.588436 kernel: BTRFS info (device sda6): enabling free space tree Jan 23 01:02:51.596471 kernel: BTRFS info (device sda6): last unmount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:51.597615 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 23 01:02:51.600367 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 23 01:02:51.636722 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 01:02:51.639836 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 01:02:51.687496 systemd-networkd[1065]: lo: Link UP Jan 23 01:02:51.687506 systemd-networkd[1065]: lo: Gained carrier Jan 23 01:02:51.689826 systemd-networkd[1065]: Enumeration completed Jan 23 01:02:51.689941 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 01:02:51.690669 systemd-networkd[1065]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:02:51.690677 systemd-networkd[1065]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 01:02:51.691539 systemd[1]: Reached target network.target - Network. Jan 23 01:02:51.692169 systemd-networkd[1065]: eth0: Link UP Jan 23 01:02:51.692598 systemd-networkd[1065]: eth0: Gained carrier Jan 23 01:02:51.692633 systemd-networkd[1065]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:02:51.752504 systemd-networkd[1065]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jan 23 01:02:52.935322 ignition[1038]: Ignition 2.22.0 Jan 23 01:02:52.935908 ignition[1038]: Stage: fetch-offline Jan 23 01:02:52.936075 ignition[1038]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:52.936086 ignition[1038]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:52.936184 ignition[1038]: parsed url from cmdline: "" Jan 23 01:02:52.936191 ignition[1038]: no config URL provided Jan 23 01:02:52.936197 ignition[1038]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 01:02:52.936205 ignition[1038]: no config at "/usr/lib/ignition/user.ign" Jan 23 01:02:52.936211 ignition[1038]: failed to fetch config: resource requires networking Jan 23 01:02:52.936406 ignition[1038]: Ignition finished successfully Jan 23 01:02:52.966429 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 01:02:52.969034 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 23 01:02:53.010875 ignition[1077]: Ignition 2.22.0 Jan 23 01:02:53.010889 ignition[1077]: Stage: fetch Jan 23 01:02:53.011107 ignition[1077]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.011118 ignition[1077]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.011215 ignition[1077]: parsed url from cmdline: "" Jan 23 01:02:53.011219 ignition[1077]: no config URL provided Jan 23 01:02:53.011225 ignition[1077]: reading system config file "/usr/lib/ignition/user.ign" Jan 23 01:02:53.011237 ignition[1077]: no config at "/usr/lib/ignition/user.ign" Jan 23 01:02:53.011257 ignition[1077]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jan 23 01:02:53.044594 systemd-networkd[1065]: eth0: Gained IPv6LL Jan 23 01:02:53.087354 ignition[1077]: GET result: OK Jan 23 01:02:53.087402 ignition[1077]: config has been read from IMDS userdata Jan 23 01:02:53.087414 ignition[1077]: parsing config with SHA512: 38f17a769707b523eed305a9e344abde35a014bd8b2673b4fdd933bd9003768b32113508fd3f0669178f9fa3d8c71056cd073ac0fd39adc640df5077d893abbe Jan 23 01:02:53.092001 unknown[1077]: fetched base config from "system" Jan 23 01:02:53.092176 ignition[1077]: fetch: fetch complete Jan 23 01:02:53.092007 unknown[1077]: fetched base config from "system" Jan 23 01:02:53.092182 ignition[1077]: fetch: fetch passed Jan 23 01:02:53.092013 unknown[1077]: fetched user config from "azure" Jan 23 01:02:53.092226 ignition[1077]: Ignition finished successfully Jan 23 01:02:53.098232 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 23 01:02:53.101162 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 23 01:02:53.147222 ignition[1087]: Ignition 2.22.0 Jan 23 01:02:53.147236 ignition[1087]: Stage: kargs Jan 23 01:02:53.147518 ignition[1087]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.147537 ignition[1087]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.148193 ignition[1087]: kargs: kargs passed Jan 23 01:02:53.148240 ignition[1087]: Ignition finished successfully Jan 23 01:02:53.160088 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 23 01:02:53.166774 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 23 01:02:53.206342 ignition[1096]: Ignition 2.22.0 Jan 23 01:02:53.206356 ignition[1096]: Stage: disks Jan 23 01:02:53.206658 ignition[1096]: no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:53.206670 ignition[1096]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:53.213823 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 23 01:02:53.207276 ignition[1096]: disks: disks passed Jan 23 01:02:53.218652 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 23 01:02:53.207318 ignition[1096]: Ignition finished successfully Jan 23 01:02:53.227057 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 23 01:02:53.235424 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 01:02:53.241598 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 01:02:53.242980 systemd[1]: Reached target basic.target - Basic System. Jan 23 01:02:53.261421 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 23 01:02:53.347618 systemd-fsck[1108]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jan 23 01:02:53.352788 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 23 01:02:53.355201 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 23 01:02:53.677482 kernel: EXT4-fs (sda9): mounted filesystem 2036722e-4586-420e-8dc7-a3b65e840c36 r/w with ordered data mode. Quota mode: none. Jan 23 01:02:53.678398 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 23 01:02:53.685021 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 23 01:02:53.699066 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 01:02:53.701935 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 23 01:02:53.725018 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jan 23 01:02:53.726588 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 23 01:02:53.754842 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1122) Jan 23 01:02:53.754868 kernel: BTRFS info (device sda6): first mount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:53.754881 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:53.726617 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 01:02:53.754531 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 23 01:02:53.779223 kernel: BTRFS info (device sda6): turning on async discard Jan 23 01:02:53.779244 kernel: BTRFS info (device sda6): enabling free space tree Jan 23 01:02:53.770397 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 23 01:02:53.781957 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 01:02:54.393846 coreos-metadata[1124]: Jan 23 01:02:54.393 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 01:02:54.401638 coreos-metadata[1124]: Jan 23 01:02:54.401 INFO Fetch successful Jan 23 01:02:54.406074 coreos-metadata[1124]: Jan 23 01:02:54.401 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jan 23 01:02:54.414381 coreos-metadata[1124]: Jan 23 01:02:54.409 INFO Fetch successful Jan 23 01:02:54.414381 coreos-metadata[1124]: Jan 23 01:02:54.409 INFO wrote hostname ci-4459.2.2-n-e9240d3ad2 to /sysroot/etc/hostname Jan 23 01:02:54.425651 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 01:02:54.651604 initrd-setup-root[1154]: cut: /sysroot/etc/passwd: No such file or directory Jan 23 01:02:54.706295 initrd-setup-root[1161]: cut: /sysroot/etc/group: No such file or directory Jan 23 01:02:54.737333 initrd-setup-root[1168]: cut: /sysroot/etc/shadow: No such file or directory Jan 23 01:02:54.744428 initrd-setup-root[1175]: cut: /sysroot/etc/gshadow: No such file or directory Jan 23 01:02:55.761715 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 23 01:02:55.764576 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 23 01:02:55.772678 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 23 01:02:55.796908 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 23 01:02:55.805895 kernel: BTRFS info (device sda6): last unmount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:55.824391 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 23 01:02:55.841323 ignition[1244]: INFO : Ignition 2.22.0 Jan 23 01:02:55.841323 ignition[1244]: INFO : Stage: mount Jan 23 01:02:55.847047 ignition[1244]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:55.847047 ignition[1244]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:55.847047 ignition[1244]: INFO : mount: mount passed Jan 23 01:02:55.847047 ignition[1244]: INFO : Ignition finished successfully Jan 23 01:02:55.848438 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 23 01:02:55.855901 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 23 01:02:55.891151 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 23 01:02:55.943480 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1258) Jan 23 01:02:55.943512 kernel: BTRFS info (device sda6): first mount of filesystem a3ccc207-e674-4ba2-b6d8-404b4581ba01 Jan 23 01:02:55.960582 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jan 23 01:02:55.974646 kernel: BTRFS info (device sda6): turning on async discard Jan 23 01:02:55.974675 kernel: BTRFS info (device sda6): enabling free space tree Jan 23 01:02:55.976880 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 23 01:02:56.011232 ignition[1275]: INFO : Ignition 2.22.0 Jan 23 01:02:56.011232 ignition[1275]: INFO : Stage: files Jan 23 01:02:56.017039 ignition[1275]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:56.017039 ignition[1275]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:56.017039 ignition[1275]: DEBUG : files: compiled without relabeling support, skipping Jan 23 01:02:56.030319 ignition[1275]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 23 01:02:56.030319 ignition[1275]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 23 01:02:56.094317 ignition[1275]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 23 01:02:56.100008 ignition[1275]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 23 01:02:56.105262 ignition[1275]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 23 01:02:56.100471 unknown[1275]: wrote ssh authorized keys file for user: core Jan 23 01:02:56.144061 ignition[1275]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 01:02:56.151114 ignition[1275]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 23 01:02:56.151114 ignition[1275]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 23 01:02:56.151114 ignition[1275]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 23 01:02:56.151114 ignition[1275]: INFO : files: files passed Jan 23 01:02:56.151114 ignition[1275]: INFO : Ignition finished successfully Jan 23 01:02:56.160836 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 23 01:02:56.169928 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 23 01:02:56.181162 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 23 01:02:56.213605 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 23 01:02:56.213717 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 23 01:02:56.227556 initrd-setup-root-after-ignition[1307]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 01:02:56.227556 initrd-setup-root-after-ignition[1307]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 23 01:02:56.239852 initrd-setup-root-after-ignition[1311]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 23 01:02:56.240055 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 01:02:56.251292 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 23 01:02:56.253908 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 23 01:02:56.314541 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 23 01:02:56.314647 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 23 01:02:56.323203 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 23 01:02:56.325182 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 23 01:02:56.337289 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 23 01:02:56.338105 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 23 01:02:56.356618 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 01:02:56.373399 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 23 01:02:56.405358 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 23 01:02:56.407144 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 01:02:56.416182 systemd[1]: Stopped target timers.target - Timer Units. Jan 23 01:02:56.424830 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 23 01:02:56.424996 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 23 01:02:56.438664 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 23 01:02:56.447044 systemd[1]: Stopped target basic.target - Basic System. Jan 23 01:02:56.455253 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 23 01:02:56.457554 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 23 01:02:56.468450 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 23 01:02:56.477358 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jan 23 01:02:56.485946 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 23 01:02:56.500726 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 23 01:02:56.510426 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 23 01:02:56.518695 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 23 01:02:56.526680 systemd[1]: Stopped target swap.target - Swaps. Jan 23 01:02:56.528225 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 23 01:02:56.528357 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 23 01:02:56.542443 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 23 01:02:56.549079 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 01:02:56.557787 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 23 01:02:56.562289 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 01:02:56.564695 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 23 01:02:56.564829 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 23 01:02:56.588972 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 23 01:02:56.589110 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 23 01:02:56.598289 systemd[1]: ignition-files.service: Deactivated successfully. Jan 23 01:02:56.598417 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 23 01:02:56.600444 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jan 23 01:02:56.600588 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 23 01:02:56.612680 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 23 01:02:56.620260 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 23 01:02:56.620386 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 01:02:56.641028 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 23 01:02:56.660633 ignition[1331]: INFO : Ignition 2.22.0 Jan 23 01:02:56.660633 ignition[1331]: INFO : Stage: umount Jan 23 01:02:56.660633 ignition[1331]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 23 01:02:56.660633 ignition[1331]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 23 01:02:56.642218 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 23 01:02:56.691724 ignition[1331]: INFO : umount: umount passed Jan 23 01:02:56.691724 ignition[1331]: INFO : Ignition finished successfully Jan 23 01:02:56.642334 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 01:02:56.653565 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 23 01:02:56.653689 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 23 01:02:56.673179 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 23 01:02:56.673278 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 23 01:02:56.678112 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 23 01:02:56.678227 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 23 01:02:56.685599 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 23 01:02:56.685652 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 23 01:02:56.693075 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 23 01:02:56.693123 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 23 01:02:56.699444 systemd[1]: Stopped target network.target - Network. Jan 23 01:02:56.709619 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 23 01:02:56.709681 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 23 01:02:56.718596 systemd[1]: Stopped target paths.target - Path Units. Jan 23 01:02:56.720070 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 23 01:02:56.723503 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 01:02:56.731667 systemd[1]: Stopped target slices.target - Slice Units. Jan 23 01:02:56.740230 systemd[1]: Stopped target sockets.target - Socket Units. Jan 23 01:02:56.746905 systemd[1]: iscsid.socket: Deactivated successfully. Jan 23 01:02:56.746957 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 23 01:02:56.754486 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 23 01:02:56.754532 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 23 01:02:56.765338 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 23 01:02:56.765395 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 23 01:02:56.772873 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 23 01:02:56.772921 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 23 01:02:56.780918 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 23 01:02:56.788912 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 23 01:02:56.797424 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 23 01:02:56.798172 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 23 01:02:56.798304 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 23 01:02:56.809208 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jan 23 01:02:56.809539 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 23 01:02:56.809641 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 23 01:02:56.824895 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jan 23 01:02:56.825152 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 23 01:02:56.825463 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 23 01:02:56.838341 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 23 01:02:56.838436 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 23 01:02:56.844064 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jan 23 01:02:56.849291 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 23 01:02:56.849349 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 23 01:02:56.860883 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 23 01:02:56.860948 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 23 01:02:56.869885 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 23 01:02:56.876920 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 23 01:02:56.876977 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 23 01:02:56.885346 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 23 01:02:56.885395 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 23 01:02:56.893091 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 23 01:02:56.893134 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 23 01:02:56.901298 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 23 01:02:56.901347 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 01:02:56.910910 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 01:02:56.919470 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jan 23 01:02:56.919536 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:02:56.943330 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 23 01:02:56.943563 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 01:02:56.947374 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 23 01:02:56.947416 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 23 01:02:56.963770 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 23 01:02:56.963806 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 01:02:56.971552 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 23 01:02:56.971602 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 23 01:02:56.983744 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 23 01:02:56.983794 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 23 01:02:56.995244 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 23 01:02:56.995296 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 23 01:02:57.008994 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 23 01:02:57.016528 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jan 23 01:02:57.016580 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 01:02:57.026378 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 23 01:02:57.026428 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 01:02:57.034945 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:02:57.034996 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:02:57.044331 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jan 23 01:02:57.044386 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jan 23 01:02:57.044427 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:02:57.044780 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 23 01:02:57.044873 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 23 01:02:57.048705 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 23 01:02:57.048790 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 23 01:02:57.060680 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 23 01:02:57.063265 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 23 01:02:57.100658 systemd[1]: Switching root. Jan 23 01:02:57.238097 systemd-journald[214]: Received SIGTERM from PID 1 (systemd). Jan 23 01:02:57.238146 systemd-journald[214]: Journal stopped Jan 23 01:03:02.388298 kernel: SELinux: policy capability network_peer_controls=1 Jan 23 01:03:02.388336 kernel: SELinux: policy capability open_perms=1 Jan 23 01:03:02.388350 kernel: SELinux: policy capability extended_socket_class=1 Jan 23 01:03:02.388359 kernel: SELinux: policy capability always_check_network=0 Jan 23 01:03:02.388368 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 23 01:03:02.388377 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 23 01:03:02.388387 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 23 01:03:02.388399 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 23 01:03:02.388409 kernel: SELinux: policy capability userspace_initial_context=0 Jan 23 01:03:02.388419 kernel: audit: type=1403 audit(1769130178.137:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jan 23 01:03:02.388431 systemd[1]: Successfully loaded SELinux policy in 237.259ms. Jan 23 01:03:02.388443 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.685ms. Jan 23 01:03:02.388468 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) Jan 23 01:03:02.388481 systemd[1]: Detected virtualization microsoft. Jan 23 01:03:02.388496 systemd[1]: Detected architecture x86-64. Jan 23 01:03:02.388506 systemd[1]: Detected first boot. Jan 23 01:03:02.388518 systemd[1]: Hostname set to . Jan 23 01:03:02.388528 systemd[1]: Initializing machine ID from random generator. Jan 23 01:03:02.388539 zram_generator::config[1377]: No configuration found. Jan 23 01:03:02.388553 kernel: Guest personality initialized and is inactive Jan 23 01:03:02.388563 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Jan 23 01:03:02.388573 kernel: Initialized host personality Jan 23 01:03:02.388582 kernel: NET: Registered PF_VSOCK protocol family Jan 23 01:03:02.388593 systemd[1]: Populated /etc with preset unit settings. Jan 23 01:03:02.388604 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jan 23 01:03:02.388614 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 23 01:03:02.388628 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 23 01:03:02.388638 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 23 01:03:02.388653 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 23 01:03:02.388665 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 23 01:03:02.388676 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 23 01:03:02.388687 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 23 01:03:02.388698 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 23 01:03:02.388710 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 23 01:03:02.388723 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 23 01:03:02.388734 systemd[1]: Created slice user.slice - User and Session Slice. Jan 23 01:03:02.388745 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 23 01:03:02.388757 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 23 01:03:02.388768 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 23 01:03:02.388779 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 23 01:03:02.388793 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 23 01:03:02.388805 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 23 01:03:02.388818 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jan 23 01:03:02.388830 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 23 01:03:02.388841 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 23 01:03:02.388852 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 23 01:03:02.388863 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 23 01:03:02.388874 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 23 01:03:02.388885 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 23 01:03:02.388903 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 23 01:03:02.388915 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 23 01:03:02.388926 systemd[1]: Reached target slices.target - Slice Units. Jan 23 01:03:02.388937 systemd[1]: Reached target swap.target - Swaps. Jan 23 01:03:02.388948 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 23 01:03:02.388959 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 23 01:03:02.388974 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jan 23 01:03:02.388985 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 23 01:03:02.388996 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 23 01:03:02.389008 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 23 01:03:02.389019 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 23 01:03:02.389030 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 23 01:03:02.389042 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 23 01:03:02.389055 systemd[1]: Mounting media.mount - External Media Directory... Jan 23 01:03:02.389067 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:02.389078 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 23 01:03:02.389090 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 23 01:03:02.389101 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 23 01:03:02.389112 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). Jan 23 01:03:02.389124 systemd[1]: Reached target machines.target - Containers. Jan 23 01:03:02.389135 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 23 01:03:02.389152 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 01:03:02.389166 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 23 01:03:02.389178 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 23 01:03:02.389189 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 01:03:02.389200 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 01:03:02.389212 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 01:03:02.389223 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 23 01:03:02.389234 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 01:03:02.389246 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 23 01:03:02.389260 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 23 01:03:02.389272 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 23 01:03:02.389283 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 23 01:03:02.389294 systemd[1]: Stopped systemd-fsck-usr.service. Jan 23 01:03:02.389306 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). Jan 23 01:03:02.389317 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 23 01:03:02.389328 kernel: fuse: init (API version 7.41) Jan 23 01:03:02.389339 kernel: loop: module loaded Jan 23 01:03:02.389352 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 23 01:03:02.389363 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 23 01:03:02.389398 systemd-journald[1477]: Collecting audit messages is disabled. Jan 23 01:03:02.389425 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 23 01:03:02.389444 systemd-journald[1477]: Journal started Jan 23 01:03:02.389479 systemd-journald[1477]: Runtime Journal (/run/log/journal/ec6a1efae7054f149c8c364ed8635008) is 8M, max 2.2G, 2.1G free. Jan 23 01:03:01.676495 systemd[1]: Queued start job for default target multi-user.target. Jan 23 01:03:01.700916 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jan 23 01:03:01.701411 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 23 01:03:02.397090 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jan 23 01:03:02.410796 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 23 01:03:02.410838 systemd[1]: verity-setup.service: Deactivated successfully. Jan 23 01:03:02.417100 systemd[1]: Stopped verity-setup.service. Jan 23 01:03:02.430473 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:02.437135 systemd[1]: Started systemd-journald.service - Journal Service. Jan 23 01:03:02.441256 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 23 01:03:02.445445 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 23 01:03:02.449960 systemd[1]: Mounted media.mount - External Media Directory. Jan 23 01:03:02.453997 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 23 01:03:02.458470 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 23 01:03:02.463018 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 23 01:03:02.467212 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 23 01:03:02.472473 kernel: ACPI: bus type drm_connector registered Jan 23 01:03:02.477380 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 23 01:03:02.482652 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 23 01:03:02.482852 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 23 01:03:02.487990 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 01:03:02.488222 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 01:03:02.493252 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 01:03:02.493444 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 01:03:02.498026 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 01:03:02.498216 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 01:03:02.507838 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 23 01:03:02.508038 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 23 01:03:02.512659 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 01:03:02.512843 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 01:03:02.517337 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 23 01:03:02.522034 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 23 01:03:02.527399 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 23 01:03:02.532629 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jan 23 01:03:02.546867 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 23 01:03:02.552510 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 23 01:03:02.569645 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 23 01:03:02.573923 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 23 01:03:02.573959 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 23 01:03:02.579017 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jan 23 01:03:02.585086 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 23 01:03:02.589140 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 01:03:02.590126 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 23 01:03:02.595182 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 23 01:03:02.599951 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 01:03:02.600878 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 23 01:03:02.605354 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 01:03:02.606267 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 23 01:03:02.611260 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 23 01:03:02.616758 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 23 01:03:02.623351 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 23 01:03:02.628440 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 23 01:03:02.634035 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 23 01:03:02.641654 systemd-journald[1477]: Time spent on flushing to /var/log/journal/ec6a1efae7054f149c8c364ed8635008 is 13.973ms for 948 entries. Jan 23 01:03:02.641654 systemd-journald[1477]: System Journal (/var/log/journal/ec6a1efae7054f149c8c364ed8635008) is 8M, max 2.6G, 2.6G free. Jan 23 01:03:02.687756 systemd-journald[1477]: Received client request to flush runtime journal. Jan 23 01:03:02.642949 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 23 01:03:02.651927 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 23 01:03:02.657739 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jan 23 01:03:02.689315 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 23 01:03:02.716469 kernel: loop0: detected capacity change from 0 to 27936 Jan 23 01:03:02.716674 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 23 01:03:02.717370 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jan 23 01:03:02.818341 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 23 01:03:02.846296 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 23 01:03:02.852045 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 23 01:03:02.935750 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Jan 23 01:03:02.935772 systemd-tmpfiles[1534]: ACLs are not supported, ignoring. Jan 23 01:03:02.939577 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 23 01:03:03.255479 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 23 01:03:03.345480 kernel: loop1: detected capacity change from 0 to 128560 Jan 23 01:03:03.462166 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 23 01:03:03.468506 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 23 01:03:03.520232 systemd-udevd[1542]: Using default interface naming scheme 'v255'. Jan 23 01:03:03.770233 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 23 01:03:03.779895 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 23 01:03:03.825707 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jan 23 01:03:03.862480 kernel: mousedev: PS/2 mouse device common for all mice Jan 23 01:03:03.865685 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 23 01:03:03.881479 kernel: hv_vmbus: registering driver hv_balloon Jan 23 01:03:03.881545 kernel: hv_vmbus: registering driver hyperv_fb Jan 23 01:03:03.886964 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jan 23 01:03:03.887012 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jan 23 01:03:03.896483 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jan 23 01:03:03.896543 kernel: Console: switching to colour dummy device 80x25 Jan 23 01:03:03.902619 kernel: Console: switching to colour frame buffer device 128x48 Jan 23 01:03:03.945480 kernel: EDAC MC: Ver: 3.0.0 Jan 23 01:03:03.952944 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 23 01:03:03.966370 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:03:03.979321 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:03:03.979544 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:03:03.985478 kernel: loop2: detected capacity change from 0 to 110984 Jan 23 01:03:03.990382 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jan 23 01:03:03.993684 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:03:03.998720 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 23 01:03:03.998910 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:03:04.005481 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 23 01:03:04.079359 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 23 01:03:04.081909 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 23 01:03:04.092539 systemd-networkd[1566]: lo: Link UP Jan 23 01:03:04.092549 systemd-networkd[1566]: lo: Gained carrier Jan 23 01:03:04.094104 systemd-networkd[1566]: Enumeration completed Jan 23 01:03:04.094212 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 23 01:03:04.102910 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:03:04.102920 systemd-networkd[1566]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 01:03:04.103390 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jan 23 01:03:04.103514 systemd-networkd[1566]: eth0: Link UP Jan 23 01:03:04.103525 systemd-networkd[1566]: eth0: Gained carrier Jan 23 01:03:04.103539 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:03:04.105799 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 23 01:03:04.127357 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 23 01:03:04.177507 systemd-networkd[1566]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jan 23 01:03:04.179687 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jan 23 01:03:04.351477 kernel: loop3: detected capacity change from 0 to 27936 Jan 23 01:03:04.366476 kernel: loop4: detected capacity change from 0 to 128560 Jan 23 01:03:04.383474 kernel: loop5: detected capacity change from 0 to 110984 Jan 23 01:03:04.400929 (sd-merge)[1704]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jan 23 01:03:04.400987 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 23 01:03:04.401403 (sd-merge)[1704]: Merged extensions into '/usr'. Jan 23 01:03:04.408650 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 23 01:03:04.415028 systemd[1]: Starting ensure-sysext.service... Jan 23 01:03:04.418721 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 23 01:03:04.449484 systemd[1]: Reload requested from client PID 1708 ('systemctl') (unit ensure-sysext.service)... Jan 23 01:03:04.449500 systemd[1]: Reloading... Jan 23 01:03:04.482388 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jan 23 01:03:04.496496 zram_generator::config[1742]: No configuration found. Jan 23 01:03:04.496098 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jan 23 01:03:04.496472 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 23 01:03:04.496788 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jan 23 01:03:04.497776 systemd-tmpfiles[1709]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jan 23 01:03:04.498088 systemd-tmpfiles[1709]: ACLs are not supported, ignoring. Jan 23 01:03:04.498159 systemd-tmpfiles[1709]: ACLs are not supported, ignoring. Jan 23 01:03:04.503344 systemd-tmpfiles[1709]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 01:03:04.503357 systemd-tmpfiles[1709]: Skipping /boot Jan 23 01:03:04.511637 systemd-tmpfiles[1709]: Detected autofs mount point /boot during canonicalization of boot. Jan 23 01:03:04.511652 systemd-tmpfiles[1709]: Skipping /boot Jan 23 01:03:04.773871 systemd[1]: Reloading finished in 323 ms. Jan 23 01:03:04.819536 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 23 01:03:04.840158 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 23 01:03:04.865362 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 23 01:03:04.869964 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 01:03:04.871101 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 01:03:04.876726 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 01:03:04.882308 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 01:03:04.886553 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 01:03:04.886678 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). Jan 23 01:03:04.887813 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 23 01:03:04.894222 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 23 01:03:04.899235 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 23 01:03:04.905840 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 01:03:04.906036 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 01:03:04.911006 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 01:03:04.911195 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 01:03:04.916498 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 01:03:04.916700 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 01:03:04.928099 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 23 01:03:04.937863 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 23 01:03:04.939471 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 23 01:03:04.955791 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 23 01:03:04.961758 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 23 01:03:04.967616 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 23 01:03:04.971654 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 23 01:03:04.971785 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). Jan 23 01:03:04.971966 systemd[1]: Reached target time-set.target - System Time Set. Jan 23 01:03:04.979532 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 23 01:03:04.979732 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 23 01:03:04.984761 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 23 01:03:04.984999 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 23 01:03:04.989930 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 23 01:03:04.990112 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 23 01:03:04.995319 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 23 01:03:04.995559 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 23 01:03:05.002535 systemd[1]: Finished ensure-sysext.service. Jan 23 01:03:05.006380 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 23 01:03:05.014854 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:05.014931 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 23 01:03:05.014985 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 23 01:03:05.015003 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jan 23 01:03:05.038131 systemd-resolved[1806]: Positive Trust Anchors: Jan 23 01:03:05.038145 systemd-resolved[1806]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 23 01:03:05.038176 systemd-resolved[1806]: 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 Jan 23 01:03:05.050247 augenrules[1842]: No rules Jan 23 01:03:05.051418 systemd[1]: audit-rules.service: Deactivated successfully. Jan 23 01:03:05.051695 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 23 01:03:05.054386 systemd-resolved[1806]: Using system hostname 'ci-4459.2.2-n-e9240d3ad2'. Jan 23 01:03:05.056281 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 23 01:03:05.061129 systemd[1]: Reached target network.target - Network. Jan 23 01:03:05.064693 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 23 01:03:05.460580 systemd-networkd[1566]: eth0: Gained IPv6LL Jan 23 01:03:05.462860 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 23 01:03:05.468224 systemd[1]: Reached target network-online.target - Network is Online. Jan 23 01:03:05.842347 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 23 01:03:05.847558 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). Jan 23 01:03:08.395146 ldconfig[1513]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 01:03:08.408017 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 23 01:03:08.414306 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 23 01:03:08.454923 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 23 01:03:08.459517 systemd[1]: Reached target sysinit.target - System Initialization. Jan 23 01:03:08.463952 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 23 01:03:08.469099 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jan 23 01:03:08.474814 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jan 23 01:03:08.479663 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 23 01:03:08.484081 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 23 01:03:08.489145 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 23 01:03:08.494139 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 23 01:03:08.494183 systemd[1]: Reached target paths.target - Path Units. Jan 23 01:03:08.497839 systemd[1]: Reached target timers.target - Timer Units. Jan 23 01:03:08.502612 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 23 01:03:08.508257 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 23 01:03:08.514208 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jan 23 01:03:08.519335 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jan 23 01:03:08.524182 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jan 23 01:03:08.530153 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 23 01:03:08.534591 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jan 23 01:03:08.539990 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 23 01:03:08.545437 systemd[1]: Reached target sockets.target - Socket Units. Jan 23 01:03:08.549368 systemd[1]: Reached target basic.target - Basic System. Jan 23 01:03:08.553128 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 23 01:03:08.553173 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 23 01:03:08.567979 systemd[1]: Starting chronyd.service - NTP client/server... Jan 23 01:03:08.572232 systemd[1]: Starting containerd.service - containerd container runtime... Jan 23 01:03:08.585330 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 23 01:03:08.590242 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 23 01:03:08.594786 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 23 01:03:08.600400 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 23 01:03:08.605303 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 23 01:03:08.609234 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). Jan 23 01:03:08.621719 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jan 23 01:03:08.624651 jq[1863]: false Jan 23 01:03:08.625777 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). Jan 23 01:03:08.626893 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jan 23 01:03:08.631122 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jan 23 01:03:08.632351 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 23 01:03:08.637074 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 23 01:03:08.642586 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 23 01:03:08.647514 extend-filesystems[1864]: Found /dev/sda6 Jan 23 01:03:08.650618 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 23 01:03:08.650784 extend-filesystems[1864]: Found /dev/sda9 Jan 23 01:03:08.654958 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 23 01:03:08.660426 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 23 01:03:08.660872 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 23 01:03:08.661257 KVP[1869]: KVP starting; pid is:1869 Jan 23 01:03:08.661628 systemd[1]: Starting update-engine.service - Update Engine... Jan 23 01:03:08.663263 chronyd[1855]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Jan 23 01:03:08.665774 extend-filesystems[1864]: Checking size of /dev/sda9 Jan 23 01:03:08.669978 kernel: hv_utils: KVP IC version 4.0 Jan 23 01:03:08.669410 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 23 01:03:08.670489 KVP[1869]: KVP LIC Version: 3.1 Jan 23 01:03:08.674577 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing passwd entry cache Jan 23 01:03:08.674585 oslogin_cache_refresh[1865]: Refreshing passwd entry cache Jan 23 01:03:08.677784 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 23 01:03:08.680064 jq[1881]: true Jan 23 01:03:08.683070 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 23 01:03:08.683304 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 23 01:03:08.683606 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 23 01:03:08.683804 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 23 01:03:08.694863 systemd[1]: motdgen.service: Deactivated successfully. Jan 23 01:03:08.698383 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting users, quitting Jan 23 01:03:08.698376 oslogin_cache_refresh[1865]: Failure getting users, quitting Jan 23 01:03:08.698511 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 01:03:08.698511 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Refreshing group entry cache Jan 23 01:03:08.698395 oslogin_cache_refresh[1865]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jan 23 01:03:08.698440 oslogin_cache_refresh[1865]: Refreshing group entry cache Jan 23 01:03:08.700303 extend-filesystems[1864]: Old size kept for /dev/sda9 Jan 23 01:03:08.703543 oslogin_cache_refresh[1865]: Failure getting groups, quitting Jan 23 01:03:08.700784 (ntainerd)[1889]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jan 23 01:03:08.712229 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Failure getting groups, quitting Jan 23 01:03:08.712229 google_oslogin_nss_cache[1865]: oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 01:03:08.703554 oslogin_cache_refresh[1865]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jan 23 01:03:08.701900 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 23 01:03:08.712640 jq[1885]: true Jan 23 01:03:08.711365 chronyd[1855]: Timezone right/UTC failed leap second check, ignoring Jan 23 01:03:08.704529 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jan 23 01:03:08.711543 chronyd[1855]: Loaded seccomp filter (level 2) Jan 23 01:03:08.704760 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jan 23 01:03:08.711635 systemd[1]: Started chronyd.service - NTP client/server. Jan 23 01:03:08.713800 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 23 01:03:08.714027 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 23 01:03:08.728184 update_engine[1879]: I20260123 01:03:08.728111 1879 main.cc:92] Flatcar Update Engine starting Jan 23 01:03:08.800553 bash[1926]: Updated "/home/core/.ssh/authorized_keys" Jan 23 01:03:08.801957 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 23 01:03:08.807576 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jan 23 01:03:08.809276 setup-nvidia[1871]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.2+nightly-20260122-2100 Jan 23 01:03:08.816693 systemd-logind[1878]: New seat seat0. Jan 23 01:03:08.820059 systemd-logind[1878]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jan 23 01:03:08.820246 systemd[1]: Started systemd-logind.service - User Login Management. Jan 23 01:03:08.899177 dbus-daemon[1858]: [system] SELinux support is enabled Jan 23 01:03:08.899340 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 23 01:03:08.902272 update_engine[1879]: I20260123 01:03:08.902215 1879 update_check_scheduler.cc:74] Next update check in 9m58s Jan 23 01:03:08.906419 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). Jan 23 01:03:08.906466 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 23 01:03:08.907280 dbus-daemon[1858]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 23 01:03:08.911509 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). Jan 23 01:03:08.911543 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 23 01:03:08.916876 systemd[1]: Started update-engine.service - Update Engine. Jan 23 01:03:08.922487 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 23 01:03:08.949266 coreos-metadata[1857]: Jan 23 01:03:08.949 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 23 01:03:08.951854 coreos-metadata[1857]: Jan 23 01:03:08.951 INFO Fetch successful Jan 23 01:03:08.952013 coreos-metadata[1857]: Jan 23 01:03:08.951 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jan 23 01:03:08.956136 coreos-metadata[1857]: Jan 23 01:03:08.956 INFO Fetch successful Jan 23 01:03:08.956475 coreos-metadata[1857]: Jan 23 01:03:08.956 INFO Fetching http://168.63.129.16/machine/0765c319-8ec3-4b9c-aff6-1dbdfe5ea849/01ab758a%2Dcda1%2D4146%2D8957%2Dff834f35c7b7.%5Fci%2D4459.2.2%2Dn%2De9240d3ad2?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jan 23 01:03:08.958250 coreos-metadata[1857]: Jan 23 01:03:08.958 INFO Fetch successful Jan 23 01:03:08.958398 coreos-metadata[1857]: Jan 23 01:03:08.958 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jan 23 01:03:08.966140 coreos-metadata[1857]: Jan 23 01:03:08.966 INFO Fetch successful Jan 23 01:03:09.001401 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 23 01:03:09.006201 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 23 01:03:09.106079 sshd_keygen[1892]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 23 01:03:09.128734 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 23 01:03:09.134853 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 23 01:03:09.139395 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jan 23 01:03:09.159270 systemd[1]: issuegen.service: Deactivated successfully. Jan 23 01:03:09.159507 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 23 01:03:09.165808 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 23 01:03:09.170834 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jan 23 01:03:09.177621 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 23 01:03:09.180561 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 23 01:03:09.199357 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jan 23 01:03:09.203916 systemd[1]: Reached target getty.target - Login Prompts. Jan 23 01:03:09.204936 locksmithd[1992]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 23 01:03:09.355551 setup-nvidia[2025]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 01:03:09.355853 setup-nvidia[2025]: Dload Upload Total Spent Left Speed Jan 23 01:03:09.854385 containerd[1889]: time="2026-01-23T01:03:09Z" 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 Jan 23 01:03:09.855063 containerd[1889]: time="2026-01-23T01:03:09.855029400Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Jan 23 01:03:09.864620 containerd[1889]: time="2026-01-23T01:03:09.864584500Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" Jan 23 01:03:09.864620 containerd[1889]: time="2026-01-23T01:03:09.864613100Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jan 23 01:03:09.864703 containerd[1889]: time="2026-01-23T01:03:09.864632000Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jan 23 01:03:09.864787 containerd[1889]: time="2026-01-23T01:03:09.864765300Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jan 23 01:03:09.864826 containerd[1889]: time="2026-01-23T01:03:09.864787700Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jan 23 01:03:09.864826 containerd[1889]: time="2026-01-23T01:03:09.864812400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 01:03:09.864891 containerd[1889]: time="2026-01-23T01:03:09.864869700Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 23 01:03:09.864891 containerd[1889]: time="2026-01-23T01:03:09.864888400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jan 23 01:03:09.865111 containerd[1889]: time="2026-01-23T01:03:09.865088500Z" 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 Jan 23 01:03:09.865140 containerd[1889]: time="2026-01-23T01:03:09.865110400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 01:03:09.865140 containerd[1889]: time="2026-01-23T01:03:09.865122700Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 23 01:03:09.865140 containerd[1889]: time="2026-01-23T01:03:09.865130500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jan 23 01:03:09.865240 containerd[1889]: time="2026-01-23T01:03:09.865220500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jan 23 01:03:09.865470 containerd[1889]: time="2026-01-23T01:03:09.865437400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jan 23 01:03:09.865502 containerd[1889]: time="2026-01-23T01:03:09.865488600Z" 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 Jan 23 01:03:09.865535 containerd[1889]: time="2026-01-23T01:03:09.865501600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jan 23 01:03:09.865561 containerd[1889]: time="2026-01-23T01:03:09.865531400Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jan 23 01:03:09.865743 containerd[1889]: time="2026-01-23T01:03:09.865718300Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jan 23 01:03:09.865807 containerd[1889]: time="2026-01-23T01:03:09.865787600Z" level=info msg="metadata content store policy set" policy=shared Jan 23 01:03:09.882409 containerd[1889]: time="2026-01-23T01:03:09.882367000Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jan 23 01:03:09.882488 containerd[1889]: time="2026-01-23T01:03:09.882421800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jan 23 01:03:09.882488 containerd[1889]: time="2026-01-23T01:03:09.882438400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jan 23 01:03:09.882488 containerd[1889]: time="2026-01-23T01:03:09.882450500Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jan 23 01:03:09.882488 containerd[1889]: time="2026-01-23T01:03:09.882480200Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jan 23 01:03:09.882576 containerd[1889]: time="2026-01-23T01:03:09.882491000Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jan 23 01:03:09.882576 containerd[1889]: time="2026-01-23T01:03:09.882504000Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jan 23 01:03:09.882576 containerd[1889]: time="2026-01-23T01:03:09.882521000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jan 23 01:03:09.882576 containerd[1889]: time="2026-01-23T01:03:09.882532200Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jan 23 01:03:09.882576 containerd[1889]: time="2026-01-23T01:03:09.882556200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jan 23 01:03:09.882576 containerd[1889]: time="2026-01-23T01:03:09.882570500Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jan 23 01:03:09.882707 containerd[1889]: time="2026-01-23T01:03:09.882582100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jan 23 01:03:09.882707 containerd[1889]: time="2026-01-23T01:03:09.882690400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jan 23 01:03:09.882752 containerd[1889]: time="2026-01-23T01:03:09.882710000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jan 23 01:03:09.882752 containerd[1889]: time="2026-01-23T01:03:09.882724200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jan 23 01:03:09.882752 containerd[1889]: time="2026-01-23T01:03:09.882735100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jan 23 01:03:09.882752 containerd[1889]: time="2026-01-23T01:03:09.882745200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jan 23 01:03:09.882831 containerd[1889]: time="2026-01-23T01:03:09.882770800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jan 23 01:03:09.882831 containerd[1889]: time="2026-01-23T01:03:09.882788300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jan 23 01:03:09.882831 containerd[1889]: time="2026-01-23T01:03:09.882798300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jan 23 01:03:09.882831 containerd[1889]: time="2026-01-23T01:03:09.882808000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jan 23 01:03:09.882831 containerd[1889]: time="2026-01-23T01:03:09.882817100Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jan 23 01:03:09.882831 containerd[1889]: time="2026-01-23T01:03:09.882826300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jan 23 01:03:09.882959 containerd[1889]: time="2026-01-23T01:03:09.882864500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jan 23 01:03:09.882959 containerd[1889]: time="2026-01-23T01:03:09.882877000Z" level=info msg="Start snapshots syncer" Jan 23 01:03:09.882959 containerd[1889]: time="2026-01-23T01:03:09.882904200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jan 23 01:03:09.883192 containerd[1889]: time="2026-01-23T01:03:09.883136000Z" 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\"}" Jan 23 01:03:09.883311 containerd[1889]: time="2026-01-23T01:03:09.883210400Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jan 23 01:03:09.883311 containerd[1889]: time="2026-01-23T01:03:09.883271800Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jan 23 01:03:09.883401 containerd[1889]: time="2026-01-23T01:03:09.883377300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jan 23 01:03:09.883480 containerd[1889]: time="2026-01-23T01:03:09.883403700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jan 23 01:03:09.883480 containerd[1889]: time="2026-01-23T01:03:09.883414500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jan 23 01:03:09.883545 containerd[1889]: time="2026-01-23T01:03:09.883486600Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jan 23 01:03:09.883545 containerd[1889]: time="2026-01-23T01:03:09.883502200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jan 23 01:03:09.883545 containerd[1889]: time="2026-01-23T01:03:09.883512600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jan 23 01:03:09.883545 containerd[1889]: time="2026-01-23T01:03:09.883522500Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jan 23 01:03:09.883545 containerd[1889]: time="2026-01-23T01:03:09.883543600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883554900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883564100Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883598600Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883614500Z" 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 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883623600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883632000Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883639600Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jan 23 01:03:09.883660 containerd[1889]: time="2026-01-23T01:03:09.883647600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jan 23 01:03:09.883820 containerd[1889]: time="2026-01-23T01:03:09.883662300Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jan 23 01:03:09.883820 containerd[1889]: time="2026-01-23T01:03:09.883678400Z" level=info msg="runtime interface created" Jan 23 01:03:09.883820 containerd[1889]: time="2026-01-23T01:03:09.883683500Z" level=info msg="created NRI interface" Jan 23 01:03:09.883820 containerd[1889]: time="2026-01-23T01:03:09.883691100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jan 23 01:03:09.883820 containerd[1889]: time="2026-01-23T01:03:09.883701000Z" level=info msg="Connect containerd service" Jan 23 01:03:09.883820 containerd[1889]: time="2026-01-23T01:03:09.883718300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 23 01:03:09.884427 containerd[1889]: time="2026-01-23T01:03:09.884396600Z" 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" Jan 23 01:03:10.404754 containerd[1889]: time="2026-01-23T01:03:10.404706000Z" level=info msg="Start subscribing containerd event" Jan 23 01:03:10.404868 containerd[1889]: time="2026-01-23T01:03:10.404777300Z" level=info msg="Start recovering state" Jan 23 01:03:10.404898 containerd[1889]: time="2026-01-23T01:03:10.404879600Z" level=info msg="Start event monitor" Jan 23 01:03:10.404922 containerd[1889]: time="2026-01-23T01:03:10.404901900Z" level=info msg="Start cni network conf syncer for default" Jan 23 01:03:10.404922 containerd[1889]: time="2026-01-23T01:03:10.404912900Z" level=info msg="Start streaming server" Jan 23 01:03:10.404990 containerd[1889]: time="2026-01-23T01:03:10.404905500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 23 01:03:10.405018 containerd[1889]: time="2026-01-23T01:03:10.404938500Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jan 23 01:03:10.405018 containerd[1889]: time="2026-01-23T01:03:10.405001200Z" level=info msg="runtime interface starting up..." Jan 23 01:03:10.405059 containerd[1889]: time="2026-01-23T01:03:10.405019700Z" level=info msg="starting plugins..." Jan 23 01:03:10.405059 containerd[1889]: time="2026-01-23T01:03:10.405005200Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 23 01:03:10.405059 containerd[1889]: time="2026-01-23T01:03:10.405042200Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jan 23 01:03:10.405245 containerd[1889]: time="2026-01-23T01:03:10.405192300Z" level=info msg="containerd successfully booted in 0.551258s" Jan 23 01:03:10.405325 systemd[1]: Started containerd.service - containerd container runtime. Jan 23 01:03:10.854041 waagent[2017]: 2026-01-23T01:03:10.853965Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jan 23 01:03:10.858308 waagent[2017]: 2026-01-23T01:03:10.858255Z INFO Daemon Daemon OS: flatcar 4459.2.2 Jan 23 01:03:10.861724 waagent[2017]: 2026-01-23T01:03:10.861683Z INFO Daemon Daemon Python: 3.11.13 Jan 23 01:03:10.865007 waagent[2017]: 2026-01-23T01:03:10.864950Z INFO Daemon Daemon Run daemon Jan 23 01:03:10.867968 waagent[2017]: 2026-01-23T01:03:10.867925Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.2' Jan 23 01:03:10.874471 waagent[2017]: 2026-01-23T01:03:10.874419Z INFO Daemon Daemon Using waagent for provisioning Jan 23 01:03:10.878414 waagent[2017]: 2026-01-23T01:03:10.878367Z INFO Daemon Daemon Activate resource disk Jan 23 01:03:10.881829 waagent[2017]: 2026-01-23T01:03:10.881785Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jan 23 01:03:10.889195 waagent[2017]: 2026-01-23T01:03:10.889144Z INFO Daemon Daemon Found device: sdb Jan 23 01:03:10.898874 waagent[2017]: 2026-01-23T01:03:10.898822Z INFO Daemon Daemon Examining partition table Jan 23 01:03:10.935864 waagent[2017]: 2026-01-23T01:03:10.935816Z INFO Daemon Daemon GPT not detected, determining filesystem Jan 23 01:03:10.966245 waagent[2017]: 2026-01-23T01:03:10.966197Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jan 23 01:03:10.970758 waagent[2017]: 2026-01-23T01:03:10.970714Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jan 23 01:03:11.650480 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#605 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:03:11.663480 kernel: sdb: sdb1 Jan 23 01:03:11.840851 waagent[2017]: 2026-01-23T01:03:11.840758Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jan 23 01:03:11.859663 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#624 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 23 01:03:11.874469 kernel: sdb: sdb1 Jan 23 01:03:11.874636 waagent[2017]: 2026-01-23T01:03:11.874595Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jan 23 01:03:17.284378 login[2024]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jan 23 01:03:17.289318 login[2020]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 01:03:17.403112 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 23 01:03:17.421674 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 23 01:03:17.572883 systemd-logind[1878]: New session 1 of user core. Jan 23 01:03:17.777302 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 23 01:03:17.872724 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 23 01:03:17.921672 waagent[2017]: 2026-01-23T01:03:17.920034Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jan 23 01:03:18.034201 (systemd)[2112]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jan 23 01:03:18.101376 systemd-logind[1878]: New session c1 of user core. Jan 23 01:03:18.317013 login[2024]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jan 23 01:03:18.444935 systemd-logind[1878]: New session 2 of user core. Jan 23 01:03:19.173793 kernel: EXT4-fs (sdb1): mounted filesystem 0fefbdc9-d2bd-4e30-9a3a-4bd01e96506b r/w with ordered data mode. Quota mode: none. Jan 23 01:03:19.185446 waagent[2017]: 2026-01-23T01:03:19.184386Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jan 23 01:03:19.221618 waagent[2017]: 2026-01-23T01:03:19.220098Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 01:03:19.248344 waagent[2017]: 2026-01-23T01:03:19.247877Z INFO Daemon Daemon Running default provisioning handler Jan 23 01:03:19.405682 waagent[2017]: 2026-01-23T01:03:19.404202Z 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. Jan 23 01:03:19.436788 waagent[2017]: 2026-01-23T01:03:19.434869Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jan 23 01:03:19.457959 waagent[2017]: 2026-01-23T01:03:19.457371Z INFO Daemon Daemon cloud-init is enabled: False Jan 23 01:03:19.478691 waagent[2017]: 2026-01-23T01:03:19.478188Z INFO Daemon Daemon Copying ovf-env.xml Jan 23 01:03:20.194642 waagent[2017]: 2026-01-23T01:03:20.193420Z INFO Daemon Daemon Successfully mounted dvd Jan 23 01:03:20.427856 setup-nvidia[2025]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 529M 0 10665 0 0 20867 0 7:23:25 --:--:-- 7:23:25 20870\u000d 9 529M 9 50.0M 0 0 33.2M 0 0:00:15 0:00:01 0:00:14 33.2M\u000d 21 529M 21 113M 0 0 45.1M 0 0:00:11 0:00:02 0:00:09 45.1M\u000d 43 529M 43 231M 0 0 66.0M 0 0:00:08 0:00:03 0:00:05 66.0M\u000d 65 529M 65 346M 0 0 76.8M 0 0:00:06 0:00:04 0:00:02 76.8M\u000d 75 529M 75 397M 0 0 72.2M 0 0:00:07 0:00:05 0:00:02 79.6M\u000d 82 529M 82 436M 0 0 67.1M 0 0:00:07 0:00:06 0:00:01 77.3M\u000d 86 529M 86 459M 0 0 60.2M 0 0:00:08 0:00:07 0:00:01 67.7M\u000d 90 529M 90 477M 0 0 56.1M 0 0:00:09 0:00:08 0:00:01 49.2M\u000d 93 529M 93 496M 0 0 52.1M 0 0:00:10 0:00:09 0:00:01 29.9M\u000d 97 529M 97 517M 0 0 49.2M 0 0:00:10 0:00:10 --:--:-- 23.9M\u000d100 529M 100 529M 0 0 47.8M 0 0:00:11 0:00:11 --:--:-- 20.3M Jan 23 01:03:20.445900 waagent[2017]: 2026-01-23T01:03:20.440078Z INFO Daemon Daemon Detect protocol endpoint Jan 23 01:03:20.473247 waagent[2017]: 2026-01-23T01:03:20.472777Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 23 01:03:20.496142 waagent[2017]: 2026-01-23T01:03:20.495591Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jan 23 01:03:20.502927 waagent[2017]: 2026-01-23T01:03:20.502590Z INFO Daemon Daemon Test for route to 168.63.129.16 Jan 23 01:03:20.516354 waagent[2017]: 2026-01-23T01:03:20.515886Z INFO Daemon Daemon Route to 168.63.129.16 exists Jan 23 01:03:20.527706 waagent[2017]: 2026-01-23T01:03:20.527294Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jan 23 01:03:20.668689 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jan 23 01:03:20.739961 waagent[2017]: 2026-01-23T01:03:20.738525Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jan 23 01:03:20.756571 waagent[2017]: 2026-01-23T01:03:20.756288Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jan 23 01:03:20.765566 waagent[2017]: 2026-01-23T01:03:20.764881Z INFO Daemon Daemon Server preferred version:2015-04-05 Jan 23 01:03:21.896198 systemd[2112]: Queued start job for default target default.target. Jan 23 01:03:21.971571 systemd[2112]: Created slice app.slice - User Application Slice. Jan 23 01:03:21.971825 systemd[2112]: Reached target paths.target - Paths. Jan 23 01:03:21.972287 systemd[2112]: Reached target timers.target - Timers. Jan 23 01:03:21.994297 systemd[2112]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 01:03:22.388043 systemd[2112]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 01:03:22.390975 systemd[2112]: Reached target sockets.target - Sockets. Jan 23 01:03:22.391798 systemd[2112]: Reached target basic.target - Basic System. Jan 23 01:03:22.392548 systemd[2112]: Reached target default.target - Main User Target. Jan 23 01:03:22.393311 systemd[2112]: Startup finished in 4.174s. Jan 23 01:03:22.395631 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 23 01:03:22.465549 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 23 01:03:22.506871 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 23 01:03:23.231767 waagent[2017]: 2026-01-23T01:03:23.230315Z INFO Daemon Daemon Initializing goal state during protocol detection Jan 23 01:03:23.444151 waagent[2017]: 2026-01-23T01:03:23.322535Z INFO Daemon Daemon Forcing an update of the goal state. Jan 23 01:03:23.497544 waagent[2017]: 2026-01-23T01:03:23.493985Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 01:03:24.180687 waagent[2017]: 2026-01-23T01:03:24.176806Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Jan 23 01:03:24.291126 waagent[2017]: 2026-01-23T01:03:24.283399Z INFO Daemon Jan 23 01:03:24.325649 waagent[2017]: 2026-01-23T01:03:24.325182Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: d0a40a1d-963e-4690-90ad-6fe690ee6c8d eTag: 13384573159035816592 source: Fabric] Jan 23 01:03:24.356165 waagent[2017]: 2026-01-23T01:03:24.354698Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jan 23 01:03:24.472339 waagent[2017]: 2026-01-23T01:03:24.469130Z INFO Daemon Jan 23 01:03:24.529665 waagent[2017]: 2026-01-23T01:03:24.528246Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jan 23 01:03:24.743169 waagent[2017]: 2026-01-23T01:03:24.741275Z INFO Daemon Daemon Downloading artifacts profile blob Jan 23 01:03:26.040678 kernel: hrtimer: interrupt took 3779500 ns Jan 23 01:03:26.120410 waagent[2017]: 2026-01-23T01:03:26.119015Z INFO Daemon Downloaded certificate {'thumbprint': '85E79F82F62EB61FF833534B1B41AFBD86D2B23B', 'hasPrivateKey': True} Jan 23 01:03:26.174720 waagent[2017]: 2026-01-23T01:03:26.174025Z INFO Daemon Fetch goal state completed Jan 23 01:03:26.389624 waagent[2017]: 2026-01-23T01:03:26.386151Z INFO Daemon Daemon Starting provisioning Jan 23 01:03:26.444351 waagent[2017]: 2026-01-23T01:03:26.440992Z INFO Daemon Daemon Handle ovf-env.xml. Jan 23 01:03:26.476095 waagent[2017]: 2026-01-23T01:03:26.475598Z INFO Daemon Daemon Set hostname [ci-4459.2.2-n-e9240d3ad2] Jan 23 01:03:26.564012 waagent[2017]: 2026-01-23T01:03:26.560906Z INFO Daemon Daemon Publish hostname [ci-4459.2.2-n-e9240d3ad2] Jan 23 01:03:26.578445 waagent[2017]: 2026-01-23T01:03:26.577956Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jan 23 01:03:26.613890 waagent[2017]: 2026-01-23T01:03:26.613138Z INFO Daemon Daemon Primary interface is [eth0] Jan 23 01:03:26.762313 systemd-networkd[1566]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jan 23 01:03:26.762402 systemd-networkd[1566]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 23 01:03:26.762865 systemd-networkd[1566]: eth0: DHCP lease lost Jan 23 01:03:26.781546 waagent[2017]: 2026-01-23T01:03:26.780025Z INFO Daemon Daemon Create user account if not exists Jan 23 01:03:26.905904 waagent[2017]: 2026-01-23T01:03:26.895910Z INFO Daemon Daemon User core already exists, skip useradd Jan 23 01:03:26.984019 waagent[2017]: 2026-01-23T01:03:26.982222Z INFO Daemon Daemon Configure sudoer Jan 23 01:03:27.052309 waagent[2017]: 2026-01-23T01:03:27.038138Z INFO Daemon Daemon Configure sshd Jan 23 01:03:27.098033 waagent[2017]: 2026-01-23T01:03:27.096813Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jan 23 01:03:27.102996 waagent[2017]: 2026-01-23T01:03:27.102662Z INFO Daemon Daemon Deploy ssh public key. Jan 23 01:03:27.421018 systemd-networkd[1566]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jan 23 01:03:28.258633 waagent[2017]: 2026-01-23T01:03:28.257638Z INFO Daemon Daemon Provisioning complete Jan 23 01:03:28.479987 waagent[2017]: 2026-01-23T01:03:28.479274Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jan 23 01:03:28.550539 waagent[2017]: 2026-01-23T01:03:28.548035Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jan 23 01:03:28.624069 waagent[2017]: 2026-01-23T01:03:28.622750Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jan 23 01:03:31.743357 waagent[2180]: 2026-01-23T01:03:31.739535Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jan 23 01:03:31.768065 waagent[2180]: 2026-01-23T01:03:31.752238Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.2 Jan 23 01:03:31.768065 waagent[2180]: 2026-01-23T01:03:31.754227Z INFO ExtHandler ExtHandler Python: 3.11.13 Jan 23 01:03:31.768065 waagent[2180]: 2026-01-23T01:03:31.758667Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jan 23 01:03:32.102256 waagent[2180]: 2026-01-23T01:03:32.098866Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.2; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jan 23 01:03:32.153625 waagent[2180]: 2026-01-23T01:03:32.150895Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 01:03:32.153625 waagent[2180]: 2026-01-23T01:03:32.152943Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 01:03:32.338808 waagent[2180]: 2026-01-23T01:03:32.328375Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 23 01:03:32.471831 waagent[2180]: 2026-01-23T01:03:32.470234Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Jan 23 01:03:32.513216 waagent[2180]: 2026-01-23T01:03:32.504336Z INFO ExtHandler Jan 23 01:03:32.517147 waagent[2180]: 2026-01-23T01:03:32.516807Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 39e3c82f-ea77-45e9-917f-948c1dee6443 eTag: 13384573159035816592 source: Fabric] Jan 23 01:03:32.525367 waagent[2180]: 2026-01-23T01:03:32.525025Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 01:03:32.539040 waagent[2180]: 2026-01-23T01:03:32.535372Z INFO ExtHandler Jan 23 01:03:32.540882 waagent[2180]: 2026-01-23T01:03:32.540578Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jan 23 01:03:32.639683 waagent[2180]: 2026-01-23T01:03:32.639301Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 01:03:32.653648 chronyd[1855]: Selected source PHC0 Jan 23 01:03:33.838759 waagent[2180]: 2026-01-23T01:03:33.836607Z INFO ExtHandler Downloaded certificate {'thumbprint': '85E79F82F62EB61FF833534B1B41AFBD86D2B23B', 'hasPrivateKey': True} Jan 23 01:03:33.854424 waagent[2180]: 2026-01-23T01:03:33.853912Z INFO ExtHandler Fetch goal state completed Jan 23 01:03:34.138062 waagent[2180]: 2026-01-23T01:03:34.135244Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Jan 23 01:03:34.267220 waagent[2180]: 2026-01-23T01:03:34.266132Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2180 Jan 23 01:03:34.274120 waagent[2180]: 2026-01-23T01:03:34.273381Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jan 23 01:03:34.280643 waagent[2180]: 2026-01-23T01:03:34.280098Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jan 23 01:03:34.308635 waagent[2180]: 2026-01-23T01:03:34.307750Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] Jan 23 01:03:34.314584 waagent[2180]: 2026-01-23T01:03:34.314064Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jan 23 01:03:34.318606 waagent[2180]: 2026-01-23T01:03:34.318016Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jan 23 01:03:34.331609 waagent[2180]: 2026-01-23T01:03:34.330922Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jan 23 01:03:34.489441 waagent[2180]: 2026-01-23T01:03:34.486324Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jan 23 01:03:34.491655 waagent[2180]: 2026-01-23T01:03:34.491201Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jan 23 01:03:34.693851 waagent[2180]: 2026-01-23T01:03:34.691306Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jan 23 01:03:34.830988 systemd[1]: Reload requested from client PID 2195 ('systemctl') (unit waagent.service)... Jan 23 01:03:34.831209 systemd[1]: Reloading... Jan 23 01:03:36.165832 zram_generator::config[2238]: No configuration found. Jan 23 01:03:36.763895 systemd[1]: Reloading finished in 1922 ms. Jan 23 01:03:36.786586 waagent[2180]: 2026-01-23T01:03:36.785641Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jan 23 01:03:36.786586 waagent[2180]: 2026-01-23T01:03:36.785800Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jan 23 01:03:37.013656 waagent[2180]: 2026-01-23T01:03:37.013575Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jan 23 01:03:37.014010 waagent[2180]: 2026-01-23T01:03:37.013934Z 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] Jan 23 01:03:37.014795 waagent[2180]: 2026-01-23T01:03:37.014740Z INFO ExtHandler ExtHandler Starting env monitor service. Jan 23 01:03:37.015285 waagent[2180]: 2026-01-23T01:03:37.015241Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 01:03:37.015330 waagent[2180]: 2026-01-23T01:03:37.015294Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jan 23 01:03:37.015769 waagent[2180]: 2026-01-23T01:03:37.015708Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jan 23 01:03:37.015832 waagent[2180]: 2026-01-23T01:03:37.015788Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jan 23 01:03:37.015996 waagent[2180]: 2026-01-23T01:03:37.015930Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 23 01:03:37.016324 waagent[2180]: 2026-01-23T01:03:37.016271Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jan 23 01:03:37.016467 waagent[2180]: 2026-01-23T01:03:37.016386Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jan 23 01:03:37.016714 waagent[2180]: 2026-01-23T01:03:37.016674Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 01:03:37.017027 waagent[2180]: 2026-01-23T01:03:37.016983Z INFO EnvHandler ExtHandler Configure routes Jan 23 01:03:37.017164 waagent[2180]: 2026-01-23T01:03:37.017131Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 23 01:03:37.017447 waagent[2180]: 2026-01-23T01:03:37.017406Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jan 23 01:03:37.018068 waagent[2180]: 2026-01-23T01:03:37.018029Z INFO EnvHandler ExtHandler Gateway:None Jan 23 01:03:37.018175 waagent[2180]: 2026-01-23T01:03:37.018097Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jan 23 01:03:37.020558 waagent[2180]: 2026-01-23T01:03:37.020517Z INFO EnvHandler ExtHandler Routes:None Jan 23 01:03:37.021311 waagent[2180]: 2026-01-23T01:03:37.021265Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jan 23 01:03:37.021311 waagent[2180]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jan 23 01:03:37.021311 waagent[2180]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jan 23 01:03:37.021311 waagent[2180]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jan 23 01:03:37.021311 waagent[2180]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jan 23 01:03:37.021311 waagent[2180]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 01:03:37.021311 waagent[2180]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 23 01:03:37.033476 waagent[2180]: 2026-01-23T01:03:37.031839Z INFO ExtHandler ExtHandler Jan 23 01:03:37.033476 waagent[2180]: 2026-01-23T01:03:37.031930Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: de207961-c7ce-445a-9ace-98e903ff3221 correlation ae478d1b-1432-46dc-8832-aa932562b545 created: 2026-01-23T01:02:02.234062Z] Jan 23 01:03:37.033476 waagent[2180]: 2026-01-23T01:03:37.032296Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 01:03:37.033476 waagent[2180]: 2026-01-23T01:03:37.032890Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Jan 23 01:03:37.065197 waagent[2180]: 2026-01-23T01:03:37.065126Z 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 Jan 23 01:03:37.065197 waagent[2180]: Try `iptables -h' or 'iptables --help' for more information.) Jan 23 01:03:37.065712 waagent[2180]: 2026-01-23T01:03:37.065671Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: B6DFAF8D-AFF1-4BF4-AA07-F79F692FDEFB;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jan 23 01:03:37.087811 waagent[2180]: 2026-01-23T01:03:37.087736Z INFO MonitorHandler ExtHandler Network interfaces: Jan 23 01:03:37.087811 waagent[2180]: Executing ['ip', '-a', '-o', 'link']: Jan 23 01:03:37.087811 waagent[2180]: 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 Jan 23 01:03:37.087811 waagent[2180]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:9b:c9:ed brd ff:ff:ff:ff:ff:ff Jan 23 01:03:37.087811 waagent[2180]: Executing ['ip', '-4', '-a', '-o', 'address']: Jan 23 01:03:37.087811 waagent[2180]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jan 23 01:03:37.087811 waagent[2180]: 2: eth0 inet 10.200.8.19/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jan 23 01:03:37.087811 waagent[2180]: Executing ['ip', '-6', '-a', '-o', 'address']: Jan 23 01:03:37.087811 waagent[2180]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jan 23 01:03:37.087811 waagent[2180]: 2: eth0 inet6 fe80::222:48ff:fe9b:c9ed/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jan 23 01:03:37.186494 waagent[2180]: 2026-01-23T01:03:37.186406Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jan 23 01:03:37.186494 waagent[2180]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:37.186494 waagent[2180]: pkts bytes target prot opt in out source destination Jan 23 01:03:37.186494 waagent[2180]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:37.186494 waagent[2180]: pkts bytes target prot opt in out source destination Jan 23 01:03:37.186494 waagent[2180]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:37.186494 waagent[2180]: pkts bytes target prot opt in out source destination Jan 23 01:03:37.186494 waagent[2180]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 01:03:37.186494 waagent[2180]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 01:03:37.186494 waagent[2180]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 01:03:37.190309 waagent[2180]: 2026-01-23T01:03:37.190258Z INFO EnvHandler ExtHandler Current Firewall rules: Jan 23 01:03:37.190309 waagent[2180]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:37.190309 waagent[2180]: pkts bytes target prot opt in out source destination Jan 23 01:03:37.190309 waagent[2180]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:37.190309 waagent[2180]: pkts bytes target prot opt in out source destination Jan 23 01:03:37.190309 waagent[2180]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jan 23 01:03:37.190309 waagent[2180]: pkts bytes target prot opt in out source destination Jan 23 01:03:37.190309 waagent[2180]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 23 01:03:37.190309 waagent[2180]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 23 01:03:37.190309 waagent[2180]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 23 01:03:37.191629 waagent[2180]: 2026-01-23T01:03:37.191588Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jan 23 01:03:37.191795 waagent[2180]: 2026-01-23T01:03:37.191760Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jan 23 01:03:46.463896 setup-nvidia[1871]: Downloading NVIDIA 535.261.03 Driver Jan 23 01:03:46.468289 setup-nvidia[2328]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 23 01:03:46.468537 setup-nvidia[2328]: Dload Upload Total Spent Left Speed Jan 23 01:03:48.560291 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 23 01:03:48.563753 systemd[1]: Started sshd@0-10.200.8.19:22-10.200.16.10:48278.service - OpenSSH per-connection server daemon (10.200.16.10:48278). Jan 23 01:03:49.360177 sshd[2330]: Accepted publickey for core from 10.200.16.10 port 48278 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:03:49.361372 sshd-session[2330]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:03:49.365571 systemd-logind[1878]: New session 3 of user core. Jan 23 01:03:49.382578 systemd[1]: Started session-3.scope - Session 3 of User core. Jan 23 01:03:49.961430 systemd[1]: Started sshd@1-10.200.8.19:22-10.200.16.10:40132.service - OpenSSH per-connection server daemon (10.200.16.10:40132). Jan 23 01:03:50.648903 sshd[2336]: Accepted publickey for core from 10.200.16.10 port 40132 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:03:50.650152 sshd-session[2336]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:03:50.654486 systemd-logind[1878]: New session 4 of user core. Jan 23 01:03:50.662576 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 23 01:03:51.125250 sshd[2339]: Connection closed by 10.200.16.10 port 40132 Jan 23 01:03:51.125610 sshd-session[2336]: pam_unix(sshd:session): session closed for user core Jan 23 01:03:51.128486 systemd[1]: sshd@1-10.200.8.19:22-10.200.16.10:40132.service: Deactivated successfully. Jan 23 01:03:51.130250 systemd[1]: session-4.scope: Deactivated successfully. Jan 23 01:03:51.131462 systemd-logind[1878]: Session 4 logged out. Waiting for processes to exit. Jan 23 01:03:51.132503 systemd-logind[1878]: Removed session 4. Jan 23 01:03:51.248765 systemd[1]: Started sshd@2-10.200.8.19:22-10.200.16.10:40148.service - OpenSSH per-connection server daemon (10.200.16.10:40148). Jan 23 01:03:51.932673 sshd[2345]: Accepted publickey for core from 10.200.16.10 port 40148 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:03:51.933934 sshd-session[2345]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:03:51.938303 systemd-logind[1878]: New session 5 of user core. Jan 23 01:03:51.945572 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 23 01:03:52.035445 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jan 23 01:03:52.411171 sshd[2348]: Connection closed by 10.200.16.10 port 40148 Jan 23 01:03:52.411555 sshd-session[2345]: pam_unix(sshd:session): session closed for user core Jan 23 01:03:52.415098 systemd[1]: sshd@2-10.200.8.19:22-10.200.16.10:40148.service: Deactivated successfully. Jan 23 01:03:52.416663 systemd[1]: session-5.scope: Deactivated successfully. Jan 23 01:03:52.417320 systemd-logind[1878]: Session 5 logged out. Waiting for processes to exit. Jan 23 01:03:52.418330 systemd-logind[1878]: Removed session 5. Jan 23 01:03:53.704887 update_engine[1879]: I20260123 01:03:53.704792 1879 update_attempter.cc:509] Updating boot flags... Jan 23 01:04:02.536818 systemd[1]: Started sshd@3-10.200.8.19:22-10.200.16.10:35976.service - OpenSSH per-connection server daemon (10.200.16.10:35976). Jan 23 01:04:03.393392 sshd[2470]: Accepted publickey for core from 10.200.16.10 port 35976 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:03.393573 sshd-session[2470]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:03.397902 systemd-logind[1878]: New session 6 of user core. Jan 23 01:04:03.408588 systemd[1]: Started session-6.scope - Session 6 of User core. Jan 23 01:04:03.786166 sshd[2473]: Connection closed by 10.200.16.10 port 35976 Jan 23 01:04:03.786552 sshd-session[2470]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:03.790436 systemd[1]: sshd@3-10.200.8.19:22-10.200.16.10:35976.service: Deactivated successfully. Jan 23 01:04:03.792098 systemd[1]: session-6.scope: Deactivated successfully. Jan 23 01:04:03.792801 systemd-logind[1878]: Session 6 logged out. Waiting for processes to exit. Jan 23 01:04:03.793710 systemd-logind[1878]: Removed session 6. Jan 23 01:04:03.915843 systemd[1]: Started sshd@4-10.200.8.19:22-10.200.16.10:35982.service - OpenSSH per-connection server daemon (10.200.16.10:35982). Jan 23 01:04:04.595154 sshd[2480]: Accepted publickey for core from 10.200.16.10 port 35982 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:04.596408 sshd-session[2480]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:04.600864 systemd-logind[1878]: New session 7 of user core. Jan 23 01:04:04.609582 systemd[1]: Started session-7.scope - Session 7 of User core. Jan 23 01:04:05.071208 sshd[2483]: Connection closed by 10.200.16.10 port 35982 Jan 23 01:04:05.071516 sshd-session[2480]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:05.074881 systemd[1]: sshd@4-10.200.8.19:22-10.200.16.10:35982.service: Deactivated successfully. Jan 23 01:04:05.076477 systemd[1]: session-7.scope: Deactivated successfully. Jan 23 01:04:05.077140 systemd-logind[1878]: Session 7 logged out. Waiting for processes to exit. Jan 23 01:04:05.078028 systemd-logind[1878]: Removed session 7. Jan 23 01:04:15.196538 systemd[1]: Started sshd@5-10.200.8.19:22-10.200.16.10:49614.service - OpenSSH per-connection server daemon (10.200.16.10:49614). Jan 23 01:04:15.877619 sshd[2489]: Accepted publickey for core from 10.200.16.10 port 49614 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:15.878875 sshd-session[2489]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:15.883109 systemd-logind[1878]: New session 8 of user core. Jan 23 01:04:15.896569 systemd[1]: Started session-8.scope - Session 8 of User core. Jan 23 01:04:16.354345 sshd[2492]: Connection closed by 10.200.16.10 port 49614 Jan 23 01:04:16.354682 sshd-session[2489]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:16.358031 systemd[1]: sshd@5-10.200.8.19:22-10.200.16.10:49614.service: Deactivated successfully. Jan 23 01:04:16.359774 systemd[1]: session-8.scope: Deactivated successfully. Jan 23 01:04:16.360532 systemd-logind[1878]: Session 8 logged out. Waiting for processes to exit. Jan 23 01:04:16.361402 systemd-logind[1878]: Removed session 8. Jan 23 01:04:16.478066 systemd[1]: Started sshd@6-10.200.8.19:22-10.200.16.10:49624.service - OpenSSH per-connection server daemon (10.200.16.10:49624). Jan 23 01:04:17.155276 sshd[2498]: Accepted publickey for core from 10.200.16.10 port 49624 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:17.156520 sshd-session[2498]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:17.160906 systemd-logind[1878]: New session 9 of user core. Jan 23 01:04:17.172573 systemd[1]: Started session-9.scope - Session 9 of User core. Jan 23 01:04:17.630852 sshd[2501]: Connection closed by 10.200.16.10 port 49624 Jan 23 01:04:17.631202 sshd-session[2498]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:17.634631 systemd[1]: sshd@6-10.200.8.19:22-10.200.16.10:49624.service: Deactivated successfully. Jan 23 01:04:17.636241 systemd[1]: session-9.scope: Deactivated successfully. Jan 23 01:04:17.636955 systemd-logind[1878]: Session 9 logged out. Waiting for processes to exit. Jan 23 01:04:17.637916 systemd-logind[1878]: Removed session 9. Jan 23 01:04:27.754817 systemd[1]: Started sshd@7-10.200.8.19:22-10.200.16.10:56654.service - OpenSSH per-connection server daemon (10.200.16.10:56654). Jan 23 01:04:28.437627 sshd[2507]: Accepted publickey for core from 10.200.16.10 port 56654 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:28.438837 sshd-session[2507]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:28.443130 systemd-logind[1878]: New session 10 of user core. Jan 23 01:04:28.462585 systemd[1]: Started session-10.scope - Session 10 of User core. Jan 23 01:04:28.916564 sshd[2510]: Connection closed by 10.200.16.10 port 56654 Jan 23 01:04:28.916914 sshd-session[2507]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:28.920235 systemd[1]: sshd@7-10.200.8.19:22-10.200.16.10:56654.service: Deactivated successfully. Jan 23 01:04:28.921854 systemd[1]: session-10.scope: Deactivated successfully. Jan 23 01:04:28.922535 systemd-logind[1878]: Session 10 logged out. Waiting for processes to exit. Jan 23 01:04:28.923415 systemd-logind[1878]: Removed session 10. Jan 23 01:04:29.039664 systemd[1]: Started sshd@8-10.200.8.19:22-10.200.16.10:56668.service - OpenSSH per-connection server daemon (10.200.16.10:56668). Jan 23 01:04:29.528569 setup-nvidia[2328]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 7 325M 7 24.0M 0 0 59.6M 0 0:00:05 --:--:-- 0:00:05 59.5M\u000d 31 325M 31 101M 0 0 72.9M 0 0:00:04 0:00:01 0:00:03 72.8M\u000d 31 325M 31 102M 0 0 42.9M 0 0:00:07 0:00:02 0:00:05 42.9M\u000d 34 325M 34 112M 0 0 30.8M 0 0:00:10 0:00:03 0:00:07 30.8M\u000d 36 325M 36 118M 0 0 25.0M 0 0:00:13 0:00:04 0:00:09 25.0M\u000d 37 325M 37 122M 0 0 22.4M 0 0:00:14 0:00:05 0:00:09 19.4M\u000d 39 325M 39 128M 0 0 19.4M 0 0:00:16 0:00:06 0:00:10 5246k\u000d 41 325M 41 134M 0 0 17.4M 0 0:00:18 0:00:07 0:00:11 6063k\u000d 42 325M 42 138M 0 0 16.4M 0 0:00:19 0:00:08 0:00:11 5636k\u000d 44 325M 44 144M 0 0 15.1M 0 0:00:21 0:00:09 0:00:12 5586k\u000d 46 325M 46 150M 0 0 14.1M 0 0:00:22 0:00:10 0:00:12 5566k\u000d 46 325M 46 152M 0 0 12.5M 0 0:00:25 0:00:12 0:00:13 4458k\u000d 49 325M 49 160M 0 0 12.8M 0 0:00:25 0:00:12 0:00:13 5568k\u000d 50 325M 50 166M 0 0 12.2M 0 0:00:26 0:00:13 0:00:13 5476k\u000d 52 325M 52 172M 0 0 11.6M 0 0:00:27 0:00:14 0:00:13 5490k\u000d 54 325M 54 176M 0 0 11.3M 0 0:00:28 0:00:15 0:00:13 5403k\u000d 55 325M 55 180M 0 0 10.4M 0 0:00:31 0:00:17 0:00:14 5566k\u000d 57 325M 57 186M 0 0 10.6M 0 0:00:30 0:00:17 0:00:13 5322k\u000d 58 325M 58 192M 0 0 10.3M 0 0:00:31 0:00:18 0:00:13 5254k\u000d 60 325M 60 196M 0 0 10.1M 0 0:00:32 0:00:19 0:00:13 5241k\u000d 62 325M 62 202M 0 0 9.8M 0 0:00:33 0:00:20 0:00:13 5320k\u000d 63 325M 63 208M 0 0 9823k 0 0:00:33 0:00:21 0:00:12 6458k\u000d 65 325M 65 212M 0 0 9675k 0 0:00:34 0:00:22 0:00:12 5345k\u000d 66 325M 66 218M 0 0 9465k 0 0:00:35 0:00:23 0:00:12 5378k\u000d 68 325M 68 224M 0 0 9281k 0 0:00:35 0:00:24 0:00:11 5392k\u000d 69 325M 69 228M 0 0 9162k 0 0:00:36 0:00:25 0:00:11 5358k\u000d 71 325M 71 234M 0 0 9007k 0 0:00:37 0:00:26 0:00:11 5407k\u000d 73 325M 73 238M 0 0 8893k 0 0:00:37 0:00:27 0:00:10 5368k\u000d 74 325M 74 244M 0 0 8761k 0 0:00:38 0:00:28 0:00:10 5402k\u000d 76 325M 76 250M 0 0 8647k 0 0:00:38 0:00:29 0:00:09 5440k\u000d 78 325M 78 256M 0 0 8545k 0 0:00:39 0:00:30 0:00:09 5529k\u000d 79 325M 79 260M 0 0 8473k 0 0:00:39 0:00:31 0:00:08 5520k\u000d 81 325M 81 266M 0 0 8378k 0 0:00:39 0:00:32 0:00:07 5615k\u000d 83 325M 83 272M 0 0 8280k 0 0:00:40 0:00:33 0:00:07 5593k\u000d 85 325M 85 277M 0 0 8275k 0 0:00:40 0:00:34 0:00:06 5977k\u000d 86 325M 86 282M 0 0 8145k 0 0:00:40 0:00:35 0:00:05 5564k\u000d 88 325M 88 288M 0 0 8075k 0 0:00:41 0:00:36 0:00:05 5630k\u000d 90 325M 90 294M 0 0 8005k 0 0:00:41 0:00:37 0:00:04 5621k\u000d 92 325M 92 300M 0 0 7942k 0 0:00:42 0:00:38 0:00:04 5696k\u000d 93 325M 93 304M 0 0 7902k 0 0:00:42 0:00:39 0:00:03 5338k\u000d 95 325M 95 310M 0 0 7829k 0 0:00:42 0:00:40 0:00:02 5630k\u000d 96 325M 96 314M 0 0 7762k 0 0:00:42 0:00:41 0:00:01 5430k\u000d 98 325M 98 322M 0 0 7734k 0 0:00:43 0:00:42 0:00:01 5708k\u000d100 325M 100 325M 0 0 7750k 0 0:00:43 0:00:43 --:--:-- 6053k Jan 23 01:04:29.601040 setup-nvidia[1871]: Extract the NVIDIA Driver Installer 535.261.03 Jan 23 01:04:29.601269 setup-nvidia[1871]: /opt/nvidia/workdir/nvidia-workdir / Jan 23 01:04:29.619685 setup-nvidia[2521]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Jan 23 01:04:29.720939 sshd[2516]: Accepted publickey for core from 10.200.16.10 port 56668 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:29.722179 sshd-session[2516]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:29.726491 systemd-logind[1878]: New session 11 of user core. Jan 23 01:04:29.733589 systemd[1]: Started session-11.scope - Session 11 of User core. Jan 23 01:04:29.879449 setup-nvidia[2521]: Verifying archive integrity... OK Jan 23 01:04:30.198866 sshd[2540]: Connection closed by 10.200.16.10 port 56668 Jan 23 01:04:30.199166 sshd-session[2516]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:30.202631 systemd[1]: sshd@8-10.200.8.19:22-10.200.16.10:56668.service: Deactivated successfully. Jan 23 01:04:30.204251 systemd[1]: session-11.scope: Deactivated successfully. Jan 23 01:04:30.204940 systemd-logind[1878]: Session 11 logged out. Waiting for processes to exit. Jan 23 01:04:30.205849 systemd-logind[1878]: Removed session 11. Jan 23 01:04:30.581093 setup-nvidia[2521]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Jan 23 01:04:32.346922 setup-nvidia[2552]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jan 23 01:04:32.407357 setup-nvidia[1871]: / Jan 23 01:04:32.407357 setup-nvidia[1871]: Spawn system-nspawn container to install the NVIDIA drivers Jan 23 01:04:32.654111 sudo[2559]: 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.2.2+nightly-20260122-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 Jan 23 01:04:32.655852 sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 23 01:04:32.661580 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jan 23 01:04:32.664191 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 01:04:32.687582 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 01:04:32.691215 systemd[1]: Starting user@0.service - User Manager for UID 0... Jan 23 01:04:32.706337 (systemd)[2562]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jan 23 01:04:32.708400 systemd-logind[1878]: New session c3 of user root. Jan 23 01:04:32.887986 systemd[2562]: Queued start job for default target default.target. Jan 23 01:04:32.906553 systemd[2562]: Created slice app.slice - User Application Slice. Jan 23 01:04:32.906583 systemd[2562]: Reached target paths.target - Paths. Jan 23 01:04:32.906628 systemd[2562]: Reached target timers.target - Timers. Jan 23 01:04:32.907779 systemd[2562]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 23 01:04:32.918301 systemd[2562]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 23 01:04:32.918359 systemd[2562]: Reached target sockets.target - Sockets. Jan 23 01:04:32.918409 systemd[2562]: Reached target basic.target - Basic System. Jan 23 01:04:32.918473 systemd[2562]: Reached target default.target - Main User Target. Jan 23 01:04:32.918510 systemd[2562]: Startup finished in 203ms. Jan 23 01:04:32.918577 systemd[1]: Started user@0.service - User Manager for UID 0. Jan 23 01:04:32.921492 systemd[1]: Started session-c2.scope - Session c2 of User root. Jan 23 01:04:32.974474 kernel: loop6: detected capacity change from 0 to 12644352 Jan 23 01:04:33.021487 kernel: loop6: p9 Jan 23 01:04:33.080919 setup-nvidia[2571]: ░ Spawning container flatcardevelopercontainer-4459.2.2nightly-20260122-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.2+nightly-20260122-2100.bin. Jan 23 01:04:33.195038 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jan 23 01:04:33.195170 kernel: EXT4-fs (loop6p9): mounted filesystem fd657f23-885c-4ff0-b4c6-ecd1f54eb88a ro without journal. Quota mode: none. Jan 23 01:04:40.324963 systemd[1]: Started sshd@9-10.200.8.19:22-10.200.16.10:42308.service - OpenSSH per-connection server daemon (10.200.16.10:42308). Jan 23 01:04:41.007780 sshd[4149]: Accepted publickey for core from 10.200.16.10 port 42308 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:41.009028 sshd-session[4149]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:41.014437 systemd-logind[1878]: New session 12 of user core. Jan 23 01:04:41.023586 systemd[1]: Started session-12.scope - Session 12 of User core. Jan 23 01:04:41.484835 sshd[4571]: Connection closed by 10.200.16.10 port 42308 Jan 23 01:04:41.485175 sshd-session[4149]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:41.488713 systemd[1]: sshd@9-10.200.8.19:22-10.200.16.10:42308.service: Deactivated successfully. Jan 23 01:04:41.490512 systemd[1]: session-12.scope: Deactivated successfully. Jan 23 01:04:41.491251 systemd-logind[1878]: Session 12 logged out. Waiting for processes to exit. Jan 23 01:04:41.492390 systemd-logind[1878]: Removed session 12. Jan 23 01:04:41.603272 systemd[1]: Started sshd@10-10.200.8.19:22-10.200.16.10:42320.service - OpenSSH per-connection server daemon (10.200.16.10:42320). Jan 23 01:04:42.286770 sshd[4926]: Accepted publickey for core from 10.200.16.10 port 42320 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:42.288144 sshd-session[4926]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:42.293069 systemd-logind[1878]: New session 13 of user core. Jan 23 01:04:42.303975 systemd[1]: Started session-13.scope - Session 13 of User core. Jan 23 01:04:42.762600 sshd[5339]: Connection closed by 10.200.16.10 port 42320 Jan 23 01:04:42.762953 sshd-session[4926]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:42.766545 systemd[1]: sshd@10-10.200.8.19:22-10.200.16.10:42320.service: Deactivated successfully. Jan 23 01:04:42.768258 systemd[1]: session-13.scope: Deactivated successfully. Jan 23 01:04:42.768991 systemd-logind[1878]: Session 13 logged out. Waiting for processes to exit. Jan 23 01:04:42.770028 systemd-logind[1878]: Removed session 13. Jan 23 01:04:52.896746 systemd[1]: Started sshd@11-10.200.8.19:22-10.200.16.10:58264.service - OpenSSH per-connection server daemon (10.200.16.10:58264). Jan 23 01:04:53.589995 sshd[9288]: Accepted publickey for core from 10.200.16.10 port 58264 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:53.591271 sshd-session[9288]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:53.597401 systemd-logind[1878]: New session 14 of user core. Jan 23 01:04:53.602643 systemd[1]: Started session-14.scope - Session 14 of User core. Jan 23 01:04:54.068380 sshd[9619]: Connection closed by 10.200.16.10 port 58264 Jan 23 01:04:54.068676 sshd-session[9288]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:54.072374 systemd[1]: sshd@11-10.200.8.19:22-10.200.16.10:58264.service: Deactivated successfully. Jan 23 01:04:54.073956 systemd[1]: session-14.scope: Deactivated successfully. Jan 23 01:04:54.075319 systemd-logind[1878]: Session 14 logged out. Waiting for processes to exit. Jan 23 01:04:54.077404 systemd-logind[1878]: Removed session 14. Jan 23 01:04:54.189677 systemd[1]: Started sshd@12-10.200.8.19:22-10.200.16.10:58274.service - OpenSSH per-connection server daemon (10.200.16.10:58274). Jan 23 01:04:54.887433 sshd[9967]: Accepted publickey for core from 10.200.16.10 port 58274 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:04:54.888698 sshd-session[9967]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:04:54.892969 systemd-logind[1878]: New session 15 of user core. Jan 23 01:04:54.905585 systemd[1]: Started session-15.scope - Session 15 of User core. Jan 23 01:04:55.365118 sshd[10382]: Connection closed by 10.200.16.10 port 58274 Jan 23 01:04:55.366856 sshd-session[9967]: pam_unix(sshd:session): session closed for user core Jan 23 01:04:55.371032 systemd[1]: sshd@12-10.200.8.19:22-10.200.16.10:58274.service: Deactivated successfully. Jan 23 01:04:55.372619 systemd[1]: session-15.scope: Deactivated successfully. Jan 23 01:04:55.373274 systemd-logind[1878]: Session 15 logged out. Waiting for processes to exit. Jan 23 01:04:55.374293 systemd-logind[1878]: Removed session 15. Jan 23 01:05:05.492676 systemd[1]: Started sshd@13-10.200.8.19:22-10.200.16.10:58456.service - OpenSSH per-connection server daemon (10.200.16.10:58456). Jan 23 01:05:06.194351 sshd[14038]: Accepted publickey for core from 10.200.16.10 port 58456 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:06.196235 sshd-session[14038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:06.203337 systemd-logind[1878]: New session 16 of user core. Jan 23 01:05:06.212624 systemd[1]: Started session-16.scope - Session 16 of User core. Jan 23 01:05:06.681030 sshd[14054]: Connection closed by 10.200.16.10 port 58456 Jan 23 01:05:06.681861 sshd-session[14038]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:06.685625 systemd[1]: sshd@13-10.200.8.19:22-10.200.16.10:58456.service: Deactivated successfully. Jan 23 01:05:06.687197 systemd[1]: session-16.scope: Deactivated successfully. Jan 23 01:05:06.689063 systemd-logind[1878]: Session 16 logged out. Waiting for processes to exit. Jan 23 01:05:06.690589 systemd-logind[1878]: Removed session 16. Jan 23 01:05:06.814700 systemd[1]: Started sshd@14-10.200.8.19:22-10.200.16.10:58458.service - OpenSSH per-connection server daemon (10.200.16.10:58458). Jan 23 01:05:07.515376 sshd[14095]: Accepted publickey for core from 10.200.16.10 port 58458 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:07.517143 sshd-session[14095]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:07.523550 systemd-logind[1878]: New session 17 of user core. Jan 23 01:05:07.537670 systemd[1]: Started session-17.scope - Session 17 of User core. Jan 23 01:05:07.992356 sshd[14116]: Connection closed by 10.200.16.10 port 58458 Jan 23 01:05:07.994760 sshd-session[14095]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:07.999305 systemd-logind[1878]: Session 17 logged out. Waiting for processes to exit. Jan 23 01:05:08.000177 systemd[1]: sshd@14-10.200.8.19:22-10.200.16.10:58458.service: Deactivated successfully. Jan 23 01:05:08.001690 systemd[1]: session-17.scope: Deactivated successfully. Jan 23 01:05:08.004444 systemd-logind[1878]: Removed session 17. Jan 23 01:05:18.115804 systemd[1]: Started sshd@15-10.200.8.19:22-10.200.16.10:42116.service - OpenSSH per-connection server daemon (10.200.16.10:42116). Jan 23 01:05:18.821556 sshd[14513]: Accepted publickey for core from 10.200.16.10 port 42116 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:18.822963 sshd-session[14513]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:18.832357 systemd-logind[1878]: New session 18 of user core. Jan 23 01:05:18.840129 systemd[1]: Started session-18.scope - Session 18 of User core. Jan 23 01:05:19.302582 sshd[14539]: Connection closed by 10.200.16.10 port 42116 Jan 23 01:05:19.304507 sshd-session[14513]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:19.308266 systemd[1]: sshd@15-10.200.8.19:22-10.200.16.10:42116.service: Deactivated successfully. Jan 23 01:05:19.310397 systemd[1]: session-18.scope: Deactivated successfully. Jan 23 01:05:19.312037 systemd-logind[1878]: Session 18 logged out. Waiting for processes to exit. Jan 23 01:05:19.313389 systemd-logind[1878]: Removed session 18. Jan 23 01:05:19.424681 systemd[1]: Started sshd@16-10.200.8.19:22-10.200.16.10:42118.service - OpenSSH per-connection server daemon (10.200.16.10:42118). Jan 23 01:05:20.143873 sshd[14557]: Accepted publickey for core from 10.200.16.10 port 42118 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:20.145262 sshd-session[14557]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:20.151580 systemd-logind[1878]: New session 19 of user core. Jan 23 01:05:20.159578 systemd[1]: Started session-19.scope - Session 19 of User core. Jan 23 01:05:20.626674 sshd[14584]: Connection closed by 10.200.16.10 port 42118 Jan 23 01:05:20.627037 sshd-session[14557]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:20.630547 systemd-logind[1878]: Session 19 logged out. Waiting for processes to exit. Jan 23 01:05:20.631421 systemd[1]: sshd@16-10.200.8.19:22-10.200.16.10:42118.service: Deactivated successfully. Jan 23 01:05:20.633496 systemd[1]: session-19.scope: Deactivated successfully. Jan 23 01:05:20.637540 systemd-logind[1878]: Removed session 19. Jan 23 01:05:30.763288 systemd[1]: Started sshd@17-10.200.8.19:22-10.200.16.10:52556.service - OpenSSH per-connection server daemon (10.200.16.10:52556). Jan 23 01:05:31.471416 sshd[14938]: Accepted publickey for core from 10.200.16.10 port 52556 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:31.472798 sshd-session[14938]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:31.479090 systemd-logind[1878]: New session 20 of user core. Jan 23 01:05:31.486626 systemd[1]: Started session-20.scope - Session 20 of User core. Jan 23 01:05:31.950444 sshd[14955]: Connection closed by 10.200.16.10 port 52556 Jan 23 01:05:31.952646 sshd-session[14938]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:31.957105 systemd-logind[1878]: Session 20 logged out. Waiting for processes to exit. Jan 23 01:05:31.960495 systemd[1]: sshd@17-10.200.8.19:22-10.200.16.10:52556.service: Deactivated successfully. Jan 23 01:05:31.962090 systemd[1]: session-20.scope: Deactivated successfully. Jan 23 01:05:31.964757 systemd-logind[1878]: Removed session 20. Jan 23 01:05:32.077242 systemd[1]: Started sshd@18-10.200.8.19:22-10.200.16.10:52568.service - OpenSSH per-connection server daemon (10.200.16.10:52568). Jan 23 01:05:32.774189 sshd[14978]: Accepted publickey for core from 10.200.16.10 port 52568 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:32.776026 sshd-session[14978]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:32.788487 systemd-logind[1878]: New session 21 of user core. Jan 23 01:05:32.794056 systemd[1]: Started session-21.scope - Session 21 of User core. Jan 23 01:05:33.255922 sshd[14998]: Connection closed by 10.200.16.10 port 52568 Jan 23 01:05:33.257869 sshd-session[14978]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:33.261479 systemd-logind[1878]: Session 21 logged out. Waiting for processes to exit. Jan 23 01:05:33.262873 systemd[1]: sshd@18-10.200.8.19:22-10.200.16.10:52568.service: Deactivated successfully. Jan 23 01:05:33.266195 systemd[1]: session-21.scope: Deactivated successfully. Jan 23 01:05:33.270376 systemd-logind[1878]: Removed session 21. Jan 23 01:05:43.386966 systemd[1]: Started sshd@19-10.200.8.19:22-10.200.16.10:44182.service - OpenSSH per-connection server daemon (10.200.16.10:44182). Jan 23 01:05:44.080562 sshd[15199]: Accepted publickey for core from 10.200.16.10 port 44182 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:44.081783 sshd-session[15199]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:44.086011 systemd-logind[1878]: New session 22 of user core. Jan 23 01:05:44.097568 systemd[1]: Started session-22.scope - Session 22 of User core. Jan 23 01:05:44.555692 sshd[15234]: Connection closed by 10.200.16.10 port 44182 Jan 23 01:05:44.555944 sshd-session[15199]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:44.559300 systemd[1]: sshd@19-10.200.8.19:22-10.200.16.10:44182.service: Deactivated successfully. Jan 23 01:05:44.560911 systemd[1]: session-22.scope: Deactivated successfully. Jan 23 01:05:44.561625 systemd-logind[1878]: Session 22 logged out. Waiting for processes to exit. Jan 23 01:05:44.562650 systemd-logind[1878]: Removed session 22. Jan 23 01:05:44.685811 systemd[1]: Started sshd@20-10.200.8.19:22-10.200.16.10:44184.service - OpenSSH per-connection server daemon (10.200.16.10:44184). Jan 23 01:05:45.374650 sshd[15240]: Accepted publickey for core from 10.200.16.10 port 44184 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:45.375917 sshd-session[15240]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:45.380276 systemd-logind[1878]: New session 23 of user core. Jan 23 01:05:45.392587 systemd[1]: Started session-23.scope - Session 23 of User core. Jan 23 01:05:45.857616 sshd[15294]: Connection closed by 10.200.16.10 port 44184 Jan 23 01:05:45.857969 sshd-session[15240]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:45.861340 systemd[1]: sshd@20-10.200.8.19:22-10.200.16.10:44184.service: Deactivated successfully. Jan 23 01:05:45.862909 systemd[1]: session-23.scope: Deactivated successfully. Jan 23 01:05:45.863678 systemd-logind[1878]: Session 23 logged out. Waiting for processes to exit. Jan 23 01:05:45.864676 systemd-logind[1878]: Removed session 23. Jan 23 01:05:45.942530 setup-nvidia[2571]: \u000d Jan 23 01:05:45.942530 setup-nvidia[2571]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jan 23 01:05:45.942530 setup-nvidia[2571]: frequently when this kernel module was built against the wrong or Jan 23 01:05:45.942530 setup-nvidia[2571]: improperly configured kernel sources, with a version of gcc that differs Jan 23 01:05:45.942530 setup-nvidia[2571]: from the one used to build the target kernel, or if another driver, such Jan 23 01:05:45.942530 setup-nvidia[2571]: as nouveau, is present and prevents the NVIDIA kernel module from Jan 23 01:05:45.942530 setup-nvidia[2571]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jan 23 01:05:45.942530 setup-nvidia[2571]: installed in this system is supported by this NVIDIA Linux graphics Jan 23 01:05:45.942530 setup-nvidia[2571]: driver release. Jan 23 01:05:45.942530 setup-nvidia[2571]: \u000d Jan 23 01:05:45.942530 setup-nvidia[2571]: Please see the log entries 'Kernel module load error' and 'Kernel Jan 23 01:05:45.942530 setup-nvidia[2571]: messages' at the end of the file Jan 23 01:05:45.942530 setup-nvidia[2571]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Jan 23 01:05:45.942530 setup-nvidia[2571]: information. Jan 23 01:05:45.942530 setup-nvidia[2571]: \u000d Jan 23 01:05:45.958178 setup-nvidia[2571]: dmesg: read kernel buffer failed: Operation not permitted Jan 23 01:05:45.976885 setup-nvidia[2571]: \u000d Jan 23 01:05:45.976885 setup-nvidia[2571]: ERROR: Installation has failed. Please see the file Jan 23 01:05:45.976885 setup-nvidia[2571]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Jan 23 01:05:45.976885 setup-nvidia[2571]: details. You may find suggestions on fixing installation problems in Jan 23 01:05:45.976885 setup-nvidia[2571]: the README available on the Linux driver download page at Jan 23 01:05:45.976885 setup-nvidia[2571]: www.nvidia.com. Jan 23 01:05:45.976885 setup-nvidia[2571]: \u000d Jan 23 01:05:45.977434 setup-nvidia[2571]: Last 50 lines of nvidia-installer.log: Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jan 23 01:05:45.978275 setup-nvidia[2571]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Jan 23 01:05:45.978275 setup-nvidia[2571]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Jan 23 01:05:45.978275 setup-nvidia[2571]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Jan 23 01:05:45.978275 setup-nvidia[2571]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Jan 23 01:05:45.978275 setup-nvidia[2571]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Jan 23 01:05:45.978275 setup-nvidia[2571]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Jan 23 01:05:45.978275 setup-nvidia[2571]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Jan 23 01:05:45.978275 setup-nvidia[2571]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Jan 23 01:05:45.978275 setup-nvidia[2571]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jan 23 01:05:45.978275 setup-nvidia[2571]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jan 23 01:05:45.978275 setup-nvidia[2571]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jan 23 01:05:45.978275 setup-nvidia[2571]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Jan 23 01:05:45.978275 setup-nvidia[2571]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Jan 23 01:05:45.978275 setup-nvidia[2571]: make[2]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/build' Jan 23 01:05:45.978275 setup-nvidia[2571]: make[1]: Leaving directory '/usr/lib/modules/6.12.66-flatcar/source' Jan 23 01:05:45.978275 setup-nvidia[2571]: -> done. Jan 23 01:05:45.978275 setup-nvidia[2571]: -> Kernel module compilation complete. Jan 23 01:05:45.978275 setup-nvidia[2571]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jan 23 01:05:45.979464 setup-nvidia[2571]: 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. Jan 23 01:05:45.979464 setup-nvidia[2571]: \u000d Jan 23 01:05:45.979464 setup-nvidia[2571]: 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. Jan 23 01:05:45.979464 setup-nvidia[2571]: -> Kernel module load error: Operation not permitted Jan 23 01:05:45.979464 setup-nvidia[2571]: -> Kernel messages: Jan 23 01:05:45.979464 setup-nvidia[2571]: 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. Jan 23 01:05:55.970773 systemd[1]: Started sshd@21-10.200.8.19:22-10.200.16.10:53798.service - OpenSSH per-connection server daemon (10.200.16.10:53798). Jan 23 01:05:56.663716 sshd[15397]: Accepted publickey for core from 10.200.16.10 port 53798 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:56.664945 sshd-session[15397]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:56.669123 systemd-logind[1878]: New session 24 of user core. Jan 23 01:05:56.685575 systemd[1]: Started session-24.scope - Session 24 of User core. Jan 23 01:05:57.139579 sshd[15400]: Connection closed by 10.200.16.10 port 53798 Jan 23 01:05:57.139973 sshd-session[15397]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:57.143588 systemd[1]: sshd@21-10.200.8.19:22-10.200.16.10:53798.service: Deactivated successfully. Jan 23 01:05:57.145224 systemd[1]: session-24.scope: Deactivated successfully. Jan 23 01:05:57.145936 systemd-logind[1878]: Session 24 logged out. Waiting for processes to exit. Jan 23 01:05:57.146846 systemd-logind[1878]: Removed session 24. Jan 23 01:05:57.274771 systemd[1]: Started sshd@22-10.200.8.19:22-10.200.16.10:53800.service - OpenSSH per-connection server daemon (10.200.16.10:53800). Jan 23 01:05:57.980641 sshd[15407]: Accepted publickey for core from 10.200.16.10 port 53800 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:05:57.981898 sshd-session[15407]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:05:57.986165 systemd-logind[1878]: New session 25 of user core. Jan 23 01:05:57.995578 systemd[1]: Started session-25.scope - Session 25 of User core. Jan 23 01:05:58.458755 sshd[15410]: Connection closed by 10.200.16.10 port 53800 Jan 23 01:05:58.459144 sshd-session[15407]: pam_unix(sshd:session): session closed for user core Jan 23 01:05:58.463855 systemd[1]: sshd@22-10.200.8.19:22-10.200.16.10:53800.service: Deactivated successfully. Jan 23 01:05:58.465540 systemd[1]: session-25.scope: Deactivated successfully. Jan 23 01:05:58.466208 systemd-logind[1878]: Session 25 logged out. Waiting for processes to exit. Jan 23 01:05:58.467111 systemd-logind[1878]: Removed session 25. Jan 23 01:06:00.387479 kernel: EXT4-fs (loop6p9): unmounting filesystem fd657f23-885c-4ff0-b4c6-ecd1f54eb88a. Jan 23 01:06:00.388290 setup-nvidia[2571]: Container flatcardevelopercontainer-4459.2.2nightly-20260122-2100.bin exited successfully. Jan 23 01:06:00.390745 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.2nightly\x2d20260122\x2d2100.bin.mount: Deactivated successfully. Jan 23 01:06:00.438332 sudo[2559]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:00.440210 systemd[1]: session-c2.scope: Deactivated successfully. Jan 23 01:06:00.440509 systemd[1]: session-c2.scope: Consumed 5min 17.663s CPU time, 889.4M memory peak. Jan 23 01:06:00.870428 setup-nvidia[1871]: /opt/nvidia / Jan 23 01:06:00.871415 setup-nvidia[1871]: / Jan 23 01:06:00.880479 kernel: IPMI message handler: version 39.2 Jan 23 01:06:00.884471 kernel: ipmi device interface Jan 23 01:06:00.896665 setup-nvidia[1871]: /opt/nvidia/current/usr/lib/modules/6.12.66-flatcar/video / Jan 23 01:06:00.949188 kernel: nvidia: loading out-of-tree module taints kernel. Jan 23 01:06:00.949339 kernel: nvidia: module license 'NVIDIA' taints kernel. Jan 23 01:06:00.949364 kernel: Disabling lock debugging due to kernel taint Jan 23 01:06:00.956471 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jan 23 01:06:00.956517 kernel: nvidia: module license taints kernel. Jan 23 01:06:01.349476 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jan 23 01:06:01.350558 kernel: Jan 23 01:06:01.350588 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jan 23 01:06:01.962477 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Jan 23 01:06:01.984484 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Jan 23 01:06:02.003494 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jan 23 01:06:02.049954 setup-nvidia[1871]: / Jan 23 01:06:02.050472 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jan 23 01:06:02.067607 setup-nvidia[15449]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 23 01:06:03.849368 setup-nvidia[15466]: Fri Jan 23 01:06:03 2026 Jan 23 01:06:03.849368 setup-nvidia[15466]: +---------------------------------------------------------------------------------------+ Jan 23 01:06:03.849368 setup-nvidia[15466]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Jan 23 01:06:03.849368 setup-nvidia[15466]: |-----------------------------------------+----------------------+----------------------+ Jan 23 01:06:03.849368 setup-nvidia[15466]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jan 23 01:06:03.849368 setup-nvidia[15466]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jan 23 01:06:03.849368 setup-nvidia[15466]: | | | MIG M. | Jan 23 01:06:03.849368 setup-nvidia[15466]: |=========================================+======================+======================| Jan 23 01:06:03.852823 setup-nvidia[15466]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jan 23 01:06:03.852823 setup-nvidia[15466]: | N/A 23C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Jan 23 01:06:03.852823 setup-nvidia[15466]: | | | N/A | Jan 23 01:06:03.852823 setup-nvidia[15466]: +-----------------------------------------+----------------------+----------------------+ Jan 23 01:06:03.852823 setup-nvidia[15466]: Jan 23 01:06:03.852823 setup-nvidia[15466]: +---------------------------------------------------------------------------------------+ Jan 23 01:06:03.852823 setup-nvidia[15466]: | Processes: | Jan 23 01:06:03.852823 setup-nvidia[15466]: | GPU GI CI PID Type Process name GPU Memory | Jan 23 01:06:03.852823 setup-nvidia[15466]: | ID ID Usage | Jan 23 01:06:03.852823 setup-nvidia[15466]: |=======================================================================================| Jan 23 01:06:03.853069 setup-nvidia[15466]: | No running processes found | Jan 23 01:06:03.853069 setup-nvidia[15466]: +---------------------------------------------------------------------------------------+ Jan 23 01:06:04.231888 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 23 01:06:04.233947 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 23 01:06:04.234399 systemd[1]: Startup finished in 948ms (firmware) + 17.305s (loader) + 3.690s (kernel) + 11.507s (initrd) + 3min 6.332s (userspace) = 3min 39.785s. Jan 23 01:06:08.586818 systemd[1]: Started sshd@23-10.200.8.19:22-10.200.16.10:55634.service - OpenSSH per-connection server daemon (10.200.16.10:55634). Jan 23 01:06:09.291186 sshd[15473]: Accepted publickey for core from 10.200.16.10 port 55634 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:09.292431 sshd-session[15473]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:09.296743 systemd-logind[1878]: New session 26 of user core. Jan 23 01:06:09.308572 systemd[1]: Started session-26.scope - Session 26 of User core. Jan 23 01:06:09.771582 sshd[15476]: Connection closed by 10.200.16.10 port 55634 Jan 23 01:06:09.771911 sshd-session[15473]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:09.775216 systemd[1]: sshd@23-10.200.8.19:22-10.200.16.10:55634.service: Deactivated successfully. Jan 23 01:06:09.776832 systemd[1]: session-26.scope: Deactivated successfully. Jan 23 01:06:09.777532 systemd-logind[1878]: Session 26 logged out. Waiting for processes to exit. Jan 23 01:06:09.778415 systemd-logind[1878]: Removed session 26. Jan 23 01:06:09.894547 systemd[1]: Started sshd@24-10.200.8.19:22-10.200.16.10:58830.service - OpenSSH per-connection server daemon (10.200.16.10:58830). Jan 23 01:06:10.577214 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jan 23 01:06:10.577999 systemd[2562]: Activating special unit exit.target... Jan 23 01:06:10.578083 systemd[2562]: Stopped target default.target - Main User Target. Jan 23 01:06:10.578123 systemd[2562]: Stopped target basic.target - Basic System. Jan 23 01:06:10.578152 systemd[2562]: Stopped target paths.target - Paths. Jan 23 01:06:10.578178 systemd[2562]: Stopped target sockets.target - Sockets. Jan 23 01:06:10.578203 systemd[2562]: Stopped target timers.target - Timers. Jan 23 01:06:10.578562 systemd[2562]: Closed dbus.socket - D-Bus User Message Bus Socket. Jan 23 01:06:10.578770 systemd[2562]: Removed slice app.slice - User Application Slice. Jan 23 01:06:10.578801 systemd[2562]: Reached target shutdown.target - Shutdown. Jan 23 01:06:10.578943 systemd[2562]: Finished systemd-exit.service - Exit the Session. Jan 23 01:06:10.578979 systemd[2562]: Reached target exit.target - Exit the Session. Jan 23 01:06:10.589519 (sd-pam)[2563]: pam_unix(systemd-user:session): session closed for user root Jan 23 01:06:10.590822 systemd[1]: user@0.service: Deactivated successfully. Jan 23 01:06:10.591196 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jan 23 01:06:10.593480 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jan 23 01:06:10.602125 sshd[15482]: Accepted publickey for core from 10.200.16.10 port 58830 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:10.603340 sshd-session[15482]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:10.607565 systemd-logind[1878]: New session 27 of user core. Jan 23 01:06:10.608816 systemd[1]: Started session-27.scope - Session 27 of User core. Jan 23 01:06:10.613927 systemd[1]: run-user-0.mount: Deactivated successfully. Jan 23 01:06:10.614804 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jan 23 01:06:10.615007 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jan 23 01:06:10.616742 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jan 23 01:06:10.616834 systemd[1]: user-0.slice: Consumed 5min 17.887s CPU time, 895.4M memory peak. Jan 23 01:06:10.617160 systemd-logind[1878]: Removed session c3. Jan 23 01:06:11.078564 sshd[15488]: Connection closed by 10.200.16.10 port 58830 Jan 23 01:06:11.078893 sshd-session[15482]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:11.082584 systemd[1]: sshd@24-10.200.8.19:22-10.200.16.10:58830.service: Deactivated successfully. Jan 23 01:06:11.084197 systemd[1]: session-27.scope: Deactivated successfully. Jan 23 01:06:11.084934 systemd-logind[1878]: Session 27 logged out. Waiting for processes to exit. Jan 23 01:06:11.085839 systemd-logind[1878]: Removed session 27. Jan 23 01:06:11.209013 systemd[1]: Started sshd@25-10.200.8.19:22-10.200.16.10:58846.service - OpenSSH per-connection server daemon (10.200.16.10:58846). Jan 23 01:06:11.913831 sshd[15495]: Accepted publickey for core from 10.200.16.10 port 58846 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:11.915095 sshd-session[15495]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:11.919538 systemd-logind[1878]: New session 28 of user core. Jan 23 01:06:11.930602 systemd[1]: Started session-28.scope - Session 28 of User core. Jan 23 01:06:12.390611 sshd[15499]: Connection closed by 10.200.16.10 port 58846 Jan 23 01:06:12.390986 sshd-session[15495]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:12.394580 systemd[1]: sshd@25-10.200.8.19:22-10.200.16.10:58846.service: Deactivated successfully. Jan 23 01:06:12.396213 systemd[1]: session-28.scope: Deactivated successfully. Jan 23 01:06:12.396964 systemd-logind[1878]: Session 28 logged out. Waiting for processes to exit. Jan 23 01:06:12.398073 systemd-logind[1878]: Removed session 28. Jan 23 01:06:12.521926 systemd[1]: Started sshd@26-10.200.8.19:22-10.200.16.10:58862.service - OpenSSH per-connection server daemon (10.200.16.10:58862). Jan 23 01:06:14.070008 sshd[15505]: Accepted publickey for core from 10.200.16.10 port 58862 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:14.070385 sshd-session[15505]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:14.076969 systemd-logind[1878]: New session 29 of user core. Jan 23 01:06:14.095575 systemd[1]: Started session-29.scope - Session 29 of User core. Jan 23 01:06:14.472835 sudo[15510]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jan 23 01:06:14.473155 sudo[15510]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 01:06:14.564116 sudo[15510]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:14.672784 sshd[15508]: Connection closed by 10.200.16.10 port 58862 Jan 23 01:06:14.673153 sshd-session[15505]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:14.676670 systemd[1]: sshd@26-10.200.8.19:22-10.200.16.10:58862.service: Deactivated successfully. Jan 23 01:06:14.678524 systemd[1]: session-29.scope: Deactivated successfully. Jan 23 01:06:14.679331 systemd-logind[1878]: Session 29 logged out. Waiting for processes to exit. Jan 23 01:06:14.680380 systemd-logind[1878]: Removed session 29. Jan 23 01:06:14.802989 systemd[1]: Started sshd@27-10.200.8.19:22-10.200.16.10:58868.service - OpenSSH per-connection server daemon (10.200.16.10:58868). Jan 23 01:06:15.481592 sshd[15516]: Accepted publickey for core from 10.200.16.10 port 58868 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:15.482867 sshd-session[15516]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:15.487271 systemd-logind[1878]: New session 30 of user core. Jan 23 01:06:15.495565 systemd[1]: Started session-30.scope - Session 30 of User core. Jan 23 01:06:15.850999 sudo[15522]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Jan 23 01:06:15.851298 sudo[15522]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 01:06:15.859102 sudo[15522]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:15.864632 sudo[15521]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jan 23 01:06:15.864909 sudo[15521]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 01:06:15.874155 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 23 01:06:15.915317 augenrules[15544]: No rules Jan 23 01:06:15.916383 systemd[1]: audit-rules.service: Deactivated successfully. Jan 23 01:06:15.916627 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 23 01:06:15.917311 sudo[15521]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:16.025554 sshd[15520]: Connection closed by 10.200.16.10 port 58868 Jan 23 01:06:16.025927 sshd-session[15516]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:16.029689 systemd[1]: sshd@27-10.200.8.19:22-10.200.16.10:58868.service: Deactivated successfully. Jan 23 01:06:16.031259 systemd[1]: session-30.scope: Deactivated successfully. Jan 23 01:06:16.031978 systemd-logind[1878]: Session 30 logged out. Waiting for processes to exit. Jan 23 01:06:16.032856 systemd-logind[1878]: Removed session 30. Jan 23 01:06:16.147929 systemd[1]: Started sshd@28-10.200.8.19:22-10.200.16.10:58874.service - OpenSSH per-connection server daemon (10.200.16.10:58874). Jan 23 01:06:16.841238 sshd[15553]: Accepted publickey for core from 10.200.16.10 port 58874 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:16.842544 sshd-session[15553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:16.846800 systemd-logind[1878]: New session 31 of user core. Jan 23 01:06:16.862576 systemd[1]: Started session-31.scope - Session 31 of User core. Jan 23 01:06:17.210994 sudo[15557]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jan 23 01:06:17.211292 sudo[15557]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 01:06:17.214697 sudo[15557]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:17.322877 sshd[15556]: Connection closed by 10.200.16.10 port 58874 Jan 23 01:06:17.323231 sshd-session[15553]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:17.326599 systemd[1]: sshd@28-10.200.8.19:22-10.200.16.10:58874.service: Deactivated successfully. Jan 23 01:06:17.328148 systemd[1]: session-31.scope: Deactivated successfully. Jan 23 01:06:17.328876 systemd-logind[1878]: Session 31 logged out. Waiting for processes to exit. Jan 23 01:06:17.329891 systemd-logind[1878]: Removed session 31. Jan 23 01:06:17.444562 systemd[1]: Started sshd@29-10.200.8.19:22-10.200.16.10:58886.service - OpenSSH per-connection server daemon (10.200.16.10:58886). Jan 23 01:06:18.175439 sshd[15563]: Accepted publickey for core from 10.200.16.10 port 58886 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:18.176689 sshd-session[15563]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:18.180972 systemd-logind[1878]: New session 32 of user core. Jan 23 01:06:18.192562 systemd[1]: Started session-32.scope - Session 32 of User core. Jan 23 01:06:18.596439 sudo[15567]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jan 23 01:06:18.596753 sudo[15567]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jan 23 01:06:22.542206 sudo[15567]: pam_unix(sudo:session): session closed for user root Jan 23 01:06:22.650026 sshd[15566]: Connection closed by 10.200.16.10 port 58886 Jan 23 01:06:22.650315 sshd-session[15563]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:22.653944 systemd[1]: sshd@29-10.200.8.19:22-10.200.16.10:58886.service: Deactivated successfully. Jan 23 01:06:22.655531 systemd[1]: session-32.scope: Deactivated successfully. Jan 23 01:06:22.656182 systemd-logind[1878]: Session 32 logged out. Waiting for processes to exit. Jan 23 01:06:22.657074 systemd-logind[1878]: Removed session 32. Jan 23 01:06:22.773656 systemd[1]: Started sshd@30-10.200.8.19:22-10.200.16.10:35176.service - OpenSSH per-connection server daemon (10.200.16.10:35176). Jan 23 01:06:23.455899 sshd[15573]: Accepted publickey for core from 10.200.16.10 port 35176 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:23.457142 sshd-session[15573]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:23.461268 systemd-logind[1878]: New session 33 of user core. Jan 23 01:06:23.471584 systemd[1]: Started session-33.scope - Session 33 of User core. Jan 23 01:06:24.914126 systemd[1]: Starting docker.service - Docker Application Container Engine... Jan 23 01:06:24.930787 (dockerd)[15597]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jan 23 01:06:26.359726 dockerd[15597]: time="2026-01-23T01:06:26.359655535Z" level=info msg="Starting up" Jan 23 01:06:26.362096 dockerd[15597]: time="2026-01-23T01:06:26.360302124Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jan 23 01:06:26.372251 dockerd[15597]: time="2026-01-23T01:06:26.372216017Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Jan 23 01:06:26.708532 dockerd[15597]: time="2026-01-23T01:06:26.708414264Z" level=info msg="Loading containers: start." Jan 23 01:06:26.726635 kernel: Initializing XFRM netlink socket Jan 23 01:06:26.835875 waagent[2180]: 2026-01-23T01:06:26.835822Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Jan 23 01:06:26.844616 waagent[2180]: 2026-01-23T01:06:26.844574Z INFO ExtHandler Jan 23 01:06:26.844709 waagent[2180]: 2026-01-23T01:06:26.844675Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 70444a6d-bbe3-4e2b-b8d3-8614ab05a69a eTag: 8812411880968147844 source: Fabric] Jan 23 01:06:26.845036 waagent[2180]: 2026-01-23T01:06:26.844994Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 23 01:06:26.845701 waagent[2180]: 2026-01-23T01:06:26.845662Z INFO ExtHandler Jan 23 01:06:26.845805 waagent[2180]: 2026-01-23T01:06:26.845749Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Jan 23 01:06:26.905868 waagent[2180]: 2026-01-23T01:06:26.905827Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 23 01:06:26.963745 waagent[2180]: 2026-01-23T01:06:26.963633Z INFO ExtHandler Downloaded certificate {'thumbprint': '85E79F82F62EB61FF833534B1B41AFBD86D2B23B', 'hasPrivateKey': True} Jan 23 01:06:26.964182 waagent[2180]: 2026-01-23T01:06:26.964143Z INFO ExtHandler Fetch goal state completed Jan 23 01:06:26.964570 waagent[2180]: 2026-01-23T01:06:26.964534Z INFO ExtHandler ExtHandler Jan 23 01:06:26.964648 waagent[2180]: 2026-01-23T01:06:26.964617Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 484cd11d-04b0-41fd-99eb-0782796818e2 correlation ae478d1b-1432-46dc-8832-aa932562b545 created: 2026-01-23T01:06:19.015465Z] Jan 23 01:06:26.965040 waagent[2180]: 2026-01-23T01:06:26.964997Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 23 01:06:26.965582 waagent[2180]: 2026-01-23T01:06:26.965545Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 1 ms] Jan 23 01:06:26.966546 systemd-networkd[1566]: docker0: Link UP Jan 23 01:06:26.983885 dockerd[15597]: time="2026-01-23T01:06:26.983847570Z" level=info msg="Loading containers: done." Jan 23 01:06:26.997104 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck88092713-merged.mount: Deactivated successfully. Jan 23 01:06:27.005635 dockerd[15597]: time="2026-01-23T01:06:27.005555292Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jan 23 01:06:27.006234 dockerd[15597]: time="2026-01-23T01:06:27.005952185Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Jan 23 01:06:27.006234 dockerd[15597]: time="2026-01-23T01:06:27.006047183Z" level=info msg="Initializing buildkit" Jan 23 01:06:27.068138 dockerd[15597]: time="2026-01-23T01:06:27.068095403Z" level=info msg="Completed buildkit initialization" Jan 23 01:06:27.073956 dockerd[15597]: time="2026-01-23T01:06:27.073912502Z" level=info msg="Daemon has completed initialization" Jan 23 01:06:27.074056 dockerd[15597]: time="2026-01-23T01:06:27.073994100Z" level=info msg="API listen on /run/docker.sock" Jan 23 01:06:27.074131 systemd[1]: Started docker.service - Docker Application Container Engine. Jan 23 01:06:28.174963 systemd[1]: var-lib-docker-overlay2-f3f3425488571e76500b7f11a753ade6366265d21501ee7258405e9d79e99977\x2dinit-merged.mount: Deactivated successfully. Jan 23 01:06:28.274245 containerd[1889]: time="2026-01-23T01:06:28.274194107Z" level=info msg="connecting to shim 0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e" address="unix:///run/containerd/s/262cd51deac14a0eb311079974bce7f41ead4095d1fc9e14f2a0c05c7b6bdec5" namespace=moby protocol=ttrpc version=3 Jan 23 01:06:28.304601 systemd[1]: Started docker-0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e.scope - libcontainer container 0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e. Jan 23 01:06:28.351962 kernel: docker0: port 1(vethd04159d) entered blocking state Jan 23 01:06:28.352053 kernel: docker0: port 1(vethd04159d) entered disabled state Jan 23 01:06:28.354600 kernel: vethd04159d: entered allmulticast mode Jan 23 01:06:28.357677 kernel: vethd04159d: entered promiscuous mode Jan 23 01:06:28.358203 systemd-networkd[1566]: vethd04159d: Link UP Jan 23 01:06:28.379480 kernel: eth0: renamed from veth1311332 Jan 23 01:06:28.386279 kernel: docker0: port 1(vethd04159d) entered blocking state Jan 23 01:06:28.386330 kernel: docker0: port 1(vethd04159d) entered forwarding state Jan 23 01:06:28.386675 systemd-networkd[1566]: vethd04159d: Gained carrier Jan 23 01:06:28.386915 systemd-networkd[1566]: docker0: Gained carrier Jan 23 01:06:28.425490 systemd[1]: docker-0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e.scope: Deactivated successfully. Jan 23 01:06:28.444440 containerd[1889]: time="2026-01-23T01:06:28.444397945Z" level=info msg="shim disconnected" id=0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e namespace=moby Jan 23 01:06:28.444440 containerd[1889]: time="2026-01-23T01:06:28.444433144Z" level=warning msg="cleaning up after shim disconnected" id=0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e namespace=moby Jan 23 01:06:28.444645 containerd[1889]: time="2026-01-23T01:06:28.444442544Z" level=info msg="cleaning up dead shim" namespace=moby Jan 23 01:06:28.444682 dockerd[15597]: time="2026-01-23T01:06:28.444435244Z" level=info msg="ignoring event" container=0f453af5c0b0238303a9012d27ca974cd40888bcd3a5980649214adc51ef742e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jan 23 01:06:28.472918 systemd-networkd[1566]: vethd04159d: Lost carrier Jan 23 01:06:28.473478 kernel: docker0: port 1(vethd04159d) entered disabled state Jan 23 01:06:28.473535 kernel: veth1311332: renamed from eth0 Jan 23 01:06:28.494015 systemd-networkd[1566]: vethd04159d: Link DOWN Jan 23 01:06:28.497486 kernel: docker0: port 1(vethd04159d) entered disabled state Jan 23 01:06:28.504248 kernel: vethd04159d (unregistering): left allmulticast mode Jan 23 01:06:28.504298 kernel: vethd04159d (unregistering): left promiscuous mode Jan 23 01:06:28.507659 kernel: docker0: port 1(vethd04159d) entered disabled state Jan 23 01:06:28.661968 sshd[15576]: Connection closed by 10.200.16.10 port 35176 Jan 23 01:06:28.662317 sshd-session[15573]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:28.666718 systemd[1]: sshd@30-10.200.8.19:22-10.200.16.10:35176.service: Deactivated successfully. Jan 23 01:06:28.668490 systemd[1]: session-33.scope: Deactivated successfully. Jan 23 01:06:28.669154 systemd-logind[1878]: Session 33 logged out. Waiting for processes to exit. Jan 23 01:06:28.670589 systemd-logind[1878]: Removed session 33. Jan 23 01:06:28.786528 systemd[1]: Started sshd@31-10.200.8.19:22-10.200.16.10:35184.service - OpenSSH per-connection server daemon (10.200.16.10:35184). Jan 23 01:06:29.171743 systemd[1]: var-lib-docker-overlay2-f3f3425488571e76500b7f11a753ade6366265d21501ee7258405e9d79e99977-merged.mount: Deactivated successfully. Jan 23 01:06:29.364678 systemd-networkd[1566]: docker0: Lost carrier Jan 23 01:06:29.468824 sshd[15893]: Accepted publickey for core from 10.200.16.10 port 35184 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:29.470006 sshd-session[15893]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:29.474028 systemd-logind[1878]: New session 34 of user core. Jan 23 01:06:29.490570 systemd[1]: Started session-34.scope - Session 34 of User core. Jan 23 01:06:29.748602 systemd-networkd[1566]: docker0: Gained IPv6LL Jan 23 01:06:29.906491 ntpdate[15906]: ntpdate 4.2.8p18@1.4062-o Thu Jan 22 21:35:53 UTC 2026 (1) Jan 23 01:06:36.650654 ntpdate[15906]: adjust time server 88.81.100.130 offset -0.028210 sec Jan 23 01:06:36.761003 sshd[15896]: Connection closed by 10.200.16.10 port 35184 Jan 23 01:06:36.761401 sshd-session[15893]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:36.764976 systemd[1]: sshd@31-10.200.8.19:22-10.200.16.10:35184.service: Deactivated successfully. Jan 23 01:06:36.766580 systemd[1]: session-34.scope: Deactivated successfully. Jan 23 01:06:36.767222 systemd-logind[1878]: Session 34 logged out. Waiting for processes to exit. Jan 23 01:06:36.768199 systemd-logind[1878]: Removed session 34. Jan 23 01:06:36.883532 systemd[1]: Started sshd@32-10.200.8.19:22-10.200.16.10:33386.service - OpenSSH per-connection server daemon (10.200.16.10:33386). Jan 23 01:06:37.586296 sshd[15911]: Accepted publickey for core from 10.200.16.10 port 33386 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:37.587591 sshd-session[15911]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:37.595336 systemd-logind[1878]: New session 35 of user core. Jan 23 01:06:37.603588 systemd[1]: Started session-35.scope - Session 35 of User core. Jan 23 01:06:38.116508 sshd[15914]: Connection closed by 10.200.16.10 port 33386 Jan 23 01:06:38.116864 sshd-session[15911]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:38.120219 systemd[1]: sshd@32-10.200.8.19:22-10.200.16.10:33386.service: Deactivated successfully. Jan 23 01:06:38.121815 systemd[1]: session-35.scope: Deactivated successfully. Jan 23 01:06:38.122474 systemd-logind[1878]: Session 35 logged out. Waiting for processes to exit. Jan 23 01:06:38.123367 systemd-logind[1878]: Removed session 35. Jan 23 01:06:38.239739 systemd[1]: Started sshd@33-10.200.8.19:22-10.200.16.10:33398.service - OpenSSH per-connection server daemon (10.200.16.10:33398). Jan 23 01:06:38.942284 sshd[15930]: Accepted publickey for core from 10.200.16.10 port 33398 ssh2: RSA SHA256:q1ldoqTxIYR6kyNWIydBjsD4MI2USGCydwKgIwdCZwA Jan 23 01:06:38.943579 sshd-session[15930]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 23 01:06:38.947759 systemd-logind[1878]: New session 36 of user core. Jan 23 01:06:38.959578 systemd[1]: Started session-36.scope - Session 36 of User core. Jan 23 01:06:39.362874 systemd[1]: var-lib-docker-overlay2-ef941140fcb1f14f136f562c98f1f846fa86056882df05f274a2895c650090bb\x2dinit-merged.mount: Deactivated successfully. Jan 23 01:06:39.454164 containerd[1889]: time="2026-01-23T01:06:39.454119355Z" level=info msg="connecting to shim 518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e" address="unix:///run/containerd/s/2a648cc66b9aa8607cce5b1c0fe4b1efad01b052a896f0da1483611d98f63088" namespace=moby protocol=ttrpc version=3 Jan 23 01:06:39.484597 systemd[1]: Started docker-518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e.scope - libcontainer container 518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e. Jan 23 01:06:39.530929 kernel: docker0: port 1(vethc588a31) entered blocking state Jan 23 01:06:39.531031 kernel: docker0: port 1(vethc588a31) entered disabled state Jan 23 01:06:39.533795 kernel: vethc588a31: entered allmulticast mode Jan 23 01:06:39.536619 kernel: vethc588a31: entered promiscuous mode Jan 23 01:06:39.537144 systemd-networkd[1566]: vethc588a31: Link UP Jan 23 01:06:39.555478 kernel: eth0: renamed from veth5482336 Jan 23 01:06:39.562391 kernel: docker0: port 1(vethc588a31) entered blocking state Jan 23 01:06:39.562442 kernel: docker0: port 1(vethc588a31) entered forwarding state Jan 23 01:06:39.562787 systemd-networkd[1566]: vethc588a31: Gained carrier Jan 23 01:06:39.563125 systemd-networkd[1566]: docker0: Gained carrier Jan 23 01:06:40.692596 systemd-networkd[1566]: vethc588a31: Gained IPv6LL Jan 23 01:06:42.799100 systemd[1]: docker-518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e.scope: Deactivated successfully. Jan 23 01:06:42.812211 containerd[1889]: time="2026-01-23T01:06:42.812173574Z" level=info msg="shim disconnected" id=518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e namespace=moby Jan 23 01:06:42.812211 containerd[1889]: time="2026-01-23T01:06:42.812207374Z" level=warning msg="cleaning up after shim disconnected" id=518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e namespace=moby Jan 23 01:06:42.812557 containerd[1889]: time="2026-01-23T01:06:42.812215674Z" level=info msg="cleaning up dead shim" namespace=moby Jan 23 01:06:42.812599 dockerd[15597]: time="2026-01-23T01:06:42.812267773Z" level=info msg="ignoring event" container=518633175d0a3a1d692e8a13585d61a229cdc58f2a4115f9ae8b98a90b09675e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jan 23 01:06:42.840800 systemd-networkd[1566]: vethc588a31: Lost carrier Jan 23 01:06:42.841519 kernel: docker0: port 1(vethc588a31) entered disabled state Jan 23 01:06:42.841602 kernel: veth5482336: renamed from eth0 Jan 23 01:06:42.867216 systemd-networkd[1566]: vethc588a31: Link DOWN Jan 23 01:06:42.871478 kernel: docker0: port 1(vethc588a31) entered disabled state Jan 23 01:06:42.877858 kernel: vethc588a31 (unregistering): left allmulticast mode Jan 23 01:06:42.877911 kernel: vethc588a31 (unregistering): left promiscuous mode Jan 23 01:06:42.881185 kernel: docker0: port 1(vethc588a31) entered disabled state Jan 23 01:06:42.900307 systemd[1]: run-docker-netns-794c9e358b49.mount: Deactivated successfully. Jan 23 01:06:42.903279 systemd[1]: var-lib-docker-overlay2-ef941140fcb1f14f136f562c98f1f846fa86056882df05f274a2895c650090bb-merged.mount: Deactivated successfully. Jan 23 01:06:43.042113 sshd[15933]: Connection closed by 10.200.16.10 port 33398 Jan 23 01:06:43.042516 sshd-session[15930]: pam_unix(sshd:session): session closed for user core Jan 23 01:06:43.046134 systemd[1]: sshd@33-10.200.8.19:22-10.200.16.10:33398.service: Deactivated successfully. Jan 23 01:06:43.047801 systemd[1]: session-36.scope: Deactivated successfully. Jan 23 01:06:43.048481 systemd-logind[1878]: Session 36 logged out. Waiting for processes to exit. Jan 23 01:06:43.049423 systemd-logind[1878]: Removed session 36. Jan 23 01:06:43.892784 systemd-networkd[1566]: docker0: Lost carrier