Dec 12 18:38:14.205763 kernel: Linux version 6.12.61-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 Fri Dec 12 15:21:28 -00 2025 Dec 12 18:38:14.205810 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022 Dec 12 18:38:14.205821 kernel: BIOS-provided physical RAM map: Dec 12 18:38:14.205827 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Dec 12 18:38:14.205833 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Dec 12 18:38:14.205840 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Dec 12 18:38:14.205848 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Dec 12 18:38:14.205858 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Dec 12 18:38:14.205864 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Dec 12 18:38:14.205871 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Dec 12 18:38:14.205878 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Dec 12 18:38:14.205884 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Dec 12 18:38:14.205891 kernel: printk: legacy bootconsole [earlyser0] enabled Dec 12 18:38:14.205898 kernel: NX (Execute Disable) protection: active Dec 12 18:38:14.205908 kernel: APIC: Static calls initialized Dec 12 18:38:14.205916 kernel: efi: EFI v2.7 by Microsoft Dec 12 18:38:14.205923 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bca98 RNG=0x3ffd1018 Dec 12 18:38:14.205930 kernel: random: crng init done Dec 12 18:38:14.205938 kernel: secureboot: Secure boot disabled Dec 12 18:38:14.205945 kernel: SMBIOS 3.1.0 present. Dec 12 18:38:14.205952 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Dec 12 18:38:14.205959 kernel: DMI: Memory slots populated: 3/3 Dec 12 18:38:14.205967 kernel: Hypervisor detected: Microsoft Hyper-V Dec 12 18:38:14.205974 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Dec 12 18:38:14.205983 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Dec 12 18:38:14.205991 kernel: Hyper-V: Using hypercall for remote TLB flush Dec 12 18:38:14.205998 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Dec 12 18:38:14.206006 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Dec 12 18:38:14.206014 kernel: tsc: Marking TSC unstable due to running on Hyper-V Dec 12 18:38:14.206022 kernel: tsc: Detected 2593.992 MHz processor Dec 12 18:38:14.206029 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Dec 12 18:38:14.206037 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Dec 12 18:38:14.206044 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Dec 12 18:38:14.206054 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Dec 12 18:38:14.206062 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Dec 12 18:38:14.206069 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Dec 12 18:38:14.206076 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Dec 12 18:38:14.206083 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Dec 12 18:38:14.206091 kernel: Using GB pages for direct mapping Dec 12 18:38:14.206098 kernel: ACPI: Early table checksum verification disabled Dec 12 18:38:14.206109 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Dec 12 18:38:14.206120 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206128 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206136 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Dec 12 18:38:14.206143 kernel: ACPI: FACS 0x000000003FFFE000 000040 Dec 12 18:38:14.206151 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206159 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206169 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206177 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206185 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206193 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206201 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Dec 12 18:38:14.206209 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Dec 12 18:38:14.206217 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Dec 12 18:38:14.206225 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Dec 12 18:38:14.206232 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Dec 12 18:38:14.206243 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Dec 12 18:38:14.206251 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Dec 12 18:38:14.206258 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Dec 12 18:38:14.206266 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Dec 12 18:38:14.206274 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Dec 12 18:38:14.206282 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Dec 12 18:38:14.206290 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Dec 12 18:38:14.206298 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Dec 12 18:38:14.206308 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Dec 12 18:38:14.206316 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Dec 12 18:38:14.206323 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Dec 12 18:38:14.206331 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Dec 12 18:38:14.206339 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Dec 12 18:38:14.206347 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Dec 12 18:38:14.206355 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Dec 12 18:38:14.206363 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Dec 12 18:38:14.206371 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Dec 12 18:38:14.206381 kernel: Zone ranges: Dec 12 18:38:14.206389 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Dec 12 18:38:14.206397 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Dec 12 18:38:14.206405 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Dec 12 18:38:14.206413 kernel: Device empty Dec 12 18:38:14.206421 kernel: Movable zone start for each node Dec 12 18:38:14.206428 kernel: Early memory node ranges Dec 12 18:38:14.206436 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Dec 12 18:38:14.206444 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Dec 12 18:38:14.206454 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Dec 12 18:38:14.206462 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Dec 12 18:38:14.206470 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Dec 12 18:38:14.206478 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Dec 12 18:38:14.206486 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Dec 12 18:38:14.206493 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Dec 12 18:38:14.206501 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Dec 12 18:38:14.206509 kernel: ACPI: PM-Timer IO Port: 0x408 Dec 12 18:38:14.206517 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Dec 12 18:38:14.206527 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Dec 12 18:38:14.206535 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Dec 12 18:38:14.206543 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Dec 12 18:38:14.206551 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Dec 12 18:38:14.206559 kernel: CPU topo: Max. logical packages: 1 Dec 12 18:38:14.206567 kernel: CPU topo: Max. logical dies: 1 Dec 12 18:38:14.206574 kernel: CPU topo: Max. dies per package: 1 Dec 12 18:38:14.206582 kernel: CPU topo: Max. threads per core: 1 Dec 12 18:38:14.206590 kernel: CPU topo: Num. cores per package: 6 Dec 12 18:38:14.206600 kernel: CPU topo: Num. threads per package: 6 Dec 12 18:38:14.206608 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Dec 12 18:38:14.206616 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Dec 12 18:38:14.206624 kernel: Booting paravirtualized kernel on Hyper-V Dec 12 18:38:14.206632 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Dec 12 18:38:14.206640 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Dec 12 18:38:14.206648 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Dec 12 18:38:14.206656 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Dec 12 18:38:14.206664 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Dec 12 18:38:14.206674 kernel: Hyper-V: PV spinlocks enabled Dec 12 18:38:14.206682 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Dec 12 18:38:14.206691 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=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022 Dec 12 18:38:14.206699 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Dec 12 18:38:14.206707 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Dec 12 18:38:14.206715 kernel: Fallback order for Node 0: 0 Dec 12 18:38:14.206723 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Dec 12 18:38:14.206731 kernel: Policy zone: Normal Dec 12 18:38:14.206741 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 12 18:38:14.206757 kernel: software IO TLB: area num 8. Dec 12 18:38:14.206765 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Dec 12 18:38:14.206776 kernel: Kernel/User page tables isolation: enabled Dec 12 18:38:14.206794 kernel: ftrace: allocating 40103 entries in 157 pages Dec 12 18:38:14.206802 kernel: ftrace: allocated 157 pages with 5 groups Dec 12 18:38:14.206811 kernel: Dynamic Preempt: voluntary Dec 12 18:38:14.206819 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 12 18:38:14.206828 kernel: rcu: RCU event tracing is enabled. Dec 12 18:38:14.206837 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Dec 12 18:38:14.206846 kernel: Trampoline variant of Tasks RCU enabled. Dec 12 18:38:14.206857 kernel: Rude variant of Tasks RCU enabled. Dec 12 18:38:14.206865 kernel: Tracing variant of Tasks RCU enabled. Dec 12 18:38:14.206874 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 12 18:38:14.206882 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Dec 12 18:38:14.206891 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 12 18:38:14.206899 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 12 18:38:14.206910 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Dec 12 18:38:14.206919 kernel: Using NULL legacy PIC Dec 12 18:38:14.206927 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Dec 12 18:38:14.206935 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Dec 12 18:38:14.206944 kernel: Console: colour dummy device 80x25 Dec 12 18:38:14.206952 kernel: printk: legacy console [tty1] enabled Dec 12 18:38:14.206960 kernel: printk: legacy console [ttyS0] enabled Dec 12 18:38:14.206969 kernel: printk: legacy bootconsole [earlyser0] disabled Dec 12 18:38:14.206977 kernel: ACPI: Core revision 20240827 Dec 12 18:38:14.206987 kernel: Failed to register legacy timer interrupt Dec 12 18:38:14.206996 kernel: APIC: Switch to symmetric I/O mode setup Dec 12 18:38:14.207004 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Dec 12 18:38:14.207012 kernel: Hyper-V: enabling crash_kexec_post_notifiers Dec 12 18:38:14.207021 kernel: Hyper-V: Using IPI hypercalls Dec 12 18:38:14.207029 kernel: APIC: send_IPI() replaced with hv_send_ipi() Dec 12 18:38:14.207037 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Dec 12 18:38:14.207045 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Dec 12 18:38:14.207054 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Dec 12 18:38:14.207065 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Dec 12 18:38:14.207073 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Dec 12 18:38:14.207081 kernel: Hyper-V: Using enlightened APIC (xapic mode) Dec 12 18:38:14.207090 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Dec 12 18:38:14.207098 kernel: APIC: read() replaced with hv_apic_read() Dec 12 18:38:14.207106 kernel: APIC: write() replaced with hv_apic_write() Dec 12 18:38:14.207114 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Dec 12 18:38:14.207123 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Dec 12 18:38:14.207131 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Dec 12 18:38:14.207142 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Dec 12 18:38:14.207151 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Dec 12 18:38:14.207159 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Dec 12 18:38:14.207167 kernel: Spectre V2 : Mitigation: Retpolines Dec 12 18:38:14.207175 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Dec 12 18:38:14.207183 kernel: Speculative Store Bypass: Vulnerable Dec 12 18:38:14.207192 kernel: MDS: Mitigation: Clear CPU buffers Dec 12 18:38:14.207200 kernel: TAA: Mitigation: Clear CPU buffers Dec 12 18:38:14.207208 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Dec 12 18:38:14.207216 kernel: active return thunk: its_return_thunk Dec 12 18:38:14.207224 kernel: ITS: Mitigation: Aligned branch/return thunks Dec 12 18:38:14.207234 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Dec 12 18:38:14.207250 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Dec 12 18:38:14.207284 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Dec 12 18:38:14.207294 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Dec 12 18:38:14.207303 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Dec 12 18:38:14.207311 kernel: Freeing SMP alternatives memory: 32K Dec 12 18:38:14.207319 kernel: pid_max: default: 32768 minimum: 301 Dec 12 18:38:14.207327 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Dec 12 18:38:14.207336 kernel: landlock: Up and running. Dec 12 18:38:14.207344 kernel: SELinux: Initializing. Dec 12 18:38:14.207352 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Dec 12 18:38:14.207366 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Dec 12 18:38:14.207374 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Dec 12 18:38:14.207383 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Dec 12 18:38:14.207391 kernel: signal: max sigframe size: 1776 Dec 12 18:38:14.207400 kernel: rcu: Hierarchical SRCU implementation. Dec 12 18:38:14.207408 kernel: rcu: Max phase no-delay instances is 400. Dec 12 18:38:14.207417 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Dec 12 18:38:14.207425 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Dec 12 18:38:14.207434 kernel: smp: Bringing up secondary CPUs ... Dec 12 18:38:14.207444 kernel: smpboot: x86: Booting SMP configuration: Dec 12 18:38:14.207453 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Dec 12 18:38:14.207461 kernel: smp: Brought up 1 node, 6 CPUs Dec 12 18:38:14.207469 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Dec 12 18:38:14.207478 kernel: Memory: 115330892K/117439364K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46188K init, 2572K bss, 2098196K reserved, 0K cma-reserved) Dec 12 18:38:14.207492 kernel: devtmpfs: initialized Dec 12 18:38:14.207501 kernel: x86/mm: Memory block size: 1024MB Dec 12 18:38:14.207510 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Dec 12 18:38:14.207518 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 12 18:38:14.207530 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Dec 12 18:38:14.207538 kernel: pinctrl core: initialized pinctrl subsystem Dec 12 18:38:14.207551 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Dec 12 18:38:14.207559 kernel: audit: initializing netlink subsys (disabled) Dec 12 18:38:14.207568 kernel: audit: type=2000 audit(1765564690.028:1): state=initialized audit_enabled=0 res=1 Dec 12 18:38:14.207576 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 12 18:38:14.207584 kernel: thermal_sys: Registered thermal governor 'user_space' Dec 12 18:38:14.207593 kernel: cpuidle: using governor menu Dec 12 18:38:14.207601 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 12 18:38:14.207613 kernel: dca service started, version 1.12.1 Dec 12 18:38:14.207621 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Dec 12 18:38:14.207629 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Dec 12 18:38:14.207638 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Dec 12 18:38:14.207646 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Dec 12 18:38:14.207655 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Dec 12 18:38:14.207663 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Dec 12 18:38:14.207671 kernel: ACPI: Added _OSI(Module Device) Dec 12 18:38:14.207680 kernel: ACPI: Added _OSI(Processor Device) Dec 12 18:38:14.207690 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 12 18:38:14.207699 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 12 18:38:14.207707 kernel: ACPI: Interpreter enabled Dec 12 18:38:14.207715 kernel: ACPI: PM: (supports S0 S5) Dec 12 18:38:14.207724 kernel: ACPI: Using IOAPIC for interrupt routing Dec 12 18:38:14.207732 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Dec 12 18:38:14.207740 kernel: PCI: Ignoring E820 reservations for host bridge windows Dec 12 18:38:14.207749 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Dec 12 18:38:14.207757 kernel: iommu: Default domain type: Translated Dec 12 18:38:14.207768 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Dec 12 18:38:14.207823 kernel: efivars: Registered efivars operations Dec 12 18:38:14.207835 kernel: PCI: Using ACPI for IRQ routing Dec 12 18:38:14.207843 kernel: PCI: System does not support PCI Dec 12 18:38:14.207852 kernel: vgaarb: loaded Dec 12 18:38:14.207861 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Dec 12 18:38:14.207869 kernel: VFS: Disk quotas dquot_6.6.0 Dec 12 18:38:14.207877 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 12 18:38:14.207885 kernel: pnp: PnP ACPI init Dec 12 18:38:14.207899 kernel: pnp: PnP ACPI: found 3 devices Dec 12 18:38:14.207907 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Dec 12 18:38:14.207916 kernel: NET: Registered PF_INET protocol family Dec 12 18:38:14.207924 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.207933 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Dec 12 18:38:14.207941 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Dec 12 18:38:14.207950 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 12 18:38:14.207958 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.207967 kernel: TCP: Hash tables configured (established 524288 bind 65536) Dec 12 18:38:14.207977 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.207986 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Dec 12 18:38:14.207994 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Dec 12 18:38:14.208003 kernel: NET: Registered PF_XDP protocol family Dec 12 18:38:14.208011 kernel: PCI: CLS 0 bytes, default 64 Dec 12 18:38:14.208019 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Dec 12 18:38:14.208028 kernel: software IO TLB: mapped [mem 0x000000003b5bc000-0x000000003f5bc000] (64MB) Dec 12 18:38:14.208036 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Dec 12 18:38:14.208045 kernel: Initialise system trusted keyrings Dec 12 18:38:14.208055 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Dec 12 18:38:14.208064 kernel: Key type asymmetric registered Dec 12 18:38:14.208072 kernel: Asymmetric key parser 'x509' registered Dec 12 18:38:14.208080 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Dec 12 18:38:14.208088 kernel: io scheduler mq-deadline registered Dec 12 18:38:14.208097 kernel: io scheduler kyber registered Dec 12 18:38:14.208105 kernel: io scheduler bfq registered Dec 12 18:38:14.208113 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Dec 12 18:38:14.208122 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 12 18:38:14.208132 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Dec 12 18:38:14.208141 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Dec 12 18:38:14.208149 kernel: i8042: PNP: No PS/2 controller found. Dec 12 18:38:14.208316 kernel: rtc_cmos 00:02: registered as rtc0 Dec 12 18:38:14.208418 kernel: rtc_cmos 00:02: setting system clock to 2025-12-12T18:38:13 UTC (1765564693) Dec 12 18:38:14.208514 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Dec 12 18:38:14.208526 kernel: intel_pstate: CPU model not supported Dec 12 18:38:14.208539 kernel: efifb: probing for efifb Dec 12 18:38:14.208548 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Dec 12 18:38:14.208556 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Dec 12 18:38:14.208565 kernel: efifb: scrolling: redraw Dec 12 18:38:14.208573 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Dec 12 18:38:14.208582 kernel: Console: switching to colour frame buffer device 128x48 Dec 12 18:38:14.208590 kernel: fb0: EFI VGA frame buffer device Dec 12 18:38:14.208599 kernel: pstore: Using crash dump compression: deflate Dec 12 18:38:14.208607 kernel: pstore: Registered efi_pstore as persistent store backend Dec 12 18:38:14.208618 kernel: NET: Registered PF_INET6 protocol family Dec 12 18:38:14.208626 kernel: Segment Routing with IPv6 Dec 12 18:38:14.208635 kernel: In-situ OAM (IOAM) with IPv6 Dec 12 18:38:14.208643 kernel: NET: Registered PF_PACKET protocol family Dec 12 18:38:14.208651 kernel: Key type dns_resolver registered Dec 12 18:38:14.208660 kernel: IPI shorthand broadcast: enabled Dec 12 18:38:14.208668 kernel: sched_clock: Marking stable (3746005800, 110181200)->(4197360800, -341173800) Dec 12 18:38:14.208677 kernel: registered taskstats version 1 Dec 12 18:38:14.208685 kernel: Loading compiled-in X.509 certificates Dec 12 18:38:14.208694 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.61-flatcar: 0d0c78e6590cb40d27f1cef749ef9f2f3425f38d' Dec 12 18:38:14.208704 kernel: Demotion targets for Node 0: null Dec 12 18:38:14.208713 kernel: Key type .fscrypt registered Dec 12 18:38:14.208721 kernel: Key type fscrypt-provisioning registered Dec 12 18:38:14.208729 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 12 18:38:14.208738 kernel: ima: Allocated hash algorithm: sha1 Dec 12 18:38:14.208746 kernel: ima: No architecture policies found Dec 12 18:38:14.208755 kernel: clk: Disabling unused clocks Dec 12 18:38:14.208763 kernel: Warning: unable to open an initial console. Dec 12 18:38:14.208772 kernel: Freeing unused kernel image (initmem) memory: 46188K Dec 12 18:38:14.208795 kernel: Write protecting the kernel read-only data: 40960k Dec 12 18:38:14.208805 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Dec 12 18:38:14.208813 kernel: Run /init as init process Dec 12 18:38:14.208822 kernel: with arguments: Dec 12 18:38:14.208830 kernel: /init Dec 12 18:38:14.208838 kernel: with environment: Dec 12 18:38:14.208846 kernel: HOME=/ Dec 12 18:38:14.208854 kernel: TERM=linux Dec 12 18:38:14.208864 systemd[1]: Successfully made /usr/ read-only. Dec 12 18:38:14.208879 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) Dec 12 18:38:14.208888 systemd[1]: Detected virtualization microsoft. Dec 12 18:38:14.208897 systemd[1]: Detected architecture x86-64. Dec 12 18:38:14.208906 systemd[1]: Running in initrd. Dec 12 18:38:14.208914 systemd[1]: No hostname configured, using default hostname. Dec 12 18:38:14.208923 systemd[1]: Hostname set to . Dec 12 18:38:14.208932 systemd[1]: Initializing machine ID from random generator. Dec 12 18:38:14.208943 systemd[1]: Queued start job for default target initrd.target. Dec 12 18:38:14.208952 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 18:38:14.208961 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 18:38:14.208971 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Dec 12 18:38:14.208980 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 12 18:38:14.208989 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Dec 12 18:38:14.208999 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Dec 12 18:38:14.209011 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Dec 12 18:38:14.209020 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Dec 12 18:38:14.209029 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 18:38:14.209038 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 12 18:38:14.209047 systemd[1]: Reached target paths.target - Path Units. Dec 12 18:38:14.209056 systemd[1]: Reached target slices.target - Slice Units. Dec 12 18:38:14.209065 systemd[1]: Reached target swap.target - Swaps. Dec 12 18:38:14.209074 systemd[1]: Reached target timers.target - Timer Units. Dec 12 18:38:14.209085 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Dec 12 18:38:14.209094 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 12 18:38:14.209103 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Dec 12 18:38:14.209112 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Dec 12 18:38:14.209121 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 12 18:38:14.209130 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 12 18:38:14.209139 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 18:38:14.209147 systemd[1]: Reached target sockets.target - Socket Units. Dec 12 18:38:14.209156 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Dec 12 18:38:14.209168 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 12 18:38:14.209176 systemd[1]: Finished network-cleanup.service - Network Cleanup. Dec 12 18:38:14.209186 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). Dec 12 18:38:14.209195 systemd[1]: Starting systemd-fsck-usr.service... Dec 12 18:38:14.209204 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 12 18:38:14.209212 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 12 18:38:14.209241 systemd-journald[214]: Collecting audit messages is disabled. Dec 12 18:38:14.209266 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:14.209276 systemd-journald[214]: Journal started Dec 12 18:38:14.209299 systemd-journald[214]: Runtime Journal (/run/log/journal/0507663534c643a3b7d0da0a797c526c) is 8M, max 2.2G, 2.1G free. Dec 12 18:38:14.212027 systemd-modules-load[216]: Inserted module 'overlay' Dec 12 18:38:14.219182 systemd[1]: Started systemd-journald.service - Journal Service. Dec 12 18:38:14.221013 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Dec 12 18:38:14.221527 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 18:38:14.223072 systemd[1]: Finished systemd-fsck-usr.service. Dec 12 18:38:14.248573 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 12 18:38:14.259267 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 12 18:38:14.274921 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:14.277720 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Dec 12 18:38:14.297613 systemd-tmpfiles[226]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Dec 12 18:38:14.304010 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Dec 12 18:38:14.300734 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 18:38:14.304994 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 18:38:14.306471 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 12 18:38:14.342420 systemd-modules-load[216]: Inserted module 'br_netfilter' Dec 12 18:38:14.344727 kernel: Bridge firewalling registered Dec 12 18:38:14.346173 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 12 18:38:14.347874 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 12 18:38:14.356090 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 18:38:14.367353 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Dec 12 18:38:14.377108 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 12 18:38:14.411338 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 12 18:38:14.421244 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 12 18:38:14.434142 dracut-cmdline[249]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a214a2d85e162c493e8b13db2df50a43e1005a0e4854a1ae089a14f442a30022 Dec 12 18:38:14.481112 systemd-resolved[261]: Positive Trust Anchors: Dec 12 18:38:14.481129 systemd-resolved[261]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 12 18:38:14.481160 systemd-resolved[261]: 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 Dec 12 18:38:14.483617 systemd-resolved[261]: Defaulting to hostname 'linux'. Dec 12 18:38:14.484623 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 12 18:38:14.494172 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 12 18:38:14.597807 kernel: SCSI subsystem initialized Dec 12 18:38:14.607803 kernel: Loading iSCSI transport class v2.0-870. Dec 12 18:38:14.620807 kernel: iscsi: registered transport (tcp) Dec 12 18:38:14.644674 kernel: iscsi: registered transport (qla4xxx) Dec 12 18:38:14.644702 kernel: QLogic iSCSI HBA Driver Dec 12 18:38:14.663909 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 12 18:38:14.691275 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 18:38:14.693155 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 12 18:38:14.755745 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Dec 12 18:38:14.757691 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Dec 12 18:38:14.831807 kernel: raid6: avx2x4 gen() 31185 MB/s Dec 12 18:38:14.851801 kernel: raid6: avx2x2 gen() 31063 MB/s Dec 12 18:38:14.873003 kernel: raid6: avx2x1 gen() 26887 MB/s Dec 12 18:38:14.873031 kernel: raid6: using algorithm avx2x4 gen() 31185 MB/s Dec 12 18:38:14.895034 kernel: raid6: .... xor() 10025 MB/s, rmw enabled Dec 12 18:38:14.895058 kernel: raid6: using avx2x2 recovery algorithm Dec 12 18:38:14.918806 kernel: xor: automatically using best checksumming function avx Dec 12 18:38:15.092817 kernel: Btrfs loaded, zoned=no, fsverity=no Dec 12 18:38:15.099437 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Dec 12 18:38:15.102425 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 18:38:15.137938 systemd-udevd[465]: Using default interface naming scheme 'v255'. Dec 12 18:38:15.143956 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 18:38:15.145629 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Dec 12 18:38:15.186071 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Dec 12 18:38:15.214806 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Dec 12 18:38:15.217495 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 12 18:38:15.303950 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 18:38:15.306624 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Dec 12 18:38:15.337803 kernel: cryptd: max_cpu_qlen set to 1000 Dec 12 18:38:15.351312 kernel: AES CTR mode by8 optimization enabled Dec 12 18:38:15.351353 kernel: hv_vmbus: Vmbus version:4.0 Dec 12 18:38:15.372910 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 18:38:15.389777 kernel: hv_vmbus: registering driver hyperv_keyboard Dec 12 18:38:15.389831 kernel: pps_core: LinuxPPS API ver. 1 registered Dec 12 18:38:15.389852 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Dec 12 18:38:15.373161 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:15.411461 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 Dec 12 18:38:15.411492 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 12 18:38:15.411505 kernel: hv_vmbus: registering driver hv_netvsc Dec 12 18:38:15.411516 kernel: hv_vmbus: registering driver hv_storvsc Dec 12 18:38:15.411533 kernel: scsi host0: storvsc_host_t Dec 12 18:38:15.413804 kernel: scsi host1: storvsc_host_t Dec 12 18:38:15.413847 kernel: PTP clock support registered Dec 12 18:38:15.415841 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 12 18:38:15.415882 kernel: hv_vmbus: registering driver hv_pci Dec 12 18:38:15.429640 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Dec 12 18:38:15.429723 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Dec 12 18:38:15.444512 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Dec 12 18:38:15.444724 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Dec 12 18:38:15.444916 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Dec 12 18:38:15.453789 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Dec 12 18:38:15.454842 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Dec 12 18:38:15.459806 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Dec 12 18:38:15.473747 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Dec 12 18:38:15.478391 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Dec 12 18:38:15.483816 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Dec 12 18:38:15.485266 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:15.496694 kernel: pci 0001:00:00.0: Enabling HDA controller Dec 12 18:38:15.500712 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:16.686649 kernel: hv_vmbus: registering driver hid_hyperv Dec 12 18:38:16.686684 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Dec 12 18:38:16.686698 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Dec 12 18:38:16.686899 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Dec 12 18:38:16.687051 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Dec 12 18:38:16.687185 kernel: sd 0:0:0:1: [sda] Write Protect is off Dec 12 18:38:16.687315 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Dec 12 18:38:16.687440 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Dec 12 18:38:16.687592 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Dec 12 18:38:16.687750 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Dec 12 18:38:16.687882 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Dec 12 18:38:16.688009 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Dec 12 18:38:16.688140 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Dec 12 18:38:16.688271 kernel: hv_utils: Registering HyperV Utility Driver Dec 12 18:38:16.688284 kernel: hv_vmbus: registering driver hv_utils Dec 12 18:38:16.688296 kernel: sd 0:0:0:0: [sdb] Write Protect is off Dec 12 18:38:16.688424 kernel: hv_utils: Heartbeat IC version 3.0 Dec 12 18:38:16.688441 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Dec 12 18:38:16.688588 kernel: hv_utils: Shutdown IC version 3.2 Dec 12 18:38:16.688602 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 12 18:38:16.688731 kernel: hv_utils: TimeSync IC version 4.0 Dec 12 18:38:16.688745 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#261 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.688889 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Dec 12 18:38:16.689073 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#68 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.689250 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#323 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.689448 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#76 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:16.629171 systemd-resolved[261]: Clock change detected. Flushing caches. Dec 12 18:38:16.670604 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 12 18:38:16.703839 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Dec 12 18:38:16.703861 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Dec 12 18:38:16.707519 kernel: sda: sda1 Dec 12 18:38:16.707548 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Dec 12 18:38:16.715524 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Dec 12 18:38:16.715754 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Dec 12 18:38:16.715770 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Dec 12 18:38:16.730192 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:17.115336 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Dec 12 18:38:17.129308 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Dec 12 18:38:17.164832 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 12 18:38:17.176087 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Dec 12 18:38:17.177583 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Dec 12 18:38:17.197919 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Dec 12 18:38:17.240524 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#271 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:17.259522 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Dec 12 18:38:18.273117 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#280 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:18.290094 disk-uuid[701]: The operation has completed successfully. Dec 12 18:38:18.293940 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Dec 12 18:38:21.099344 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Dec 12 18:38:21.108329 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 12 18:38:21.111906 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Dec 12 18:38:21.122832 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Dec 12 18:38:21.132783 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 18:38:21.142240 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 12 18:38:21.151451 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Dec 12 18:38:21.175119 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Dec 12 18:38:21.184491 sh[868]: Success Dec 12 18:38:21.189843 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Dec 12 18:38:21.218397 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Dec 12 18:38:21.218434 kernel: device-mapper: uevent: version 1.0.3 Dec 12 18:38:21.223217 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Dec 12 18:38:21.235520 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Dec 12 18:38:21.440256 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Dec 12 18:38:21.450362 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Dec 12 18:38:21.474250 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Dec 12 18:38:21.492547 kernel: BTRFS: device fsid a6ae7f96-a076-4d3c-81ed-46dd341492f8 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (891) Dec 12 18:38:21.501903 kernel: BTRFS info (device dm-0): first mount of filesystem a6ae7f96-a076-4d3c-81ed-46dd341492f8 Dec 12 18:38:21.501928 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:21.732865 kernel: BTRFS info (device dm-0): disabling log replay at mount time Dec 12 18:38:21.732943 kernel: BTRFS info (device dm-0): enabling free space tree Dec 12 18:38:21.757124 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Dec 12 18:38:21.764644 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Dec 12 18:38:21.773590 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Dec 12 18:38:21.774399 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Dec 12 18:38:21.802039 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Dec 12 18:38:21.835521 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (922) Dec 12 18:38:21.845151 kernel: BTRFS info (device sdb6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:21.845196 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:21.866594 kernel: BTRFS info (device sdb6): turning on async discard Dec 12 18:38:21.866630 kernel: BTRFS info (device sdb6): enabling free space tree Dec 12 18:38:21.875523 kernel: BTRFS info (device sdb6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:21.879649 systemd[1]: Finished ignition-setup.service - Ignition (setup). Dec 12 18:38:21.884852 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Dec 12 18:38:21.911652 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 12 18:38:21.914667 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 12 18:38:21.961406 systemd-networkd[1060]: lo: Link UP Dec 12 18:38:21.961417 systemd-networkd[1060]: lo: Gained carrier Dec 12 18:38:21.962440 systemd-networkd[1060]: Enumeration completed Dec 12 18:38:21.962524 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 12 18:38:21.962816 systemd-networkd[1060]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:21.962821 systemd-networkd[1060]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 18:38:21.963579 systemd-networkd[1060]: eth0: Link UP Dec 12 18:38:21.963746 systemd-networkd[1060]: eth0: Gained carrier Dec 12 18:38:21.963756 systemd-networkd[1060]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:21.968810 systemd[1]: Reached target network.target - Network. Dec 12 18:38:22.020542 systemd-networkd[1060]: eth0: DHCPv4 address 10.200.4.26/24, gateway 10.200.4.1 acquired from 168.63.129.16 Dec 12 18:38:22.919882 ignition[1045]: Ignition 2.22.0 Dec 12 18:38:22.919896 ignition[1045]: Stage: fetch-offline Dec 12 18:38:22.920015 ignition[1045]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:22.925584 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Dec 12 18:38:22.920025 ignition[1045]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:22.920113 ignition[1045]: parsed url from cmdline: "" Dec 12 18:38:22.938171 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Dec 12 18:38:22.920117 ignition[1045]: no config URL provided Dec 12 18:38:22.920122 ignition[1045]: reading system config file "/usr/lib/ignition/user.ign" Dec 12 18:38:22.920131 ignition[1045]: no config at "/usr/lib/ignition/user.ign" Dec 12 18:38:22.920136 ignition[1045]: failed to fetch config: resource requires networking Dec 12 18:38:22.922623 ignition[1045]: Ignition finished successfully Dec 12 18:38:22.976629 ignition[1072]: Ignition 2.22.0 Dec 12 18:38:22.976643 ignition[1072]: Stage: fetch Dec 12 18:38:22.976870 ignition[1072]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:22.976881 ignition[1072]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:22.977011 ignition[1072]: parsed url from cmdline: "" Dec 12 18:38:22.977015 ignition[1072]: no config URL provided Dec 12 18:38:22.977021 ignition[1072]: reading system config file "/usr/lib/ignition/user.ign" Dec 12 18:38:22.977031 ignition[1072]: no config at "/usr/lib/ignition/user.ign" Dec 12 18:38:22.977049 ignition[1072]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Dec 12 18:38:23.081357 ignition[1072]: GET result: OK Dec 12 18:38:23.081407 ignition[1072]: config has been read from IMDS userdata Dec 12 18:38:23.081419 ignition[1072]: parsing config with SHA512: d1f34cafadd185b4b51915973accedc5e7e5954bb07630d3cb5dcc609511e0059d9986183ec5869e9346b536333c72d0e7af1159d194ddeadb05b65a2ff0ccf0 Dec 12 18:38:23.085622 unknown[1072]: fetched base config from "system" Dec 12 18:38:23.085822 ignition[1072]: fetch: fetch complete Dec 12 18:38:23.085634 unknown[1072]: fetched base config from "system" Dec 12 18:38:23.085831 ignition[1072]: fetch: fetch passed Dec 12 18:38:23.085641 unknown[1072]: fetched user config from "azure" Dec 12 18:38:23.085874 ignition[1072]: Ignition finished successfully Dec 12 18:38:23.089399 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Dec 12 18:38:23.092929 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Dec 12 18:38:23.137526 ignition[1082]: Ignition 2.22.0 Dec 12 18:38:23.137540 ignition[1082]: Stage: kargs Dec 12 18:38:23.137762 ignition[1082]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:23.137773 ignition[1082]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:23.138223 ignition[1082]: kargs: kargs passed Dec 12 18:38:23.146340 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Dec 12 18:38:23.138268 ignition[1082]: Ignition finished successfully Dec 12 18:38:23.155010 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Dec 12 18:38:23.197933 ignition[1092]: Ignition 2.22.0 Dec 12 18:38:23.197946 ignition[1092]: Stage: disks Dec 12 18:38:23.198237 ignition[1092]: no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:23.198249 ignition[1092]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:23.204499 systemd[1]: Finished ignition-disks.service - Ignition (disks). Dec 12 18:38:23.198937 ignition[1092]: disks: disks passed Dec 12 18:38:23.208122 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Dec 12 18:38:23.198986 ignition[1092]: Ignition finished successfully Dec 12 18:38:23.216431 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Dec 12 18:38:23.224912 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 12 18:38:23.232485 systemd[1]: Reached target sysinit.target - System Initialization. Dec 12 18:38:23.240968 systemd[1]: Reached target basic.target - Basic System. Dec 12 18:38:23.255104 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Dec 12 18:38:23.345030 systemd-fsck[1103]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Dec 12 18:38:23.352016 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Dec 12 18:38:23.363345 systemd[1]: Mounting sysroot.mount - /sysroot... Dec 12 18:38:23.615521 kernel: EXT4-fs (sdb9): mounted filesystem e48ca59c-1206-4abd-b121-5e9b35e49852 r/w with ordered data mode. Quota mode: none. Dec 12 18:38:23.616062 systemd[1]: Mounted sysroot.mount - /sysroot. Dec 12 18:38:23.622814 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Dec 12 18:38:23.636699 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 12 18:38:23.638911 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Dec 12 18:38:23.663142 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Dec 12 18:38:23.672444 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Dec 12 18:38:23.672484 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Dec 12 18:38:23.704401 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1118) Dec 12 18:38:23.704426 kernel: BTRFS info (device sdb6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:23.704439 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:23.704204 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Dec 12 18:38:23.723160 kernel: BTRFS info (device sdb6): turning on async discard Dec 12 18:38:23.723182 kernel: BTRFS info (device sdb6): enabling free space tree Dec 12 18:38:23.717725 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 12 18:38:23.719457 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Dec 12 18:38:23.744587 systemd-networkd[1060]: eth0: Gained IPv6LL Dec 12 18:38:24.210290 coreos-metadata[1120]: Dec 12 18:38:24.210 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 12 18:38:24.216373 coreos-metadata[1120]: Dec 12 18:38:24.216 INFO Fetch successful Dec 12 18:38:24.216373 coreos-metadata[1120]: Dec 12 18:38:24.216 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Dec 12 18:38:24.228237 coreos-metadata[1120]: Dec 12 18:38:24.220 INFO Fetch successful Dec 12 18:38:24.232131 coreos-metadata[1120]: Dec 12 18:38:24.232 INFO wrote hostname ci-4459.2.2-a-1874b56392 to /sysroot/etc/hostname Dec 12 18:38:24.233372 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 12 18:38:24.335768 initrd-setup-root[1149]: cut: /sysroot/etc/passwd: No such file or directory Dec 12 18:38:24.361825 initrd-setup-root[1156]: cut: /sysroot/etc/group: No such file or directory Dec 12 18:38:24.379833 initrd-setup-root[1163]: cut: /sysroot/etc/shadow: No such file or directory Dec 12 18:38:24.385780 initrd-setup-root[1170]: cut: /sysroot/etc/gshadow: No such file or directory Dec 12 18:38:25.034972 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Dec 12 18:38:25.037805 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Dec 12 18:38:25.060276 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Dec 12 18:38:25.065900 systemd[1]: sysroot-oem.mount: Deactivated successfully. Dec 12 18:38:25.077520 kernel: BTRFS info (device sdb6): last unmount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:25.099952 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Dec 12 18:38:25.107409 ignition[1239]: INFO : Ignition 2.22.0 Dec 12 18:38:25.107409 ignition[1239]: INFO : Stage: mount Dec 12 18:38:25.107409 ignition[1239]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:25.107409 ignition[1239]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:25.129595 ignition[1239]: INFO : mount: mount passed Dec 12 18:38:25.129595 ignition[1239]: INFO : Ignition finished successfully Dec 12 18:38:25.108835 systemd[1]: Finished ignition-mount.service - Ignition (mount). Dec 12 18:38:25.115244 systemd[1]: Starting ignition-files.service - Ignition (files)... Dec 12 18:38:25.145577 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Dec 12 18:38:25.169526 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1252) Dec 12 18:38:25.179372 kernel: BTRFS info (device sdb6): first mount of filesystem 7e9ead35-f0ec-40e8-bc31-5061934f865a Dec 12 18:38:25.179397 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Dec 12 18:38:25.190070 kernel: BTRFS info (device sdb6): turning on async discard Dec 12 18:38:25.190101 kernel: BTRFS info (device sdb6): enabling free space tree Dec 12 18:38:25.192242 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Dec 12 18:38:25.229606 ignition[1269]: INFO : Ignition 2.22.0 Dec 12 18:38:25.229606 ignition[1269]: INFO : Stage: files Dec 12 18:38:25.235441 ignition[1269]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:25.235441 ignition[1269]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:25.235441 ignition[1269]: DEBUG : files: compiled without relabeling support, skipping Dec 12 18:38:25.249050 ignition[1269]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Dec 12 18:38:25.249050 ignition[1269]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Dec 12 18:38:25.304614 ignition[1269]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Dec 12 18:38:25.310198 ignition[1269]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Dec 12 18:38:25.315942 ignition[1269]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Dec 12 18:38:25.310370 unknown[1269]: wrote ssh authorized keys file for user: core Dec 12 18:38:25.344469 ignition[1269]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Dec 12 18:38:25.352247 ignition[1269]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Dec 12 18:38:25.352247 ignition[1269]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Dec 12 18:38:25.352247 ignition[1269]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Dec 12 18:38:25.352247 ignition[1269]: INFO : files: files passed Dec 12 18:38:25.352247 ignition[1269]: INFO : Ignition finished successfully Dec 12 18:38:25.368160 systemd[1]: Finished ignition-files.service - Ignition (files). Dec 12 18:38:25.375869 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Dec 12 18:38:25.407103 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Dec 12 18:38:25.410618 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 12 18:38:25.410738 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Dec 12 18:38:25.430449 initrd-setup-root-after-ignition[1301]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 12 18:38:25.430449 initrd-setup-root-after-ignition[1301]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Dec 12 18:38:25.442846 initrd-setup-root-after-ignition[1305]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Dec 12 18:38:25.444231 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 12 18:38:25.445561 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Dec 12 18:38:25.461131 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Dec 12 18:38:25.519200 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 12 18:38:25.519311 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Dec 12 18:38:25.527990 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Dec 12 18:38:25.536808 systemd[1]: Reached target initrd.target - Initrd Default Target. Dec 12 18:38:25.544843 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Dec 12 18:38:25.545603 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Dec 12 18:38:25.596543 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 12 18:38:25.599280 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Dec 12 18:38:25.631726 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Dec 12 18:38:25.632647 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 18:38:25.645782 systemd[1]: Stopped target timers.target - Timer Units. Dec 12 18:38:25.653979 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 12 18:38:25.654105 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Dec 12 18:38:25.670846 systemd[1]: Stopped target initrd.target - Initrd Default Target. Dec 12 18:38:25.672468 systemd[1]: Stopped target basic.target - Basic System. Dec 12 18:38:25.680525 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Dec 12 18:38:25.687357 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Dec 12 18:38:25.695840 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Dec 12 18:38:25.712237 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Dec 12 18:38:25.713819 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Dec 12 18:38:25.722619 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Dec 12 18:38:25.730629 systemd[1]: Stopped target sysinit.target - System Initialization. Dec 12 18:38:25.743085 systemd[1]: Stopped target local-fs.target - Local File Systems. Dec 12 18:38:25.751018 systemd[1]: Stopped target swap.target - Swaps. Dec 12 18:38:25.758053 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 12 18:38:25.758197 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Dec 12 18:38:25.769074 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Dec 12 18:38:25.777290 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 18:38:25.786441 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Dec 12 18:38:25.786586 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 18:38:25.795999 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 12 18:38:25.796153 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Dec 12 18:38:25.809143 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Dec 12 18:38:25.809273 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Dec 12 18:38:25.817758 systemd[1]: ignition-files.service: Deactivated successfully. Dec 12 18:38:25.817880 systemd[1]: Stopped ignition-files.service - Ignition (files). Dec 12 18:38:25.825666 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Dec 12 18:38:25.825780 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Dec 12 18:38:25.835783 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Dec 12 18:38:25.843945 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Dec 12 18:38:25.850458 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 12 18:38:25.850602 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 18:38:25.892807 ignition[1325]: INFO : Ignition 2.22.0 Dec 12 18:38:25.892807 ignition[1325]: INFO : Stage: umount Dec 12 18:38:25.892807 ignition[1325]: INFO : no configs at "/usr/lib/ignition/base.d" Dec 12 18:38:25.892807 ignition[1325]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Dec 12 18:38:25.892807 ignition[1325]: INFO : umount: umount passed Dec 12 18:38:25.892807 ignition[1325]: INFO : Ignition finished successfully Dec 12 18:38:25.858615 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 12 18:38:25.858729 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Dec 12 18:38:25.875042 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 12 18:38:25.875137 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Dec 12 18:38:25.898695 systemd[1]: ignition-mount.service: Deactivated successfully. Dec 12 18:38:25.898814 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Dec 12 18:38:25.906736 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 12 18:38:25.906803 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Dec 12 18:38:25.908391 systemd[1]: ignition-kargs.service: Deactivated successfully. Dec 12 18:38:25.908435 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Dec 12 18:38:25.920478 systemd[1]: ignition-fetch.service: Deactivated successfully. Dec 12 18:38:25.920544 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Dec 12 18:38:25.927726 systemd[1]: Stopped target network.target - Network. Dec 12 18:38:25.935209 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Dec 12 18:38:25.935265 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Dec 12 18:38:25.942754 systemd[1]: Stopped target paths.target - Path Units. Dec 12 18:38:25.950834 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 12 18:38:25.963545 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 18:38:25.967531 systemd[1]: Stopped target slices.target - Slice Units. Dec 12 18:38:25.975928 systemd[1]: Stopped target sockets.target - Socket Units. Dec 12 18:38:25.982560 systemd[1]: iscsid.socket: Deactivated successfully. Dec 12 18:38:25.982612 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Dec 12 18:38:25.996583 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 12 18:38:25.996636 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Dec 12 18:38:25.998108 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 12 18:38:25.998167 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Dec 12 18:38:26.008562 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Dec 12 18:38:26.008614 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Dec 12 18:38:26.019130 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Dec 12 18:38:26.026753 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Dec 12 18:38:26.036174 systemd[1]: sysroot-boot.mount: Deactivated successfully. Dec 12 18:38:26.036780 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 12 18:38:26.036883 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Dec 12 18:38:26.040349 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 12 18:38:26.040419 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Dec 12 18:38:26.047732 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 12 18:38:26.047839 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Dec 12 18:38:26.071087 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Dec 12 18:38:26.071342 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 12 18:38:26.071490 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Dec 12 18:38:26.081120 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Dec 12 18:38:26.082167 systemd[1]: Stopped target network-pre.target - Preparation for Network. Dec 12 18:38:26.085768 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 12 18:38:26.085845 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Dec 12 18:38:26.101692 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Dec 12 18:38:26.108168 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Dec 12 18:38:26.108227 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Dec 12 18:38:26.115329 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 12 18:38:26.115379 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Dec 12 18:38:26.131397 systemd[1]: systemd-modules-load.service: Deactivated successfully. Dec 12 18:38:26.131446 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Dec 12 18:38:26.139803 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Dec 12 18:38:26.139857 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 18:38:26.149590 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 18:38:26.157809 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Dec 12 18:38:26.157876 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Dec 12 18:38:26.203913 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 12 18:38:26.207662 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 18:38:26.209416 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 12 18:38:26.209471 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Dec 12 18:38:26.221659 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 12 18:38:26.221694 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 18:38:26.237791 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 12 18:38:26.237852 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Dec 12 18:38:26.249474 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 12 18:38:26.249544 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Dec 12 18:38:26.265904 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 12 18:38:26.265962 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Dec 12 18:38:26.282045 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Dec 12 18:38:26.290545 systemd[1]: systemd-network-generator.service: Deactivated successfully. Dec 12 18:38:26.290600 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 18:38:26.304641 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 12 18:38:26.304698 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 18:38:26.315317 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Dec 12 18:38:26.315365 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 18:38:26.324121 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 12 18:38:26.324170 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 18:38:26.332861 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 18:38:26.332907 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:26.343007 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Dec 12 18:38:26.343064 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Dec 12 18:38:26.343104 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Dec 12 18:38:26.343143 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Dec 12 18:38:26.343585 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 12 18:38:26.468568 systemd-journald[214]: Received SIGTERM from PID 1 (systemd). Dec 12 18:38:26.343684 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Dec 12 18:38:26.346732 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 12 18:38:26.346821 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Dec 12 18:38:26.354779 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Dec 12 18:38:26.363122 systemd[1]: Starting initrd-switch-root.service - Switch Root... Dec 12 18:38:26.388027 systemd[1]: Switching root. Dec 12 18:38:26.481440 systemd-journald[214]: Journal stopped Dec 12 18:38:29.918878 kernel: SELinux: policy capability network_peer_controls=1 Dec 12 18:38:29.918910 kernel: SELinux: policy capability open_perms=1 Dec 12 18:38:29.918922 kernel: SELinux: policy capability extended_socket_class=1 Dec 12 18:38:29.918936 kernel: SELinux: policy capability always_check_network=0 Dec 12 18:38:29.918945 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 12 18:38:29.918955 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 12 18:38:29.918966 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 12 18:38:29.918978 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Dec 12 18:38:29.918988 kernel: SELinux: policy capability userspace_initial_context=0 Dec 12 18:38:29.918998 kernel: audit: type=1403 audit(1765564707.165:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 12 18:38:29.919010 systemd[1]: Successfully loaded SELinux policy in 169.523ms. Dec 12 18:38:29.919022 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.669ms. Dec 12 18:38:29.919034 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) Dec 12 18:38:29.919045 systemd[1]: Detected virtualization microsoft. Dec 12 18:38:29.919059 systemd[1]: Detected architecture x86-64. Dec 12 18:38:29.919070 systemd[1]: Detected first boot. Dec 12 18:38:29.919081 systemd[1]: Hostname set to . Dec 12 18:38:29.919092 systemd[1]: Initializing machine ID from random generator. Dec 12 18:38:29.919103 zram_generator::config[1370]: No configuration found. Dec 12 18:38:29.919117 kernel: Guest personality initialized and is inactive Dec 12 18:38:29.919128 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Dec 12 18:38:29.919138 kernel: Initialized host personality Dec 12 18:38:29.919148 kernel: NET: Registered PF_VSOCK protocol family Dec 12 18:38:29.919159 systemd[1]: Populated /etc with preset unit settings. Dec 12 18:38:29.919174 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Dec 12 18:38:29.919185 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 12 18:38:29.919198 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Dec 12 18:38:29.919210 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 12 18:38:29.919221 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Dec 12 18:38:29.919233 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Dec 12 18:38:29.919244 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 12 18:38:29.919255 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Dec 12 18:38:29.919266 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Dec 12 18:38:29.919277 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Dec 12 18:38:29.919291 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Dec 12 18:38:29.919302 systemd[1]: Created slice user.slice - User and Session Slice. Dec 12 18:38:29.919313 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Dec 12 18:38:29.919324 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Dec 12 18:38:29.919335 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Dec 12 18:38:29.919347 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Dec 12 18:38:29.919362 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Dec 12 18:38:29.919374 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Dec 12 18:38:29.919389 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Dec 12 18:38:29.919400 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Dec 12 18:38:29.919411 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Dec 12 18:38:29.919426 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Dec 12 18:38:29.919438 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Dec 12 18:38:29.919450 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Dec 12 18:38:29.919461 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Dec 12 18:38:29.919475 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Dec 12 18:38:29.919487 systemd[1]: Reached target remote-fs.target - Remote File Systems. Dec 12 18:38:29.919498 systemd[1]: Reached target slices.target - Slice Units. Dec 12 18:38:29.919530 systemd[1]: Reached target swap.target - Swaps. Dec 12 18:38:29.919542 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Dec 12 18:38:29.919553 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Dec 12 18:38:29.919569 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Dec 12 18:38:29.919581 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Dec 12 18:38:29.919592 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Dec 12 18:38:29.919604 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Dec 12 18:38:29.919615 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Dec 12 18:38:29.919627 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Dec 12 18:38:29.919639 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Dec 12 18:38:29.919653 systemd[1]: Mounting media.mount - External Media Directory... Dec 12 18:38:29.919665 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:29.919676 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Dec 12 18:38:29.919688 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Dec 12 18:38:29.919703 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Dec 12 18:38:29.919715 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). Dec 12 18:38:29.919727 systemd[1]: Reached target machines.target - Containers. Dec 12 18:38:29.919739 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Dec 12 18:38:29.919751 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 18:38:29.919765 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Dec 12 18:38:29.919777 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Dec 12 18:38:29.919788 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 18:38:29.919800 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 12 18:38:29.919812 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 18:38:29.919824 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Dec 12 18:38:29.919835 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 18:38:29.919847 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Dec 12 18:38:29.919861 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 12 18:38:29.919873 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Dec 12 18:38:29.919885 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 12 18:38:29.919896 systemd[1]: Stopped systemd-fsck-usr.service. Dec 12 18:38:29.919908 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). Dec 12 18:38:29.919920 systemd[1]: Starting systemd-journald.service - Journal Service... Dec 12 18:38:29.919931 kernel: loop: module loaded Dec 12 18:38:29.919941 kernel: fuse: init (API version 7.41) Dec 12 18:38:29.919958 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Dec 12 18:38:29.919994 systemd-journald[1470]: Collecting audit messages is disabled. Dec 12 18:38:29.920021 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Dec 12 18:38:29.920034 systemd-journald[1470]: Journal started Dec 12 18:38:29.920060 systemd-journald[1470]: Runtime Journal (/run/log/journal/da3ad64257d94f94bce511d8e37c3b3f) is 8M, max 2.2G, 2.1G free. Dec 12 18:38:29.215864 systemd[1]: Queued start job for default target multi-user.target. Dec 12 18:38:29.241908 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Dec 12 18:38:29.242467 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 12 18:38:29.938584 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Dec 12 18:38:29.946519 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Dec 12 18:38:29.953521 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Dec 12 18:38:29.962894 systemd[1]: verity-setup.service: Deactivated successfully. Dec 12 18:38:29.962937 systemd[1]: Stopped verity-setup.service. Dec 12 18:38:29.973524 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:29.982171 systemd[1]: Started systemd-journald.service - Journal Service. Dec 12 18:38:29.985632 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Dec 12 18:38:29.989955 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Dec 12 18:38:29.994530 systemd[1]: Mounted media.mount - External Media Directory. Dec 12 18:38:29.998723 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Dec 12 18:38:30.003365 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Dec 12 18:38:30.008050 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Dec 12 18:38:30.012323 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Dec 12 18:38:30.017596 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Dec 12 18:38:30.023010 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 12 18:38:30.023210 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Dec 12 18:38:30.028158 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 18:38:30.028337 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 18:38:30.033241 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 18:38:30.033430 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 18:38:30.038632 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 12 18:38:30.038823 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Dec 12 18:38:30.043392 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 18:38:30.043591 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 18:38:30.048283 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Dec 12 18:38:30.053160 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Dec 12 18:38:30.058471 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Dec 12 18:38:30.063883 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Dec 12 18:38:30.069525 kernel: ACPI: bus type drm_connector registered Dec 12 18:38:30.072676 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 12 18:38:30.072903 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 12 18:38:30.086105 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Dec 12 18:38:30.092525 systemd[1]: Reached target network-pre.target - Preparation for Network. Dec 12 18:38:30.098203 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Dec 12 18:38:30.115002 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Dec 12 18:38:30.119448 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Dec 12 18:38:30.119485 systemd[1]: Reached target local-fs.target - Local File Systems. Dec 12 18:38:30.124612 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Dec 12 18:38:30.130727 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Dec 12 18:38:30.134806 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 18:38:30.135920 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Dec 12 18:38:30.141022 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Dec 12 18:38:30.145657 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 12 18:38:30.146553 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Dec 12 18:38:30.151111 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 12 18:38:30.152056 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Dec 12 18:38:30.157638 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Dec 12 18:38:30.163218 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Dec 12 18:38:30.169937 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Dec 12 18:38:30.174906 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Dec 12 18:38:30.188440 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Dec 12 18:38:30.193345 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Dec 12 18:38:30.196497 systemd-journald[1470]: Time spent on flushing to /var/log/journal/da3ad64257d94f94bce511d8e37c3b3f is 17.205ms for 953 entries. Dec 12 18:38:30.196497 systemd-journald[1470]: System Journal (/var/log/journal/da3ad64257d94f94bce511d8e37c3b3f) is 8M, max 2.6G, 2.6G free. Dec 12 18:38:30.302883 systemd-journald[1470]: Received client request to flush runtime journal. Dec 12 18:38:30.303012 kernel: loop0: detected capacity change from 0 to 128560 Dec 12 18:38:30.203179 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Dec 12 18:38:30.235123 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Dec 12 18:38:30.285478 systemd-tmpfiles[1515]: ACLs are not supported, ignoring. Dec 12 18:38:30.285494 systemd-tmpfiles[1515]: ACLs are not supported, ignoring. Dec 12 18:38:30.290412 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Dec 12 18:38:30.297413 systemd[1]: Starting systemd-sysusers.service - Create System Users... Dec 12 18:38:30.312682 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Dec 12 18:38:30.358830 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 12 18:38:30.359539 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Dec 12 18:38:30.477819 systemd[1]: Finished systemd-sysusers.service - Create System Users. Dec 12 18:38:30.483536 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Dec 12 18:38:30.515823 systemd-tmpfiles[1532]: ACLs are not supported, ignoring. Dec 12 18:38:30.515845 systemd-tmpfiles[1532]: ACLs are not supported, ignoring. Dec 12 18:38:30.519616 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Dec 12 18:38:30.763535 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Dec 12 18:38:30.853528 kernel: loop1: detected capacity change from 0 to 27936 Dec 12 18:38:30.913314 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Dec 12 18:38:30.920057 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Dec 12 18:38:30.965050 systemd-udevd[1538]: Using default interface naming scheme 'v255'. Dec 12 18:38:31.195476 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Dec 12 18:38:31.202825 systemd[1]: Starting systemd-networkd.service - Network Configuration... Dec 12 18:38:31.260246 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Dec 12 18:38:31.277528 kernel: hv_vmbus: registering driver hyperv_fb Dec 12 18:38:31.277609 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Dec 12 18:38:31.284939 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Dec 12 18:38:31.288955 kernel: Console: switching to colour dummy device 80x25 Dec 12 18:38:31.294148 kernel: Console: switching to colour frame buffer device 128x48 Dec 12 18:38:31.298523 kernel: hv_vmbus: registering driver hv_balloon Dec 12 18:38:31.304629 kernel: mousedev: PS/2 mouse device common for all mice Dec 12 18:38:31.307192 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Dec 12 18:38:31.314520 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Dec 12 18:38:31.378656 kernel: EDAC MC: Ver: 3.0.0 Dec 12 18:38:31.385947 systemd[1]: Started systemd-userdbd.service - User Database Manager. Dec 12 18:38:31.400733 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:31.410067 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 12 18:38:31.410271 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:31.416534 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Dec 12 18:38:31.427524 kernel: loop2: detected capacity change from 0 to 110984 Dec 12 18:38:31.546112 systemd-networkd[1555]: lo: Link UP Dec 12 18:38:31.546124 systemd-networkd[1555]: lo: Gained carrier Dec 12 18:38:31.547994 systemd-networkd[1555]: Enumeration completed Dec 12 18:38:31.548583 systemd-networkd[1555]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:31.548594 systemd-networkd[1555]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 18:38:31.549312 systemd-networkd[1555]: eth0: Link UP Dec 12 18:38:31.549322 systemd-networkd[1555]: eth0: Gained carrier Dec 12 18:38:31.549337 systemd-networkd[1555]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:31.549681 systemd[1]: Started systemd-networkd.service - Network Configuration. Dec 12 18:38:31.559344 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Dec 12 18:38:31.565364 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Dec 12 18:38:31.587558 systemd-networkd[1555]: eth0: DHCPv4 address 10.200.4.26/24, gateway 10.200.4.1 acquired from 168.63.129.16 Dec 12 18:38:31.588615 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Dec 12 18:38:31.593570 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Dec 12 18:38:31.609464 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Dec 12 18:38:31.632284 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Dec 12 18:38:31.725533 kernel: loop3: detected capacity change from 0 to 128560 Dec 12 18:38:31.744523 kernel: loop4: detected capacity change from 0 to 27936 Dec 12 18:38:31.762525 kernel: loop5: detected capacity change from 0 to 110984 Dec 12 18:38:31.780551 (sd-merge)[1693]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Dec 12 18:38:31.781046 (sd-merge)[1693]: Merged extensions into '/usr'. Dec 12 18:38:31.784731 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Dec 12 18:38:31.787562 systemd[1]: Starting ensure-sysext.service... Dec 12 18:38:31.789151 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Dec 12 18:38:31.825150 systemd[1]: Reload requested from client PID 1695 ('systemctl') (unit ensure-sysext.service)... Dec 12 18:38:31.825167 systemd[1]: Reloading... Dec 12 18:38:31.838985 systemd-tmpfiles[1696]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Dec 12 18:38:31.839025 systemd-tmpfiles[1696]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Dec 12 18:38:31.839316 systemd-tmpfiles[1696]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Dec 12 18:38:31.839634 systemd-tmpfiles[1696]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Dec 12 18:38:31.840520 systemd-tmpfiles[1696]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Dec 12 18:38:31.840809 systemd-tmpfiles[1696]: ACLs are not supported, ignoring. Dec 12 18:38:31.840876 systemd-tmpfiles[1696]: ACLs are not supported, ignoring. Dec 12 18:38:31.864568 zram_generator::config[1728]: No configuration found. Dec 12 18:38:31.868905 systemd-tmpfiles[1696]: Detected autofs mount point /boot during canonicalization of boot. Dec 12 18:38:31.868920 systemd-tmpfiles[1696]: Skipping /boot Dec 12 18:38:31.877410 systemd-tmpfiles[1696]: Detected autofs mount point /boot during canonicalization of boot. Dec 12 18:38:31.877424 systemd-tmpfiles[1696]: Skipping /boot Dec 12 18:38:32.133988 systemd[1]: Reloading finished in 308 ms. Dec 12 18:38:32.156051 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Dec 12 18:38:32.196961 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Dec 12 18:38:32.216160 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.217423 systemd[1]: Starting audit-rules.service - Load Audit Rules... Dec 12 18:38:32.238430 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Dec 12 18:38:32.244223 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 18:38:32.245375 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 18:38:32.250836 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 18:38:32.256637 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 18:38:32.260877 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 18:38:32.261001 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). Dec 12 18:38:32.262308 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Dec 12 18:38:32.268766 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Dec 12 18:38:32.274003 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Dec 12 18:38:32.278876 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.281059 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 18:38:32.281249 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 18:38:32.286375 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 18:38:32.286573 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 18:38:32.291987 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 18:38:32.292169 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 18:38:32.308793 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Dec 12 18:38:32.320268 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.320557 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Dec 12 18:38:32.321805 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Dec 12 18:38:32.327140 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Dec 12 18:38:32.345426 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Dec 12 18:38:32.351751 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Dec 12 18:38:32.355987 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Dec 12 18:38:32.356157 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). Dec 12 18:38:32.356368 systemd[1]: Reached target time-set.target - System Time Set. Dec 12 18:38:32.360808 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Dec 12 18:38:32.362613 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Dec 12 18:38:32.362809 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Dec 12 18:38:32.368118 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 12 18:38:32.368310 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Dec 12 18:38:32.373245 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Dec 12 18:38:32.378660 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Dec 12 18:38:32.378841 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Dec 12 18:38:32.384259 systemd[1]: modprobe@loop.service: Deactivated successfully. Dec 12 18:38:32.384444 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Dec 12 18:38:32.391604 systemd[1]: Finished ensure-sysext.service. Dec 12 18:38:32.398469 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Dec 12 18:38:32.398545 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Dec 12 18:38:32.425280 systemd-resolved[1798]: Positive Trust Anchors: Dec 12 18:38:32.425296 systemd-resolved[1798]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 12 18:38:32.425328 systemd-resolved[1798]: 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 Dec 12 18:38:32.493149 augenrules[1833]: No rules Dec 12 18:38:32.494700 systemd[1]: audit-rules.service: Deactivated successfully. Dec 12 18:38:32.494944 systemd[1]: Finished audit-rules.service - Load Audit Rules. Dec 12 18:38:32.597071 systemd-resolved[1798]: Using system hostname 'ci-4459.2.2-a-1874b56392'. Dec 12 18:38:32.598761 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Dec 12 18:38:32.603372 systemd[1]: Reached target network.target - Network. Dec 12 18:38:32.607064 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Dec 12 18:38:32.896630 systemd-networkd[1555]: eth0: Gained IPv6LL Dec 12 18:38:32.898912 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Dec 12 18:38:32.904155 systemd[1]: Reached target network-online.target - Network is Online. Dec 12 18:38:33.215290 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Dec 12 18:38:33.220728 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). Dec 12 18:38:35.534624 ldconfig[1508]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 12 18:38:35.543999 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Dec 12 18:38:35.550261 systemd[1]: Starting systemd-update-done.service - Update is Completed... Dec 12 18:38:35.584364 systemd[1]: Finished systemd-update-done.service - Update is Completed. Dec 12 18:38:35.589196 systemd[1]: Reached target sysinit.target - System Initialization. Dec 12 18:38:35.593672 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Dec 12 18:38:35.598742 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Dec 12 18:38:35.603846 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Dec 12 18:38:35.608740 systemd[1]: Started logrotate.timer - Daily rotation of log files. Dec 12 18:38:35.613207 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Dec 12 18:38:35.618383 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Dec 12 18:38:35.623587 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Dec 12 18:38:35.623625 systemd[1]: Reached target paths.target - Path Units. Dec 12 18:38:35.627382 systemd[1]: Reached target timers.target - Timer Units. Dec 12 18:38:35.632489 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Dec 12 18:38:35.638292 systemd[1]: Starting docker.socket - Docker Socket for the API... Dec 12 18:38:35.644314 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Dec 12 18:38:35.649609 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Dec 12 18:38:35.654885 systemd[1]: Reached target ssh-access.target - SSH Access Available. Dec 12 18:38:35.664525 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Dec 12 18:38:35.669599 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Dec 12 18:38:35.675322 systemd[1]: Listening on docker.socket - Docker Socket for the API. Dec 12 18:38:35.680670 systemd[1]: Reached target sockets.target - Socket Units. Dec 12 18:38:35.684651 systemd[1]: Reached target basic.target - Basic System. Dec 12 18:38:35.688422 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Dec 12 18:38:35.688454 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Dec 12 18:38:35.690952 systemd[1]: Starting chronyd.service - NTP client/server... Dec 12 18:38:35.695263 systemd[1]: Starting containerd.service - containerd container runtime... Dec 12 18:38:35.715420 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Dec 12 18:38:35.720776 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Dec 12 18:38:35.725456 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Dec 12 18:38:35.731007 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Dec 12 18:38:35.736264 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Dec 12 18:38:35.740849 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). Dec 12 18:38:35.743202 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Dec 12 18:38:35.743916 jq[1854]: false Dec 12 18:38:35.747809 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). Dec 12 18:38:35.748852 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Dec 12 18:38:35.752963 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Dec 12 18:38:35.753955 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Dec 12 18:38:35.759149 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Dec 12 18:38:35.763884 extend-filesystems[1855]: Found /dev/sdb6 Dec 12 18:38:35.764059 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Dec 12 18:38:35.772692 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Dec 12 18:38:35.779103 systemd[1]: Starting systemd-logind.service - User Login Management... Dec 12 18:38:35.779657 google_oslogin_nss_cache[1856]: oslogin_cache_refresh[1856]: Refreshing passwd entry cache Dec 12 18:38:35.779663 oslogin_cache_refresh[1856]: Refreshing passwd entry cache Dec 12 18:38:35.784677 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Dec 12 18:38:35.785182 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 12 18:38:35.785775 systemd[1]: Starting update-engine.service - Update Engine... Dec 12 18:38:35.790855 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Dec 12 18:38:35.795078 google_oslogin_nss_cache[1856]: oslogin_cache_refresh[1856]: Failure getting users, quitting Dec 12 18:38:35.795072 oslogin_cache_refresh[1856]: Failure getting users, quitting Dec 12 18:38:35.795185 google_oslogin_nss_cache[1856]: oslogin_cache_refresh[1856]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Dec 12 18:38:35.795185 google_oslogin_nss_cache[1856]: oslogin_cache_refresh[1856]: Refreshing group entry cache Dec 12 18:38:35.795092 oslogin_cache_refresh[1856]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Dec 12 18:38:35.795144 oslogin_cache_refresh[1856]: Refreshing group entry cache Dec 12 18:38:35.796634 KVP[1858]: KVP starting; pid is:1858 Dec 12 18:38:35.796914 extend-filesystems[1855]: Found /dev/sdb9 Dec 12 18:38:35.797672 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Dec 12 18:38:35.799628 jq[1871]: true Dec 12 18:38:35.799889 chronyd[1846]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Dec 12 18:38:35.800424 KVP[1858]: KVP LIC Version: 3.1 Dec 12 18:38:35.801198 google_oslogin_nss_cache[1856]: oslogin_cache_refresh[1856]: Failure getting groups, quitting Dec 12 18:38:35.801198 google_oslogin_nss_cache[1856]: oslogin_cache_refresh[1856]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Dec 12 18:38:35.800626 oslogin_cache_refresh[1856]: Failure getting groups, quitting Dec 12 18:38:35.800638 oslogin_cache_refresh[1856]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Dec 12 18:38:35.801540 kernel: hv_utils: KVP IC version 4.0 Dec 12 18:38:35.807329 extend-filesystems[1855]: Checking size of /dev/sdb9 Dec 12 18:38:35.807199 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Dec 12 18:38:35.807628 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Dec 12 18:38:35.807916 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Dec 12 18:38:35.808110 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Dec 12 18:38:35.815771 systemd[1]: motdgen.service: Deactivated successfully. Dec 12 18:38:35.823766 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Dec 12 18:38:35.828612 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 12 18:38:35.829422 chronyd[1846]: Timezone right/UTC failed leap second check, ignoring Dec 12 18:38:35.828830 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Dec 12 18:38:35.829684 chronyd[1846]: Loaded seccomp filter (level 2) Dec 12 18:38:35.830996 extend-filesystems[1855]: Old size kept for /dev/sdb9 Dec 12 18:38:35.834411 systemd[1]: Started chronyd.service - NTP client/server. Dec 12 18:38:35.837109 update_engine[1870]: I20251212 18:38:35.832129 1870 main.cc:92] Flatcar Update Engine starting Dec 12 18:38:35.836570 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 12 18:38:35.836789 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Dec 12 18:38:35.861897 (ntainerd)[1889]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Dec 12 18:38:35.866098 jq[1888]: true Dec 12 18:38:35.907535 setup-nvidia[1862]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.2 Dec 12 18:38:35.923133 systemd-logind[1868]: New seat seat0. Dec 12 18:38:35.925608 systemd-logind[1868]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Dec 12 18:38:35.928250 systemd[1]: Started systemd-logind.service - User Login Management. Dec 12 18:38:35.967150 bash[1937]: Updated "/home/core/.ssh/authorized_keys" Dec 12 18:38:35.968635 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Dec 12 18:38:35.974194 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Dec 12 18:38:35.987203 sshd_keygen[1883]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Dec 12 18:38:36.008678 dbus-daemon[1849]: [system] SELinux support is enabled Dec 12 18:38:36.009123 systemd[1]: Started dbus.service - D-Bus System Message Bus. Dec 12 18:38:36.011872 update_engine[1870]: I20251212 18:38:36.011819 1870 update_check_scheduler.cc:74] Next update check in 9m57s Dec 12 18:38:36.015978 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Dec 12 18:38:36.021655 dbus-daemon[1849]: [system] Successfully activated service 'org.freedesktop.systemd1' Dec 12 18:38:36.021939 systemd[1]: Starting issuegen.service - Generate /run/issue... Dec 12 18:38:36.025561 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). Dec 12 18:38:36.025596 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Dec 12 18:38:36.030788 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). Dec 12 18:38:36.030815 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Dec 12 18:38:36.038364 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Dec 12 18:38:36.044803 systemd[1]: issuegen.service: Deactivated successfully. Dec 12 18:38:36.045013 systemd[1]: Finished issuegen.service - Generate /run/issue. Dec 12 18:38:36.051669 systemd[1]: Started update-engine.service - Update Engine. Dec 12 18:38:36.057378 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 12 18:38:36.058591 coreos-metadata[1848]: Dec 12 18:38:36.058 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Dec 12 18:38:36.061009 coreos-metadata[1848]: Dec 12 18:38:36.060 INFO Fetch successful Dec 12 18:38:36.061147 coreos-metadata[1848]: Dec 12 18:38:36.061 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Dec 12 18:38:36.063734 systemd[1]: Started locksmithd.service - Cluster reboot manager. Dec 12 18:38:36.065853 coreos-metadata[1848]: Dec 12 18:38:36.064 INFO Fetch successful Dec 12 18:38:36.065853 coreos-metadata[1848]: Dec 12 18:38:36.065 INFO Fetching http://168.63.129.16/machine/48ca32a0-9b4e-4738-9715-6d249725db9f/28f56fb9%2D682f%2D4d36%2Db2ea%2D41da22e3cfec.%5Fci%2D4459.2.2%2Da%2D1874b56392?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Dec 12 18:38:36.067116 coreos-metadata[1848]: Dec 12 18:38:36.067 INFO Fetch successful Dec 12 18:38:36.067217 coreos-metadata[1848]: Dec 12 18:38:36.067 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Dec 12 18:38:36.068324 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Dec 12 18:38:36.074209 coreos-metadata[1848]: Dec 12 18:38:36.074 INFO Fetch successful Dec 12 18:38:36.077651 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 12 18:38:36.084890 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 12 18:38:36.089714 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Dec 12 18:38:36.094312 systemd[1]: Reached target getty.target - Login Prompts. Dec 12 18:38:36.117286 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Dec 12 18:38:36.122270 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Dec 12 18:38:36.234138 locksmithd[2002]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 12 18:38:36.606169 setup-nvidia[2022]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 12 18:38:36.606520 setup-nvidia[2022]: Dload Upload Total Spent Left Speed Dec 12 18:38:36.852373 containerd[1889]: time="2025-12-12T18:38:36Z" 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 Dec 12 18:38:36.853096 containerd[1889]: time="2025-12-12T18:38:36.853059100Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Dec 12 18:38:36.862267 containerd[1889]: time="2025-12-12T18:38:36.862180800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="13.4µs" Dec 12 18:38:36.862267 containerd[1889]: time="2025-12-12T18:38:36.862213900Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Dec 12 18:38:36.862345 containerd[1889]: time="2025-12-12T18:38:36.862269400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Dec 12 18:38:36.862530 containerd[1889]: time="2025-12-12T18:38:36.862426600Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Dec 12 18:38:36.862530 containerd[1889]: time="2025-12-12T18:38:36.862457400Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Dec 12 18:38:36.862530 containerd[1889]: time="2025-12-12T18:38:36.862489300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 12 18:38:36.862714 containerd[1889]: time="2025-12-12T18:38:36.862674900Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Dec 12 18:38:36.862745 containerd[1889]: time="2025-12-12T18:38:36.862714000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Dec 12 18:38:36.863061 containerd[1889]: time="2025-12-12T18:38:36.863032500Z" 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 Dec 12 18:38:36.863095 containerd[1889]: time="2025-12-12T18:38:36.863058000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 12 18:38:36.863095 containerd[1889]: time="2025-12-12T18:38:36.863072200Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Dec 12 18:38:36.863095 containerd[1889]: time="2025-12-12T18:38:36.863080500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Dec 12 18:38:36.863190 containerd[1889]: time="2025-12-12T18:38:36.863169500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Dec 12 18:38:36.863428 containerd[1889]: time="2025-12-12T18:38:36.863406400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Dec 12 18:38:36.863455 containerd[1889]: time="2025-12-12T18:38:36.863444700Z" 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 Dec 12 18:38:36.863482 containerd[1889]: time="2025-12-12T18:38:36.863456600Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Dec 12 18:38:36.863518 containerd[1889]: time="2025-12-12T18:38:36.863487100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Dec 12 18:38:36.863715 containerd[1889]: time="2025-12-12T18:38:36.863691700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Dec 12 18:38:36.863783 containerd[1889]: time="2025-12-12T18:38:36.863764100Z" level=info msg="metadata content store policy set" policy=shared Dec 12 18:38:36.888032 containerd[1889]: time="2025-12-12T18:38:36.887975100Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Dec 12 18:38:36.888032 containerd[1889]: time="2025-12-12T18:38:36.888034200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Dec 12 18:38:36.888164 containerd[1889]: time="2025-12-12T18:38:36.888054200Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Dec 12 18:38:36.888164 containerd[1889]: time="2025-12-12T18:38:36.888071700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Dec 12 18:38:36.888164 containerd[1889]: time="2025-12-12T18:38:36.888086700Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Dec 12 18:38:36.888164 containerd[1889]: time="2025-12-12T18:38:36.888098300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Dec 12 18:38:36.888164 containerd[1889]: time="2025-12-12T18:38:36.888115400Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Dec 12 18:38:36.888164 containerd[1889]: time="2025-12-12T18:38:36.888150000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Dec 12 18:38:36.888293 containerd[1889]: time="2025-12-12T18:38:36.888167300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Dec 12 18:38:36.888293 containerd[1889]: time="2025-12-12T18:38:36.888178800Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Dec 12 18:38:36.888293 containerd[1889]: time="2025-12-12T18:38:36.888189100Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Dec 12 18:38:36.888293 containerd[1889]: time="2025-12-12T18:38:36.888202300Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Dec 12 18:38:36.888416 containerd[1889]: time="2025-12-12T18:38:36.888388300Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Dec 12 18:38:36.888452 containerd[1889]: time="2025-12-12T18:38:36.888427000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Dec 12 18:38:36.888452 containerd[1889]: time="2025-12-12T18:38:36.888444800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Dec 12 18:38:36.888498 containerd[1889]: time="2025-12-12T18:38:36.888458200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Dec 12 18:38:36.888498 containerd[1889]: time="2025-12-12T18:38:36.888469400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Dec 12 18:38:36.888498 containerd[1889]: time="2025-12-12T18:38:36.888480200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Dec 12 18:38:36.888498 containerd[1889]: time="2025-12-12T18:38:36.888491700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Dec 12 18:38:36.888617 containerd[1889]: time="2025-12-12T18:38:36.888538700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Dec 12 18:38:36.888617 containerd[1889]: time="2025-12-12T18:38:36.888559200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Dec 12 18:38:36.888617 containerd[1889]: time="2025-12-12T18:38:36.888571000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Dec 12 18:38:36.888684 containerd[1889]: time="2025-12-12T18:38:36.888635500Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Dec 12 18:38:36.888755 containerd[1889]: time="2025-12-12T18:38:36.888728800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Dec 12 18:38:36.888789 containerd[1889]: time="2025-12-12T18:38:36.888754900Z" level=info msg="Start snapshots syncer" Dec 12 18:38:36.888789 containerd[1889]: time="2025-12-12T18:38:36.888782300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Dec 12 18:38:36.889218 containerd[1889]: time="2025-12-12T18:38:36.889157500Z" 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\"}" Dec 12 18:38:36.889331 containerd[1889]: time="2025-12-12T18:38:36.889230100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Dec 12 18:38:36.889331 containerd[1889]: time="2025-12-12T18:38:36.889279500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Dec 12 18:38:36.889496 containerd[1889]: time="2025-12-12T18:38:36.889467300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Dec 12 18:38:36.889578 containerd[1889]: time="2025-12-12T18:38:36.889514100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Dec 12 18:38:36.889578 containerd[1889]: time="2025-12-12T18:38:36.889529800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Dec 12 18:38:36.889578 containerd[1889]: time="2025-12-12T18:38:36.889542500Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Dec 12 18:38:36.889578 containerd[1889]: time="2025-12-12T18:38:36.889557800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Dec 12 18:38:36.889578 containerd[1889]: time="2025-12-12T18:38:36.889569600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889581400Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889604300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889615900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889627500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889666200Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889684900Z" 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 Dec 12 18:38:36.889699 containerd[1889]: time="2025-12-12T18:38:36.889696400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889706300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889714200Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889723900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889786700Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889812600Z" level=info msg="runtime interface created" Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889818900Z" level=info msg="created NRI interface" Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889827600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Dec 12 18:38:36.889846 containerd[1889]: time="2025-12-12T18:38:36.889839100Z" level=info msg="Connect containerd service" Dec 12 18:38:36.890015 containerd[1889]: time="2025-12-12T18:38:36.889859700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Dec 12 18:38:36.890828 containerd[1889]: time="2025-12-12T18:38:36.890778500Z" 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" Dec 12 18:38:37.381167 containerd[1889]: time="2025-12-12T18:38:37.381121300Z" level=info msg="Start subscribing containerd event" Dec 12 18:38:37.381277 containerd[1889]: time="2025-12-12T18:38:37.381179300Z" level=info msg="Start recovering state" Dec 12 18:38:37.381365 containerd[1889]: time="2025-12-12T18:38:37.381297600Z" level=info msg="Start event monitor" Dec 12 18:38:37.381365 containerd[1889]: time="2025-12-12T18:38:37.381314200Z" level=info msg="Start cni network conf syncer for default" Dec 12 18:38:37.381365 containerd[1889]: time="2025-12-12T18:38:37.381321600Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Dec 12 18:38:37.381365 containerd[1889]: time="2025-12-12T18:38:37.381345900Z" level=info msg="Start streaming server" Dec 12 18:38:37.381365 containerd[1889]: time="2025-12-12T18:38:37.381364800Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Dec 12 18:38:37.381474 containerd[1889]: time="2025-12-12T18:38:37.381372900Z" level=info msg="runtime interface starting up..." Dec 12 18:38:37.381474 containerd[1889]: time="2025-12-12T18:38:37.381378700Z" level=info msg="starting plugins..." Dec 12 18:38:37.381474 containerd[1889]: time="2025-12-12T18:38:37.381384000Z" level=info msg=serving... address=/run/containerd/containerd.sock Dec 12 18:38:37.381474 containerd[1889]: time="2025-12-12T18:38:37.381395800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Dec 12 18:38:37.381791 containerd[1889]: time="2025-12-12T18:38:37.381563100Z" level=info msg="containerd successfully booted in 0.529699s" Dec 12 18:38:37.381669 systemd[1]: Started containerd.service - containerd container runtime. Dec 12 18:38:37.779866 waagent[2003]: 2025-12-12T18:38:37.779539Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Dec 12 18:38:37.784447 waagent[2003]: 2025-12-12T18:38:37.784355Z INFO Daemon Daemon OS: flatcar 4459.2.2 Dec 12 18:38:37.788053 waagent[2003]: 2025-12-12T18:38:37.788002Z INFO Daemon Daemon Python: 3.11.13 Dec 12 18:38:37.791520 waagent[2003]: 2025-12-12T18:38:37.791463Z INFO Daemon Daemon Run daemon Dec 12 18:38:37.794608 waagent[2003]: 2025-12-12T18:38:37.794566Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.2' Dec 12 18:38:37.801160 waagent[2003]: 2025-12-12T18:38:37.801107Z INFO Daemon Daemon Using waagent for provisioning Dec 12 18:38:37.805129 waagent[2003]: 2025-12-12T18:38:37.805081Z INFO Daemon Daemon Activate resource disk Dec 12 18:38:37.808932 waagent[2003]: 2025-12-12T18:38:37.808887Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Dec 12 18:38:37.816384 waagent[2003]: 2025-12-12T18:38:37.816329Z INFO Daemon Daemon Found device: sda Dec 12 18:38:37.826251 waagent[2003]: 2025-12-12T18:38:37.826199Z INFO Daemon Daemon Examining partition table Dec 12 18:38:37.864759 waagent[2003]: 2025-12-12T18:38:37.864695Z INFO Daemon Daemon GPT not detected, determining filesystem Dec 12 18:38:37.898895 waagent[2003]: 2025-12-12T18:38:37.898836Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Dec 12 18:38:37.903889 waagent[2003]: 2025-12-12T18:38:37.903835Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Dec 12 18:38:38.802787 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#419 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:38.815527 kernel: sda: sda1 Dec 12 18:38:38.990437 waagent[2003]: 2025-12-12T18:38:38.990345Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Dec 12 18:38:39.009264 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#581 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Dec 12 18:38:39.021524 kernel: sda: sda1 Dec 12 18:38:39.021623 waagent[2003]: 2025-12-12T18:38:39.021576Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Dec 12 18:38:44.322581 login[2008]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Dec 12 18:38:44.436359 login[2009]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Dec 12 18:38:44.444169 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Dec 12 18:38:44.445328 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Dec 12 18:38:44.451594 systemd-logind[1868]: New session 1 of user core. Dec 12 18:38:44.604928 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Dec 12 18:38:44.607480 systemd[1]: Starting user@500.service - User Manager for UID 500... Dec 12 18:38:44.787384 (systemd)[2104]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 12 18:38:44.789840 systemd-logind[1868]: New session c1 of user core. Dec 12 18:38:44.867338 waagent[2003]: 2025-12-12T18:38:44.867207Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Dec 12 18:38:44.981538 kernel: EXT4-fs (sda1): mounted filesystem af72eea2-2a5d-49f1-b9bc-0afc802eac79 r/w with ordered data mode. Quota mode: none. Dec 12 18:38:44.981844 waagent[2003]: 2025-12-12T18:38:44.981769Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Dec 12 18:38:44.983900 waagent[2003]: 2025-12-12T18:38:44.983855Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 12 18:38:44.984191 waagent[2003]: 2025-12-12T18:38:44.984149Z INFO Daemon Daemon Running default provisioning handler Dec 12 18:38:44.992127 waagent[2003]: 2025-12-12T18:38:44.992070Z 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. Dec 12 18:38:44.993827 waagent[2003]: 2025-12-12T18:38:44.993785Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Dec 12 18:38:44.994186 waagent[2003]: 2025-12-12T18:38:44.994151Z INFO Daemon Daemon cloud-init is enabled: False Dec 12 18:38:44.995512 waagent[2003]: 2025-12-12T18:38:44.995476Z INFO Daemon Daemon Copying ovf-env.xml Dec 12 18:38:45.039074 systemd[2104]: Queued start job for default target default.target. Dec 12 18:38:45.048083 waagent[2003]: 2025-12-12T18:38:45.047724Z INFO Daemon Daemon Successfully mounted dvd Dec 12 18:38:45.055529 systemd[2104]: Created slice app.slice - User Application Slice. Dec 12 18:38:45.055573 systemd[2104]: Reached target paths.target - Paths. Dec 12 18:38:45.055622 systemd[2104]: Reached target timers.target - Timers. Dec 12 18:38:45.056897 systemd[2104]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 12 18:38:45.078106 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Dec 12 18:38:45.078256 waagent[2003]: 2025-12-12T18:38:45.078139Z INFO Daemon Daemon Detect protocol endpoint Dec 12 18:38:45.079775 waagent[2003]: 2025-12-12T18:38:45.079727Z INFO Daemon Daemon Clean protocol and wireserver endpoint Dec 12 18:38:45.080474 waagent[2003]: 2025-12-12T18:38:45.080438Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Dec 12 18:38:45.081129 waagent[2003]: 2025-12-12T18:38:45.081098Z INFO Daemon Daemon Test for route to 168.63.129.16 Dec 12 18:38:45.083654 waagent[2003]: 2025-12-12T18:38:45.082663Z INFO Daemon Daemon Route to 168.63.129.16 exists Dec 12 18:38:45.083654 waagent[2003]: 2025-12-12T18:38:45.083233Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Dec 12 18:38:45.094924 waagent[2003]: 2025-12-12T18:38:45.094876Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Dec 12 18:38:45.095847 waagent[2003]: 2025-12-12T18:38:45.095821Z INFO Daemon Daemon Wire protocol version:2012-11-30 Dec 12 18:38:45.097117 waagent[2003]: 2025-12-12T18:38:45.097080Z INFO Daemon Daemon Server preferred version:2015-04-05 Dec 12 18:38:45.104943 systemd[2104]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 12 18:38:45.105066 systemd[2104]: Reached target sockets.target - Sockets. Dec 12 18:38:45.105121 systemd[2104]: Reached target basic.target - Basic System. Dec 12 18:38:45.105167 systemd[2104]: Reached target default.target - Main User Target. Dec 12 18:38:45.105203 systemd[2104]: Startup finished in 307ms. Dec 12 18:38:45.105218 systemd[1]: Started user@500.service - User Manager for UID 500. Dec 12 18:38:45.106478 systemd[1]: Started session-1.scope - Session 1 of User core. Dec 12 18:38:45.205030 waagent[2003]: 2025-12-12T18:38:45.204946Z INFO Daemon Daemon Initializing goal state during protocol detection Dec 12 18:38:45.206437 waagent[2003]: 2025-12-12T18:38:45.206388Z INFO Daemon Daemon Forcing an update of the goal state. Dec 12 18:38:45.210973 waagent[2003]: 2025-12-12T18:38:45.210917Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 12 18:38:45.243938 waagent[2003]: 2025-12-12T18:38:45.243884Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Dec 12 18:38:45.245905 waagent[2003]: 2025-12-12T18:38:45.245837Z INFO Daemon Dec 12 18:38:45.246079 waagent[2003]: 2025-12-12T18:38:45.246042Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: ac26cb41-7bf7-48e7-82c8-41660b677ae0 eTag: 14649992926198071042 source: Fabric] Dec 12 18:38:45.247709 waagent[2003]: 2025-12-12T18:38:45.247647Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Dec 12 18:38:45.248284 waagent[2003]: 2025-12-12T18:38:45.248224Z INFO Daemon Dec 12 18:38:45.249298 waagent[2003]: 2025-12-12T18:38:45.249258Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Dec 12 18:38:45.254970 waagent[2003]: 2025-12-12T18:38:45.254931Z INFO Daemon Daemon Downloading artifacts profile blob Dec 12 18:38:45.324769 login[2008]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Dec 12 18:38:45.330846 systemd-logind[1868]: New session 2 of user core. Dec 12 18:38:45.343638 systemd[1]: Started session-2.scope - Session 2 of User core. Dec 12 18:38:45.378766 waagent[2003]: 2025-12-12T18:38:45.378696Z INFO Daemon Downloaded certificate {'thumbprint': 'A03293469EC24E8F69F5D66C6A000EC474614C08', 'hasPrivateKey': True} Dec 12 18:38:45.380802 waagent[2003]: 2025-12-12T18:38:45.380760Z INFO Daemon Fetch goal state completed Dec 12 18:38:45.416442 waagent[2003]: 2025-12-12T18:38:45.416375Z INFO Daemon Daemon Starting provisioning Dec 12 18:38:45.418867 waagent[2003]: 2025-12-12T18:38:45.418809Z INFO Daemon Daemon Handle ovf-env.xml. Dec 12 18:38:45.421121 waagent[2003]: 2025-12-12T18:38:45.421066Z INFO Daemon Daemon Set hostname [ci-4459.2.2-a-1874b56392] Dec 12 18:38:45.422899 setup-nvidia[2022]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 8 473M 8 40.8M 0 0 78.1M 0 0:00:06 --:--:-- 0:00:06 78.0M\u000d 20 473M 20 97.0M 0 0 62.9M 0 0:00:07 0:00:01 0:00:06 62.9M\u000d 30 473M 30 143M 0 0 56.6M 0 0:00:08 0:00:02 0:00:06 56.6M\u000d 43 473M 43 204M 0 0 58.0M 0 0:00:08 0:00:03 0:00:05 58.0M\u000d 63 473M 63 299M 0 0 65.4M 0 0:00:07 0:00:04 0:00:03 65.4M\u000d 74 473M 74 352M 0 0 63.7M 0 0:00:07 0:00:05 0:00:02 62.2M\u000d 85 473M 85 403M 0 0 61.6M 0 0:00:07 0:00:06 0:00:01 61.2M\u000d 88 473M 88 421M 0 0 55.9M 0 0:00:08 0:00:07 0:00:01 55.5M\u000d 95 473M 95 451M 0 0 52.9M 0 0:00:08 0:00:08 --:--:-- 49.3M\u000d100 473M 100 473M 0 0 53.7M 0 0:00:08 0:00:08 --:--:-- 41.1M Dec 12 18:38:45.430394 waagent[2003]: 2025-12-12T18:38:45.430349Z INFO Daemon Daemon Publish hostname [ci-4459.2.2-a-1874b56392] Dec 12 18:38:45.432162 waagent[2003]: 2025-12-12T18:38:45.432116Z INFO Daemon Daemon Examine /proc/net/route for primary interface Dec 12 18:38:45.433580 waagent[2003]: 2025-12-12T18:38:45.433543Z INFO Daemon Daemon Primary interface is [eth0] Dec 12 18:38:45.450951 systemd-networkd[1555]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Dec 12 18:38:45.450961 systemd-networkd[1555]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Dec 12 18:38:45.450985 systemd-networkd[1555]: eth0: DHCP lease lost Dec 12 18:38:45.451450 waagent[2003]: 2025-12-12T18:38:45.451405Z INFO Daemon Daemon Create user account if not exists Dec 12 18:38:45.453090 waagent[2003]: 2025-12-12T18:38:45.453048Z INFO Daemon Daemon User core already exists, skip useradd Dec 12 18:38:45.454497 waagent[2003]: 2025-12-12T18:38:45.454460Z INFO Daemon Daemon Configure sudoer Dec 12 18:38:45.466998 waagent[2003]: 2025-12-12T18:38:45.466923Z INFO Daemon Daemon Configure sshd Dec 12 18:38:45.471750 waagent[2003]: 2025-12-12T18:38:45.471699Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Dec 12 18:38:45.473352 waagent[2003]: 2025-12-12T18:38:45.473298Z INFO Daemon Daemon Deploy ssh public key. Dec 12 18:38:45.528571 systemd-networkd[1555]: eth0: DHCPv4 address 10.200.4.26/24, gateway 10.200.4.1 acquired from 168.63.129.16 Dec 12 18:38:55.909824 setup-nvidia[1862]: Downloading NVIDIA 535.261.03 Driver Dec 12 18:38:55.914094 setup-nvidia[2173]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 12 18:38:55.914323 setup-nvidia[2173]: Dload Upload Total Spent Left Speed Dec 12 18:38:59.611696 chronyd[1846]: Selected source PHC0 Dec 12 18:39:01.044544 setup-nvidia[2173]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 2049k 0 0 9462k 0 0:00:35 --:--:-- 0:00:35 9443k\u000d 20 325M 20 68.0M 0 0 54.9M 0 0:00:05 0:00:01 0:00:04 54.9M\u000d 41 325M 41 134M 0 0 60.4M 0 0:00:05 0:00:02 0:00:03 60.4M\u000d 62 325M 62 202M 0 0 63.1M 0 0:00:05 0:00:03 0:00:02 63.1M\u000d 80 325M 80 262M 0 0 62.2M 0 0:00:05 0:00:04 0:00:01 62.2M\u000d100 325M 100 325M 0 0 63.5M 0 0:00:05 0:00:05 --:--:-- 65.9M Dec 12 18:39:01.119517 setup-nvidia[1862]: Extract the NVIDIA Driver Installer 535.261.03 Dec 12 18:39:01.119825 setup-nvidia[1862]: /opt/nvidia/workdir/nvidia-workdir / Dec 12 18:39:01.139463 setup-nvidia[2176]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Dec 12 18:39:01.405325 setup-nvidia[2176]: Verifying archive integrity... OK Dec 12 18:39:02.152796 setup-nvidia[2176]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Dec 12 18:39:04.129234 setup-nvidia[2202]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Dec 12 18:39:04.208239 setup-nvidia[1862]: / Dec 12 18:39:04.208497 setup-nvidia[1862]: Spawn system-nspawn container to install the NVIDIA drivers Dec 12 18:39:04.356716 sudo[2209]: 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.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 Dec 12 18:39:04.358487 sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:39:04.364359 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Dec 12 18:39:04.365337 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Dec 12 18:39:04.390702 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Dec 12 18:39:04.393055 systemd[1]: Starting user@0.service - User Manager for UID 0... Dec 12 18:39:04.413667 (systemd)[2212]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Dec 12 18:39:04.415967 systemd-logind[1868]: New session c3 of user root. Dec 12 18:39:04.609579 systemd[2212]: Queued start job for default target default.target. Dec 12 18:39:04.630578 systemd[2212]: Created slice app.slice - User Application Slice. Dec 12 18:39:04.630607 systemd[2212]: Reached target paths.target - Paths. Dec 12 18:39:04.630652 systemd[2212]: Reached target timers.target - Timers. Dec 12 18:39:04.631783 systemd[2212]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 12 18:39:04.642664 systemd[2212]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 12 18:39:04.642781 systemd[2212]: Reached target sockets.target - Sockets. Dec 12 18:39:04.642842 systemd[2212]: Reached target basic.target - Basic System. Dec 12 18:39:04.642891 systemd[2212]: Reached target default.target - Main User Target. Dec 12 18:39:04.642921 systemd[2212]: Startup finished in 220ms. Dec 12 18:39:04.642952 systemd[1]: Started user@0.service - User Manager for UID 0. Dec 12 18:39:04.644292 systemd[1]: Started session-c2.scope - Session c2 of User root. Dec 12 18:39:04.707528 kernel: loop6: detected capacity change from 0 to 12644352 Dec 12 18:39:04.760531 kernel: loop6: p9 Dec 12 18:39:04.930197 setup-nvidia[2221]: ░ Spawning container flatcardevelopercontainer-4459.2.2.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.2.bin. Dec 12 18:39:06.393810 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Dec 12 18:39:06.393936 kernel: EXT4-fs (loop6p9): mounted filesystem 02782f9c-2fea-4b2e-9279-7381cb6c9c28 ro without journal. Quota mode: none. Dec 12 18:39:15.540094 waagent[2003]: 2025-12-12T18:39:15.540029Z INFO Daemon Daemon Provisioning complete Dec 12 18:39:15.549558 waagent[2003]: 2025-12-12T18:39:15.549494Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Dec 12 18:39:15.560762 waagent[2003]: 2025-12-12T18:39:15.551172Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Dec 12 18:39:15.560762 waagent[2003]: 2025-12-12T18:39:15.552622Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Dec 12 18:39:15.734587 waagent[2283]: 2025-12-12T18:39:15.734476Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Dec 12 18:39:15.734951 waagent[2283]: 2025-12-12T18:39:15.734643Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.2 Dec 12 18:39:15.734951 waagent[2283]: 2025-12-12T18:39:15.734720Z INFO ExtHandler ExtHandler Python: 3.11.13 Dec 12 18:39:15.734951 waagent[2283]: 2025-12-12T18:39:15.734792Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Dec 12 18:39:15.832954 waagent[2283]: 2025-12-12T18:39:15.832823Z 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; Dec 12 18:39:15.833110 waagent[2283]: 2025-12-12T18:39:15.833070Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 18:39:15.833198 waagent[2283]: 2025-12-12T18:39:15.833163Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 18:39:15.839799 waagent[2283]: 2025-12-12T18:39:15.839746Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Dec 12 18:39:15.849465 waagent[2283]: 2025-12-12T18:39:15.849421Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Dec 12 18:39:15.849932 waagent[2283]: 2025-12-12T18:39:15.849891Z INFO ExtHandler Dec 12 18:39:15.850015 waagent[2283]: 2025-12-12T18:39:15.849983Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: eebbc163-8ea3-4486-b4f1-b0e57b32f31b eTag: 14649992926198071042 source: Fabric] Dec 12 18:39:15.850346 waagent[2283]: 2025-12-12T18:39:15.850303Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Dec 12 18:39:15.851057 waagent[2283]: 2025-12-12T18:39:15.851011Z INFO ExtHandler Dec 12 18:39:15.851128 waagent[2283]: 2025-12-12T18:39:15.851096Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Dec 12 18:39:15.884141 waagent[2283]: 2025-12-12T18:39:15.884099Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Dec 12 18:39:15.939638 waagent[2283]: 2025-12-12T18:39:15.939572Z INFO ExtHandler Downloaded certificate {'thumbprint': 'A03293469EC24E8F69F5D66C6A000EC474614C08', 'hasPrivateKey': True} Dec 12 18:39:15.940115 waagent[2283]: 2025-12-12T18:39:15.940068Z INFO ExtHandler Fetch goal state completed Dec 12 18:39:15.951876 waagent[2283]: 2025-12-12T18:39:15.951832Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Dec 12 18:39:15.956648 waagent[2283]: 2025-12-12T18:39:15.956601Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2283 Dec 12 18:39:15.956786 waagent[2283]: 2025-12-12T18:39:15.956750Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Dec 12 18:39:15.957122 waagent[2283]: 2025-12-12T18:39:15.957086Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Dec 12 18:39:15.958779 waagent[2283]: 2025-12-12T18:39:15.958741Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.2', '', 'Flatcar Container Linux by Kinvolk'] Dec 12 18:39:15.959216 waagent[2283]: 2025-12-12T18:39:15.959179Z 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 Dec 12 18:39:15.959372 waagent[2283]: 2025-12-12T18:39:15.959339Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Dec 12 18:39:15.959989 waagent[2283]: 2025-12-12T18:39:15.959953Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Dec 12 18:39:16.002760 waagent[2283]: 2025-12-12T18:39:16.002713Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Dec 12 18:39:16.002943 waagent[2283]: 2025-12-12T18:39:16.002906Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Dec 12 18:39:16.009427 waagent[2283]: 2025-12-12T18:39:16.009393Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Dec 12 18:39:16.015925 systemd[1]: Reload requested from client PID 2298 ('systemctl') (unit waagent.service)... Dec 12 18:39:16.015941 systemd[1]: Reloading... Dec 12 18:39:16.066568 zram_generator::config[2344]: No configuration found. Dec 12 18:39:16.344444 systemd[1]: Reloading finished in 328 ms. Dec 12 18:39:16.372108 waagent[2283]: 2025-12-12T18:39:16.372035Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Dec 12 18:39:16.372218 waagent[2283]: 2025-12-12T18:39:16.372181Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Dec 12 18:39:16.893187 waagent[2283]: 2025-12-12T18:39:16.893097Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Dec 12 18:39:16.893560 waagent[2283]: 2025-12-12T18:39:16.893450Z 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] Dec 12 18:39:16.894247 waagent[2283]: 2025-12-12T18:39:16.894199Z INFO ExtHandler ExtHandler Starting env monitor service. Dec 12 18:39:16.894723 waagent[2283]: 2025-12-12T18:39:16.894681Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Dec 12 18:39:16.894875 waagent[2283]: 2025-12-12T18:39:16.894830Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 18:39:16.895052 waagent[2283]: 2025-12-12T18:39:16.895010Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Dec 12 18:39:16.895414 waagent[2283]: 2025-12-12T18:39:16.895361Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Dec 12 18:39:16.895468 waagent[2283]: 2025-12-12T18:39:16.895432Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 18:39:16.895523 waagent[2283]: 2025-12-12T18:39:16.895471Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Dec 12 18:39:16.895622 waagent[2283]: 2025-12-12T18:39:16.895574Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Dec 12 18:39:16.896339 waagent[2283]: 2025-12-12T18:39:16.896294Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Dec 12 18:39:16.896476 waagent[2283]: 2025-12-12T18:39:16.896431Z INFO EnvHandler ExtHandler Configure routes Dec 12 18:39:16.896612 waagent[2283]: 2025-12-12T18:39:16.896557Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Dec 12 18:39:16.896693 waagent[2283]: 2025-12-12T18:39:16.896654Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Dec 12 18:39:16.896997 waagent[2283]: 2025-12-12T18:39:16.896960Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Dec 12 18:39:16.897057 waagent[2283]: 2025-12-12T18:39:16.897022Z INFO EnvHandler ExtHandler Gateway:None Dec 12 18:39:16.897394 waagent[2283]: 2025-12-12T18:39:16.897353Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Dec 12 18:39:16.897394 waagent[2283]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Dec 12 18:39:16.897394 waagent[2283]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Dec 12 18:39:16.897394 waagent[2283]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Dec 12 18:39:16.897394 waagent[2283]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Dec 12 18:39:16.897394 waagent[2283]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 12 18:39:16.897394 waagent[2283]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Dec 12 18:39:16.897698 waagent[2283]: 2025-12-12T18:39:16.897652Z INFO EnvHandler ExtHandler Routes:None Dec 12 18:39:16.903480 waagent[2283]: 2025-12-12T18:39:16.903429Z INFO ExtHandler ExtHandler Dec 12 18:39:16.903815 waagent[2283]: 2025-12-12T18:39:16.903772Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: d19d7734-e2f1-42cc-bd5d-809bd1ecef6f correlation b51fb83b-bc5c-419c-83ff-208fc60ee97c created: 2025-12-12T18:37:31.817574Z] Dec 12 18:39:16.904666 waagent[2283]: 2025-12-12T18:39:16.904622Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Dec 12 18:39:16.906658 waagent[2283]: 2025-12-12T18:39:16.906615Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Dec 12 18:39:17.049906 waagent[2283]: 2025-12-12T18:39:17.049835Z 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 Dec 12 18:39:17.049906 waagent[2283]: Try `iptables -h' or 'iptables --help' for more information.) Dec 12 18:39:17.050355 waagent[2283]: 2025-12-12T18:39:17.050316Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 12B51C47-1777-4EE9-A143-5D76CE9CD7C3;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Dec 12 18:39:17.146076 waagent[2283]: 2025-12-12T18:39:17.145956Z INFO MonitorHandler ExtHandler Network interfaces: Dec 12 18:39:17.146076 waagent[2283]: Executing ['ip', '-a', '-o', 'link']: Dec 12 18:39:17.146076 waagent[2283]: 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 Dec 12 18:39:17.146076 waagent[2283]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d6:2a:74 brd ff:ff:ff:ff:ff:ff Dec 12 18:39:17.146076 waagent[2283]: Executing ['ip', '-4', '-a', '-o', 'address']: Dec 12 18:39:17.146076 waagent[2283]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Dec 12 18:39:17.146076 waagent[2283]: 2: eth0 inet 10.200.4.26/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Dec 12 18:39:17.146076 waagent[2283]: Executing ['ip', '-6', '-a', '-o', 'address']: Dec 12 18:39:17.146076 waagent[2283]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Dec 12 18:39:17.146076 waagent[2283]: 2: eth0 inet6 fe80::20d:3aff:fed6:2a74/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Dec 12 18:39:17.200580 waagent[2283]: 2025-12-12T18:39:17.200487Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Dec 12 18:39:17.200580 waagent[2283]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:17.200580 waagent[2283]: pkts bytes target prot opt in out source destination Dec 12 18:39:17.200580 waagent[2283]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:17.200580 waagent[2283]: pkts bytes target prot opt in out source destination Dec 12 18:39:17.200580 waagent[2283]: Chain OUTPUT (policy ACCEPT 9 packets, 1052 bytes) Dec 12 18:39:17.200580 waagent[2283]: pkts bytes target prot opt in out source destination Dec 12 18:39:17.200580 waagent[2283]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 12 18:39:17.200580 waagent[2283]: 6 520 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 12 18:39:17.200580 waagent[2283]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 12 18:39:17.204587 waagent[2283]: 2025-12-12T18:39:17.204546Z INFO EnvHandler ExtHandler Current Firewall rules: Dec 12 18:39:17.204587 waagent[2283]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:17.204587 waagent[2283]: pkts bytes target prot opt in out source destination Dec 12 18:39:17.204587 waagent[2283]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Dec 12 18:39:17.204587 waagent[2283]: pkts bytes target prot opt in out source destination Dec 12 18:39:17.204587 waagent[2283]: Chain OUTPUT (policy ACCEPT 10 packets, 1112 bytes) Dec 12 18:39:17.204587 waagent[2283]: pkts bytes target prot opt in out source destination Dec 12 18:39:17.204587 waagent[2283]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Dec 12 18:39:17.204587 waagent[2283]: 15 1515 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Dec 12 18:39:17.204587 waagent[2283]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Dec 12 18:39:17.204845 waagent[2283]: 2025-12-12T18:39:17.204810Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Dec 12 18:39:17.204996 waagent[2283]: 2025-12-12T18:39:17.204966Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Dec 12 18:39:18.118035 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Dec 12 18:39:18.120865 systemd[1]: Started sshd@0-10.200.4.26:22-10.200.16.10:60984.service - OpenSSH per-connection server daemon (10.200.16.10:60984). Dec 12 18:39:18.937452 sshd[2435]: Accepted publickey for core from 10.200.16.10 port 60984 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:18.938716 sshd-session[2435]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:18.942975 systemd-logind[1868]: New session 3 of user core. Dec 12 18:39:18.958620 systemd[1]: Started session-3.scope - Session 3 of User core. Dec 12 18:39:19.411533 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Dec 12 18:39:19.482671 systemd[1]: Started sshd@1-10.200.4.26:22-10.200.16.10:60990.service - OpenSSH per-connection server daemon (10.200.16.10:60990). Dec 12 18:39:20.075284 sshd[2461]: Accepted publickey for core from 10.200.16.10 port 60990 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:20.076623 sshd-session[2461]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:20.080853 systemd-logind[1868]: New session 4 of user core. Dec 12 18:39:20.101641 systemd[1]: Started session-4.scope - Session 4 of User core. Dec 12 18:39:20.513951 sshd[2489]: Connection closed by 10.200.16.10 port 60990 Dec 12 18:39:20.514287 sshd-session[2461]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:20.517677 systemd[1]: sshd@1-10.200.4.26:22-10.200.16.10:60990.service: Deactivated successfully. Dec 12 18:39:20.519193 systemd[1]: session-4.scope: Deactivated successfully. Dec 12 18:39:20.519898 systemd-logind[1868]: Session 4 logged out. Waiting for processes to exit. Dec 12 18:39:20.520770 systemd-logind[1868]: Removed session 4. Dec 12 18:39:20.621523 systemd[1]: Started sshd@2-10.200.4.26:22-10.200.16.10:42654.service - OpenSSH per-connection server daemon (10.200.16.10:42654). Dec 12 18:39:20.926011 update_engine[1870]: I20251212 18:39:20.925944 1870 update_attempter.cc:509] Updating boot flags... Dec 12 18:39:21.239996 sshd[2499]: Accepted publickey for core from 10.200.16.10 port 42654 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:21.241145 sshd-session[2499]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:21.250356 systemd-logind[1868]: New session 5 of user core. Dec 12 18:39:21.267622 systemd[1]: Started session-5.scope - Session 5 of User core. Dec 12 18:39:21.658800 sshd[2564]: Connection closed by 10.200.16.10 port 42654 Dec 12 18:39:21.659170 sshd-session[2499]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:21.662880 systemd[1]: sshd@2-10.200.4.26:22-10.200.16.10:42654.service: Deactivated successfully. Dec 12 18:39:21.664445 systemd[1]: session-5.scope: Deactivated successfully. Dec 12 18:39:21.665164 systemd-logind[1868]: Session 5 logged out. Waiting for processes to exit. Dec 12 18:39:21.666210 systemd-logind[1868]: Removed session 5. Dec 12 18:39:31.775491 systemd[1]: Started sshd@3-10.200.4.26:22-10.200.16.10:37474.service - OpenSSH per-connection server daemon (10.200.16.10:37474). Dec 12 18:39:32.375956 sshd[3348]: Accepted publickey for core from 10.200.16.10 port 37474 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:32.377201 sshd-session[3348]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:32.382207 systemd-logind[1868]: New session 6 of user core. Dec 12 18:39:32.395631 systemd[1]: Started session-6.scope - Session 6 of User core. Dec 12 18:39:32.799931 sshd[3351]: Connection closed by 10.200.16.10 port 37474 Dec 12 18:39:32.800235 sshd-session[3348]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:32.803733 systemd[1]: sshd@3-10.200.4.26:22-10.200.16.10:37474.service: Deactivated successfully. Dec 12 18:39:32.805481 systemd[1]: session-6.scope: Deactivated successfully. Dec 12 18:39:32.806235 systemd-logind[1868]: Session 6 logged out. Waiting for processes to exit. Dec 12 18:39:32.807183 systemd-logind[1868]: Removed session 6. Dec 12 18:39:32.921058 systemd[1]: Started sshd@4-10.200.4.26:22-10.200.16.10:37490.service - OpenSSH per-connection server daemon (10.200.16.10:37490). Dec 12 18:39:33.515454 sshd[3357]: Accepted publickey for core from 10.200.16.10 port 37490 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:33.516745 sshd-session[3357]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:33.522738 systemd-logind[1868]: New session 7 of user core. Dec 12 18:39:33.540645 systemd[1]: Started session-7.scope - Session 7 of User core. Dec 12 18:39:33.950254 sshd[3360]: Connection closed by 10.200.16.10 port 37490 Dec 12 18:39:33.950664 sshd-session[3357]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:33.954133 systemd[1]: sshd@4-10.200.4.26:22-10.200.16.10:37490.service: Deactivated successfully. Dec 12 18:39:33.955735 systemd[1]: session-7.scope: Deactivated successfully. Dec 12 18:39:33.956383 systemd-logind[1868]: Session 7 logged out. Waiting for processes to exit. Dec 12 18:39:33.957292 systemd-logind[1868]: Removed session 7. Dec 12 18:39:44.063048 systemd[1]: Started sshd@5-10.200.4.26:22-10.200.16.10:46658.service - OpenSSH per-connection server daemon (10.200.16.10:46658). Dec 12 18:39:44.658621 sshd[4700]: Accepted publickey for core from 10.200.16.10 port 46658 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:44.659942 sshd-session[4700]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:44.664206 systemd-logind[1868]: New session 8 of user core. Dec 12 18:39:44.672692 systemd[1]: Started session-8.scope - Session 8 of User core. Dec 12 18:39:45.083774 sshd[5061]: Connection closed by 10.200.16.10 port 46658 Dec 12 18:39:45.084699 sshd-session[4700]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:45.089454 systemd[1]: sshd@5-10.200.4.26:22-10.200.16.10:46658.service: Deactivated successfully. Dec 12 18:39:45.091308 systemd[1]: session-8.scope: Deactivated successfully. Dec 12 18:39:45.092811 systemd-logind[1868]: Session 8 logged out. Waiting for processes to exit. Dec 12 18:39:45.094144 systemd-logind[1868]: Removed session 8. Dec 12 18:39:45.193850 systemd[1]: Started sshd@6-10.200.4.26:22-10.200.16.10:46672.service - OpenSSH per-connection server daemon (10.200.16.10:46672). Dec 12 18:39:45.785612 sshd[5344]: Accepted publickey for core from 10.200.16.10 port 46672 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:45.786949 sshd-session[5344]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:45.792371 systemd-logind[1868]: New session 9 of user core. Dec 12 18:39:45.800670 systemd[1]: Started session-9.scope - Session 9 of User core. Dec 12 18:39:46.203318 sshd[5691]: Connection closed by 10.200.16.10 port 46672 Dec 12 18:39:46.205102 sshd-session[5344]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:46.207999 systemd[1]: sshd@6-10.200.4.26:22-10.200.16.10:46672.service: Deactivated successfully. Dec 12 18:39:46.210051 systemd[1]: session-9.scope: Deactivated successfully. Dec 12 18:39:46.211835 systemd-logind[1868]: Session 9 logged out. Waiting for processes to exit. Dec 12 18:39:46.212792 systemd-logind[1868]: Removed session 9. Dec 12 18:39:56.317861 systemd[1]: Started sshd@7-10.200.4.26:22-10.200.16.10:47534.service - OpenSSH per-connection server daemon (10.200.16.10:47534). Dec 12 18:39:56.926784 sshd[9369]: Accepted publickey for core from 10.200.16.10 port 47534 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:56.928009 sshd-session[9369]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:56.934911 systemd-logind[1868]: New session 10 of user core. Dec 12 18:39:56.944661 systemd[1]: Started session-10.scope - Session 10 of User core. Dec 12 18:39:57.365209 sshd[9700]: Connection closed by 10.200.16.10 port 47534 Dec 12 18:39:57.365593 sshd-session[9369]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:57.368943 systemd[1]: sshd@7-10.200.4.26:22-10.200.16.10:47534.service: Deactivated successfully. Dec 12 18:39:57.370538 systemd[1]: session-10.scope: Deactivated successfully. Dec 12 18:39:57.371218 systemd-logind[1868]: Session 10 logged out. Waiting for processes to exit. Dec 12 18:39:57.372210 systemd-logind[1868]: Removed session 10. Dec 12 18:39:57.472826 systemd[1]: Started sshd@8-10.200.4.26:22-10.200.16.10:47546.service - OpenSSH per-connection server daemon (10.200.16.10:47546). Dec 12 18:39:58.068964 sshd[10011]: Accepted publickey for core from 10.200.16.10 port 47546 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:39:58.070251 sshd-session[10011]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:39:58.076593 systemd-logind[1868]: New session 11 of user core. Dec 12 18:39:58.085726 systemd[1]: Started session-11.scope - Session 11 of User core. Dec 12 18:39:58.504104 sshd[10356]: Connection closed by 10.200.16.10 port 47546 Dec 12 18:39:58.505706 sshd-session[10011]: pam_unix(sshd:session): session closed for user core Dec 12 18:39:58.508812 systemd[1]: sshd@8-10.200.4.26:22-10.200.16.10:47546.service: Deactivated successfully. Dec 12 18:39:58.510607 systemd[1]: session-11.scope: Deactivated successfully. Dec 12 18:39:58.512493 systemd-logind[1868]: Session 11 logged out. Waiting for processes to exit. Dec 12 18:39:58.513449 systemd-logind[1868]: Removed session 11. Dec 12 18:40:08.618577 systemd[1]: Started sshd@9-10.200.4.26:22-10.200.16.10:52096.service - OpenSSH per-connection server daemon (10.200.16.10:52096). Dec 12 18:40:09.237731 sshd[14031]: Accepted publickey for core from 10.200.16.10 port 52096 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:09.239522 sshd-session[14031]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:09.250558 systemd-logind[1868]: New session 12 of user core. Dec 12 18:40:09.258637 systemd[1]: Started session-12.scope - Session 12 of User core. Dec 12 18:40:09.665337 sshd[14064]: Connection closed by 10.200.16.10 port 52096 Dec 12 18:40:09.668834 sshd-session[14031]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:09.673024 systemd-logind[1868]: Session 12 logged out. Waiting for processes to exit. Dec 12 18:40:09.675779 systemd[1]: sshd@9-10.200.4.26:22-10.200.16.10:52096.service: Deactivated successfully. Dec 12 18:40:09.678694 systemd[1]: session-12.scope: Deactivated successfully. Dec 12 18:40:09.682118 systemd-logind[1868]: Removed session 12. Dec 12 18:40:09.772694 systemd[1]: Started sshd@10-10.200.4.26:22-10.200.16.10:52100.service - OpenSSH per-connection server daemon (10.200.16.10:52100). Dec 12 18:40:10.388383 sshd[14076]: Accepted publickey for core from 10.200.16.10 port 52100 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:10.389784 sshd-session[14076]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:10.394997 systemd-logind[1868]: New session 13 of user core. Dec 12 18:40:10.404659 systemd[1]: Started session-13.scope - Session 13 of User core. Dec 12 18:40:10.823199 sshd[14109]: Connection closed by 10.200.16.10 port 52100 Dec 12 18:40:10.825119 sshd-session[14076]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:10.828669 systemd[1]: sshd@10-10.200.4.26:22-10.200.16.10:52100.service: Deactivated successfully. Dec 12 18:40:10.831962 systemd[1]: session-13.scope: Deactivated successfully. Dec 12 18:40:10.833210 systemd-logind[1868]: Session 13 logged out. Waiting for processes to exit. Dec 12 18:40:10.834710 systemd-logind[1868]: Removed session 13. Dec 12 18:40:20.929198 systemd[1]: Started sshd@11-10.200.4.26:22-10.200.16.10:50932.service - OpenSSH per-connection server daemon (10.200.16.10:50932). Dec 12 18:40:21.544013 sshd[14523]: Accepted publickey for core from 10.200.16.10 port 50932 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:21.545813 sshd-session[14523]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:21.552569 systemd-logind[1868]: New session 14 of user core. Dec 12 18:40:21.561686 systemd[1]: Started session-14.scope - Session 14 of User core. Dec 12 18:40:21.985951 sshd[14550]: Connection closed by 10.200.16.10 port 50932 Dec 12 18:40:21.986742 sshd-session[14523]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:21.994655 systemd[1]: sshd@11-10.200.4.26:22-10.200.16.10:50932.service: Deactivated successfully. Dec 12 18:40:21.996290 systemd[1]: session-14.scope: Deactivated successfully. Dec 12 18:40:21.997224 systemd-logind[1868]: Session 14 logged out. Waiting for processes to exit. Dec 12 18:40:21.999377 systemd-logind[1868]: Removed session 14. Dec 12 18:40:22.097535 systemd[1]: Started sshd@12-10.200.4.26:22-10.200.16.10:50944.service - OpenSSH per-connection server daemon (10.200.16.10:50944). Dec 12 18:40:22.710869 sshd[14562]: Accepted publickey for core from 10.200.16.10 port 50944 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:22.712264 sshd-session[14562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:22.718283 systemd-logind[1868]: New session 15 of user core. Dec 12 18:40:22.727554 systemd[1]: Started session-15.scope - Session 15 of User core. Dec 12 18:40:23.138648 sshd[14583]: Connection closed by 10.200.16.10 port 50944 Dec 12 18:40:23.139025 sshd-session[14562]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:23.144683 systemd[1]: sshd@12-10.200.4.26:22-10.200.16.10:50944.service: Deactivated successfully. Dec 12 18:40:23.146368 systemd[1]: session-15.scope: Deactivated successfully. Dec 12 18:40:23.146899 systemd-logind[1868]: Session 15 logged out. Waiting for processes to exit. Dec 12 18:40:23.149295 systemd-logind[1868]: Removed session 15. Dec 12 18:40:33.246753 systemd[1]: Started sshd@13-10.200.4.26:22-10.200.16.10:55338.service - OpenSSH per-connection server daemon (10.200.16.10:55338). Dec 12 18:40:33.864053 sshd[14937]: Accepted publickey for core from 10.200.16.10 port 55338 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:33.866037 sshd-session[14937]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:33.872037 systemd-logind[1868]: New session 16 of user core. Dec 12 18:40:33.880170 systemd[1]: Started session-16.scope - Session 16 of User core. Dec 12 18:40:34.291591 sshd[14953]: Connection closed by 10.200.16.10 port 55338 Dec 12 18:40:34.292123 sshd-session[14937]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:34.295814 systemd-logind[1868]: Session 16 logged out. Waiting for processes to exit. Dec 12 18:40:34.296584 systemd[1]: sshd@13-10.200.4.26:22-10.200.16.10:55338.service: Deactivated successfully. Dec 12 18:40:34.298187 systemd[1]: session-16.scope: Deactivated successfully. Dec 12 18:40:34.302940 systemd-logind[1868]: Removed session 16. Dec 12 18:40:34.395814 systemd[1]: Started sshd@14-10.200.4.26:22-10.200.16.10:55350.service - OpenSSH per-connection server daemon (10.200.16.10:55350). Dec 12 18:40:35.010641 sshd[14970]: Accepted publickey for core from 10.200.16.10 port 55350 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:35.012378 sshd-session[14970]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:35.020775 systemd-logind[1868]: New session 17 of user core. Dec 12 18:40:35.031641 systemd[1]: Started session-17.scope - Session 17 of User core. Dec 12 18:40:35.441412 sshd[14986]: Connection closed by 10.200.16.10 port 55350 Dec 12 18:40:35.442680 sshd-session[14970]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:35.446771 systemd-logind[1868]: Session 17 logged out. Waiting for processes to exit. Dec 12 18:40:35.447875 systemd[1]: sshd@14-10.200.4.26:22-10.200.16.10:55350.service: Deactivated successfully. Dec 12 18:40:35.450048 systemd[1]: session-17.scope: Deactivated successfully. Dec 12 18:40:35.452421 systemd-logind[1868]: Removed session 17. Dec 12 18:40:45.562041 systemd[1]: Started sshd@15-10.200.4.26:22-10.200.16.10:55798.service - OpenSSH per-connection server daemon (10.200.16.10:55798). Dec 12 18:40:46.154927 sshd[15210]: Accepted publickey for core from 10.200.16.10 port 55798 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:46.156204 sshd-session[15210]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:46.161665 systemd-logind[1868]: New session 18 of user core. Dec 12 18:40:46.173641 systemd[1]: Started session-18.scope - Session 18 of User core. Dec 12 18:40:46.574570 sshd[15213]: Connection closed by 10.200.16.10 port 55798 Dec 12 18:40:46.576270 sshd-session[15210]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:46.579699 systemd[1]: sshd@15-10.200.4.26:22-10.200.16.10:55798.service: Deactivated successfully. Dec 12 18:40:46.581257 systemd[1]: session-18.scope: Deactivated successfully. Dec 12 18:40:46.581937 systemd-logind[1868]: Session 18 logged out. Waiting for processes to exit. Dec 12 18:40:46.582886 systemd-logind[1868]: Removed session 18. Dec 12 18:40:46.683909 systemd[1]: Started sshd@16-10.200.4.26:22-10.200.16.10:55806.service - OpenSSH per-connection server daemon (10.200.16.10:55806). Dec 12 18:40:47.278720 sshd[15219]: Accepted publickey for core from 10.200.16.10 port 55806 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:47.279950 sshd-session[15219]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:47.284101 systemd-logind[1868]: New session 19 of user core. Dec 12 18:40:47.292626 systemd[1]: Started session-19.scope - Session 19 of User core. Dec 12 18:40:47.712527 sshd[15254]: Connection closed by 10.200.16.10 port 55806 Dec 12 18:40:47.712909 sshd-session[15219]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:47.716403 systemd[1]: sshd@16-10.200.4.26:22-10.200.16.10:55806.service: Deactivated successfully. Dec 12 18:40:47.717999 systemd[1]: session-19.scope: Deactivated successfully. Dec 12 18:40:47.718676 systemd-logind[1868]: Session 19 logged out. Waiting for processes to exit. Dec 12 18:40:47.719798 systemd-logind[1868]: Removed session 19. Dec 12 18:40:49.474891 setup-nvidia[2221]: \u000d Dec 12 18:40:49.474891 setup-nvidia[2221]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Dec 12 18:40:49.474891 setup-nvidia[2221]: frequently when this kernel module was built against the wrong or Dec 12 18:40:49.474891 setup-nvidia[2221]: improperly configured kernel sources, with a version of gcc that differs Dec 12 18:40:49.474891 setup-nvidia[2221]: from the one used to build the target kernel, or if another driver, such Dec 12 18:40:49.474891 setup-nvidia[2221]: as nouveau, is present and prevents the NVIDIA kernel module from Dec 12 18:40:49.474891 setup-nvidia[2221]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Dec 12 18:40:49.474891 setup-nvidia[2221]: installed in this system is supported by this NVIDIA Linux graphics Dec 12 18:40:49.474891 setup-nvidia[2221]: driver release. Dec 12 18:40:49.474891 setup-nvidia[2221]: \u000d Dec 12 18:40:49.474891 setup-nvidia[2221]: Please see the log entries 'Kernel module load error' and 'Kernel Dec 12 18:40:49.474891 setup-nvidia[2221]: messages' at the end of the file Dec 12 18:40:49.474891 setup-nvidia[2221]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Dec 12 18:40:49.474891 setup-nvidia[2221]: information. Dec 12 18:40:49.474891 setup-nvidia[2221]: \u000d Dec 12 18:40:49.490638 setup-nvidia[2221]: dmesg: read kernel buffer failed: Operation not permitted Dec 12 18:40:49.509142 setup-nvidia[2221]: \u000d Dec 12 18:40:49.509142 setup-nvidia[2221]: ERROR: Installation has failed. Please see the file Dec 12 18:40:49.509142 setup-nvidia[2221]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Dec 12 18:40:49.509142 setup-nvidia[2221]: details. You may find suggestions on fixing installation problems in Dec 12 18:40:49.509142 setup-nvidia[2221]: the README available on the Linux driver download page at Dec 12 18:40:49.509142 setup-nvidia[2221]: www.nvidia.com. Dec 12 18:40:49.509142 setup-nvidia[2221]: \u000d Dec 12 18:40:49.509516 setup-nvidia[2221]: Last 50 lines of nvidia-installer.log: Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510396 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510793 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510793 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510793 setup-nvidia[2221]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Dec 12 18:40:49.510793 setup-nvidia[2221]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Dec 12 18:40:49.510793 setup-nvidia[2221]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Dec 12 18:40:49.510793 setup-nvidia[2221]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Dec 12 18:40:49.510793 setup-nvidia[2221]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Dec 12 18:40:49.510793 setup-nvidia[2221]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Dec 12 18:40:49.510793 setup-nvidia[2221]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Dec 12 18:40:49.510793 setup-nvidia[2221]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Dec 12 18:40:49.510793 setup-nvidia[2221]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Dec 12 18:40:49.510793 setup-nvidia[2221]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Dec 12 18:40:49.510793 setup-nvidia[2221]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Dec 12 18:40:49.510793 setup-nvidia[2221]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Dec 12 18:40:49.510793 setup-nvidia[2221]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Dec 12 18:40:49.511312 setup-nvidia[2221]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Dec 12 18:40:49.511312 setup-nvidia[2221]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Dec 12 18:40:49.511312 setup-nvidia[2221]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Dec 12 18:40:49.511312 setup-nvidia[2221]: make[2]: Leaving directory '/usr/lib/modules/6.12.61-flatcar/build' Dec 12 18:40:49.511312 setup-nvidia[2221]: make[1]: Leaving directory '/usr/lib/modules/6.12.61-flatcar/source' Dec 12 18:40:49.511312 setup-nvidia[2221]: -> done. Dec 12 18:40:49.511312 setup-nvidia[2221]: -> Kernel module compilation complete. Dec 12 18:40:49.511312 setup-nvidia[2221]: -> Unable to determine if Secure Boot is enabled: No such file or directory Dec 12 18:40:49.511312 setup-nvidia[2221]: 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. Dec 12 18:40:49.511312 setup-nvidia[2221]: \u000d Dec 12 18:40:49.511312 setup-nvidia[2221]: 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. Dec 12 18:40:49.511312 setup-nvidia[2221]: -> Kernel module load error: Operation not permitted Dec 12 18:40:49.511312 setup-nvidia[2221]: -> Kernel messages: Dec 12 18:40:49.511312 setup-nvidia[2221]: 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. Dec 12 18:40:57.834649 systemd[1]: Started sshd@17-10.200.4.26:22-10.200.16.10:56018.service - OpenSSH per-connection server daemon (10.200.16.10:56018). Dec 12 18:40:58.445512 sshd[15408]: Accepted publickey for core from 10.200.16.10 port 56018 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:58.446798 sshd-session[15408]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:58.451574 systemd-logind[1868]: New session 20 of user core. Dec 12 18:40:58.459620 systemd[1]: Started session-20.scope - Session 20 of User core. Dec 12 18:40:59.079238 sshd[15412]: Connection closed by 10.200.16.10 port 56018 Dec 12 18:40:58.999544 systemd[1]: Started sshd@18-10.200.4.26:22-10.200.16.10:56028.service - OpenSSH per-connection server daemon (10.200.16.10:56028). Dec 12 18:40:59.079340 sshd-session[15408]: pam_unix(sshd:session): session closed for user core Dec 12 18:40:59.083359 systemd[1]: sshd@17-10.200.4.26:22-10.200.16.10:56018.service: Deactivated successfully. Dec 12 18:40:59.085410 systemd[1]: session-20.scope: Deactivated successfully. Dec 12 18:40:59.086324 systemd-logind[1868]: Session 20 logged out. Waiting for processes to exit. Dec 12 18:40:59.087553 systemd-logind[1868]: Removed session 20. Dec 12 18:40:59.592692 sshd[15415]: Accepted publickey for core from 10.200.16.10 port 56028 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:40:59.593947 sshd-session[15415]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:40:59.598536 systemd-logind[1868]: New session 21 of user core. Dec 12 18:40:59.616625 systemd[1]: Started session-21.scope - Session 21 of User core. Dec 12 18:41:00.016586 sshd[15422]: Connection closed by 10.200.16.10 port 56028 Dec 12 18:41:00.016961 sshd-session[15415]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:00.020419 systemd[1]: sshd@18-10.200.4.26:22-10.200.16.10:56028.service: Deactivated successfully. Dec 12 18:41:00.023017 systemd[1]: session-21.scope: Deactivated successfully. Dec 12 18:41:00.023722 systemd-logind[1868]: Session 21 logged out. Waiting for processes to exit. Dec 12 18:41:00.024873 systemd-logind[1868]: Removed session 21. Dec 12 18:41:02.090529 kernel: EXT4-fs (loop6p9): unmounting filesystem 02782f9c-2fea-4b2e-9279-7381cb6c9c28. Dec 12 18:41:02.090787 setup-nvidia[2221]: Container flatcardevelopercontainer-4459.2.2.bin exited successfully. Dec 12 18:41:02.093171 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.2.bin.mount: Deactivated successfully. Dec 12 18:41:02.137390 sudo[2209]: pam_unix(sudo:session): session closed for user root Dec 12 18:41:02.139388 systemd[1]: session-c2.scope: Deactivated successfully. Dec 12 18:41:02.139672 systemd[1]: session-c2.scope: Consumed 5min 23.541s CPU time, 889.6M memory peak. Dec 12 18:41:02.567372 setup-nvidia[1862]: /opt/nvidia / Dec 12 18:41:02.568372 setup-nvidia[1862]: / Dec 12 18:41:02.577534 kernel: IPMI message handler: version 39.2 Dec 12 18:41:02.581522 kernel: ipmi device interface Dec 12 18:41:02.594356 setup-nvidia[1862]: /opt/nvidia/current/usr/lib/modules/6.12.61-flatcar/video / Dec 12 18:41:02.647432 kernel: nvidia: loading out-of-tree module taints kernel. Dec 12 18:41:02.647586 kernel: nvidia: module license 'NVIDIA' taints kernel. Dec 12 18:41:02.647612 kernel: Disabling lock debugging due to kernel taint Dec 12 18:41:02.654798 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Dec 12 18:41:02.654844 kernel: nvidia: module license taints kernel. Dec 12 18:41:03.052528 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Dec 12 18:41:03.458127 kernel: Dec 12 18:41:03.458260 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Dec 12 18:41:03.513518 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Dec 12 18:41:03.535533 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Dec 12 18:41:03.555546 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Dec 12 18:41:03.605405 setup-nvidia[1862]: / Dec 12 18:41:03.605850 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Dec 12 18:41:03.620705 setup-nvidia[15461]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Dec 12 18:41:05.213481 setup-nvidia[15478]: Fri Dec 12 18:41:05 2025 Dec 12 18:41:05.213481 setup-nvidia[15478]: +---------------------------------------------------------------------------------------+ Dec 12 18:41:05.213481 setup-nvidia[15478]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Dec 12 18:41:05.213481 setup-nvidia[15478]: |-----------------------------------------+----------------------+----------------------+ Dec 12 18:41:05.213481 setup-nvidia[15478]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Dec 12 18:41:05.213481 setup-nvidia[15478]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Dec 12 18:41:05.213481 setup-nvidia[15478]: | | | MIG M. | Dec 12 18:41:05.213481 setup-nvidia[15478]: |=========================================+======================+======================| Dec 12 18:41:05.218814 setup-nvidia[15478]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Dec 12 18:41:05.218814 setup-nvidia[15478]: | N/A 30C P0 34W / 250W | 0MiB / 16384MiB | 0% Default | Dec 12 18:41:05.218814 setup-nvidia[15478]: | | | N/A | Dec 12 18:41:05.218814 setup-nvidia[15478]: +-----------------------------------------+----------------------+----------------------+ Dec 12 18:41:05.218814 setup-nvidia[15478]: Dec 12 18:41:05.218814 setup-nvidia[15478]: +---------------------------------------------------------------------------------------+ Dec 12 18:41:05.218814 setup-nvidia[15478]: | Processes: | Dec 12 18:41:05.218814 setup-nvidia[15478]: | GPU GI CI PID Type Process name GPU Memory | Dec 12 18:41:05.218814 setup-nvidia[15478]: | ID ID Usage | Dec 12 18:41:05.218814 setup-nvidia[15478]: |=======================================================================================| Dec 12 18:41:05.219099 setup-nvidia[15478]: | No running processes found | Dec 12 18:41:05.219099 setup-nvidia[15478]: +---------------------------------------------------------------------------------------+ Dec 12 18:41:05.594516 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Dec 12 18:41:05.597377 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 12 18:41:05.597737 systemd[1]: Startup finished in 1.266s (firmware) + 14.191s (loader) + 3.860s (kernel) + 12.210s (initrd) + 2min 38.601s (userspace) = 3min 10.129s. Dec 12 18:41:10.133622 systemd[1]: Started sshd@19-10.200.4.26:22-10.200.16.10:34882.service - OpenSSH per-connection server daemon (10.200.16.10:34882). Dec 12 18:41:10.738938 sshd[15485]: Accepted publickey for core from 10.200.16.10 port 34882 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:10.740152 sshd-session[15485]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:10.744240 systemd-logind[1868]: New session 22 of user core. Dec 12 18:41:10.751626 systemd[1]: Started session-22.scope - Session 22 of User core. Dec 12 18:41:11.168077 sshd[15488]: Connection closed by 10.200.16.10 port 34882 Dec 12 18:41:11.168459 sshd-session[15485]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:11.172051 systemd[1]: sshd@19-10.200.4.26:22-10.200.16.10:34882.service: Deactivated successfully. Dec 12 18:41:11.173817 systemd[1]: session-22.scope: Deactivated successfully. Dec 12 18:41:11.174582 systemd-logind[1868]: Session 22 logged out. Waiting for processes to exit. Dec 12 18:41:11.175471 systemd-logind[1868]: Removed session 22. Dec 12 18:41:11.276963 systemd[1]: Started sshd@20-10.200.4.26:22-10.200.16.10:34896.service - OpenSSH per-connection server daemon (10.200.16.10:34896). Dec 12 18:41:11.868996 sshd[15494]: Accepted publickey for core from 10.200.16.10 port 34896 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:11.870233 sshd-session[15494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:11.874435 systemd-logind[1868]: New session 23 of user core. Dec 12 18:41:11.888645 systemd[1]: Started session-23.scope - Session 23 of User core. Dec 12 18:41:12.315357 sshd[15497]: Connection closed by 10.200.16.10 port 34896 Dec 12 18:41:12.315685 sshd-session[15494]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:12.317780 systemd[1]: Stopping user@0.service - User Manager for UID 0... Dec 12 18:41:12.318590 systemd[2212]: Activating special unit exit.target... Dec 12 18:41:12.318665 systemd[2212]: Stopped target default.target - Main User Target. Dec 12 18:41:12.318749 systemd[2212]: Stopped target basic.target - Basic System. Dec 12 18:41:12.318781 systemd[2212]: Stopped target paths.target - Paths. Dec 12 18:41:12.318807 systemd[2212]: Stopped target sockets.target - Sockets. Dec 12 18:41:12.318832 systemd[2212]: Stopped target timers.target - Timers. Dec 12 18:41:12.319163 systemd[2212]: Closed dbus.socket - D-Bus User Message Bus Socket. Dec 12 18:41:12.319378 systemd[2212]: Removed slice app.slice - User Application Slice. Dec 12 18:41:12.319409 systemd[2212]: Reached target shutdown.target - Shutdown. Dec 12 18:41:12.319534 systemd[1]: sshd@20-10.200.4.26:22-10.200.16.10:34896.service: Deactivated successfully. Dec 12 18:41:12.319566 systemd[2212]: Finished systemd-exit.service - Exit the Session. Dec 12 18:41:12.319596 systemd[2212]: Reached target exit.target - Exit the Session. Dec 12 18:41:12.321120 systemd[1]: session-23.scope: Deactivated successfully. Dec 12 18:41:12.321789 systemd-logind[1868]: Session 23 logged out. Waiting for processes to exit. Dec 12 18:41:12.326524 systemd-logind[1868]: Removed session 23. Dec 12 18:41:12.341558 (sd-pam)[2213]: pam_unix(systemd-user:session): session closed for user root Dec 12 18:41:12.342726 systemd[1]: user@0.service: Deactivated successfully. Dec 12 18:41:12.343061 systemd[1]: Stopped user@0.service - User Manager for UID 0. Dec 12 18:41:12.345226 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Dec 12 18:41:12.370177 systemd[1]: run-user-0.mount: Deactivated successfully. Dec 12 18:41:12.371098 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Dec 12 18:41:12.371280 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Dec 12 18:41:12.372785 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Dec 12 18:41:12.372876 systemd[1]: user-0.slice: Consumed 5min 23.849s CPU time, 895.7M memory peak. Dec 12 18:41:12.373271 systemd-logind[1868]: Removed session c3. Dec 12 18:41:12.430532 systemd[1]: Started sshd@21-10.200.4.26:22-10.200.16.10:34904.service - OpenSSH per-connection server daemon (10.200.16.10:34904). Dec 12 18:41:13.023637 sshd[15509]: Accepted publickey for core from 10.200.16.10 port 34904 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:13.024871 sshd-session[15509]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:13.029287 systemd-logind[1868]: New session 24 of user core. Dec 12 18:41:13.038628 systemd[1]: Started session-24.scope - Session 24 of User core. Dec 12 18:41:13.449661 sshd[15512]: Connection closed by 10.200.16.10 port 34904 Dec 12 18:41:13.450062 sshd-session[15509]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:13.453712 systemd[1]: sshd@21-10.200.4.26:22-10.200.16.10:34904.service: Deactivated successfully. Dec 12 18:41:13.455302 systemd[1]: session-24.scope: Deactivated successfully. Dec 12 18:41:13.455973 systemd-logind[1868]: Session 24 logged out. Waiting for processes to exit. Dec 12 18:41:13.456895 systemd-logind[1868]: Removed session 24. Dec 12 18:41:13.567053 systemd[1]: Started sshd@22-10.200.4.26:22-10.200.16.10:34910.service - OpenSSH per-connection server daemon (10.200.16.10:34910). Dec 12 18:41:14.175824 sshd[15518]: Accepted publickey for core from 10.200.16.10 port 34910 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:14.177157 sshd-session[15518]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:14.181464 systemd-logind[1868]: New session 25 of user core. Dec 12 18:41:14.194625 systemd[1]: Started session-25.scope - Session 25 of User core. Dec 12 18:41:14.505144 sudo[15522]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Dec 12 18:41:14.505441 sudo[15522]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 12 18:41:15.797236 sudo[15522]: pam_unix(sudo:session): session closed for user root Dec 12 18:41:15.896264 sshd[15521]: Connection closed by 10.200.16.10 port 34910 Dec 12 18:41:15.896727 sshd-session[15518]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:15.900622 systemd[1]: sshd@22-10.200.4.26:22-10.200.16.10:34910.service: Deactivated successfully. Dec 12 18:41:15.902248 systemd[1]: session-25.scope: Deactivated successfully. Dec 12 18:41:15.903028 systemd-logind[1868]: Session 25 logged out. Waiting for processes to exit. Dec 12 18:41:15.903960 systemd-logind[1868]: Removed session 25. Dec 12 18:41:16.000715 systemd[1]: Started sshd@23-10.200.4.26:22-10.200.16.10:34920.service - OpenSSH per-connection server daemon (10.200.16.10:34920). Dec 12 18:41:16.598647 sshd[15528]: Accepted publickey for core from 10.200.16.10 port 34920 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:16.599943 sshd-session[15528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:16.604072 systemd-logind[1868]: New session 26 of user core. Dec 12 18:41:16.617634 systemd[1]: Started session-26.scope - Session 26 of User core. Dec 12 18:41:16.926383 sudo[15533]: 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 Dec 12 18:41:16.926704 sudo[15533]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 12 18:41:16.933378 sudo[15533]: pam_unix(sudo:session): session closed for user root Dec 12 18:41:16.939281 sudo[15532]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Dec 12 18:41:16.939583 sudo[15532]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 12 18:41:16.948870 systemd[1]: Starting audit-rules.service - Load Audit Rules... Dec 12 18:41:16.987135 augenrules[15555]: No rules Dec 12 18:41:16.988306 systemd[1]: audit-rules.service: Deactivated successfully. Dec 12 18:41:16.988562 systemd[1]: Finished audit-rules.service - Load Audit Rules. Dec 12 18:41:16.989392 sudo[15532]: pam_unix(sudo:session): session closed for user root Dec 12 18:41:17.083591 sshd[15531]: Connection closed by 10.200.16.10 port 34920 Dec 12 18:41:17.083951 sshd-session[15528]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:17.087655 systemd[1]: sshd@23-10.200.4.26:22-10.200.16.10:34920.service: Deactivated successfully. Dec 12 18:41:17.089174 systemd[1]: session-26.scope: Deactivated successfully. Dec 12 18:41:17.089860 systemd-logind[1868]: Session 26 logged out. Waiting for processes to exit. Dec 12 18:41:17.090783 systemd-logind[1868]: Removed session 26. Dec 12 18:41:17.191774 systemd[1]: Started sshd@24-10.200.4.26:22-10.200.16.10:34922.service - OpenSSH per-connection server daemon (10.200.16.10:34922). Dec 12 18:41:17.806972 sshd[15564]: Accepted publickey for core from 10.200.16.10 port 34922 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:17.808213 sshd-session[15564]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:17.812298 systemd-logind[1868]: New session 27 of user core. Dec 12 18:41:17.821625 systemd[1]: Started session-27.scope - Session 27 of User core. Dec 12 18:41:18.134665 sudo[15568]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Dec 12 18:41:18.134957 sudo[15568]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 12 18:41:18.138216 sudo[15568]: pam_unix(sudo:session): session closed for user root Dec 12 18:41:18.235839 sshd[15567]: Connection closed by 10.200.16.10 port 34922 Dec 12 18:41:18.236216 sshd-session[15564]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:18.239648 systemd[1]: sshd@24-10.200.4.26:22-10.200.16.10:34922.service: Deactivated successfully. Dec 12 18:41:18.241218 systemd[1]: session-27.scope: Deactivated successfully. Dec 12 18:41:18.241897 systemd-logind[1868]: Session 27 logged out. Waiting for processes to exit. Dec 12 18:41:18.242780 systemd-logind[1868]: Removed session 27. Dec 12 18:41:18.344541 systemd[1]: Started sshd@25-10.200.4.26:22-10.200.16.10:34934.service - OpenSSH per-connection server daemon (10.200.16.10:34934). Dec 12 18:41:18.937918 sshd[15574]: Accepted publickey for core from 10.200.16.10 port 34934 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:18.939164 sshd-session[15574]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:18.943317 systemd-logind[1868]: New session 28 of user core. Dec 12 18:41:18.951628 systemd[1]: Started session-28.scope - Session 28 of User core. Dec 12 18:41:19.266616 sudo[15579]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Dec 12 18:41:19.266920 sudo[15579]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Dec 12 18:41:22.505100 sudo[15579]: pam_unix(sudo:session): session closed for user root Dec 12 18:41:22.599300 sshd[15578]: Connection closed by 10.200.16.10 port 34934 Dec 12 18:41:22.599678 sshd-session[15574]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:22.603203 systemd[1]: sshd@25-10.200.4.26:22-10.200.16.10:34934.service: Deactivated successfully. Dec 12 18:41:22.604810 systemd[1]: session-28.scope: Deactivated successfully. Dec 12 18:41:22.605473 systemd-logind[1868]: Session 28 logged out. Waiting for processes to exit. Dec 12 18:41:22.606361 systemd-logind[1868]: Removed session 28. Dec 12 18:41:22.718745 systemd[1]: Started sshd@26-10.200.4.26:22-10.200.16.10:41400.service - OpenSSH per-connection server daemon (10.200.16.10:41400). Dec 12 18:41:23.335160 sshd[15585]: Accepted publickey for core from 10.200.16.10 port 41400 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:23.336371 sshd-session[15585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:23.340729 systemd-logind[1868]: New session 29 of user core. Dec 12 18:41:23.349614 systemd[1]: Started session-29.scope - Session 29 of User core. Dec 12 18:41:23.817401 sshd[15590]: Connection closed by 10.200.16.10 port 41400 Dec 12 18:41:23.817832 sshd-session[15585]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:23.820769 systemd[1]: sshd@26-10.200.4.26:22-10.200.16.10:41400.service: Deactivated successfully. Dec 12 18:41:23.822373 systemd[1]: session-29.scope: Deactivated successfully. Dec 12 18:41:23.823070 systemd-logind[1868]: Session 29 logged out. Waiting for processes to exit. Dec 12 18:41:23.824517 systemd-logind[1868]: Removed session 29. Dec 12 18:41:23.924660 systemd[1]: Started sshd@27-10.200.4.26:22-10.200.16.10:41402.service - OpenSSH per-connection server daemon (10.200.16.10:41402). Dec 12 18:41:24.514074 sshd[15605]: Accepted publickey for core from 10.200.16.10 port 41402 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:24.515293 sshd-session[15605]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:24.519769 systemd-logind[1868]: New session 30 of user core. Dec 12 18:41:24.529622 systemd[1]: Started session-30.scope - Session 30 of User core. Dec 12 18:41:25.473800 systemd[1]: Starting docker.service - Docker Application Container Engine... Dec 12 18:41:25.491842 (dockerd)[15628]: 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 Dec 12 18:41:26.649834 dockerd[15628]: time="2025-12-12T18:41:26.649761062Z" level=info msg="Starting up" Dec 12 18:41:26.650455 dockerd[15628]: time="2025-12-12T18:41:26.650431043Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Dec 12 18:41:26.661268 dockerd[15628]: time="2025-12-12T18:41:26.661234125Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Dec 12 18:41:27.120865 dockerd[15628]: time="2025-12-12T18:41:27.120821317Z" level=info msg="Loading containers: start." Dec 12 18:41:27.133525 kernel: Initializing XFRM netlink socket Dec 12 18:41:27.389125 systemd-networkd[1555]: docker0: Link UP Dec 12 18:41:27.401472 dockerd[15628]: time="2025-12-12T18:41:27.401431169Z" level=info msg="Loading containers: done." Dec 12 18:41:27.417024 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1407368844-merged.mount: Deactivated successfully. Dec 12 18:41:27.423583 dockerd[15628]: time="2025-12-12T18:41:27.423546319Z" 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 Dec 12 18:41:27.423683 dockerd[15628]: time="2025-12-12T18:41:27.423657016Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Dec 12 18:41:27.423769 dockerd[15628]: time="2025-12-12T18:41:27.423749613Z" level=info msg="Initializing buildkit" Dec 12 18:41:27.466050 dockerd[15628]: time="2025-12-12T18:41:27.466018871Z" level=info msg="Completed buildkit initialization" Dec 12 18:41:27.471142 dockerd[15628]: time="2025-12-12T18:41:27.471116221Z" level=info msg="Daemon has completed initialization" Dec 12 18:41:27.471214 dockerd[15628]: time="2025-12-12T18:41:27.471176519Z" level=info msg="API listen on /run/docker.sock" Dec 12 18:41:27.471281 systemd[1]: Started docker.service - Docker Application Container Engine. Dec 12 18:41:28.458418 systemd[1]: var-lib-docker-overlay2-fbb35c0c0471928954193b2c2e5730f9f527a82b1a2e92f2712c11b2b6dd1b48\x2dinit-merged.mount: Deactivated successfully. Dec 12 18:41:28.536088 containerd[1889]: time="2025-12-12T18:41:28.536043421Z" level=info msg="connecting to shim 70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44" address="unix:///run/containerd/s/4fac268990b991783cbb29be4b240b598777793e809a1ff84be78019056ca492" namespace=moby protocol=ttrpc version=3 Dec 12 18:41:28.561735 systemd[1]: Started docker-70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44.scope - libcontainer container 70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44. Dec 12 18:41:28.611932 kernel: docker0: port 1(veth2ca7afc) entered blocking state Dec 12 18:41:28.612025 kernel: docker0: port 1(veth2ca7afc) entered disabled state Dec 12 18:41:28.612048 kernel: veth2ca7afc: entered allmulticast mode Dec 12 18:41:28.617524 kernel: veth2ca7afc: entered promiscuous mode Dec 12 18:41:28.618133 systemd-networkd[1555]: veth2ca7afc: Link UP Dec 12 18:41:28.638529 kernel: eth0: renamed from vethbb9e025 Dec 12 18:41:28.638587 kernel: docker0: port 1(veth2ca7afc) entered blocking state Dec 12 18:41:28.644631 kernel: docker0: port 1(veth2ca7afc) entered forwarding state Dec 12 18:41:28.645050 systemd-networkd[1555]: veth2ca7afc: Gained carrier Dec 12 18:41:28.645319 systemd-networkd[1555]: docker0: Gained carrier Dec 12 18:41:30.432666 systemd-networkd[1555]: docker0: Gained IPv6LL Dec 12 18:41:30.496629 systemd-networkd[1555]: veth2ca7afc: Gained IPv6LL Dec 12 18:41:31.877333 systemd[1]: docker-70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44.scope: Deactivated successfully. Dec 12 18:41:31.891564 dockerd[15628]: time="2025-12-12T18:41:31.891299950Z" level=info msg="ignoring event" container=70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Dec 12 18:41:31.891887 containerd[1889]: time="2025-12-12T18:41:31.891390547Z" level=info msg="shim disconnected" id=70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44 namespace=moby Dec 12 18:41:31.891887 containerd[1889]: time="2025-12-12T18:41:31.891423547Z" level=warning msg="cleaning up after shim disconnected" id=70973b5a0078d38738f83b93cedf9bcb0f3740817c52c49ceb4c27a16eb53e44 namespace=moby Dec 12 18:41:31.891887 containerd[1889]: time="2025-12-12T18:41:31.891431746Z" level=info msg="cleaning up dead shim" namespace=moby Dec 12 18:41:31.920523 kernel: docker0: port 1(veth2ca7afc) entered disabled state Dec 12 18:41:31.920781 kernel: vethbb9e025: renamed from eth0 Dec 12 18:41:31.920618 systemd-networkd[1555]: veth2ca7afc: Lost carrier Dec 12 18:41:31.945279 systemd-networkd[1555]: veth2ca7afc: Link DOWN Dec 12 18:41:31.949541 kernel: docker0: port 1(veth2ca7afc) entered disabled state Dec 12 18:41:31.955985 kernel: veth2ca7afc (unregistering): left allmulticast mode Dec 12 18:41:31.956035 kernel: veth2ca7afc (unregistering): left promiscuous mode Dec 12 18:41:31.959413 kernel: docker0: port 1(veth2ca7afc) entered disabled state Dec 12 18:41:31.980615 systemd[1]: run-docker-netns-7de47c2305f4.mount: Deactivated successfully. Dec 12 18:41:31.983100 systemd[1]: var-lib-docker-overlay2-fbb35c0c0471928954193b2c2e5730f9f527a82b1a2e92f2712c11b2b6dd1b48-merged.mount: Deactivated successfully. Dec 12 18:41:32.108791 sshd[15608]: Connection closed by 10.200.16.10 port 41402 Dec 12 18:41:32.109182 sshd-session[15605]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:32.112884 systemd[1]: sshd@27-10.200.4.26:22-10.200.16.10:41402.service: Deactivated successfully. Dec 12 18:41:32.114461 systemd[1]: session-30.scope: Deactivated successfully. Dec 12 18:41:32.115147 systemd-logind[1868]: Session 30 logged out. Waiting for processes to exit. Dec 12 18:41:32.116085 systemd-logind[1868]: Removed session 30. Dec 12 18:41:32.213924 systemd[1]: Started sshd@28-10.200.4.26:22-10.200.16.10:47392.service - OpenSSH per-connection server daemon (10.200.16.10:47392). Dec 12 18:41:32.821013 sshd[15918]: Accepted publickey for core from 10.200.16.10 port 47392 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:32.822249 sshd-session[15918]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:32.827204 systemd-logind[1868]: New session 31 of user core. Dec 12 18:41:32.847641 systemd[1]: Started session-31.scope - Session 31 of User core. Dec 12 18:41:32.928800 systemd-networkd[1555]: docker0: Lost carrier Dec 12 18:41:33.195477 systemd[1]: var-lib-docker-overlay2-9ce336505393372ce5bd7fd159c5b32512f7a24a326046cad8359ccd742d400b\x2dinit-merged.mount: Deactivated successfully. Dec 12 18:41:33.269745 containerd[1889]: time="2025-12-12T18:41:33.269698326Z" level=info msg="connecting to shim fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601" address="unix:///run/containerd/s/3324b87c92037fe4ef8c25bfb42217da23e595067f528f63359f29c5ab8b4f0c" namespace=moby protocol=ttrpc version=3 Dec 12 18:41:33.295629 systemd[1]: Started docker-fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601.scope - libcontainer container fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601. Dec 12 18:41:33.342627 kernel: docker0: port 1(veth0d781ff) entered blocking state Dec 12 18:41:33.342722 kernel: docker0: port 1(veth0d781ff) entered disabled state Dec 12 18:41:33.345467 kernel: veth0d781ff: entered allmulticast mode Dec 12 18:41:33.348458 kernel: veth0d781ff: entered promiscuous mode Dec 12 18:41:33.349216 systemd-networkd[1555]: veth0d781ff: Link UP Dec 12 18:41:33.364520 kernel: eth0: renamed from vethe8701ea Dec 12 18:41:33.364577 kernel: docker0: port 1(veth0d781ff) entered blocking state Dec 12 18:41:33.370678 kernel: docker0: port 1(veth0d781ff) entered forwarding state Dec 12 18:41:33.371073 systemd-networkd[1555]: veth0d781ff: Gained carrier Dec 12 18:41:33.371968 systemd-networkd[1555]: docker0: Gained carrier Dec 12 18:41:33.401777 systemd[1]: docker-fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601.scope: Deactivated successfully. Dec 12 18:41:33.420436 containerd[1889]: time="2025-12-12T18:41:33.420393892Z" level=info msg="shim disconnected" id=fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601 namespace=moby Dec 12 18:41:33.420980 dockerd[15628]: time="2025-12-12T18:41:33.420480290Z" level=info msg="ignoring event" container=fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Dec 12 18:41:33.421299 containerd[1889]: time="2025-12-12T18:41:33.421267372Z" level=warning msg="cleaning up after shim disconnected" id=fa086311643ce5825d4dc15599db73c68f98f9d75959d99b14d1cf4f87bdc601 namespace=moby Dec 12 18:41:33.421381 containerd[1889]: time="2025-12-12T18:41:33.421292671Z" level=info msg="cleaning up dead shim" namespace=moby Dec 12 18:41:33.448855 systemd-networkd[1555]: veth0d781ff: Lost carrier Dec 12 18:41:33.449522 kernel: docker0: port 1(veth0d781ff) entered disabled state Dec 12 18:41:33.449574 kernel: vethe8701ea: renamed from eth0 Dec 12 18:41:33.473096 systemd-networkd[1555]: veth0d781ff: Link DOWN Dec 12 18:41:33.480325 kernel: docker0: port 1(veth0d781ff) entered disabled state Dec 12 18:41:33.480377 kernel: veth0d781ff (unregistering): left allmulticast mode Dec 12 18:41:33.483609 kernel: veth0d781ff (unregistering): left promiscuous mode Dec 12 18:41:33.486986 kernel: docker0: port 1(veth0d781ff) entered disabled state Dec 12 18:41:33.632541 sshd[15921]: Connection closed by 10.200.16.10 port 47392 Dec 12 18:41:33.633026 sshd-session[15918]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:33.636727 systemd[1]: sshd@28-10.200.4.26:22-10.200.16.10:47392.service: Deactivated successfully. Dec 12 18:41:33.638334 systemd[1]: session-31.scope: Deactivated successfully. Dec 12 18:41:33.639006 systemd-logind[1868]: Session 31 logged out. Waiting for processes to exit. Dec 12 18:41:33.639962 systemd-logind[1868]: Removed session 31. Dec 12 18:41:33.741800 systemd[1]: Started sshd@29-10.200.4.26:22-10.200.16.10:47404.service - OpenSSH per-connection server daemon (10.200.16.10:47404). Dec 12 18:41:33.936781 systemd-networkd[1555]: docker0: Lost carrier Dec 12 18:41:34.192767 systemd[1]: run-docker-netns-0e6ee994a2c3.mount: Deactivated successfully. Dec 12 18:41:34.192869 systemd[1]: var-lib-docker-overlay2-9ce336505393372ce5bd7fd159c5b32512f7a24a326046cad8359ccd742d400b-merged.mount: Deactivated successfully. Dec 12 18:41:34.361519 sshd[16024]: Accepted publickey for core from 10.200.16.10 port 47404 ssh2: RSA SHA256:Mm4sgh7kFPjXsj1Q3AUNPWEaOjO9Acpy3NtDaY0+PXk Dec 12 18:41:34.362776 sshd-session[16024]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Dec 12 18:41:34.366882 systemd-logind[1868]: New session 32 of user core. Dec 12 18:41:34.376627 systemd[1]: Started session-32.scope - Session 32 of User core. Dec 12 18:41:34.732977 ntpdate[16039]: ntpdate 4.2.8p18@1.4062-o Fri Dec 12 14:44:13 UTC 2025 (1) Dec 12 18:41:41.439797 ntpdate[16039]: adjust time server 178.62.18.76 offset -0.007626 sec Dec 12 18:41:41.549377 sshd[16027]: Connection closed by 10.200.16.10 port 47404 Dec 12 18:41:41.549774 sshd-session[16024]: pam_unix(sshd:session): session closed for user core Dec 12 18:41:41.553315 systemd[1]: sshd@29-10.200.4.26:22-10.200.16.10:47404.service: Deactivated successfully. Dec 12 18:41:41.554907 systemd[1]: session-32.scope: Deactivated successfully. Dec 12 18:41:41.555577 systemd-logind[1868]: Session 32 logged out. Waiting for processes to exit. Dec 12 18:41:41.556822 systemd-logind[1868]: Removed session 32.