Nov 24 00:14:02.166143 kernel: Linux version 6.12.58-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 Sun Nov 23 20:49:05 -00 2025 Nov 24 00:14:02.166171 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=a5a093dfb613b73c778207057706f88d5254927e05ae90617f314b938bd34a14 Nov 24 00:14:02.166180 kernel: BIOS-provided physical RAM map: Nov 24 00:14:02.166187 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 24 00:14:02.166193 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 24 00:14:02.166200 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 24 00:14:02.166207 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 24 00:14:02.166217 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 24 00:14:02.166224 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 24 00:14:02.166230 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 24 00:14:02.166237 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 24 00:14:02.166243 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 24 00:14:02.166250 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 24 00:14:02.166257 kernel: NX (Execute Disable) protection: active Nov 24 00:14:02.166267 kernel: APIC: Static calls initialized Nov 24 00:14:02.166275 kernel: efi: EFI v2.7 by Microsoft Nov 24 00:14:02.166282 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bba98 RNG=0x3ffd1018 Nov 24 00:14:02.166290 kernel: random: crng init done Nov 24 00:14:02.166297 kernel: secureboot: Secure boot disabled Nov 24 00:14:02.166305 kernel: SMBIOS 3.1.0 present. Nov 24 00:14:02.166312 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 24 00:14:02.166320 kernel: DMI: Memory slots populated: 3/3 Nov 24 00:14:02.166327 kernel: Hypervisor detected: Microsoft Hyper-V Nov 24 00:14:02.166334 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 24 00:14:02.166344 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 24 00:14:02.166351 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 24 00:14:02.166359 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 24 00:14:02.166367 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 24 00:14:02.166375 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 24 00:14:02.166382 kernel: tsc: Detected 2593.992 MHz processor Nov 24 00:14:02.166390 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 24 00:14:02.166398 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 24 00:14:02.166405 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 24 00:14:02.166416 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 24 00:14:02.166423 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 24 00:14:02.166431 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 24 00:14:02.166438 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 24 00:14:02.166446 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 24 00:14:02.166453 kernel: Using GB pages for direct mapping Nov 24 00:14:02.166461 kernel: ACPI: Early table checksum verification disabled Nov 24 00:14:02.166472 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 24 00:14:02.166482 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166490 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166498 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 24 00:14:02.166506 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 24 00:14:02.166514 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166522 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166533 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166540 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166548 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166556 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166564 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:14:02.166572 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 24 00:14:02.166580 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 24 00:14:02.166588 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 24 00:14:02.166596 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 24 00:14:02.166607 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 24 00:14:02.166614 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 24 00:14:02.166622 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 24 00:14:02.166630 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 24 00:14:02.166638 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 24 00:14:02.166646 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 24 00:14:02.166654 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 24 00:14:02.166662 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 24 00:14:02.166672 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 24 00:14:02.166680 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 24 00:14:02.166688 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 24 00:14:02.166696 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 24 00:14:02.166704 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 24 00:14:02.166712 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 24 00:14:02.166720 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 24 00:14:02.166728 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 24 00:14:02.166736 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 24 00:14:02.166747 kernel: Zone ranges: Nov 24 00:14:02.166755 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 24 00:14:02.166763 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 24 00:14:02.166770 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 24 00:14:02.166778 kernel: Device empty Nov 24 00:14:02.166786 kernel: Movable zone start for each node Nov 24 00:14:02.166794 kernel: Early memory node ranges Nov 24 00:14:02.166802 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 24 00:14:02.166810 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 24 00:14:02.166820 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 24 00:14:02.166828 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 24 00:14:02.166836 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 24 00:14:02.166844 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 24 00:14:02.166851 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 24 00:14:02.166859 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 24 00:14:02.166867 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 24 00:14:02.166875 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 24 00:14:02.166883 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 24 00:14:02.166893 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 24 00:14:02.166927 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 24 00:14:02.166936 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 24 00:14:02.166944 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 24 00:14:02.166952 kernel: CPU topo: Max. logical packages: 1 Nov 24 00:14:02.166960 kernel: CPU topo: Max. logical dies: 1 Nov 24 00:14:02.166968 kernel: CPU topo: Max. dies per package: 1 Nov 24 00:14:02.166976 kernel: CPU topo: Max. threads per core: 1 Nov 24 00:14:02.166983 kernel: CPU topo: Num. cores per package: 6 Nov 24 00:14:02.166994 kernel: CPU topo: Num. threads per package: 6 Nov 24 00:14:02.167002 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 24 00:14:02.167010 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 24 00:14:02.167018 kernel: Booting paravirtualized kernel on Hyper-V Nov 24 00:14:02.167026 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 24 00:14:02.167034 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 24 00:14:02.167042 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 24 00:14:02.167050 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 24 00:14:02.167058 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 24 00:14:02.167068 kernel: Hyper-V: PV spinlocks enabled Nov 24 00:14:02.167076 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 24 00:14:02.167085 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=a5a093dfb613b73c778207057706f88d5254927e05ae90617f314b938bd34a14 Nov 24 00:14:02.167094 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 24 00:14:02.167102 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 24 00:14:02.167109 kernel: Fallback order for Node 0: 0 Nov 24 00:14:02.167117 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 24 00:14:02.167125 kernel: Policy zone: Normal Nov 24 00:14:02.167136 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 24 00:14:02.167151 kernel: software IO TLB: area num 8. Nov 24 00:14:02.167159 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 24 00:14:02.167170 kernel: Kernel/User page tables isolation: enabled Nov 24 00:14:02.167179 kernel: ftrace: allocating 40103 entries in 157 pages Nov 24 00:14:02.167187 kernel: ftrace: allocated 157 pages with 5 groups Nov 24 00:14:02.167195 kernel: Dynamic Preempt: voluntary Nov 24 00:14:02.167204 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 24 00:14:02.167213 kernel: rcu: RCU event tracing is enabled. Nov 24 00:14:02.167221 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 24 00:14:02.167230 kernel: Trampoline variant of Tasks RCU enabled. Nov 24 00:14:02.167241 kernel: Rude variant of Tasks RCU enabled. Nov 24 00:14:02.167249 kernel: Tracing variant of Tasks RCU enabled. Nov 24 00:14:02.167257 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 24 00:14:02.167266 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 24 00:14:02.167274 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:14:02.167283 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:14:02.167294 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:14:02.167302 kernel: Using NULL legacy PIC Nov 24 00:14:02.167311 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 24 00:14:02.167319 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 24 00:14:02.167327 kernel: Console: colour dummy device 80x25 Nov 24 00:14:02.167336 kernel: printk: legacy console [tty1] enabled Nov 24 00:14:02.167344 kernel: printk: legacy console [ttyS0] enabled Nov 24 00:14:02.167353 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 24 00:14:02.167361 kernel: ACPI: Core revision 20240827 Nov 24 00:14:02.167372 kernel: Failed to register legacy timer interrupt Nov 24 00:14:02.167380 kernel: APIC: Switch to symmetric I/O mode setup Nov 24 00:14:02.167388 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 24 00:14:02.167397 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 24 00:14:02.167405 kernel: Hyper-V: Using IPI hypercalls Nov 24 00:14:02.167413 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 24 00:14:02.167422 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 24 00:14:02.167430 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 24 00:14:02.167438 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 24 00:14:02.167449 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 24 00:14:02.167457 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 24 00:14:02.167466 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 24 00:14:02.167474 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 24 00:14:02.167482 kernel: APIC: read() replaced with hv_apic_read() Nov 24 00:14:02.167491 kernel: APIC: write() replaced with hv_apic_write() Nov 24 00:14:02.167499 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 24 00:14:02.167507 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 24 00:14:02.167516 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Nov 24 00:14:02.167527 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 24 00:14:02.167535 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 24 00:14:02.167543 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 24 00:14:02.167552 kernel: Spectre V2 : Mitigation: Retpolines Nov 24 00:14:02.167560 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 24 00:14:02.167568 kernel: Speculative Store Bypass: Vulnerable Nov 24 00:14:02.167576 kernel: MDS: Mitigation: Clear CPU buffers Nov 24 00:14:02.167584 kernel: TAA: Mitigation: Clear CPU buffers Nov 24 00:14:02.167593 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 24 00:14:02.167601 kernel: active return thunk: its_return_thunk Nov 24 00:14:02.167609 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 24 00:14:02.167619 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 24 00:14:02.167628 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 24 00:14:02.167636 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 24 00:14:02.167644 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 24 00:14:02.167652 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 24 00:14:02.167661 kernel: Freeing SMP alternatives memory: 32K Nov 24 00:14:02.167669 kernel: pid_max: default: 32768 minimum: 301 Nov 24 00:14:02.167677 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 24 00:14:02.167685 kernel: landlock: Up and running. Nov 24 00:14:02.167693 kernel: SELinux: Initializing. Nov 24 00:14:02.167702 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 24 00:14:02.167712 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 24 00:14:02.167721 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 24 00:14:02.167729 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 24 00:14:02.167738 kernel: signal: max sigframe size: 1776 Nov 24 00:14:02.167746 kernel: rcu: Hierarchical SRCU implementation. Nov 24 00:14:02.167755 kernel: rcu: Max phase no-delay instances is 400. Nov 24 00:14:02.167763 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 24 00:14:02.167771 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 24 00:14:02.167780 kernel: smp: Bringing up secondary CPUs ... Nov 24 00:14:02.167790 kernel: smpboot: x86: Booting SMP configuration: Nov 24 00:14:02.167799 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 24 00:14:02.167807 kernel: smp: Brought up 1 node, 6 CPUs Nov 24 00:14:02.167815 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Nov 24 00:14:02.167824 kernel: Memory: 115330896K/117439364K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46200K init, 2560K bss, 2098192K reserved, 0K cma-reserved) Nov 24 00:14:02.167833 kernel: devtmpfs: initialized Nov 24 00:14:02.167841 kernel: x86/mm: Memory block size: 1024MB Nov 24 00:14:02.167850 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 24 00:14:02.167858 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 24 00:14:02.167869 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 24 00:14:02.167877 kernel: pinctrl core: initialized pinctrl subsystem Nov 24 00:14:02.167885 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 24 00:14:02.167894 kernel: audit: initializing netlink subsys (disabled) Nov 24 00:14:02.167912 kernel: audit: type=2000 audit(1763943239.028:1): state=initialized audit_enabled=0 res=1 Nov 24 00:14:02.167921 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 24 00:14:02.167930 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 24 00:14:02.167938 kernel: cpuidle: using governor menu Nov 24 00:14:02.167946 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 24 00:14:02.167958 kernel: dca service started, version 1.12.1 Nov 24 00:14:02.167966 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 24 00:14:02.167974 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 24 00:14:02.167983 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 24 00:14:02.167991 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 24 00:14:02.167999 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 24 00:14:02.168008 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 24 00:14:02.168016 kernel: ACPI: Added _OSI(Module Device) Nov 24 00:14:02.168024 kernel: ACPI: Added _OSI(Processor Device) Nov 24 00:14:02.168035 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 24 00:14:02.168044 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 24 00:14:02.168052 kernel: ACPI: Interpreter enabled Nov 24 00:14:02.168060 kernel: ACPI: PM: (supports S0 S5) Nov 24 00:14:02.168069 kernel: ACPI: Using IOAPIC for interrupt routing Nov 24 00:14:02.168077 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 24 00:14:02.168085 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 24 00:14:02.168094 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 24 00:14:02.168102 kernel: iommu: Default domain type: Translated Nov 24 00:14:02.168112 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 24 00:14:02.168121 kernel: efivars: Registered efivars operations Nov 24 00:14:02.168129 kernel: PCI: Using ACPI for IRQ routing Nov 24 00:14:02.168137 kernel: PCI: System does not support PCI Nov 24 00:14:02.168146 kernel: vgaarb: loaded Nov 24 00:14:02.168154 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 24 00:14:02.168162 kernel: VFS: Disk quotas dquot_6.6.0 Nov 24 00:14:02.168171 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 24 00:14:02.168179 kernel: pnp: PnP ACPI init Nov 24 00:14:02.168190 kernel: pnp: PnP ACPI: found 3 devices Nov 24 00:14:02.168198 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 24 00:14:02.168207 kernel: NET: Registered PF_INET protocol family Nov 24 00:14:02.168215 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 24 00:14:02.168224 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 24 00:14:02.168232 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 24 00:14:02.168241 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 24 00:14:02.168249 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:14:02.168257 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 24 00:14:02.168268 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:14:02.168277 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:14:02.168285 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 24 00:14:02.168293 kernel: NET: Registered PF_XDP protocol family Nov 24 00:14:02.168301 kernel: PCI: CLS 0 bytes, default 64 Nov 24 00:14:02.168310 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 24 00:14:02.168318 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Nov 24 00:14:02.168326 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 24 00:14:02.168335 kernel: Initialise system trusted keyrings Nov 24 00:14:02.168345 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 24 00:14:02.168354 kernel: Key type asymmetric registered Nov 24 00:14:02.168362 kernel: Asymmetric key parser 'x509' registered Nov 24 00:14:02.168370 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 24 00:14:02.168379 kernel: io scheduler mq-deadline registered Nov 24 00:14:02.168387 kernel: io scheduler kyber registered Nov 24 00:14:02.168395 kernel: io scheduler bfq registered Nov 24 00:14:02.168404 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 24 00:14:02.168412 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 24 00:14:02.168423 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 24 00:14:02.168431 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 24 00:14:02.168440 kernel: i8042: PNP: No PS/2 controller found. Nov 24 00:14:02.168587 kernel: rtc_cmos 00:02: registered as rtc0 Nov 24 00:14:02.168702 kernel: rtc_cmos 00:02: setting system clock to 2025-11-24T00:14:01 UTC (1763943241) Nov 24 00:14:02.168798 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 24 00:14:02.168811 kernel: intel_pstate: CPU model not supported Nov 24 00:14:02.168825 kernel: efifb: probing for efifb Nov 24 00:14:02.168833 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 24 00:14:02.168842 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 24 00:14:02.168850 kernel: efifb: scrolling: redraw Nov 24 00:14:02.168859 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 24 00:14:02.168867 kernel: Console: switching to colour frame buffer device 128x48 Nov 24 00:14:02.168876 kernel: fb0: EFI VGA frame buffer device Nov 24 00:14:02.168884 kernel: pstore: Using crash dump compression: deflate Nov 24 00:14:02.168893 kernel: pstore: Registered efi_pstore as persistent store backend Nov 24 00:14:02.168917 kernel: NET: Registered PF_INET6 protocol family Nov 24 00:14:02.168925 kernel: Segment Routing with IPv6 Nov 24 00:14:02.168934 kernel: In-situ OAM (IOAM) with IPv6 Nov 24 00:14:02.168942 kernel: NET: Registered PF_PACKET protocol family Nov 24 00:14:02.168951 kernel: Key type dns_resolver registered Nov 24 00:14:02.168959 kernel: IPI shorthand broadcast: enabled Nov 24 00:14:02.168967 kernel: sched_clock: Marking stable (3587004600, 109363500)->(4028723100, -332355000) Nov 24 00:14:02.168976 kernel: registered taskstats version 1 Nov 24 00:14:02.168984 kernel: Loading compiled-in X.509 certificates Nov 24 00:14:02.168993 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.58-flatcar: 960cbe7f2b1ea74b5c881d6d42eea4d1ac19a607' Nov 24 00:14:02.169004 kernel: Demotion targets for Node 0: null Nov 24 00:14:02.169013 kernel: Key type .fscrypt registered Nov 24 00:14:02.169021 kernel: Key type fscrypt-provisioning registered Nov 24 00:14:02.169029 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 24 00:14:02.169037 kernel: ima: Allocated hash algorithm: sha1 Nov 24 00:14:02.169046 kernel: ima: No architecture policies found Nov 24 00:14:02.169054 kernel: clk: Disabling unused clocks Nov 24 00:14:02.169062 kernel: Warning: unable to open an initial console. Nov 24 00:14:02.169071 kernel: Freeing unused kernel image (initmem) memory: 46200K Nov 24 00:14:02.169082 kernel: Write protecting the kernel read-only data: 40960k Nov 24 00:14:02.169090 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Nov 24 00:14:02.169098 kernel: Run /init as init process Nov 24 00:14:02.169107 kernel: with arguments: Nov 24 00:14:02.169115 kernel: /init Nov 24 00:14:02.169123 kernel: with environment: Nov 24 00:14:02.169131 kernel: HOME=/ Nov 24 00:14:02.169140 kernel: TERM=linux Nov 24 00:14:02.169149 systemd[1]: Successfully made /usr/ read-only. Nov 24 00:14:02.169163 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 24 00:14:02.169173 systemd[1]: Detected virtualization microsoft. Nov 24 00:14:02.169181 systemd[1]: Detected architecture x86-64. Nov 24 00:14:02.169190 systemd[1]: Running in initrd. Nov 24 00:14:02.169198 systemd[1]: No hostname configured, using default hostname. Nov 24 00:14:02.169207 systemd[1]: Hostname set to . Nov 24 00:14:02.169216 systemd[1]: Initializing machine ID from random generator. Nov 24 00:14:02.169228 systemd[1]: Queued start job for default target initrd.target. Nov 24 00:14:02.169236 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:14:02.169245 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:14:02.169255 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 24 00:14:02.169264 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 24 00:14:02.169273 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 24 00:14:02.169282 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 24 00:14:02.169295 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 24 00:14:02.169304 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 24 00:14:02.169313 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:14:02.169322 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:14:02.169331 systemd[1]: Reached target paths.target - Path Units. Nov 24 00:14:02.169340 systemd[1]: Reached target slices.target - Slice Units. Nov 24 00:14:02.169349 systemd[1]: Reached target swap.target - Swaps. Nov 24 00:14:02.169358 systemd[1]: Reached target timers.target - Timer Units. Nov 24 00:14:02.169369 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 24 00:14:02.169378 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 24 00:14:02.169387 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 24 00:14:02.169396 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 24 00:14:02.169405 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:14:02.169414 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 24 00:14:02.169422 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:14:02.169431 systemd[1]: Reached target sockets.target - Socket Units. Nov 24 00:14:02.169440 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 24 00:14:02.169452 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 24 00:14:02.169461 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 24 00:14:02.169470 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 24 00:14:02.169479 systemd[1]: Starting systemd-fsck-usr.service... Nov 24 00:14:02.169488 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 24 00:14:02.169497 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 24 00:14:02.169525 systemd-journald[213]: Collecting audit messages is disabled. Nov 24 00:14:02.169551 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:14:02.169561 systemd-journald[213]: Journal started Nov 24 00:14:02.169584 systemd-journald[213]: Runtime Journal (/run/log/journal/701d83c070204bdbadffd8680a80d585) is 8M, max 2.2G, 2.1G free. Nov 24 00:14:02.172589 systemd-modules-load[215]: Inserted module 'overlay' Nov 24 00:14:02.178463 systemd[1]: Started systemd-journald.service - Journal Service. Nov 24 00:14:02.178846 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 24 00:14:02.179339 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:14:02.180033 systemd[1]: Finished systemd-fsck-usr.service. Nov 24 00:14:02.182372 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 24 00:14:02.183276 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 24 00:14:02.230262 systemd-tmpfiles[224]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 24 00:14:02.231305 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 24 00:14:02.233734 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 24 00:14:02.256202 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:14:02.257849 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:14:02.267109 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 24 00:14:02.283799 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:14:02.294563 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 24 00:14:02.304775 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 24 00:14:02.321948 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 24 00:14:02.326349 systemd-modules-load[215]: Inserted module 'br_netfilter' Nov 24 00:14:02.327826 kernel: Bridge firewalling registered Nov 24 00:14:02.327849 dracut-cmdline[246]: 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=a5a093dfb613b73c778207057706f88d5254927e05ae90617f314b938bd34a14 Nov 24 00:14:02.327347 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 24 00:14:02.357923 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 24 00:14:02.381860 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:14:02.391667 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 24 00:14:02.435609 systemd-resolved[318]: Positive Trust Anchors: Nov 24 00:14:02.435627 systemd-resolved[318]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 24 00:14:02.435658 systemd-resolved[318]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 24 00:14:02.438228 systemd-resolved[318]: Defaulting to hostname 'linux'. Nov 24 00:14:02.484021 kernel: SCSI subsystem initialized Nov 24 00:14:02.439252 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 24 00:14:02.440930 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:14:02.499925 kernel: Loading iSCSI transport class v2.0-870. Nov 24 00:14:02.511921 kernel: iscsi: registered transport (tcp) Nov 24 00:14:02.535663 kernel: iscsi: registered transport (qla4xxx) Nov 24 00:14:02.535691 kernel: QLogic iSCSI HBA Driver Nov 24 00:14:02.554749 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 24 00:14:02.585499 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:14:02.588002 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 24 00:14:02.639249 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 24 00:14:02.641988 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 24 00:14:02.712927 kernel: raid6: avx2x4 gen() 31056 MB/s Nov 24 00:14:02.731927 kernel: raid6: avx2x2 gen() 31174 MB/s Nov 24 00:14:02.752991 kernel: raid6: avx2x1 gen() 27750 MB/s Nov 24 00:14:02.753019 kernel: raid6: using algorithm avx2x2 gen() 31174 MB/s Nov 24 00:14:02.777277 kernel: raid6: .... xor() 18495 MB/s, rmw enabled Nov 24 00:14:02.777307 kernel: raid6: using avx2x2 recovery algorithm Nov 24 00:14:02.799922 kernel: xor: automatically using best checksumming function avx Nov 24 00:14:02.972935 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 24 00:14:02.979729 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 24 00:14:02.982727 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:14:03.020578 systemd-udevd[463]: Using default interface naming scheme 'v255'. Nov 24 00:14:03.026561 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:14:03.028758 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 24 00:14:03.067337 dracut-pre-trigger[470]: rd.md=0: removing MD RAID activation Nov 24 00:14:03.092374 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 24 00:14:03.098135 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 24 00:14:03.173772 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:14:03.177223 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 24 00:14:03.218205 kernel: cryptd: max_cpu_qlen set to 1000 Nov 24 00:14:03.223922 kernel: hv_vmbus: Vmbus version:4.0 Nov 24 00:14:03.233956 kernel: AES CTR mode by8 optimization enabled Nov 24 00:14:03.250673 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:14:03.260331 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 24 00:14:03.265064 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 24 00:14:03.250853 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:14:03.270293 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:14:03.275509 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:14:03.283642 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:14:03.305429 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 24 00:14:03.305456 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Nov 24 00:14:03.305469 kernel: hv_vmbus: registering driver hv_storvsc Nov 24 00:14:03.309929 kernel: scsi host0: storvsc_host_t Nov 24 00:14:03.309984 kernel: scsi host1: storvsc_host_t Nov 24 00:14:03.318109 kernel: hv_vmbus: registering driver hv_pci Nov 24 00:14:03.318152 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 24 00:14:03.318193 kernel: hv_vmbus: registering driver hv_netvsc Nov 24 00:14:03.320925 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 24 00:14:03.321138 kernel: scsi 1:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 24 00:14:03.324918 kernel: PTP clock support registered Nov 24 00:14:03.324938 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 24 00:14:03.331253 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 24 00:14:03.344448 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 24 00:14:03.344657 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 24 00:14:03.344790 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 24 00:14:03.344934 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 24 00:14:03.352924 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 24 00:14:03.366954 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 24 00:14:03.372050 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 24 00:14:03.377568 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 24 00:14:03.377691 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:14:03.391511 kernel: hv_vmbus: registering driver hid_hyperv Nov 24 00:14:03.391538 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 24 00:14:03.397348 kernel: hv_utils: Registering HyperV Utility Driver Nov 24 00:14:03.397378 kernel: hv_vmbus: registering driver hv_utils Nov 24 00:14:03.400995 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 24 00:14:03.401226 kernel: sd 1:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 24 00:14:03.401388 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 24 00:14:03.401521 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 24 00:14:03.401666 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 24 00:14:03.401795 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 24 00:14:03.405787 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks Nov 24 00:14:03.405964 kernel: sd 1:0:0:1: [sdb] 4096-byte physical blocks Nov 24 00:14:03.414919 kernel: sd 1:0:0:0: [sda] Write Protect is off Nov 24 00:14:03.415099 kernel: sd 1:0:0:1: [sdb] Write Protect is off Nov 24 00:14:03.421387 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 24 00:14:03.421548 kernel: sd 1:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 24 00:14:03.426474 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 24 00:14:03.426628 kernel: sd 1:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 24 00:14:03.428919 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#66 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:03.431922 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#355 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:03.436064 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#73 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:03.440914 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#362 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:03.482230 kernel: sdb: sdb1 Nov 24 00:14:03.482258 kernel: sd 1:0:0:1: [sdb] Attached SCSI disk Nov 24 00:14:03.488469 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:14:03.488493 kernel: sd 1:0:0:0: [sda] Attached SCSI disk Nov 24 00:14:03.674405 kernel: hv_utils: Heartbeat IC version 3.0 Nov 24 00:14:03.674498 kernel: hv_utils: Shutdown IC version 3.2 Nov 24 00:14:03.674521 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 24 00:14:03.674533 kernel: hv_utils: TimeSync IC version 4.0 Nov 24 00:14:03.680933 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 24 00:14:04.080661 systemd-resolved[318]: Clock change detected. Flushing caches. Nov 24 00:14:04.098053 kernel: sr 1:0:0:2: [sr0] scsi-1 drive Nov 24 00:14:04.098248 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 24 00:14:04.099682 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 Nov 24 00:14:04.532734 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 24 00:14:04.571590 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 24 00:14:04.589793 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 24 00:14:04.609506 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 24 00:14:04.611026 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 24 00:14:04.621342 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 24 00:14:04.661881 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#434 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:04.682634 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:14:04.692010 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#602 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:04.716632 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:14:05.723762 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#103 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:05.739461 disk-uuid[706]: The operation has completed successfully. Nov 24 00:14:05.743173 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:14:07.689333 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 24 00:14:07.689444 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 24 00:14:07.694829 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 24 00:14:07.703435 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 24 00:14:07.712325 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:14:07.717126 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 24 00:14:07.726403 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 24 00:14:07.747706 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 24 00:14:07.780956 sh[871]: Success Nov 24 00:14:07.788935 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 24 00:14:07.818343 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 24 00:14:07.818392 kernel: device-mapper: uevent: version 1.0.3 Nov 24 00:14:07.823397 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 24 00:14:07.834632 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Nov 24 00:14:08.033878 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 24 00:14:08.043664 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 24 00:14:08.064572 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 24 00:14:08.082632 kernel: BTRFS: device fsid 3af95a3e-5df6-49e0-91e3-ddf2109f68c7 devid 1 transid 35 /dev/mapper/usr (254:0) scanned by mount (896) Nov 24 00:14:08.082670 kernel: BTRFS info (device dm-0): first mount of filesystem 3af95a3e-5df6-49e0-91e3-ddf2109f68c7 Nov 24 00:14:08.091068 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:14:08.298738 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 24 00:14:08.298815 kernel: BTRFS info (device dm-0): enabling free space tree Nov 24 00:14:08.324405 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 24 00:14:08.332179 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 24 00:14:08.333795 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 24 00:14:08.334727 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 24 00:14:08.372199 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 24 00:14:08.409631 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (927) Nov 24 00:14:08.419362 kernel: BTRFS info (device sda6): first mount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:14:08.419397 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:14:08.442525 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:14:08.442573 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:14:08.451760 kernel: BTRFS info (device sda6): last unmount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:14:08.451864 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 24 00:14:08.457023 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 24 00:14:08.481008 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 24 00:14:08.485531 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 24 00:14:08.529467 systemd-networkd[1065]: lo: Link UP Nov 24 00:14:08.529478 systemd-networkd[1065]: lo: Gained carrier Nov 24 00:14:08.530499 systemd-networkd[1065]: Enumeration completed Nov 24 00:14:08.530593 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 24 00:14:08.530866 systemd-networkd[1065]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:14:08.530870 systemd-networkd[1065]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:14:08.531626 systemd-networkd[1065]: eth0: Link UP Nov 24 00:14:08.531793 systemd-networkd[1065]: eth0: Gained carrier Nov 24 00:14:08.531803 systemd-networkd[1065]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:14:08.536431 systemd[1]: Reached target network.target - Network. Nov 24 00:14:08.586657 systemd-networkd[1065]: eth0: DHCPv4 address 10.200.4.11/24, gateway 10.200.4.1 acquired from 168.63.129.16 Nov 24 00:14:09.512511 ignition[1040]: Ignition 2.22.0 Nov 24 00:14:09.512528 ignition[1040]: Stage: fetch-offline Nov 24 00:14:09.512703 ignition[1040]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:09.512714 ignition[1040]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:09.512832 ignition[1040]: parsed url from cmdline: "" Nov 24 00:14:09.512836 ignition[1040]: no config URL provided Nov 24 00:14:09.512842 ignition[1040]: reading system config file "/usr/lib/ignition/user.ign" Nov 24 00:14:09.512851 ignition[1040]: no config at "/usr/lib/ignition/user.ign" Nov 24 00:14:09.512857 ignition[1040]: failed to fetch config: resource requires networking Nov 24 00:14:09.513026 ignition[1040]: Ignition finished successfully Nov 24 00:14:09.543460 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 24 00:14:09.545904 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 24 00:14:09.584865 ignition[1078]: Ignition 2.22.0 Nov 24 00:14:09.584879 ignition[1078]: Stage: fetch Nov 24 00:14:09.585117 ignition[1078]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:09.585129 ignition[1078]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:09.585220 ignition[1078]: parsed url from cmdline: "" Nov 24 00:14:09.585224 ignition[1078]: no config URL provided Nov 24 00:14:09.585230 ignition[1078]: reading system config file "/usr/lib/ignition/user.ign" Nov 24 00:14:09.585238 ignition[1078]: no config at "/usr/lib/ignition/user.ign" Nov 24 00:14:09.585255 ignition[1078]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 24 00:14:09.692601 ignition[1078]: GET result: OK Nov 24 00:14:09.692674 ignition[1078]: config has been read from IMDS userdata Nov 24 00:14:09.692688 ignition[1078]: parsing config with SHA512: 35fee39a04a952da46335eaa0822635fab377705bc7296d0cf02d28b450403f9431c48356702f03e52487d66cd3ef8065c045403ca2384d4bd8c3d5ed0ab503e Nov 24 00:14:09.696993 unknown[1078]: fetched base config from "system" Nov 24 00:14:09.697155 ignition[1078]: fetch: fetch complete Nov 24 00:14:09.697000 unknown[1078]: fetched base config from "system" Nov 24 00:14:09.697161 ignition[1078]: fetch: fetch passed Nov 24 00:14:09.697005 unknown[1078]: fetched user config from "azure" Nov 24 00:14:09.697205 ignition[1078]: Ignition finished successfully Nov 24 00:14:09.707655 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 24 00:14:09.710642 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 24 00:14:09.757715 ignition[1088]: Ignition 2.22.0 Nov 24 00:14:09.757729 ignition[1088]: Stage: kargs Nov 24 00:14:09.757960 ignition[1088]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:09.757972 ignition[1088]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:09.766278 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 24 00:14:09.758568 ignition[1088]: kargs: kargs passed Nov 24 00:14:09.774936 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 24 00:14:09.758633 ignition[1088]: Ignition finished successfully Nov 24 00:14:09.819386 ignition[1098]: Ignition 2.22.0 Nov 24 00:14:09.819400 ignition[1098]: Stage: disks Nov 24 00:14:09.819631 ignition[1098]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:09.819644 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:09.825993 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 24 00:14:09.820081 ignition[1098]: disks: disks passed Nov 24 00:14:09.828707 systemd-networkd[1065]: eth0: Gained IPv6LL Nov 24 00:14:09.820123 ignition[1098]: Ignition finished successfully Nov 24 00:14:09.832847 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 24 00:14:09.841226 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 24 00:14:09.849713 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 24 00:14:09.851169 systemd[1]: Reached target sysinit.target - System Initialization. Nov 24 00:14:09.860396 systemd[1]: Reached target basic.target - Basic System. Nov 24 00:14:09.867359 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 24 00:14:09.950848 systemd-fsck[1110]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Nov 24 00:14:09.955570 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 24 00:14:09.958277 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 24 00:14:10.207631 kernel: EXT4-fs (sda9): mounted filesystem f89e2a65-2a4a-426b-9659-02844cc29a2a r/w with ordered data mode. Quota mode: none. Nov 24 00:14:10.208360 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 24 00:14:10.210473 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 24 00:14:10.228186 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 24 00:14:10.230788 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 24 00:14:10.254201 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 24 00:14:10.264962 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 24 00:14:10.265001 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 24 00:14:10.297033 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1124) Nov 24 00:14:10.297061 kernel: BTRFS info (device sda6): first mount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:14:10.297074 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:14:10.284391 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 24 00:14:10.309650 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:14:10.309674 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:14:10.299781 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 24 00:14:10.312406 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 24 00:14:10.738927 coreos-metadata[1126]: Nov 24 00:14:10.738 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 24 00:14:10.746695 coreos-metadata[1126]: Nov 24 00:14:10.746 INFO Fetch successful Nov 24 00:14:10.750525 coreos-metadata[1126]: Nov 24 00:14:10.746 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 24 00:14:10.765145 coreos-metadata[1126]: Nov 24 00:14:10.765 INFO Fetch successful Nov 24 00:14:10.776300 coreos-metadata[1126]: Nov 24 00:14:10.776 INFO wrote hostname ci-4459.2.1-a-09f0a397ab to /sysroot/etc/hostname Nov 24 00:14:10.778175 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 24 00:14:10.956195 initrd-setup-root[1156]: cut: /sysroot/etc/passwd: No such file or directory Nov 24 00:14:11.009286 initrd-setup-root[1163]: cut: /sysroot/etc/group: No such file or directory Nov 24 00:14:11.023861 initrd-setup-root[1170]: cut: /sysroot/etc/shadow: No such file or directory Nov 24 00:14:11.032326 initrd-setup-root[1177]: cut: /sysroot/etc/gshadow: No such file or directory Nov 24 00:14:11.751585 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 24 00:14:11.754453 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 24 00:14:11.755192 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 24 00:14:11.790709 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 24 00:14:11.799473 kernel: BTRFS info (device sda6): last unmount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:14:11.821400 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 24 00:14:11.834202 ignition[1246]: INFO : Ignition 2.22.0 Nov 24 00:14:11.834202 ignition[1246]: INFO : Stage: mount Nov 24 00:14:11.840231 ignition[1246]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:11.840231 ignition[1246]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:11.840231 ignition[1246]: INFO : mount: mount passed Nov 24 00:14:11.840231 ignition[1246]: INFO : Ignition finished successfully Nov 24 00:14:11.841175 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 24 00:14:11.848777 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 24 00:14:11.887305 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 24 00:14:11.913634 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1260) Nov 24 00:14:11.922622 kernel: BTRFS info (device sda6): first mount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:14:11.922662 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:14:11.933507 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:14:11.933537 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:14:11.935858 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 24 00:14:11.980809 ignition[1277]: INFO : Ignition 2.22.0 Nov 24 00:14:11.980809 ignition[1277]: INFO : Stage: files Nov 24 00:14:11.986389 ignition[1277]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:11.986389 ignition[1277]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:11.986389 ignition[1277]: DEBUG : files: compiled without relabeling support, skipping Nov 24 00:14:11.999752 ignition[1277]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 24 00:14:11.999752 ignition[1277]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 24 00:14:12.016181 ignition[1277]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 24 00:14:12.021915 ignition[1277]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 24 00:14:12.028349 unknown[1277]: wrote ssh authorized keys file for user: core Nov 24 00:14:12.032117 ignition[1277]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 24 00:14:12.046778 ignition[1277]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 24 00:14:12.053714 ignition[1277]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 24 00:14:12.053714 ignition[1277]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 24 00:14:12.053714 ignition[1277]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 24 00:14:12.053714 ignition[1277]: INFO : files: files passed Nov 24 00:14:12.053714 ignition[1277]: INFO : Ignition finished successfully Nov 24 00:14:12.062587 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 24 00:14:12.070369 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 24 00:14:12.100123 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 24 00:14:12.103473 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 24 00:14:12.103563 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 24 00:14:12.133168 initrd-setup-root-after-ignition[1310]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:14:12.133168 initrd-setup-root-after-ignition[1310]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:14:12.146542 initrd-setup-root-after-ignition[1314]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:14:12.146774 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 24 00:14:12.149273 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 24 00:14:12.164541 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 24 00:14:12.216443 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 24 00:14:12.216555 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 24 00:14:12.225358 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 24 00:14:12.235219 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 24 00:14:12.236715 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 24 00:14:12.237515 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 24 00:14:12.281238 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 24 00:14:12.291924 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 24 00:14:12.328885 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:14:12.330444 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:14:12.339033 systemd[1]: Stopped target timers.target - Timer Units. Nov 24 00:14:12.348072 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 24 00:14:12.348198 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 24 00:14:12.364101 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 24 00:14:12.372435 systemd[1]: Stopped target basic.target - Basic System. Nov 24 00:14:12.380479 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 24 00:14:12.388277 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 24 00:14:12.396885 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 24 00:14:12.405827 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 24 00:14:12.407421 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 24 00:14:12.415873 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 24 00:14:12.424043 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 24 00:14:12.432754 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 24 00:14:12.441555 systemd[1]: Stopped target swap.target - Swaps. Nov 24 00:14:12.448435 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 24 00:14:12.448572 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 24 00:14:12.461837 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:14:12.467170 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:14:12.475296 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 24 00:14:12.475403 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:14:12.483427 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 24 00:14:12.483559 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 24 00:14:12.499994 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 24 00:14:12.500124 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 24 00:14:12.510012 systemd[1]: ignition-files.service: Deactivated successfully. Nov 24 00:14:12.510134 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 24 00:14:12.517363 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 24 00:14:12.517476 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 24 00:14:12.528495 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 24 00:14:12.535335 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 24 00:14:12.535457 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:14:12.566942 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 24 00:14:12.573058 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 24 00:14:12.573195 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:14:12.582439 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 24 00:14:12.582561 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 24 00:14:12.597840 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 24 00:14:12.597938 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 24 00:14:12.620098 ignition[1334]: INFO : Ignition 2.22.0 Nov 24 00:14:12.620098 ignition[1334]: INFO : Stage: umount Nov 24 00:14:12.620098 ignition[1334]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:14:12.620098 ignition[1334]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:14:12.620098 ignition[1334]: INFO : umount: umount passed Nov 24 00:14:12.620098 ignition[1334]: INFO : Ignition finished successfully Nov 24 00:14:12.601641 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 24 00:14:12.618773 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 24 00:14:12.618875 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 24 00:14:12.627817 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 24 00:14:12.627924 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 24 00:14:12.630800 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 24 00:14:12.630848 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 24 00:14:12.637680 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 24 00:14:12.637727 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 24 00:14:12.649123 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 24 00:14:12.649170 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 24 00:14:12.656064 systemd[1]: Stopped target network.target - Network. Nov 24 00:14:12.662966 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 24 00:14:12.663018 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 24 00:14:12.670826 systemd[1]: Stopped target paths.target - Path Units. Nov 24 00:14:12.672218 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 24 00:14:12.675676 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:14:12.683191 systemd[1]: Stopped target slices.target - Slice Units. Nov 24 00:14:12.691436 systemd[1]: Stopped target sockets.target - Socket Units. Nov 24 00:14:12.709635 systemd[1]: iscsid.socket: Deactivated successfully. Nov 24 00:14:12.709685 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 24 00:14:12.717152 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 24 00:14:12.717192 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 24 00:14:12.731171 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 24 00:14:12.731239 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 24 00:14:12.738813 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 24 00:14:12.738861 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 24 00:14:12.746554 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 24 00:14:12.746605 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 24 00:14:12.754606 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 24 00:14:12.761539 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 24 00:14:12.774267 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 24 00:14:12.774405 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 24 00:14:12.786501 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 24 00:14:12.786816 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 24 00:14:12.786901 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:14:12.800400 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:14:12.803675 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 24 00:14:12.803803 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 24 00:14:12.814877 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 24 00:14:12.815034 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 24 00:14:12.821634 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 24 00:14:12.821674 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:14:12.830657 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 24 00:14:12.837028 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 24 00:14:12.837079 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 24 00:14:12.846894 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 24 00:14:12.846943 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:14:12.858664 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 24 00:14:12.858710 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 24 00:14:12.861497 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:14:12.873337 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 24 00:14:12.894248 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 24 00:14:12.894364 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 24 00:14:12.924234 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 24 00:14:12.924392 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:14:12.926339 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 24 00:14:12.926383 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 24 00:14:12.938448 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 24 00:14:12.938488 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:14:12.949113 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 24 00:14:12.949173 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 24 00:14:12.960874 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 24 00:14:12.960925 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 24 00:14:12.969713 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 24 00:14:12.969770 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 24 00:14:12.983293 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 24 00:14:12.990544 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 24 00:14:12.990598 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:14:13.000730 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 24 00:14:13.000777 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:14:13.009509 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:14:13.009554 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:14:13.019857 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Nov 24 00:14:13.019912 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 24 00:14:13.019952 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:14:13.149533 systemd-journald[213]: Received SIGTERM from PID 1 (systemd). Nov 24 00:14:13.020263 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 24 00:14:13.020353 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 24 00:14:13.024040 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 24 00:14:13.032081 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 24 00:14:13.056737 systemd[1]: Switching root. Nov 24 00:14:13.154013 systemd-journald[213]: Journal stopped Nov 24 00:14:16.644523 kernel: SELinux: policy capability network_peer_controls=1 Nov 24 00:14:16.644554 kernel: SELinux: policy capability open_perms=1 Nov 24 00:14:16.644568 kernel: SELinux: policy capability extended_socket_class=1 Nov 24 00:14:16.644578 kernel: SELinux: policy capability always_check_network=0 Nov 24 00:14:16.644587 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 24 00:14:16.644644 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 24 00:14:16.644665 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 24 00:14:16.644680 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 24 00:14:16.644690 kernel: SELinux: policy capability userspace_initial_context=0 Nov 24 00:14:16.644700 kernel: audit: type=1403 audit(1763943253.747:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 24 00:14:16.644712 systemd[1]: Successfully loaded SELinux policy in 137.949ms. Nov 24 00:14:16.644724 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.683ms. Nov 24 00:14:16.644736 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 24 00:14:16.644747 systemd[1]: Detected virtualization microsoft. Nov 24 00:14:16.644766 systemd[1]: Detected architecture x86-64. Nov 24 00:14:16.644778 systemd[1]: Detected first boot. Nov 24 00:14:16.644790 systemd[1]: Hostname set to . Nov 24 00:14:16.644801 systemd[1]: Initializing machine ID from random generator. Nov 24 00:14:16.644812 zram_generator::config[1378]: No configuration found. Nov 24 00:14:16.644826 kernel: Guest personality initialized and is inactive Nov 24 00:14:16.644837 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Nov 24 00:14:16.644847 kernel: Initialized host personality Nov 24 00:14:16.644857 kernel: NET: Registered PF_VSOCK protocol family Nov 24 00:14:16.644868 systemd[1]: Populated /etc with preset unit settings. Nov 24 00:14:16.644880 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 24 00:14:16.644891 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 24 00:14:16.644904 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 24 00:14:16.644916 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 24 00:14:16.644927 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 24 00:14:16.644939 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 24 00:14:16.644950 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 24 00:14:16.644961 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 24 00:14:16.644972 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 24 00:14:16.644983 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 24 00:14:16.644997 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 24 00:14:16.645012 systemd[1]: Created slice user.slice - User and Session Slice. Nov 24 00:14:16.645024 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:14:16.645035 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:14:16.645047 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 24 00:14:16.645058 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 24 00:14:16.645074 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 24 00:14:16.645086 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 24 00:14:16.645100 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 24 00:14:16.645112 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:14:16.645123 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:14:16.645135 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 24 00:14:16.645146 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 24 00:14:16.645157 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 24 00:14:16.645169 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 24 00:14:16.645183 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:14:16.645195 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 24 00:14:16.645206 systemd[1]: Reached target slices.target - Slice Units. Nov 24 00:14:16.645218 systemd[1]: Reached target swap.target - Swaps. Nov 24 00:14:16.645229 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 24 00:14:16.645241 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 24 00:14:16.645259 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 24 00:14:16.645275 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:14:16.645287 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 24 00:14:16.645299 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:14:16.645311 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 24 00:14:16.645323 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 24 00:14:16.645334 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 24 00:14:16.645349 systemd[1]: Mounting media.mount - External Media Directory... Nov 24 00:14:16.645361 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:14:16.645373 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 24 00:14:16.645385 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 24 00:14:16.645396 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 24 00:14:16.645409 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 24 00:14:16.645421 systemd[1]: Reached target machines.target - Containers. Nov 24 00:14:16.645432 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 24 00:14:16.645444 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:14:16.645458 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 24 00:14:16.645470 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 24 00:14:16.645482 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:14:16.645493 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 24 00:14:16.645505 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:14:16.645517 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 24 00:14:16.645532 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:14:16.645544 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 24 00:14:16.645559 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 24 00:14:16.645570 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 24 00:14:16.645582 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 24 00:14:16.645594 systemd[1]: Stopped systemd-fsck-usr.service. Nov 24 00:14:16.645606 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 24 00:14:16.645630 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 24 00:14:16.645642 kernel: fuse: init (API version 7.41) Nov 24 00:14:16.645653 kernel: loop: module loaded Nov 24 00:14:16.645667 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 24 00:14:16.645679 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 24 00:14:16.645691 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 24 00:14:16.645702 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 24 00:14:16.645740 systemd-journald[1485]: Collecting audit messages is disabled. Nov 24 00:14:16.645772 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 24 00:14:16.645785 systemd-journald[1485]: Journal started Nov 24 00:14:16.645808 systemd-journald[1485]: Runtime Journal (/run/log/journal/acd3d934e34f4b96aeab1c52cc336bf2) is 8M, max 2.2G, 2.1G free. Nov 24 00:14:15.929971 systemd[1]: Queued start job for default target multi-user.target. Nov 24 00:14:15.954049 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 24 00:14:15.954541 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 24 00:14:16.655201 systemd[1]: verity-setup.service: Deactivated successfully. Nov 24 00:14:16.655253 systemd[1]: Stopped verity-setup.service. Nov 24 00:14:16.667626 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:14:16.675139 systemd[1]: Started systemd-journald.service - Journal Service. Nov 24 00:14:16.679242 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 24 00:14:16.683381 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 24 00:14:16.687919 systemd[1]: Mounted media.mount - External Media Directory. Nov 24 00:14:16.691794 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 24 00:14:16.696289 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 24 00:14:16.700722 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 24 00:14:16.706105 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 24 00:14:16.711120 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:14:16.715631 kernel: ACPI: bus type drm_connector registered Nov 24 00:14:16.718767 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 24 00:14:16.718960 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 24 00:14:16.723873 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:14:16.724059 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:14:16.728775 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 24 00:14:16.728964 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 24 00:14:16.733437 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:14:16.733713 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:14:16.738772 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 24 00:14:16.738961 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 24 00:14:16.743506 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:14:16.743705 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:14:16.748196 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 24 00:14:16.752923 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:14:16.758387 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 24 00:14:16.764027 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 24 00:14:16.769426 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:14:16.783725 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 24 00:14:16.789360 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 24 00:14:16.809836 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 24 00:14:16.814865 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 24 00:14:16.814903 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 24 00:14:16.819997 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 24 00:14:16.826028 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 24 00:14:16.830067 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:14:16.831654 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 24 00:14:16.836739 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 24 00:14:16.841329 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 24 00:14:16.842326 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 24 00:14:16.846737 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 24 00:14:16.847794 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 24 00:14:16.852674 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 24 00:14:16.858268 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 24 00:14:16.860221 systemd-journald[1485]: Time spent on flushing to /var/log/journal/acd3d934e34f4b96aeab1c52cc336bf2 is 18.169ms for 948 entries. Nov 24 00:14:16.860221 systemd-journald[1485]: System Journal (/var/log/journal/acd3d934e34f4b96aeab1c52cc336bf2) is 8M, max 2.6G, 2.6G free. Nov 24 00:14:16.917151 systemd-journald[1485]: Received client request to flush runtime journal. Nov 24 00:14:16.917208 kernel: loop0: detected capacity change from 0 to 128560 Nov 24 00:14:16.867790 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 24 00:14:16.872544 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 24 00:14:16.877497 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 24 00:14:16.887968 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 24 00:14:16.893749 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 24 00:14:16.918797 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 24 00:14:16.932832 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:14:16.949726 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 24 00:14:16.950421 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 24 00:14:17.042937 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 24 00:14:17.048640 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 24 00:14:17.127027 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Nov 24 00:14:17.127049 systemd-tmpfiles[1536]: ACLs are not supported, ignoring. Nov 24 00:14:17.132874 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:14:17.199636 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 24 00:14:17.273642 kernel: loop1: detected capacity change from 0 to 110984 Nov 24 00:14:17.576469 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 24 00:14:17.584576 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:14:17.631306 systemd-udevd[1542]: Using default interface naming scheme 'v255'. Nov 24 00:14:17.718639 kernel: loop2: detected capacity change from 0 to 27936 Nov 24 00:14:17.757155 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:14:17.766580 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 24 00:14:17.811552 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 24 00:14:17.824774 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 24 00:14:17.838644 kernel: mousedev: PS/2 mouse device common for all mice Nov 24 00:14:17.857655 kernel: hv_vmbus: registering driver hv_balloon Nov 24 00:14:17.862770 kernel: hv_vmbus: registering driver hyperv_fb Nov 24 00:14:17.870405 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 24 00:14:17.881841 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 24 00:14:17.881894 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 24 00:14:17.881927 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 24 00:14:17.893629 kernel: Console: switching to colour dummy device 80x25 Nov 24 00:14:17.896640 kernel: Console: switching to colour frame buffer device 128x48 Nov 24 00:14:17.909630 kernel: EDAC MC: Ver: 3.0.0 Nov 24 00:14:17.933322 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:14:17.951305 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:14:17.951516 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:14:17.957288 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:14:18.017724 systemd-networkd[1567]: lo: Link UP Nov 24 00:14:18.017737 systemd-networkd[1567]: lo: Gained carrier Nov 24 00:14:18.019045 systemd-networkd[1567]: Enumeration completed Nov 24 00:14:18.019428 systemd-networkd[1567]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:14:18.019441 systemd-networkd[1567]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:14:18.019698 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 24 00:14:18.020630 systemd-networkd[1567]: eth0: Link UP Nov 24 00:14:18.020640 systemd-networkd[1567]: eth0: Gained carrier Nov 24 00:14:18.020657 systemd-networkd[1567]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:14:18.026721 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 24 00:14:18.033136 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 24 00:14:18.061683 systemd-networkd[1567]: eth0: DHCPv4 address 10.200.4.11/24, gateway 10.200.4.1 acquired from 168.63.129.16 Nov 24 00:14:18.089687 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 24 00:14:18.092309 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 24 00:14:18.095268 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 24 00:14:18.120745 kernel: loop3: detected capacity change from 0 to 128560 Nov 24 00:14:18.140632 kernel: loop4: detected capacity change from 0 to 110984 Nov 24 00:14:18.157340 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 24 00:14:18.168638 kernel: loop5: detected capacity change from 0 to 27936 Nov 24 00:14:18.182756 (sd-merge)[1695]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Nov 24 00:14:18.183227 (sd-merge)[1695]: Merged extensions into '/usr'. Nov 24 00:14:18.187081 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 24 00:14:18.193559 systemd[1]: Starting ensure-sysext.service... Nov 24 00:14:18.197285 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 24 00:14:18.229999 systemd[1]: Reload requested from client PID 1699 ('systemctl') (unit ensure-sysext.service)... Nov 24 00:14:18.230017 systemd[1]: Reloading... Nov 24 00:14:18.249338 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 24 00:14:18.249377 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 24 00:14:18.249690 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 24 00:14:18.250004 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 24 00:14:18.250924 systemd-tmpfiles[1700]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 24 00:14:18.251216 systemd-tmpfiles[1700]: ACLs are not supported, ignoring. Nov 24 00:14:18.251283 systemd-tmpfiles[1700]: ACLs are not supported, ignoring. Nov 24 00:14:18.270756 zram_generator::config[1733]: No configuration found. Nov 24 00:14:18.283765 systemd-tmpfiles[1700]: Detected autofs mount point /boot during canonicalization of boot. Nov 24 00:14:18.283779 systemd-tmpfiles[1700]: Skipping /boot Nov 24 00:14:18.292310 systemd-tmpfiles[1700]: Detected autofs mount point /boot during canonicalization of boot. Nov 24 00:14:18.292325 systemd-tmpfiles[1700]: Skipping /boot Nov 24 00:14:18.550282 systemd[1]: Reloading finished in 319 ms. Nov 24 00:14:18.578840 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:14:18.604433 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:14:18.620047 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:14:18.621316 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 24 00:14:18.647681 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 24 00:14:18.652533 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:14:18.653780 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:14:18.658972 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:14:18.664563 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:14:18.668557 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:14:18.668753 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 24 00:14:18.669940 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 24 00:14:18.676304 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 24 00:14:18.681566 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 24 00:14:18.686331 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:14:18.688863 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:14:18.689056 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:14:18.694101 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:14:18.694299 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:14:18.699598 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:14:18.699801 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:14:18.711422 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 24 00:14:18.724647 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:14:18.724921 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:14:18.726268 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:14:18.737513 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 24 00:14:18.742416 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:14:18.747997 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:14:18.752046 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:14:18.752175 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 24 00:14:18.752353 systemd[1]: Reached target time-set.target - System Time Set. Nov 24 00:14:18.756328 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:14:18.758047 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:14:18.758247 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:14:18.763262 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 24 00:14:18.763468 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 24 00:14:18.768134 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:14:18.768316 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:14:18.773742 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:14:18.773932 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:14:18.778820 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 24 00:14:18.788862 systemd[1]: Finished ensure-sysext.service. Nov 24 00:14:18.792431 augenrules[1834]: No rules Nov 24 00:14:18.793253 systemd[1]: audit-rules.service: Deactivated successfully. Nov 24 00:14:18.793481 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 24 00:14:18.800962 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 24 00:14:18.801049 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 24 00:14:18.811487 systemd-resolved[1802]: Positive Trust Anchors: Nov 24 00:14:18.811502 systemd-resolved[1802]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 24 00:14:18.811534 systemd-resolved[1802]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 24 00:14:18.816131 systemd-resolved[1802]: Using system hostname 'ci-4459.2.1-a-09f0a397ab'. Nov 24 00:14:18.817851 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 24 00:14:18.822411 systemd[1]: Reached target network.target - Network. Nov 24 00:14:18.825914 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:14:19.213246 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 24 00:14:19.218715 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 24 00:14:19.812842 systemd-networkd[1567]: eth0: Gained IPv6LL Nov 24 00:14:19.815223 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 24 00:14:19.820399 systemd[1]: Reached target network-online.target - Network is Online. Nov 24 00:14:21.568813 ldconfig[1516]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 24 00:14:21.578347 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 24 00:14:21.584190 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 24 00:14:21.615015 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 24 00:14:21.620138 systemd[1]: Reached target sysinit.target - System Initialization. Nov 24 00:14:21.624434 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 24 00:14:21.629424 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 24 00:14:21.634405 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 24 00:14:21.639170 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 24 00:14:21.643536 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 24 00:14:21.649039 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 24 00:14:21.654469 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 24 00:14:21.654505 systemd[1]: Reached target paths.target - Path Units. Nov 24 00:14:21.657998 systemd[1]: Reached target timers.target - Timer Units. Nov 24 00:14:21.673803 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 24 00:14:21.679596 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 24 00:14:21.685484 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 24 00:14:21.691366 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 24 00:14:21.696549 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 24 00:14:21.704272 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 24 00:14:21.708905 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 24 00:14:21.714421 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 24 00:14:21.719771 systemd[1]: Reached target sockets.target - Socket Units. Nov 24 00:14:21.723501 systemd[1]: Reached target basic.target - Basic System. Nov 24 00:14:21.727152 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 24 00:14:21.727187 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 24 00:14:21.730024 systemd[1]: Starting chronyd.service - NTP client/server... Nov 24 00:14:21.734293 systemd[1]: Starting containerd.service - containerd container runtime... Nov 24 00:14:21.755420 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 24 00:14:21.760718 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 24 00:14:21.765437 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 24 00:14:21.770912 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 24 00:14:21.775836 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 24 00:14:21.777321 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 24 00:14:21.778261 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 24 00:14:21.783945 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Nov 24 00:14:21.784082 jq[1859]: false Nov 24 00:14:21.784918 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 24 00:14:21.786455 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 24 00:14:21.787558 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 24 00:14:21.792973 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 24 00:14:21.797754 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 24 00:14:21.803978 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Refreshing passwd entry cache Nov 24 00:14:21.803988 oslogin_cache_refresh[1861]: Refreshing passwd entry cache Nov 24 00:14:21.813069 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 24 00:14:21.813980 KVP[1862]: KVP starting; pid is:1862 Nov 24 00:14:21.817663 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Failure getting users, quitting Nov 24 00:14:21.817663 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 24 00:14:21.817663 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Refreshing group entry cache Nov 24 00:14:21.816998 oslogin_cache_refresh[1861]: Failure getting users, quitting Nov 24 00:14:21.817016 oslogin_cache_refresh[1861]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 24 00:14:21.817062 oslogin_cache_refresh[1861]: Refreshing group entry cache Nov 24 00:14:21.817987 KVP[1862]: KVP LIC Version: 3.1 Nov 24 00:14:21.817991 chronyd[1851]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 24 00:14:21.818631 kernel: hv_utils: KVP IC version 4.0 Nov 24 00:14:21.822065 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Failure getting groups, quitting Nov 24 00:14:21.822065 google_oslogin_nss_cache[1861]: oslogin_cache_refresh[1861]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 24 00:14:21.822022 oslogin_cache_refresh[1861]: Failure getting groups, quitting Nov 24 00:14:21.822035 oslogin_cache_refresh[1861]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 24 00:14:21.823793 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 24 00:14:21.828603 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 24 00:14:21.829171 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 24 00:14:21.831339 systemd[1]: Starting update-engine.service - Update Engine... Nov 24 00:14:21.834929 extend-filesystems[1860]: Found /dev/sda6 Nov 24 00:14:21.835934 chronyd[1851]: Timezone right/UTC failed leap second check, ignoring Nov 24 00:14:21.838000 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 24 00:14:21.836172 chronyd[1851]: Loaded seccomp filter (level 2) Nov 24 00:14:21.843679 systemd[1]: Started chronyd.service - NTP client/server. Nov 24 00:14:21.847948 extend-filesystems[1860]: Found /dev/sda9 Nov 24 00:14:21.848618 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 24 00:14:21.850901 extend-filesystems[1860]: Checking size of /dev/sda9 Nov 24 00:14:21.856279 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 24 00:14:21.868234 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 24 00:14:21.868619 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 24 00:14:21.868828 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 24 00:14:21.871122 jq[1883]: true Nov 24 00:14:21.873654 extend-filesystems[1860]: Old size kept for /dev/sda9 Nov 24 00:14:21.873763 systemd[1]: motdgen.service: Deactivated successfully. Nov 24 00:14:21.873975 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 24 00:14:21.881735 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 24 00:14:21.881945 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 24 00:14:21.886828 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 24 00:14:21.887036 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 24 00:14:21.895849 update_engine[1879]: I20251124 00:14:21.895776 1879 main.cc:92] Flatcar Update Engine starting Nov 24 00:14:21.907986 (ntainerd)[1896]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 24 00:14:21.911256 jq[1895]: true Nov 24 00:14:21.931352 systemd-logind[1874]: New seat seat0. Nov 24 00:14:21.932714 systemd-logind[1874]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 24 00:14:21.932908 systemd[1]: Started systemd-logind.service - User Login Management. Nov 24 00:14:21.939926 setup-nvidia[1864]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.1 Nov 24 00:14:21.992435 bash[1968]: Updated "/home/core/.ssh/authorized_keys" Nov 24 00:14:21.994280 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 24 00:14:21.999912 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 24 00:14:22.008896 dbus-daemon[1854]: [system] SELinux support is enabled Nov 24 00:14:22.009054 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 24 00:14:22.015366 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 24 00:14:22.015403 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 24 00:14:22.016409 dbus-daemon[1854]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 24 00:14:22.020427 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 24 00:14:22.020451 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 24 00:14:22.029739 systemd[1]: Started update-engine.service - Update Engine. Nov 24 00:14:22.030584 update_engine[1879]: I20251124 00:14:22.030520 1879 update_check_scheduler.cc:74] Next update check in 8m40s Nov 24 00:14:22.035486 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 24 00:14:22.053353 coreos-metadata[1853]: Nov 24 00:14:22.053 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 24 00:14:22.055745 coreos-metadata[1853]: Nov 24 00:14:22.055 INFO Fetch successful Nov 24 00:14:22.055904 coreos-metadata[1853]: Nov 24 00:14:22.055 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 24 00:14:22.059783 coreos-metadata[1853]: Nov 24 00:14:22.059 INFO Fetch successful Nov 24 00:14:22.060059 coreos-metadata[1853]: Nov 24 00:14:22.060 INFO Fetching http://168.63.129.16/machine/303d3762-c0d5-407f-acce-e3b1a055d341/9d81f312%2D3dbb%2D4e0f%2D8816%2Dcacc7247d7df.%5Fci%2D4459.2.1%2Da%2D09f0a397ab?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 24 00:14:22.061658 coreos-metadata[1853]: Nov 24 00:14:22.061 INFO Fetch successful Nov 24 00:14:22.061773 coreos-metadata[1853]: Nov 24 00:14:22.061 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 24 00:14:22.068744 coreos-metadata[1853]: Nov 24 00:14:22.068 INFO Fetch successful Nov 24 00:14:22.113401 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 24 00:14:22.118204 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 24 00:14:22.219435 locksmithd[1996]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 24 00:14:22.288107 sshd_keygen[1881]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 24 00:14:22.314016 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 24 00:14:22.320644 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 24 00:14:22.325145 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 24 00:14:22.344763 systemd[1]: issuegen.service: Deactivated successfully. Nov 24 00:14:22.344992 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 24 00:14:22.352225 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 24 00:14:22.358743 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 24 00:14:22.366670 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 24 00:14:22.372797 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 24 00:14:22.379265 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 24 00:14:22.383871 systemd[1]: Reached target getty.target - Login Prompts. Nov 24 00:14:22.615697 setup-nvidia[2034]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 00:14:22.615996 setup-nvidia[2034]: Dload Upload Total Spent Left Speed Nov 24 00:14:22.689518 containerd[1896]: time="2025-11-24T00:14:22Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 24 00:14:22.690197 containerd[1896]: time="2025-11-24T00:14:22.690150400Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 24 00:14:22.699542 containerd[1896]: time="2025-11-24T00:14:22.699507500Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.5µs" Nov 24 00:14:22.699806 containerd[1896]: time="2025-11-24T00:14:22.699533200Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 24 00:14:22.700246 containerd[1896]: time="2025-11-24T00:14:22.700217700Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 24 00:14:22.700667 containerd[1896]: time="2025-11-24T00:14:22.700484200Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 24 00:14:22.700667 containerd[1896]: time="2025-11-24T00:14:22.700523800Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 24 00:14:22.700667 containerd[1896]: time="2025-11-24T00:14:22.700555400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701084 containerd[1896]: time="2025-11-24T00:14:22.700869000Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701084 containerd[1896]: time="2025-11-24T00:14:22.700901800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701215 containerd[1896]: time="2025-11-24T00:14:22.701176900Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701215 containerd[1896]: time="2025-11-24T00:14:22.701208800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701273 containerd[1896]: time="2025-11-24T00:14:22.701223900Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701273 containerd[1896]: time="2025-11-24T00:14:22.701232800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701341 containerd[1896]: time="2025-11-24T00:14:22.701318200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701613 containerd[1896]: time="2025-11-24T00:14:22.701586800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701669 containerd[1896]: time="2025-11-24T00:14:22.701649300Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 24 00:14:22.701669 containerd[1896]: time="2025-11-24T00:14:22.701664900Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 24 00:14:22.701729 containerd[1896]: time="2025-11-24T00:14:22.701696300Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 24 00:14:22.701881 containerd[1896]: time="2025-11-24T00:14:22.701860200Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 24 00:14:22.701946 containerd[1896]: time="2025-11-24T00:14:22.701925300Z" level=info msg="metadata content store policy set" policy=shared Nov 24 00:14:22.734748 containerd[1896]: time="2025-11-24T00:14:22.734704900Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 24 00:14:22.734818 containerd[1896]: time="2025-11-24T00:14:22.734764000Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 24 00:14:22.734818 containerd[1896]: time="2025-11-24T00:14:22.734782200Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 24 00:14:22.734818 containerd[1896]: time="2025-11-24T00:14:22.734794300Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 24 00:14:22.734818 containerd[1896]: time="2025-11-24T00:14:22.734808200Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734819600Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734832300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734844200Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734855900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734867200Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734877900Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 24 00:14:22.734916 containerd[1896]: time="2025-11-24T00:14:22.734893200Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 24 00:14:22.735062 containerd[1896]: time="2025-11-24T00:14:22.735015500Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 24 00:14:22.735062 containerd[1896]: time="2025-11-24T00:14:22.735037500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 24 00:14:22.735062 containerd[1896]: time="2025-11-24T00:14:22.735051900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 24 00:14:22.735127 containerd[1896]: time="2025-11-24T00:14:22.735062700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 24 00:14:22.735127 containerd[1896]: time="2025-11-24T00:14:22.735074000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 24 00:14:22.735127 containerd[1896]: time="2025-11-24T00:14:22.735084000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 24 00:14:22.735127 containerd[1896]: time="2025-11-24T00:14:22.735096000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 24 00:14:22.735127 containerd[1896]: time="2025-11-24T00:14:22.735106400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 24 00:14:22.735127 containerd[1896]: time="2025-11-24T00:14:22.735117600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 24 00:14:22.735256 containerd[1896]: time="2025-11-24T00:14:22.735127600Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 24 00:14:22.735256 containerd[1896]: time="2025-11-24T00:14:22.735137900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 24 00:14:22.735256 containerd[1896]: time="2025-11-24T00:14:22.735183600Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 24 00:14:22.735256 containerd[1896]: time="2025-11-24T00:14:22.735198700Z" level=info msg="Start snapshots syncer" Nov 24 00:14:22.735256 containerd[1896]: time="2025-11-24T00:14:22.735240200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 24 00:14:22.735546 containerd[1896]: time="2025-11-24T00:14:22.735503300Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 24 00:14:22.735689 containerd[1896]: time="2025-11-24T00:14:22.735557100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 24 00:14:22.735689 containerd[1896]: time="2025-11-24T00:14:22.735662300Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 24 00:14:22.735796 containerd[1896]: time="2025-11-24T00:14:22.735771100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 24 00:14:22.735826 containerd[1896]: time="2025-11-24T00:14:22.735804400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 24 00:14:22.735826 containerd[1896]: time="2025-11-24T00:14:22.735816600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 24 00:14:22.735873 containerd[1896]: time="2025-11-24T00:14:22.735826600Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 24 00:14:22.735873 containerd[1896]: time="2025-11-24T00:14:22.735838300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 24 00:14:22.735873 containerd[1896]: time="2025-11-24T00:14:22.735850200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 24 00:14:22.735873 containerd[1896]: time="2025-11-24T00:14:22.735860600Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 24 00:14:22.735958 containerd[1896]: time="2025-11-24T00:14:22.735882300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 24 00:14:22.735958 containerd[1896]: time="2025-11-24T00:14:22.735894800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 24 00:14:22.735958 containerd[1896]: time="2025-11-24T00:14:22.735905400Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 24 00:14:22.735958 containerd[1896]: time="2025-11-24T00:14:22.735932300Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 24 00:14:22.735958 containerd[1896]: time="2025-11-24T00:14:22.735947900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 24 00:14:22.735958 containerd[1896]: time="2025-11-24T00:14:22.735956500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.735965300Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.735973000Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.735984900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.736002400Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.736018100Z" level=info msg="runtime interface created" Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.736023900Z" level=info msg="created NRI interface" Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.736032200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.736042900Z" level=info msg="Connect containerd service" Nov 24 00:14:22.736080 containerd[1896]: time="2025-11-24T00:14:22.736064200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 24 00:14:22.736788 containerd[1896]: time="2025-11-24T00:14:22.736759700Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 24 00:14:23.102397 containerd[1896]: time="2025-11-24T00:14:23.102341000Z" level=info msg="Start subscribing containerd event" Nov 24 00:14:23.102532 containerd[1896]: time="2025-11-24T00:14:23.102413700Z" level=info msg="Start recovering state" Nov 24 00:14:23.102532 containerd[1896]: time="2025-11-24T00:14:23.102432400Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 24 00:14:23.102592 containerd[1896]: time="2025-11-24T00:14:23.102535800Z" level=info msg="Start event monitor" Nov 24 00:14:23.102592 containerd[1896]: time="2025-11-24T00:14:23.102565800Z" level=info msg="Start cni network conf syncer for default" Nov 24 00:14:23.102592 containerd[1896]: time="2025-11-24T00:14:23.102577000Z" level=info msg="Start streaming server" Nov 24 00:14:23.102592 containerd[1896]: time="2025-11-24T00:14:23.102586100Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 24 00:14:23.102706 containerd[1896]: time="2025-11-24T00:14:23.102592400Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 24 00:14:23.102706 containerd[1896]: time="2025-11-24T00:14:23.102594300Z" level=info msg="runtime interface starting up..." Nov 24 00:14:23.102706 containerd[1896]: time="2025-11-24T00:14:23.102690300Z" level=info msg="starting plugins..." Nov 24 00:14:23.102771 containerd[1896]: time="2025-11-24T00:14:23.102709600Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 24 00:14:23.102872 containerd[1896]: time="2025-11-24T00:14:23.102843600Z" level=info msg="containerd successfully booted in 0.413883s" Nov 24 00:14:23.102962 systemd[1]: Started containerd.service - containerd container runtime. Nov 24 00:14:23.624988 waagent[2024]: 2025-11-24T00:14:23.624907Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 24 00:14:23.629247 waagent[2024]: 2025-11-24T00:14:23.629199Z INFO Daemon Daemon OS: flatcar 4459.2.1 Nov 24 00:14:23.632580 waagent[2024]: 2025-11-24T00:14:23.632531Z INFO Daemon Daemon Python: 3.11.13 Nov 24 00:14:23.635879 waagent[2024]: 2025-11-24T00:14:23.635810Z INFO Daemon Daemon Run daemon Nov 24 00:14:23.638815 waagent[2024]: 2025-11-24T00:14:23.638772Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.1' Nov 24 00:14:23.645343 waagent[2024]: 2025-11-24T00:14:23.645294Z INFO Daemon Daemon Using waagent for provisioning Nov 24 00:14:23.649110 waagent[2024]: 2025-11-24T00:14:23.649069Z INFO Daemon Daemon Activate resource disk Nov 24 00:14:23.652465 waagent[2024]: 2025-11-24T00:14:23.652419Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 24 00:14:23.662595 waagent[2024]: 2025-11-24T00:14:23.662549Z INFO Daemon Daemon Found device: sdb Nov 24 00:14:23.671785 waagent[2024]: 2025-11-24T00:14:23.671729Z INFO Daemon Daemon Examining partition table Nov 24 00:14:23.703709 waagent[2024]: 2025-11-24T00:14:23.703603Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 24 00:14:23.739290 waagent[2024]: 2025-11-24T00:14:23.739215Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 24 00:14:23.743914 waagent[2024]: 2025-11-24T00:14:23.743856Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 24 00:14:24.423072 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#91 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:24.440317 kernel: sdb: sdb1 Nov 24 00:14:24.468416 waagent[2024]: 2025-11-24T00:14:24.468334Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 24 00:14:24.486635 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#322 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:14:24.499630 kernel: sdb: sdb1 Nov 24 00:14:24.499862 waagent[2024]: 2025-11-24T00:14:24.499804Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 24 00:14:30.372942 login[2027]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 24 00:14:30.374696 login[2028]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 24 00:14:30.381556 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 24 00:14:30.382747 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 24 00:14:30.389095 systemd-logind[1874]: New session 2 of user core. Nov 24 00:14:30.419809 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 24 00:14:30.422408 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 24 00:14:30.454194 (systemd)[2115]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 24 00:14:30.456186 waagent[2024]: 2025-11-24T00:14:30.456123Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 24 00:14:30.458631 systemd-logind[1874]: New session c1 of user core. Nov 24 00:14:30.569646 kernel: EXT4-fs (sdb1): mounted filesystem 82c6cd3e-e648-4849-8811-d9e65c7bb1a5 r/w with ordered data mode. Quota mode: none. Nov 24 00:14:30.569795 waagent[2024]: 2025-11-24T00:14:30.569727Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 24 00:14:30.575926 waagent[2024]: 2025-11-24T00:14:30.575877Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 24 00:14:30.580314 waagent[2024]: 2025-11-24T00:14:30.580262Z INFO Daemon Daemon Running default provisioning handler Nov 24 00:14:30.591460 waagent[2024]: 2025-11-24T00:14:30.591408Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 24 00:14:30.601619 waagent[2024]: 2025-11-24T00:14:30.601558Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 24 00:14:30.603974 waagent[2024]: 2025-11-24T00:14:30.603921Z INFO Daemon Daemon cloud-init is enabled: False Nov 24 00:14:30.604667 waagent[2024]: 2025-11-24T00:14:30.604628Z INFO Daemon Daemon Copying ovf-env.xml Nov 24 00:14:30.658389 waagent[2024]: 2025-11-24T00:14:30.658266Z INFO Daemon Daemon Successfully mounted dvd Nov 24 00:14:30.687883 systemd[2115]: Queued start job for default target default.target. Nov 24 00:14:30.704279 systemd[2115]: Created slice app.slice - User Application Slice. Nov 24 00:14:30.704309 systemd[2115]: Reached target paths.target - Paths. Nov 24 00:14:30.704356 systemd[2115]: Reached target timers.target - Timers. Nov 24 00:14:30.705579 systemd[2115]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 00:14:30.716385 systemd[2115]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:14:30.716504 systemd[2115]: Reached target sockets.target - Sockets. Nov 24 00:14:30.716558 systemd[2115]: Reached target basic.target - Basic System. Nov 24 00:14:30.716602 systemd[2115]: Reached target default.target - Main User Target. Nov 24 00:14:30.716652 systemd[2115]: Startup finished in 251ms. Nov 24 00:14:30.716684 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 24 00:14:30.718004 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 24 00:14:30.802707 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 24 00:14:30.803039 waagent[2024]: 2025-11-24T00:14:30.802984Z INFO Daemon Daemon Detect protocol endpoint Nov 24 00:14:30.827074 waagent[2024]: 2025-11-24T00:14:30.804480Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 24 00:14:30.827074 waagent[2024]: 2025-11-24T00:14:30.805924Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 24 00:14:30.827074 waagent[2024]: 2025-11-24T00:14:30.806625Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 24 00:14:30.827074 waagent[2024]: 2025-11-24T00:14:30.807430Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 24 00:14:30.827074 waagent[2024]: 2025-11-24T00:14:30.808720Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 24 00:14:30.829678 waagent[2024]: 2025-11-24T00:14:30.829638Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 24 00:14:30.841715 waagent[2024]: 2025-11-24T00:14:30.831328Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 24 00:14:30.841715 waagent[2024]: 2025-11-24T00:14:30.832616Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 24 00:14:30.949317 waagent[2024]: 2025-11-24T00:14:30.949188Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 24 00:14:30.950938 waagent[2024]: 2025-11-24T00:14:30.950889Z INFO Daemon Daemon Forcing an update of the goal state. Nov 24 00:14:30.954636 waagent[2024]: 2025-11-24T00:14:30.954581Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 24 00:14:30.966087 waagent[2024]: 2025-11-24T00:14:30.966044Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Nov 24 00:14:30.968004 waagent[2024]: 2025-11-24T00:14:30.967962Z INFO Daemon Nov 24 00:14:30.968288 waagent[2024]: 2025-11-24T00:14:30.968252Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 70e4c602-6798-4891-bb11-ec00d4fdbf7d eTag: 9379603226974283287 source: Fabric] Nov 24 00:14:30.969969 waagent[2024]: 2025-11-24T00:14:30.969931Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 24 00:14:30.970723 waagent[2024]: 2025-11-24T00:14:30.970684Z INFO Daemon Nov 24 00:14:30.971154 waagent[2024]: 2025-11-24T00:14:30.971121Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 24 00:14:30.992572 waagent[2024]: 2025-11-24T00:14:30.992537Z INFO Daemon Daemon Downloading artifacts profile blob Nov 24 00:14:31.054807 waagent[2024]: 2025-11-24T00:14:31.054735Z INFO Daemon Downloaded certificate {'thumbprint': 'B826EAC9442E52D1835BDBCD284B7103A30822BD', 'hasPrivateKey': True} Nov 24 00:14:31.056075 waagent[2024]: 2025-11-24T00:14:31.056032Z INFO Daemon Fetch goal state completed Nov 24 00:14:31.098916 waagent[2024]: 2025-11-24T00:14:31.098846Z INFO Daemon Daemon Starting provisioning Nov 24 00:14:31.100526 waagent[2024]: 2025-11-24T00:14:31.100446Z INFO Daemon Daemon Handle ovf-env.xml. Nov 24 00:14:31.101925 waagent[2024]: 2025-11-24T00:14:31.101854Z INFO Daemon Daemon Set hostname [ci-4459.2.1-a-09f0a397ab] Nov 24 00:14:31.121850 waagent[2024]: 2025-11-24T00:14:31.121797Z INFO Daemon Daemon Publish hostname [ci-4459.2.1-a-09f0a397ab] Nov 24 00:14:31.123648 waagent[2024]: 2025-11-24T00:14:31.123586Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 24 00:14:31.124474 waagent[2024]: 2025-11-24T00:14:31.124427Z INFO Daemon Daemon Primary interface is [eth0] Nov 24 00:14:31.134676 systemd-networkd[1567]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:14:31.134687 systemd-networkd[1567]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:14:31.134710 systemd-networkd[1567]: eth0: DHCP lease lost Nov 24 00:14:31.135542 waagent[2024]: 2025-11-24T00:14:31.135493Z INFO Daemon Daemon Create user account if not exists Nov 24 00:14:31.139753 waagent[2024]: 2025-11-24T00:14:31.139702Z INFO Daemon Daemon User core already exists, skip useradd Nov 24 00:14:31.141268 waagent[2024]: 2025-11-24T00:14:31.141222Z INFO Daemon Daemon Configure sudoer Nov 24 00:14:31.149040 waagent[2024]: 2025-11-24T00:14:31.148988Z INFO Daemon Daemon Configure sshd Nov 24 00:14:31.153712 waagent[2024]: 2025-11-24T00:14:31.153668Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 24 00:14:31.155258 waagent[2024]: 2025-11-24T00:14:31.155212Z INFO Daemon Daemon Deploy ssh public key. Nov 24 00:14:31.195689 systemd-networkd[1567]: eth0: DHCPv4 address 10.200.4.11/24, gateway 10.200.4.1 acquired from 168.63.129.16 Nov 24 00:14:31.373416 login[2027]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 24 00:14:31.378272 systemd-logind[1874]: New session 1 of user core. Nov 24 00:14:31.392787 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 24 00:14:32.256629 waagent[2024]: 2025-11-24T00:14:32.256560Z INFO Daemon Daemon Provisioning complete Nov 24 00:14:32.266121 waagent[2024]: 2025-11-24T00:14:32.266078Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 24 00:14:32.269406 waagent[2024]: 2025-11-24T00:14:32.269356Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 24 00:14:32.270698 waagent[2024]: 2025-11-24T00:14:32.270660Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 24 00:14:32.450423 waagent[2173]: 2025-11-24T00:14:32.450341Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 24 00:14:32.450812 waagent[2173]: 2025-11-24T00:14:32.450491Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.1 Nov 24 00:14:32.450812 waagent[2173]: 2025-11-24T00:14:32.450562Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 24 00:14:32.450812 waagent[2173]: 2025-11-24T00:14:32.450652Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 24 00:14:32.508743 waagent[2173]: 2025-11-24T00:14:32.508590Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 24 00:14:32.508902 waagent[2173]: 2025-11-24T00:14:32.508862Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:14:32.508982 waagent[2173]: 2025-11-24T00:14:32.508948Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:14:32.516340 waagent[2173]: 2025-11-24T00:14:32.516286Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 24 00:14:32.522439 waagent[2173]: 2025-11-24T00:14:32.522385Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Nov 24 00:14:32.522942 waagent[2173]: 2025-11-24T00:14:32.522901Z INFO ExtHandler Nov 24 00:14:32.523027 waagent[2173]: 2025-11-24T00:14:32.522993Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 724678cb-8201-4696-8625-c47db77739f0 eTag: 9379603226974283287 source: Fabric] Nov 24 00:14:32.523355 waagent[2173]: 2025-11-24T00:14:32.523315Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 24 00:14:32.523987 waagent[2173]: 2025-11-24T00:14:32.523949Z INFO ExtHandler Nov 24 00:14:32.524062 waagent[2173]: 2025-11-24T00:14:32.524030Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 24 00:14:32.527377 waagent[2173]: 2025-11-24T00:14:32.527346Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 24 00:14:32.587864 waagent[2173]: 2025-11-24T00:14:32.587805Z INFO ExtHandler Downloaded certificate {'thumbprint': 'B826EAC9442E52D1835BDBCD284B7103A30822BD', 'hasPrivateKey': True} Nov 24 00:14:32.588373 waagent[2173]: 2025-11-24T00:14:32.588332Z INFO ExtHandler Fetch goal state completed Nov 24 00:14:32.600821 waagent[2173]: 2025-11-24T00:14:32.600767Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 24 00:14:32.605774 waagent[2173]: 2025-11-24T00:14:32.605731Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2173 Nov 24 00:14:32.605911 waagent[2173]: 2025-11-24T00:14:32.605876Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 24 00:14:32.606252 waagent[2173]: 2025-11-24T00:14:32.606214Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 24 00:14:32.607962 waagent[2173]: 2025-11-24T00:14:32.607911Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.1', '', 'Flatcar Container Linux by Kinvolk'] Nov 24 00:14:32.608428 waagent[2173]: 2025-11-24T00:14:32.608387Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 24 00:14:32.608587 waagent[2173]: 2025-11-24T00:14:32.608550Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 24 00:14:32.609229 waagent[2173]: 2025-11-24T00:14:32.609189Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 24 00:14:32.700047 waagent[2173]: 2025-11-24T00:14:32.700001Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 24 00:14:32.700252 waagent[2173]: 2025-11-24T00:14:32.700214Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 24 00:14:32.707465 waagent[2173]: 2025-11-24T00:14:32.707431Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 24 00:14:32.714566 systemd[1]: Reload requested from client PID 2188 ('systemctl') (unit waagent.service)... Nov 24 00:14:32.714583 systemd[1]: Reloading... Nov 24 00:14:32.777870 zram_generator::config[2228]: No configuration found. Nov 24 00:14:33.115848 systemd[1]: Reloading finished in 400 ms. Nov 24 00:14:33.141213 waagent[2173]: 2025-11-24T00:14:33.141143Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 24 00:14:33.141324 waagent[2173]: 2025-11-24T00:14:33.141299Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 24 00:14:33.323550 setup-nvidia[2034]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 5 472M 5 25.2M 0 0 26.7M 0 0:00:17 --:--:-- 0:00:17 26.7M\u000d 13 472M 13 61.4M 0 0 31.6M 0 0:00:14 0:00:01 0:00:13 31.6M\u000d 21 472M 21 100M 0 0 34.0M 0 0:00:13 0:00:02 0:00:11 34.0M\u000d 31 472M 31 147M 0 0 37.3M 0 0:00:12 0:00:03 0:00:09 37.3M\u000d 40 472M 40 193M 0 0 38.9M 0 0:00:12 0:00:04 0:00:08 38.9M\u000d 46 472M 46 219M 0 0 36.8M 0 0:00:12 0:00:05 0:00:07 38.7M\u000d 57 472M 57 269M 0 0 38.7M 0 0:00:12 0:00:06 0:00:06 41.5M\u000d 66 472M 66 314M 0 0 39.5M 0 0:00:11 0:00:07 0:00:04 42.8M\u000d 78 472M 78 369M 0 0 41.2M 0 0:00:11 0:00:08 0:00:03 44.3M\u000d 95 472M 95 453M 0 0 45.4M 0 0:00:10 0:00:09 0:00:01 52.0M\u000d100 472M 100 472M 0 0 44.1M 0 0:00:10 0:00:10 --:--:-- 53.2M Nov 24 00:14:33.364628 waagent[2173]: 2025-11-24T00:14:33.364488Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 24 00:14:33.366953 waagent[2173]: 2025-11-24T00:14:33.366869Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 24 00:14:33.367883 waagent[2173]: 2025-11-24T00:14:33.367828Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 24 00:14:33.368260 waagent[2173]: 2025-11-24T00:14:33.368211Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:14:33.368349 waagent[2173]: 2025-11-24T00:14:33.368315Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:14:33.368583 waagent[2173]: 2025-11-24T00:14:33.368542Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 24 00:14:33.368815 waagent[2173]: 2025-11-24T00:14:33.368778Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 24 00:14:33.368815 waagent[2173]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 24 00:14:33.368815 waagent[2173]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Nov 24 00:14:33.368815 waagent[2173]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 24 00:14:33.368815 waagent[2173]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:14:33.368815 waagent[2173]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:14:33.368815 waagent[2173]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:14:33.369893 waagent[2173]: 2025-11-24T00:14:33.369805Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 24 00:14:33.370351 waagent[2173]: 2025-11-24T00:14:33.370305Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:14:33.371635 waagent[2173]: 2025-11-24T00:14:33.370429Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:14:33.371635 waagent[2173]: 2025-11-24T00:14:33.370817Z INFO EnvHandler ExtHandler Configure routes Nov 24 00:14:33.371635 waagent[2173]: 2025-11-24T00:14:33.370934Z INFO EnvHandler ExtHandler Gateway:None Nov 24 00:14:33.371635 waagent[2173]: 2025-11-24T00:14:33.371299Z INFO EnvHandler ExtHandler Routes:None Nov 24 00:14:33.371903 waagent[2173]: 2025-11-24T00:14:33.371845Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 24 00:14:33.372029 waagent[2173]: 2025-11-24T00:14:33.371978Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 24 00:14:33.372532 waagent[2173]: 2025-11-24T00:14:33.372483Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 24 00:14:33.372586 waagent[2173]: 2025-11-24T00:14:33.372540Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 24 00:14:33.375629 waagent[2173]: 2025-11-24T00:14:33.374713Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 24 00:14:33.379316 waagent[2173]: 2025-11-24T00:14:33.379270Z INFO ExtHandler ExtHandler Nov 24 00:14:33.379402 waagent[2173]: 2025-11-24T00:14:33.379365Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 94b00ca9-4dff-4644-b7b3-f43d81057ce8 correlation d2f52795-6817-4e55-bffa-af017f44dca8 created: 2025-11-24T00:13:19.366783Z] Nov 24 00:14:33.380904 waagent[2173]: 2025-11-24T00:14:33.380857Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 24 00:14:33.384947 waagent[2173]: 2025-11-24T00:14:33.384902Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 5 ms] Nov 24 00:14:33.428042 waagent[2173]: 2025-11-24T00:14:33.427970Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 24 00:14:33.428042 waagent[2173]: Try `iptables -h' or 'iptables --help' for more information.) Nov 24 00:14:33.429584 waagent[2173]: 2025-11-24T00:14:33.429541Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 050E68F7-0204-478C-8159-C3D9517E3ED6;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 24 00:14:33.449045 waagent[2173]: 2025-11-24T00:14:33.448980Z INFO MonitorHandler ExtHandler Network interfaces: Nov 24 00:14:33.449045 waagent[2173]: Executing ['ip', '-a', '-o', 'link']: Nov 24 00:14:33.449045 waagent[2173]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 24 00:14:33.449045 waagent[2173]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:d6:67:19 brd ff:ff:ff:ff:ff:ff Nov 24 00:14:33.449045 waagent[2173]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 24 00:14:33.449045 waagent[2173]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 24 00:14:33.449045 waagent[2173]: 2: eth0 inet 10.200.4.11/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 24 00:14:33.449045 waagent[2173]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 24 00:14:33.449045 waagent[2173]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 24 00:14:33.449045 waagent[2173]: 2: eth0 inet6 fe80::20d:3aff:fed6:6719/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 24 00:14:33.546299 waagent[2173]: 2025-11-24T00:14:33.546211Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 24 00:14:33.546299 waagent[2173]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:14:33.546299 waagent[2173]: pkts bytes target prot opt in out source destination Nov 24 00:14:33.546299 waagent[2173]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:14:33.546299 waagent[2173]: pkts bytes target prot opt in out source destination Nov 24 00:14:33.546299 waagent[2173]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:14:33.546299 waagent[2173]: pkts bytes target prot opt in out source destination Nov 24 00:14:33.546299 waagent[2173]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 24 00:14:33.546299 waagent[2173]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 24 00:14:33.546299 waagent[2173]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 24 00:14:33.555106 waagent[2173]: 2025-11-24T00:14:33.555033Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 24 00:14:33.555106 waagent[2173]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:14:33.555106 waagent[2173]: pkts bytes target prot opt in out source destination Nov 24 00:14:33.555106 waagent[2173]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:14:33.555106 waagent[2173]: pkts bytes target prot opt in out source destination Nov 24 00:14:33.555106 waagent[2173]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:14:33.555106 waagent[2173]: pkts bytes target prot opt in out source destination Nov 24 00:14:33.555106 waagent[2173]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 24 00:14:33.555106 waagent[2173]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 24 00:14:33.555106 waagent[2173]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 24 00:14:33.557466 waagent[2173]: 2025-11-24T00:14:33.557037Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 24 00:14:33.557466 waagent[2173]: 2025-11-24T00:14:33.557275Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 24 00:14:35.516856 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 24 00:14:35.519994 systemd[1]: Started sshd@0-10.200.4.11:22-10.200.16.10:37100.service - OpenSSH per-connection server daemon (10.200.16.10:37100). Nov 24 00:14:36.228681 sshd[2331]: Accepted publickey for core from 10.200.16.10 port 37100 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:14:36.264048 sshd-session[2331]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:14:36.282640 systemd-logind[1874]: New session 3 of user core. Nov 24 00:14:36.289821 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 24 00:14:36.750740 systemd[1]: Started sshd@1-10.200.4.11:22-10.200.16.10:37114.service - OpenSSH per-connection server daemon (10.200.16.10:37114). Nov 24 00:14:37.388711 sshd[2337]: Accepted publickey for core from 10.200.16.10 port 37114 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:14:37.390055 sshd-session[2337]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:14:37.399158 systemd-logind[1874]: New session 4 of user core. Nov 24 00:14:37.405911 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 24 00:14:37.811718 sshd[2340]: Connection closed by 10.200.16.10 port 37114 Nov 24 00:14:37.811839 sshd-session[2337]: pam_unix(sshd:session): session closed for user core Nov 24 00:14:37.816601 systemd[1]: sshd@1-10.200.4.11:22-10.200.16.10:37114.service: Deactivated successfully. Nov 24 00:14:37.817097 systemd-logind[1874]: Session 4 logged out. Waiting for processes to exit. Nov 24 00:14:37.819427 systemd[1]: session-4.scope: Deactivated successfully. Nov 24 00:14:37.821140 systemd-logind[1874]: Removed session 4. Nov 24 00:14:37.916328 systemd[1]: Started sshd@2-10.200.4.11:22-10.200.16.10:37116.service - OpenSSH per-connection server daemon (10.200.16.10:37116). Nov 24 00:14:38.530062 sshd[2346]: Accepted publickey for core from 10.200.16.10 port 37116 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:14:38.531398 sshd-session[2346]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:14:38.538678 systemd-logind[1874]: New session 5 of user core. Nov 24 00:14:38.544916 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 24 00:14:38.962901 sshd[2349]: Connection closed by 10.200.16.10 port 37116 Nov 24 00:14:38.963303 sshd-session[2346]: pam_unix(sshd:session): session closed for user core Nov 24 00:14:38.967526 systemd[1]: sshd@2-10.200.4.11:22-10.200.16.10:37116.service: Deactivated successfully. Nov 24 00:14:38.970226 systemd[1]: session-5.scope: Deactivated successfully. Nov 24 00:14:38.973833 systemd-logind[1874]: Session 5 logged out. Waiting for processes to exit. Nov 24 00:14:38.975582 systemd-logind[1874]: Removed session 5. Nov 24 00:14:43.659204 setup-nvidia[1864]: Downloading NVIDIA 535.261.03 Driver Nov 24 00:14:43.663518 setup-nvidia[2355]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 00:14:43.663787 setup-nvidia[2355]: Dload Upload Total Spent Left Speed Nov 24 00:14:45.618149 chronyd[1851]: Selected source PHC0 Nov 24 00:14:49.073878 systemd[1]: Started sshd@3-10.200.4.11:22-10.200.16.10:59892.service - OpenSSH per-connection server daemon (10.200.16.10:59892). Nov 24 00:14:49.678968 sshd[2357]: Accepted publickey for core from 10.200.16.10 port 59892 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:14:49.680174 sshd-session[2357]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:14:49.685229 systemd-logind[1874]: New session 6 of user core. Nov 24 00:14:49.694725 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 24 00:14:50.115939 sshd[2360]: Connection closed by 10.200.16.10 port 59892 Nov 24 00:14:50.116323 sshd-session[2357]: pam_unix(sshd:session): session closed for user core Nov 24 00:14:50.119819 systemd[1]: sshd@3-10.200.4.11:22-10.200.16.10:59892.service: Deactivated successfully. Nov 24 00:14:50.121518 systemd[1]: session-6.scope: Deactivated successfully. Nov 24 00:14:50.122209 systemd-logind[1874]: Session 6 logged out. Waiting for processes to exit. Nov 24 00:14:50.123084 systemd-logind[1874]: Removed session 6. Nov 24 00:14:50.226871 systemd[1]: Started sshd@4-10.200.4.11:22-10.200.16.10:58064.service - OpenSSH per-connection server daemon (10.200.16.10:58064). Nov 24 00:14:50.841209 sshd[2366]: Accepted publickey for core from 10.200.16.10 port 58064 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:14:50.842393 sshd-session[2366]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:14:50.846634 systemd-logind[1874]: New session 7 of user core. Nov 24 00:14:50.854726 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 24 00:14:51.649074 sshd[2369]: Connection closed by 10.200.16.10 port 58064 Nov 24 00:14:51.649479 sshd-session[2366]: pam_unix(sshd:session): session closed for user core Nov 24 00:14:51.653116 systemd[1]: sshd@4-10.200.4.11:22-10.200.16.10:58064.service: Deactivated successfully. Nov 24 00:14:51.654762 systemd[1]: session-7.scope: Deactivated successfully. Nov 24 00:14:51.655474 systemd-logind[1874]: Session 7 logged out. Waiting for processes to exit. Nov 24 00:14:51.656685 systemd-logind[1874]: Removed session 7. Nov 24 00:15:01.374801 systemd[1]: Started sshd@5-10.200.4.11:22-10.200.16.10:50464.service - OpenSSH per-connection server daemon (10.200.16.10:50464). Nov 24 00:15:01.992206 sshd[2375]: Accepted publickey for core from 10.200.16.10 port 50464 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:01.993435 sshd-session[2375]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:01.997669 systemd-logind[1874]: New session 8 of user core. Nov 24 00:15:02.005735 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 24 00:15:02.410759 sshd[2378]: Connection closed by 10.200.16.10 port 50464 Nov 24 00:15:02.411130 sshd-session[2375]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:02.414624 systemd[1]: sshd@5-10.200.4.11:22-10.200.16.10:50464.service: Deactivated successfully. Nov 24 00:15:02.416379 systemd[1]: session-8.scope: Deactivated successfully. Nov 24 00:15:02.417141 systemd-logind[1874]: Session 8 logged out. Waiting for processes to exit. Nov 24 00:15:02.418056 systemd-logind[1874]: Removed session 8. Nov 24 00:15:02.525884 systemd[1]: Started sshd@6-10.200.4.11:22-10.200.16.10:50480.service - OpenSSH per-connection server daemon (10.200.16.10:50480). Nov 24 00:15:03.117703 sshd[2384]: Accepted publickey for core from 10.200.16.10 port 50480 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:03.118925 sshd-session[2384]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:03.123166 systemd-logind[1874]: New session 9 of user core. Nov 24 00:15:03.142745 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 24 00:15:03.542041 sshd[2387]: Connection closed by 10.200.16.10 port 50480 Nov 24 00:15:03.542347 sshd-session[2384]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:03.545732 systemd[1]: sshd@6-10.200.4.11:22-10.200.16.10:50480.service: Deactivated successfully. Nov 24 00:15:03.547270 systemd[1]: session-9.scope: Deactivated successfully. Nov 24 00:15:03.547950 systemd-logind[1874]: Session 9 logged out. Waiting for processes to exit. Nov 24 00:15:03.548833 systemd-logind[1874]: Removed session 9. Nov 24 00:15:06.001520 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 24 00:15:07.395320 update_engine[1879]: I20251124 00:15:07.395237 1879 update_attempter.cc:509] Updating boot flags... Nov 24 00:15:13.653969 systemd[1]: Started sshd@7-10.200.4.11:22-10.200.16.10:57654.service - OpenSSH per-connection server daemon (10.200.16.10:57654). Nov 24 00:15:14.249756 sshd[2561]: Accepted publickey for core from 10.200.16.10 port 57654 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:14.250986 sshd-session[2561]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:14.255176 systemd-logind[1874]: New session 10 of user core. Nov 24 00:15:14.264733 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 24 00:15:14.669234 sshd[2564]: Connection closed by 10.200.16.10 port 57654 Nov 24 00:15:14.669644 sshd-session[2561]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:14.673123 systemd[1]: sshd@7-10.200.4.11:22-10.200.16.10:57654.service: Deactivated successfully. Nov 24 00:15:14.674819 systemd[1]: session-10.scope: Deactivated successfully. Nov 24 00:15:14.675481 systemd-logind[1874]: Session 10 logged out. Waiting for processes to exit. Nov 24 00:15:14.676386 systemd-logind[1874]: Removed session 10. Nov 24 00:15:14.776730 systemd[1]: Started sshd@8-10.200.4.11:22-10.200.16.10:57668.service - OpenSSH per-connection server daemon (10.200.16.10:57668). Nov 24 00:15:15.379882 sshd[2571]: Accepted publickey for core from 10.200.16.10 port 57668 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:15.381057 sshd-session[2571]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:15.385054 systemd-logind[1874]: New session 11 of user core. Nov 24 00:15:15.401737 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 24 00:15:15.805165 sshd[2574]: Connection closed by 10.200.16.10 port 57668 Nov 24 00:15:15.805539 sshd-session[2571]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:15.809152 systemd[1]: sshd@8-10.200.4.11:22-10.200.16.10:57668.service: Deactivated successfully. Nov 24 00:15:15.811021 systemd[1]: session-11.scope: Deactivated successfully. Nov 24 00:15:15.811865 systemd-logind[1874]: Session 11 logged out. Waiting for processes to exit. Nov 24 00:15:15.812779 systemd-logind[1874]: Removed session 11. Nov 24 00:15:25.925260 systemd[1]: Started sshd@9-10.200.4.11:22-10.200.16.10:54144.service - OpenSSH per-connection server daemon (10.200.16.10:54144). Nov 24 00:15:26.535231 sshd[2580]: Accepted publickey for core from 10.200.16.10 port 54144 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:26.536469 sshd-session[2580]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:26.540761 systemd-logind[1874]: New session 12 of user core. Nov 24 00:15:26.551731 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 24 00:15:26.959855 sshd[2583]: Connection closed by 10.200.16.10 port 54144 Nov 24 00:15:26.960231 sshd-session[2580]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:26.963744 systemd[1]: sshd@9-10.200.4.11:22-10.200.16.10:54144.service: Deactivated successfully. Nov 24 00:15:26.965375 systemd[1]: session-12.scope: Deactivated successfully. Nov 24 00:15:26.966071 systemd-logind[1874]: Session 12 logged out. Waiting for processes to exit. Nov 24 00:15:26.967030 systemd-logind[1874]: Removed session 12. Nov 24 00:15:27.066945 systemd[1]: Started sshd@10-10.200.4.11:22-10.200.16.10:54154.service - OpenSSH per-connection server daemon (10.200.16.10:54154). Nov 24 00:15:27.679360 sshd[2589]: Accepted publickey for core from 10.200.16.10 port 54154 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:27.680607 sshd-session[2589]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:27.684984 systemd-logind[1874]: New session 13 of user core. Nov 24 00:15:27.697728 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 24 00:15:28.104576 sshd[2592]: Connection closed by 10.200.16.10 port 54154 Nov 24 00:15:28.104968 sshd-session[2589]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:28.108489 systemd[1]: sshd@10-10.200.4.11:22-10.200.16.10:54154.service: Deactivated successfully. Nov 24 00:15:28.110055 systemd[1]: session-13.scope: Deactivated successfully. Nov 24 00:15:28.110768 systemd-logind[1874]: Session 13 logged out. Waiting for processes to exit. Nov 24 00:15:28.111735 systemd-logind[1874]: Removed session 13. Nov 24 00:15:38.222744 systemd[1]: Started sshd@11-10.200.4.11:22-10.200.16.10:45250.service - OpenSSH per-connection server daemon (10.200.16.10:45250). Nov 24 00:15:38.843940 sshd[2598]: Accepted publickey for core from 10.200.16.10 port 45250 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:38.845144 sshd-session[2598]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:38.849219 systemd-logind[1874]: New session 14 of user core. Nov 24 00:15:38.859738 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 24 00:15:39.265352 sshd[2601]: Connection closed by 10.200.16.10 port 45250 Nov 24 00:15:39.265640 sshd-session[2598]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:39.268447 systemd[1]: sshd@11-10.200.4.11:22-10.200.16.10:45250.service: Deactivated successfully. Nov 24 00:15:39.270295 systemd[1]: session-14.scope: Deactivated successfully. Nov 24 00:15:39.271583 systemd-logind[1874]: Session 14 logged out. Waiting for processes to exit. Nov 24 00:15:39.272546 systemd-logind[1874]: Removed session 14. Nov 24 00:15:39.381402 systemd[1]: Started sshd@12-10.200.4.11:22-10.200.16.10:45264.service - OpenSSH per-connection server daemon (10.200.16.10:45264). Nov 24 00:15:40.000247 sshd[2607]: Accepted publickey for core from 10.200.16.10 port 45264 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:40.001509 sshd-session[2607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:40.005705 systemd-logind[1874]: New session 15 of user core. Nov 24 00:15:40.021735 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 24 00:15:40.416572 sshd[2610]: Connection closed by 10.200.16.10 port 45264 Nov 24 00:15:40.416972 sshd-session[2607]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:40.420450 systemd[1]: sshd@12-10.200.4.11:22-10.200.16.10:45264.service: Deactivated successfully. Nov 24 00:15:40.422063 systemd[1]: session-15.scope: Deactivated successfully. Nov 24 00:15:40.422810 systemd-logind[1874]: Session 15 logged out. Waiting for processes to exit. Nov 24 00:15:40.423724 systemd-logind[1874]: Removed session 15. Nov 24 00:15:47.902387 setup-nvidia[2355]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 111k 0 0 122k 0 0:45:17 --:--:-- 0:45:17 122k\u000d 1 325M 1 6144k 0 0 3069k 0 0:01:48 0:00:02 0:01:46 3070k\u000d 3 325M 3 10.0M 0 0 3208k 0 0:01:44 0:00:03 0:01:41 3209k\u000d 4 325M 4 14.0M 0 0 3478k 0 0:01:35 0:00:04 0:01:31 3479k\u000d 5 325M 5 18.0M 0 0 3681k 0 0:01:30 0:00:05 0:01:25 3682k\u000d 6 325M 6 22.2M 0 0 3857k 0 0:01:26 0:00:05 0:01:21 4533k\u000d 8 325M 8 28.0M 0 0 3961k 0 0:01:24 0:00:07 0:01:17 4303k\u000d 9 325M 9 32.0M 0 0 4027k 0 0:01:22 0:00:08 0:01:14 4558k\u000d 11 325M 11 36.0M 0 0 4067k 0 0:01:22 0:00:09 0:01:13 4558k\u000d 12 325M 12 40.0M 0 0 4131k 0 0:01:20 0:00:09 0:01:11 4590k\u000d 14 325M 14 46.0M 0 0 4269k 0 0:01:18 0:00:11 0:01:07 4742k\u000d 15 325M 15 52.0M 0 0 4367k 0 0:01:16 0:00:12 0:01:04 4960k\u000d 17 325M 17 56.0M 0 0 4439k 0 0:01:15 0:00:12 0:01:03 5140k\u000d 19 325M 19 62.0M 0 0 4536k 0 0:01:13 0:00:13 0:01:00 5396k\u000d 20 325M 20 68.0M 0 0 4601k 0 0:01:12 0:00:15 0:00:57 5494k\u000d 22 325M 22 74.0M 0 0 4677k 0 0:01:11 0:00:16 0:00:55 5547k\u000d 23 325M 23 78.0M 0 0 4702k 0 0:01:10 0:00:16 0:00:54 5552k\u000d 25 325M 25 84.0M 0 0 4755k 0 0:01:10 0:00:18 0:00:52 5544k\u000d 27 325M 27 90.0M 0 0 4798k 0 0:01:09 0:00:19 0:00:50 5503k\u000d 28 325M 28 94.0M 0 0 4813k 0 0:01:09 0:00:19 0:00:50 5473k\u000d 30 325M 30 100M 0 0 4853k 0 0:01:08 0:00:21 0:00:47 5436k\u000d 32 325M 32 105M 0 0 4942k 0 0:01:07 0:00:21 0:00:46 5771k\u000d 33 325M 33 110M 0 0 4890k 0 0:01:08 0:00:23 0:00:45 5386k\u000d 35 325M 35 116M 0 0 4909k 0 0:01:07 0:00:24 0:00:43 5335k\u000d 36 325M 36 120M 0 0 4919k 0 0:01:07 0:00:24 0:00:43 5345k\u000d 38 325M 38 126M 0 0 4932k 0 0:01:07 0:00:26 0:00:41 5264k\u000d 39 325M 39 130M 0 0 4959k 0 0:01:07 0:00:26 0:00:41 5033k\u000d 41 325M 41 136M 0 0 4963k 0 0:01:07 0:00:28 0:00:39 5298k\u000d 43 325M 43 142M 0 0 4983k 0 0:01:06 0:00:29 0:00:37 5340k\u000d 44 325M 44 146M 0 0 4996k 0 0:01:06 0:00:29 0:00:37 5385k\u000d 46 325M 46 152M 0 0 5005k 0 0:01:06 0:00:31 0:00:35 5391k\u000d 47 325M 47 156M 0 0 4995k 0 0:01:06 0:00:31 0:00:35 5184k\u000d 49 325M 49 162M 0 0 4999k 0 0:01:06 0:00:33 0:00:33 5193k\u000d 50 325M 50 166M 0 0 5012k 0 0:01:06 0:00:33 0:00:33 5195k\u000d 52 325M 52 172M 0 0 5022k 0 0:01:06 0:00:35 0:00:31 5169k\u000d 54 325M 54 178M 0 0 5036k 0 0:01:06 0:00:36 0:00:30 5221k\u000d 55 325M 55 182M 0 0 5041k 0 0:01:06 0:00:36 0:00:30 5336k\u000d 57 325M 57 188M 0 0 5053k 0 0:01:06 0:00:38 0:00:28 5423k\u000d 59 325M 59 193M 0 0 5106k 0 0:01:05 0:00:38 0:00:27 5741k\u000d 60 325M 60 198M 0 0 5077k 0 0:01:05 0:00:39 0:00:26 5473k\u000d 62 325M 62 204M 0 0 5086k 0 0:01:05 0:00:41 0:00:24 5457k\u000d 64 325M 64 209M 0 0 5129k 0 0:01:05 0:00:41 0:00:24 5785k\u000d 65 325M 65 214M 0 0 5092k 0 0:01:05 0:00:43 0:00:22 5387k\u000d 67 325M 67 220M 0 0 5100k 0 0:01:05 0:00:44 0:00:21 5058k\u000d 68 325M 68 224M 0 0 5103k 0 0:01:05 0:00:44 0:00:21 5313k\u000d 70 325M 70 230M 0 0 5113k 0 0:01:05 0:00:46 0:00:19 5334k\u000d 72 325M 72 236M 0 0 5153k 0 0:01:04 0:00:46 0:00:18 5352k\u000d 73 325M 73 240M 0 0 5117k 0 0:01:05 0:00:48 0:00:17 5335k\u000d 74 325M 74 242M 0 0 5026k 0 0:01:06 0:00:49 0:00:17 4409k\u000d 76 325M 76 250M 0 0 5116k 0 0:01:05 0:00:50 0:00:15 5229k\u000d 78 325M 78 256M 0 0 5128k 0 0:01:05 0:00:51 0:00:14 5265k\u000d 79 325M 79 260M 0 0 5125k 0 0:01:05 0:00:51 0:00:14 4871k\u000d 81 325M 81 266M 0 0 5131k 0 0:01:05 0:00:53 0:00:12 5265k\u000d 82 325M 82 270M 0 0 5128k 0 0:01:05 0:00:53 0:00:12 6273k\u000d 84 325M 84 276M 0 0 5140k 0 0:01:04 0:00:54 0:00:10 5380k\u000d 86 325M 86 282M 0 0 5147k 0 0:01:04 0:00:56 0:00:08 5341k\u000d 88 325M 88 287M 0 0 5182k 0 0:01:04 0:00:56 0:00:08 5782k\u000d 89 325M 89 292M 0 0 5157k 0 0:01:04 0:00:57 0:00:07 5437k\u000d 91 325M 91 298M 0 0 5165k 0 0:01:04 0:00:59 0:00:05 5554k\u000d 93 325M 93 304M 0 0 5175k 0 0:01:04 0:01:00 0:00:04 5549k\u000d 94 325M 94 308M 0 0 5168k 0 0:01:04 0:01:01 0:00:03 5408k\u000d 96 325M 96 314M 0 0 5167k 0 0:01:04 0:01:02 0:00:02 5007k\u000d 97 325M 97 318M 0 0 5168k 0 0:01:04 0:01:03 0:00:01 5293k\u000d 99 325M 99 324M 0 0 5165k 0 0:01:04 0:01:04 --:--:-- 5168k\u000d100 325M 100 325M 0 0 5195k 0 0:01:04 0:01:04 --:--:-- 5491k Nov 24 00:15:47.975118 setup-nvidia[1864]: Extract the NVIDIA Driver Installer 535.261.03 Nov 24 00:15:47.975118 setup-nvidia[1864]: /opt/nvidia/workdir/nvidia-workdir / Nov 24 00:15:47.994194 setup-nvidia[2617]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Nov 24 00:15:48.311007 setup-nvidia[2617]: Verifying archive integrity... OK Nov 24 00:15:49.079993 setup-nvidia[2617]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Nov 24 00:15:50.527293 systemd[1]: Started sshd@13-10.200.4.11:22-10.200.16.10:39758.service - OpenSSH per-connection server daemon (10.200.16.10:39758). Nov 24 00:15:50.881387 setup-nvidia[2643]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 24 00:15:50.919025 setup-nvidia[1864]: / Nov 24 00:15:50.919291 setup-nvidia[1864]: Spawn system-nspawn container to install the NVIDIA drivers Nov 24 00:15:51.082851 sudo[2654]: 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.1.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Nov 24 00:15:51.084556 sudo[2654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 24 00:15:51.090110 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 24 00:15:51.095386 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 24 00:15:51.124248 sshd[2648]: Accepted publickey for core from 10.200.16.10 port 39758 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:51.125488 sshd-session[2648]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:51.128281 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 24 00:15:51.132577 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 24 00:15:51.135874 systemd-logind[1874]: New session 16 of user core. Nov 24 00:15:51.147191 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 24 00:15:51.149383 (systemd)[2657]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 24 00:15:51.152025 systemd-logind[1874]: New session c3 of user root. Nov 24 00:15:51.323005 systemd[2657]: Queued start job for default target default.target. Nov 24 00:15:51.344714 systemd[2657]: Created slice app.slice - User Application Slice. Nov 24 00:15:51.344743 systemd[2657]: Reached target paths.target - Paths. Nov 24 00:15:51.344790 systemd[2657]: Reached target timers.target - Timers. Nov 24 00:15:51.346003 systemd[2657]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 00:15:51.356968 systemd[2657]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:15:51.357111 systemd[2657]: Reached target sockets.target - Sockets. Nov 24 00:15:51.357166 systemd[2657]: Reached target basic.target - Basic System. Nov 24 00:15:51.357209 systemd[2657]: Reached target default.target - Main User Target. Nov 24 00:15:51.357248 systemd[2657]: Startup finished in 197ms. Nov 24 00:15:51.357287 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 24 00:15:51.360099 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 24 00:15:51.560407 sshd[2658]: Connection closed by 10.200.16.10 port 39758 Nov 24 00:15:51.560729 sshd-session[2648]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:51.566716 systemd[1]: sshd@13-10.200.4.11:22-10.200.16.10:39758.service: Deactivated successfully. Nov 24 00:15:51.568287 systemd[1]: session-16.scope: Deactivated successfully. Nov 24 00:15:51.569041 systemd-logind[1874]: Session 16 logged out. Waiting for processes to exit. Nov 24 00:15:51.570411 systemd-logind[1874]: Removed session 16. Nov 24 00:15:51.640641 kernel: loop6: detected capacity change from 0 to 12644352 Nov 24 00:15:51.676028 systemd[1]: Started sshd@14-10.200.4.11:22-10.200.16.10:39762.service - OpenSSH per-connection server daemon (10.200.16.10:39762). Nov 24 00:15:51.703631 kernel: loop6: p9 Nov 24 00:15:52.010095 setup-nvidia[2667]: ░ Spawning container flatcardevelopercontainer-4459.2.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.1.bin. Nov 24 00:15:52.182736 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Nov 24 00:15:52.182836 kernel: EXT4-fs (loop6p9): mounted filesystem 4d642f52-8507-4059-99b7-cbb575c77e1f ro without journal. Quota mode: none. Nov 24 00:15:52.279130 sshd[2674]: Accepted publickey for core from 10.200.16.10 port 39762 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:15:52.280287 sshd-session[2674]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:15:52.284568 systemd-logind[1874]: New session 17 of user core. Nov 24 00:15:52.293757 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 24 00:15:52.713111 sshd[2681]: Connection closed by 10.200.16.10 port 39762 Nov 24 00:15:52.713477 sshd-session[2674]: pam_unix(sshd:session): session closed for user core Nov 24 00:15:52.717133 systemd[1]: sshd@14-10.200.4.11:22-10.200.16.10:39762.service: Deactivated successfully. Nov 24 00:15:52.718792 systemd[1]: session-17.scope: Deactivated successfully. Nov 24 00:15:52.719503 systemd-logind[1874]: Session 17 logged out. Waiting for processes to exit. Nov 24 00:15:52.720388 systemd-logind[1874]: Removed session 17. Nov 24 00:16:02.828839 systemd[1]: Started sshd@15-10.200.4.11:22-10.200.16.10:50540.service - OpenSSH per-connection server daemon (10.200.16.10:50540). Nov 24 00:16:03.442137 sshd[6576]: Accepted publickey for core from 10.200.16.10 port 50540 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:03.443557 sshd-session[6576]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:03.451313 systemd-logind[1874]: New session 18 of user core. Nov 24 00:16:03.458037 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 24 00:16:03.872701 sshd[6776]: Connection closed by 10.200.16.10 port 50540 Nov 24 00:16:03.875145 sshd-session[6576]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:03.879677 systemd-logind[1874]: Session 18 logged out. Waiting for processes to exit. Nov 24 00:16:03.880579 systemd[1]: sshd@15-10.200.4.11:22-10.200.16.10:50540.service: Deactivated successfully. Nov 24 00:16:03.882576 systemd[1]: session-18.scope: Deactivated successfully. Nov 24 00:16:03.886417 systemd-logind[1874]: Removed session 18. Nov 24 00:16:03.977843 systemd[1]: Started sshd@16-10.200.4.11:22-10.200.16.10:50544.service - OpenSSH per-connection server daemon (10.200.16.10:50544). Nov 24 00:16:04.584254 sshd[6898]: Accepted publickey for core from 10.200.16.10 port 50544 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:04.585540 sshd-session[6898]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:04.590625 systemd-logind[1874]: New session 19 of user core. Nov 24 00:16:04.597767 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 24 00:16:05.014634 sshd[7126]: Connection closed by 10.200.16.10 port 50544 Nov 24 00:16:05.015167 sshd-session[6898]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:05.021263 systemd-logind[1874]: Session 19 logged out. Waiting for processes to exit. Nov 24 00:16:05.022242 systemd[1]: sshd@16-10.200.4.11:22-10.200.16.10:50544.service: Deactivated successfully. Nov 24 00:16:05.025253 systemd[1]: session-19.scope: Deactivated successfully. Nov 24 00:16:05.027396 systemd-logind[1874]: Removed session 19. Nov 24 00:16:15.130838 systemd[1]: Started sshd@17-10.200.4.11:22-10.200.16.10:50874.service - OpenSSH per-connection server daemon (10.200.16.10:50874). Nov 24 00:16:15.740813 sshd[11226]: Accepted publickey for core from 10.200.16.10 port 50874 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:15.742553 sshd-session[11226]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:15.750990 systemd-logind[1874]: New session 20 of user core. Nov 24 00:16:15.763709 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 24 00:16:16.190784 sshd[11488]: Connection closed by 10.200.16.10 port 50874 Nov 24 00:16:16.194011 sshd-session[11226]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:16.197408 systemd-logind[1874]: Session 20 logged out. Waiting for processes to exit. Nov 24 00:16:16.198174 systemd[1]: sshd@17-10.200.4.11:22-10.200.16.10:50874.service: Deactivated successfully. Nov 24 00:16:16.199866 systemd[1]: session-20.scope: Deactivated successfully. Nov 24 00:16:16.206772 systemd-logind[1874]: Removed session 20. Nov 24 00:16:16.303913 systemd[1]: Started sshd@18-10.200.4.11:22-10.200.16.10:50888.service - OpenSSH per-connection server daemon (10.200.16.10:50888). Nov 24 00:16:16.918469 sshd[11665]: Accepted publickey for core from 10.200.16.10 port 50888 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:16.920235 sshd-session[11665]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:16.928506 systemd-logind[1874]: New session 21 of user core. Nov 24 00:16:16.934764 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 24 00:16:17.355723 sshd[11877]: Connection closed by 10.200.16.10 port 50888 Nov 24 00:16:17.357548 sshd-session[11665]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:17.361231 systemd[1]: sshd@18-10.200.4.11:22-10.200.16.10:50888.service: Deactivated successfully. Nov 24 00:16:17.362824 systemd[1]: session-21.scope: Deactivated successfully. Nov 24 00:16:17.365105 systemd-logind[1874]: Session 21 logged out. Waiting for processes to exit. Nov 24 00:16:17.366778 systemd-logind[1874]: Removed session 21. Nov 24 00:16:27.472852 systemd[1]: Started sshd@19-10.200.4.11:22-10.200.16.10:56562.service - OpenSSH per-connection server daemon (10.200.16.10:56562). Nov 24 00:16:28.248669 sshd[14186]: Accepted publickey for core from 10.200.16.10 port 56562 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:28.104212 systemd-logind[1874]: New session 22 of user core. Nov 24 00:16:28.097940 sshd-session[14186]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:28.131880 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 24 00:16:28.538357 sshd[14198]: Connection closed by 10.200.16.10 port 56562 Nov 24 00:16:28.539805 sshd-session[14186]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:28.544275 systemd-logind[1874]: Session 22 logged out. Waiting for processes to exit. Nov 24 00:16:28.545563 systemd[1]: sshd@19-10.200.4.11:22-10.200.16.10:56562.service: Deactivated successfully. Nov 24 00:16:28.549507 systemd[1]: session-22.scope: Deactivated successfully. Nov 24 00:16:28.552508 systemd-logind[1874]: Removed session 22. Nov 24 00:16:28.644982 systemd[1]: Started sshd@20-10.200.4.11:22-10.200.16.10:56566.service - OpenSSH per-connection server daemon (10.200.16.10:56566). Nov 24 00:16:29.269763 sshd[14228]: Accepted publickey for core from 10.200.16.10 port 56566 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:29.271760 sshd-session[14228]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:29.277942 systemd-logind[1874]: New session 23 of user core. Nov 24 00:16:29.285784 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 24 00:16:29.698506 sshd[14265]: Connection closed by 10.200.16.10 port 56566 Nov 24 00:16:29.701797 sshd-session[14228]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:29.706096 systemd-logind[1874]: Session 23 logged out. Waiting for processes to exit. Nov 24 00:16:29.708942 systemd[1]: sshd@20-10.200.4.11:22-10.200.16.10:56566.service: Deactivated successfully. Nov 24 00:16:29.711168 systemd[1]: session-23.scope: Deactivated successfully. Nov 24 00:16:29.713607 systemd-logind[1874]: Removed session 23. Nov 24 00:16:39.813119 systemd[1]: Started sshd@21-10.200.4.11:22-10.200.16.10:58358.service - OpenSSH per-connection server daemon (10.200.16.10:58358). Nov 24 00:16:40.431380 sshd[14647]: Accepted publickey for core from 10.200.16.10 port 58358 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:40.433257 sshd-session[14647]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:40.439176 systemd-logind[1874]: New session 24 of user core. Nov 24 00:16:40.445803 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 24 00:16:40.871920 sshd[14673]: Connection closed by 10.200.16.10 port 58358 Nov 24 00:16:40.874460 sshd-session[14647]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:40.879135 systemd-logind[1874]: Session 24 logged out. Waiting for processes to exit. Nov 24 00:16:40.880558 systemd[1]: sshd@21-10.200.4.11:22-10.200.16.10:58358.service: Deactivated successfully. Nov 24 00:16:40.882222 systemd[1]: session-24.scope: Deactivated successfully. Nov 24 00:16:40.884600 systemd-logind[1874]: Removed session 24. Nov 24 00:16:40.980977 systemd[1]: Started sshd@22-10.200.4.11:22-10.200.16.10:48862.service - OpenSSH per-connection server daemon (10.200.16.10:48862). Nov 24 00:16:41.595562 sshd[14698]: Accepted publickey for core from 10.200.16.10 port 48862 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:41.597313 sshd-session[14698]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:41.605435 systemd-logind[1874]: New session 25 of user core. Nov 24 00:16:41.611228 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 24 00:16:42.019735 sshd[14717]: Connection closed by 10.200.16.10 port 48862 Nov 24 00:16:42.020547 sshd-session[14698]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:42.024236 systemd[1]: sshd@22-10.200.4.11:22-10.200.16.10:48862.service: Deactivated successfully. Nov 24 00:16:42.024959 systemd-logind[1874]: Session 25 logged out. Waiting for processes to exit. Nov 24 00:16:42.027017 systemd[1]: session-25.scope: Deactivated successfully. Nov 24 00:16:42.032198 systemd-logind[1874]: Removed session 25. Nov 24 00:16:52.132006 systemd[1]: Started sshd@23-10.200.4.11:22-10.200.16.10:39928.service - OpenSSH per-connection server daemon (10.200.16.10:39928). Nov 24 00:16:52.762453 sshd[15059]: Accepted publickey for core from 10.200.16.10 port 39928 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:52.764264 sshd-session[15059]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:52.773041 systemd-logind[1874]: New session 26 of user core. Nov 24 00:16:52.779706 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 24 00:16:53.182338 sshd[15075]: Connection closed by 10.200.16.10 port 39928 Nov 24 00:16:53.183201 sshd-session[15059]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:53.189017 systemd[1]: sshd@23-10.200.4.11:22-10.200.16.10:39928.service: Deactivated successfully. Nov 24 00:16:53.189470 systemd-logind[1874]: Session 26 logged out. Waiting for processes to exit. Nov 24 00:16:53.191566 systemd[1]: session-26.scope: Deactivated successfully. Nov 24 00:16:53.195764 systemd-logind[1874]: Removed session 26. Nov 24 00:16:53.294964 systemd[1]: Started sshd@24-10.200.4.11:22-10.200.16.10:39932.service - OpenSSH per-connection server daemon (10.200.16.10:39932). Nov 24 00:16:53.900374 sshd[15104]: Accepted publickey for core from 10.200.16.10 port 39932 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:16:53.901732 sshd-session[15104]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:16:53.915546 systemd-logind[1874]: New session 27 of user core. Nov 24 00:16:53.921772 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 24 00:16:54.327313 sshd[15115]: Connection closed by 10.200.16.10 port 39932 Nov 24 00:16:54.329242 sshd-session[15104]: pam_unix(sshd:session): session closed for user core Nov 24 00:16:54.335470 systemd[1]: sshd@24-10.200.4.11:22-10.200.16.10:39932.service: Deactivated successfully. Nov 24 00:16:54.337089 systemd[1]: session-27.scope: Deactivated successfully. Nov 24 00:16:54.337131 systemd-logind[1874]: Session 27 logged out. Waiting for processes to exit. Nov 24 00:16:54.341233 systemd-logind[1874]: Removed session 27. Nov 24 00:17:04.444419 systemd[1]: Started sshd@25-10.200.4.11:22-10.200.16.10:56184.service - OpenSSH per-connection server daemon (10.200.16.10:56184). Nov 24 00:17:05.039236 sshd[15308]: Accepted publickey for core from 10.200.16.10 port 56184 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:05.040478 sshd-session[15308]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:05.045225 systemd-logind[1874]: New session 28 of user core. Nov 24 00:17:05.054739 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 24 00:17:05.465329 sshd[15343]: Connection closed by 10.200.16.10 port 56184 Nov 24 00:17:05.465783 sshd-session[15308]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:05.469445 systemd[1]: sshd@25-10.200.4.11:22-10.200.16.10:56184.service: Deactivated successfully. Nov 24 00:17:05.471035 systemd[1]: session-28.scope: Deactivated successfully. Nov 24 00:17:05.471803 systemd-logind[1874]: Session 28 logged out. Waiting for processes to exit. Nov 24 00:17:05.472706 systemd-logind[1874]: Removed session 28. Nov 24 00:17:05.572772 systemd[1]: Started sshd@26-10.200.4.11:22-10.200.16.10:56196.service - OpenSSH per-connection server daemon (10.200.16.10:56196). Nov 24 00:17:06.168558 sshd[15349]: Accepted publickey for core from 10.200.16.10 port 56196 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:06.172212 sshd-session[15349]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:06.180569 systemd-logind[1874]: New session 29 of user core. Nov 24 00:17:06.186761 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 24 00:17:06.613739 sshd[15372]: Connection closed by 10.200.16.10 port 56196 Nov 24 00:17:06.614124 sshd-session[15349]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:06.617658 systemd[1]: sshd@26-10.200.4.11:22-10.200.16.10:56196.service: Deactivated successfully. Nov 24 00:17:06.619275 systemd[1]: session-29.scope: Deactivated successfully. Nov 24 00:17:06.620008 systemd-logind[1874]: Session 29 logged out. Waiting for processes to exit. Nov 24 00:17:06.620901 systemd-logind[1874]: Removed session 29. Nov 24 00:17:07.119270 setup-nvidia[2667]: \u000d Nov 24 00:17:07.119270 setup-nvidia[2667]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 24 00:17:07.119270 setup-nvidia[2667]: frequently when this kernel module was built against the wrong or Nov 24 00:17:07.119270 setup-nvidia[2667]: improperly configured kernel sources, with a version of gcc that differs Nov 24 00:17:07.119270 setup-nvidia[2667]: from the one used to build the target kernel, or if another driver, such Nov 24 00:17:07.119270 setup-nvidia[2667]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 24 00:17:07.119270 setup-nvidia[2667]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 24 00:17:07.119270 setup-nvidia[2667]: installed in this system is supported by this NVIDIA Linux graphics Nov 24 00:17:07.119270 setup-nvidia[2667]: driver release. Nov 24 00:17:07.119270 setup-nvidia[2667]: \u000d Nov 24 00:17:07.119270 setup-nvidia[2667]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 24 00:17:07.119270 setup-nvidia[2667]: messages' at the end of the file Nov 24 00:17:07.119270 setup-nvidia[2667]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Nov 24 00:17:07.119270 setup-nvidia[2667]: information. Nov 24 00:17:07.119270 setup-nvidia[2667]: \u000d Nov 24 00:17:07.136227 setup-nvidia[2667]: dmesg: read kernel buffer failed: Operation not permitted Nov 24 00:17:07.154995 setup-nvidia[2667]: \u000d Nov 24 00:17:07.154995 setup-nvidia[2667]: ERROR: Installation has failed. Please see the file Nov 24 00:17:07.154995 setup-nvidia[2667]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Nov 24 00:17:07.154995 setup-nvidia[2667]: details. You may find suggestions on fixing installation problems in Nov 24 00:17:07.154995 setup-nvidia[2667]: the README available on the Linux driver download page at Nov 24 00:17:07.154995 setup-nvidia[2667]: www.nvidia.com. Nov 24 00:17:07.154995 setup-nvidia[2667]: \u000d Nov 24 00:17:07.155369 setup-nvidia[2667]: Last 50 lines of nvidia-installer.log: Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:17:07.156187 setup-nvidia[2667]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Nov 24 00:17:07.156187 setup-nvidia[2667]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Nov 24 00:17:07.156187 setup-nvidia[2667]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Nov 24 00:17:07.156187 setup-nvidia[2667]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Nov 24 00:17:07.156187 setup-nvidia[2667]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Nov 24 00:17:07.156187 setup-nvidia[2667]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Nov 24 00:17:07.156187 setup-nvidia[2667]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Nov 24 00:17:07.156187 setup-nvidia[2667]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 24 00:17:07.156187 setup-nvidia[2667]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 24 00:17:07.156187 setup-nvidia[2667]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 24 00:17:07.156187 setup-nvidia[2667]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 24 00:17:07.156187 setup-nvidia[2667]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 24 00:17:07.157166 setup-nvidia[2667]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 24 00:17:07.157166 setup-nvidia[2667]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Nov 24 00:17:07.157166 setup-nvidia[2667]: make[2]: Leaving directory '/usr/lib/modules/6.12.58-flatcar/build' Nov 24 00:17:07.157166 setup-nvidia[2667]: make[1]: Leaving directory '/usr/lib/modules/6.12.58-flatcar/source' Nov 24 00:17:07.157166 setup-nvidia[2667]: -> done. Nov 24 00:17:07.157166 setup-nvidia[2667]: -> Kernel module compilation complete. Nov 24 00:17:07.157166 setup-nvidia[2667]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 24 00:17:07.157166 setup-nvidia[2667]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Nov 24 00:17:07.157166 setup-nvidia[2667]: \u000d Nov 24 00:17:07.157166 setup-nvidia[2667]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Nov 24 00:17:07.157166 setup-nvidia[2667]: -> Kernel module load error: Operation not permitted Nov 24 00:17:07.157166 setup-nvidia[2667]: -> Kernel messages: Nov 24 00:17:07.157166 setup-nvidia[2667]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 24 00:17:16.738667 systemd[1]: Started sshd@27-10.200.4.11:22-10.200.16.10:52496.service - OpenSSH per-connection server daemon (10.200.16.10:52496). Nov 24 00:17:17.946894 sshd[15508]: Accepted publickey for core from 10.200.16.10 port 52496 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:17.336559 sshd-session[15508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:17.340931 systemd-logind[1874]: New session 30 of user core. Nov 24 00:17:17.947523 sshd[15513]: Connection closed by 10.200.16.10 port 52496 Nov 24 00:17:17.761244 sshd-session[15508]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:17.357739 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 24 00:17:17.764889 systemd[1]: sshd@27-10.200.4.11:22-10.200.16.10:52496.service: Deactivated successfully. Nov 24 00:17:17.766849 systemd[1]: session-30.scope: Deactivated successfully. Nov 24 00:17:17.767672 systemd-logind[1874]: Session 30 logged out. Waiting for processes to exit. Nov 24 00:17:17.768580 systemd-logind[1874]: Removed session 30. Nov 24 00:17:17.870842 systemd[1]: Started sshd@28-10.200.4.11:22-10.200.16.10:52500.service - OpenSSH per-connection server daemon (10.200.16.10:52500). Nov 24 00:17:18.489789 sshd[15519]: Accepted publickey for core from 10.200.16.10 port 52500 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:18.491025 sshd-session[15519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:18.495603 systemd-logind[1874]: New session 31 of user core. Nov 24 00:17:18.505741 systemd[1]: Started session-31.scope - Session 31 of User core. Nov 24 00:17:18.935783 sshd[15522]: Connection closed by 10.200.16.10 port 52500 Nov 24 00:17:18.936180 sshd-session[15519]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:18.939757 systemd[1]: sshd@28-10.200.4.11:22-10.200.16.10:52500.service: Deactivated successfully. Nov 24 00:17:18.941351 systemd[1]: session-31.scope: Deactivated successfully. Nov 24 00:17:18.942030 systemd-logind[1874]: Session 31 logged out. Waiting for processes to exit. Nov 24 00:17:18.942921 systemd-logind[1874]: Removed session 31. Nov 24 00:17:20.426646 kernel: EXT4-fs (loop6p9): unmounting filesystem 4d642f52-8507-4059-99b7-cbb575c77e1f. Nov 24 00:17:20.427517 setup-nvidia[2667]: Container flatcardevelopercontainer-4459.2.1.bin exited successfully. Nov 24 00:17:20.430000 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.1.bin.mount: Deactivated successfully. Nov 24 00:17:20.483440 sudo[2654]: pam_unix(sudo:session): session closed for user root Nov 24 00:17:20.485236 systemd[1]: session-c2.scope: Deactivated successfully. Nov 24 00:17:20.485502 systemd[1]: session-c2.scope: Consumed 5min 25.663s CPU time, 889.8M memory peak. Nov 24 00:17:20.906644 setup-nvidia[1864]: /opt/nvidia / Nov 24 00:17:20.907595 setup-nvidia[1864]: / Nov 24 00:17:20.917641 kernel: IPMI message handler: version 39.2 Nov 24 00:17:20.920627 kernel: ipmi device interface Nov 24 00:17:20.932733 setup-nvidia[1864]: /opt/nvidia/current/usr/lib/modules/6.12.58-flatcar/video / Nov 24 00:17:20.986653 kernel: nvidia: loading out-of-tree module taints kernel. Nov 24 00:17:20.986791 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 24 00:17:20.986815 kernel: Disabling lock debugging due to kernel taint Nov 24 00:17:20.994040 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 24 00:17:20.994073 kernel: nvidia: module license taints kernel. Nov 24 00:17:21.397659 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 24 00:17:21.792548 kernel: Nov 24 00:17:21.792715 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 24 00:17:21.845635 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Nov 24 00:17:21.866639 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Nov 24 00:17:21.887649 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 24 00:17:21.937135 setup-nvidia[1864]: / Nov 24 00:17:21.937634 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 24 00:17:21.953175 setup-nvidia[15561]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 24 00:17:23.564561 setup-nvidia[15578]: Mon Nov 24 00:17:23 2025 Nov 24 00:17:23.564561 setup-nvidia[15578]: +---------------------------------------------------------------------------------------+ Nov 24 00:17:23.564561 setup-nvidia[15578]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Nov 24 00:17:23.564561 setup-nvidia[15578]: |-----------------------------------------+----------------------+----------------------+ Nov 24 00:17:23.564561 setup-nvidia[15578]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 24 00:17:23.564561 setup-nvidia[15578]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 24 00:17:23.564561 setup-nvidia[15578]: | | | MIG M. | Nov 24 00:17:23.564561 setup-nvidia[15578]: |=========================================+======================+======================| Nov 24 00:17:23.571996 setup-nvidia[15578]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | 0 | Nov 24 00:17:23.571996 setup-nvidia[15578]: | N/A 30C P0 35W / 250W | 0MiB / 16384MiB | 1% Default | Nov 24 00:17:23.571996 setup-nvidia[15578]: | | | N/A | Nov 24 00:17:23.571996 setup-nvidia[15578]: +-----------------------------------------+----------------------+----------------------+ Nov 24 00:17:23.571996 setup-nvidia[15578]: Nov 24 00:17:23.571996 setup-nvidia[15578]: +---------------------------------------------------------------------------------------+ Nov 24 00:17:23.571996 setup-nvidia[15578]: | Processes: | Nov 24 00:17:23.571996 setup-nvidia[15578]: | GPU GI CI PID Type Process name GPU Memory | Nov 24 00:17:23.571996 setup-nvidia[15578]: | ID ID Usage | Nov 24 00:17:23.571996 setup-nvidia[15578]: |=======================================================================================| Nov 24 00:17:23.572266 setup-nvidia[15578]: | No running processes found | Nov 24 00:17:23.572266 setup-nvidia[15578]: +---------------------------------------------------------------------------------------+ Nov 24 00:17:23.959001 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 24 00:17:23.961041 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 24 00:17:23.961458 systemd[1]: Startup finished in 1.152s (firmware) + 15.534s (loader) + 3.704s (kernel) + 11.512s (initrd) + 3min 10.351s (userspace) = 3min 42.255s. Nov 24 00:17:29.053748 systemd[1]: Started sshd@29-10.200.4.11:22-10.200.16.10:47008.service - OpenSSH per-connection server daemon (10.200.16.10:47008). Nov 24 00:17:29.669814 sshd[15585]: Accepted publickey for core from 10.200.16.10 port 47008 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:29.671036 sshd-session[15585]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:29.675241 systemd-logind[1874]: New session 32 of user core. Nov 24 00:17:29.682769 systemd[1]: Started session-32.scope - Session 32 of User core. Nov 24 00:17:30.095018 sshd[15588]: Connection closed by 10.200.16.10 port 47008 Nov 24 00:17:30.095411 sshd-session[15585]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:30.099188 systemd[1]: sshd@29-10.200.4.11:22-10.200.16.10:47008.service: Deactivated successfully. Nov 24 00:17:30.101080 systemd[1]: session-32.scope: Deactivated successfully. Nov 24 00:17:30.101912 systemd-logind[1874]: Session 32 logged out. Waiting for processes to exit. Nov 24 00:17:30.103191 systemd-logind[1874]: Removed session 32. Nov 24 00:17:30.211307 systemd[1]: Started sshd@30-10.200.4.11:22-10.200.16.10:53764.service - OpenSSH per-connection server daemon (10.200.16.10:53764). Nov 24 00:17:30.591870 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 24 00:17:30.592652 systemd[2657]: Activating special unit exit.target... Nov 24 00:17:30.592737 systemd[2657]: Stopped target default.target - Main User Target. Nov 24 00:17:30.592775 systemd[2657]: Stopped target basic.target - Basic System. Nov 24 00:17:30.592803 systemd[2657]: Stopped target paths.target - Paths. Nov 24 00:17:30.592828 systemd[2657]: Stopped target sockets.target - Sockets. Nov 24 00:17:30.592853 systemd[2657]: Stopped target timers.target - Timers. Nov 24 00:17:30.593184 systemd[2657]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:17:30.593389 systemd[2657]: Removed slice app.slice - User Application Slice. Nov 24 00:17:30.593420 systemd[2657]: Reached target shutdown.target - Shutdown. Nov 24 00:17:30.593559 systemd[2657]: Finished systemd-exit.service - Exit the Session. Nov 24 00:17:30.593594 systemd[2657]: Reached target exit.target - Exit the Session. Nov 24 00:17:30.615270 (sd-pam)[2659]: pam_unix(systemd-user:session): session closed for user root Nov 24 00:17:30.616510 systemd[1]: user@0.service: Deactivated successfully. Nov 24 00:17:30.616882 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 24 00:17:30.619126 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 24 00:17:30.654063 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 24 00:17:30.655069 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 24 00:17:30.655274 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 24 00:17:30.656966 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 24 00:17:30.657053 systemd[1]: user-0.slice: Consumed 5min 25.889s CPU time, 896M memory peak. Nov 24 00:17:30.657466 systemd-logind[1874]: Removed session c3. Nov 24 00:17:30.826134 sshd[15594]: Accepted publickey for core from 10.200.16.10 port 53764 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:30.827327 sshd-session[15594]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:30.831658 systemd-logind[1874]: New session 33 of user core. Nov 24 00:17:30.847755 systemd[1]: Started session-33.scope - Session 33 of User core. Nov 24 00:17:31.250567 sshd[15601]: Connection closed by 10.200.16.10 port 53764 Nov 24 00:17:31.250875 sshd-session[15594]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:31.254516 systemd[1]: sshd@30-10.200.4.11:22-10.200.16.10:53764.service: Deactivated successfully. Nov 24 00:17:31.256166 systemd[1]: session-33.scope: Deactivated successfully. Nov 24 00:17:31.257580 systemd-logind[1874]: Session 33 logged out. Waiting for processes to exit. Nov 24 00:17:31.258564 systemd-logind[1874]: Removed session 33. Nov 24 00:17:31.360960 systemd[1]: Started sshd@31-10.200.4.11:22-10.200.16.10:53772.service - OpenSSH per-connection server daemon (10.200.16.10:53772). Nov 24 00:17:31.956153 sshd[15607]: Accepted publickey for core from 10.200.16.10 port 53772 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:31.957376 sshd-session[15607]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:31.962704 systemd-logind[1874]: New session 34 of user core. Nov 24 00:17:31.970767 systemd[1]: Started session-34.scope - Session 34 of User core. Nov 24 00:17:32.386012 sshd[15610]: Connection closed by 10.200.16.10 port 53772 Nov 24 00:17:32.386403 sshd-session[15607]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:32.389974 systemd[1]: sshd@31-10.200.4.11:22-10.200.16.10:53772.service: Deactivated successfully. Nov 24 00:17:32.391605 systemd[1]: session-34.scope: Deactivated successfully. Nov 24 00:17:32.392377 systemd-logind[1874]: Session 34 logged out. Waiting for processes to exit. Nov 24 00:17:32.393281 systemd-logind[1874]: Removed session 34. Nov 24 00:17:32.495928 systemd[1]: Started sshd@32-10.200.4.11:22-10.200.16.10:53778.service - OpenSSH per-connection server daemon (10.200.16.10:53778). Nov 24 00:17:33.118637 sshd[15616]: Accepted publickey for core from 10.200.16.10 port 53778 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:33.119899 sshd-session[15616]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:33.124086 systemd-logind[1874]: New session 35 of user core. Nov 24 00:17:33.134739 systemd[1]: Started session-35.scope - Session 35 of User core. Nov 24 00:17:34.358851 sshd[15619]: Connection closed by 10.200.16.10 port 53778 Nov 24 00:17:33.659261 systemd[1]: Started sshd@33-10.200.4.11:22-10.200.16.10:53786.service - OpenSSH per-connection server daemon (10.200.16.10:53786). Nov 24 00:17:34.358920 sshd-session[15616]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:34.359580 sshd[15622]: Accepted publickey for core from 10.200.16.10 port 53786 ssh2: RSA SHA256:gizC8XLymWbjwRmHfFo5afN1IW0itY0QbJobGVV/7ys Nov 24 00:17:34.359172 sshd-session[15622]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:17:34.363312 systemd-logind[1874]: New session 36 of user core. Nov 24 00:17:34.363895 systemd[1]: sshd@32-10.200.4.11:22-10.200.16.10:53778.service: Deactivated successfully. Nov 24 00:17:34.365553 systemd[1]: session-35.scope: Deactivated successfully. Nov 24 00:17:34.367533 systemd-logind[1874]: Session 35 logged out. Waiting for processes to exit. Nov 24 00:17:34.378788 systemd[1]: Started session-36.scope - Session 36 of User core. Nov 24 00:17:34.379767 systemd-logind[1874]: Removed session 35. Nov 24 00:17:36.216179 sshd[15628]: Connection closed by 10.200.16.10 port 53786 Nov 24 00:17:36.216586 sshd-session[15622]: pam_unix(sshd:session): session closed for user core Nov 24 00:17:36.220119 systemd[1]: sshd@33-10.200.4.11:22-10.200.16.10:53786.service: Deactivated successfully. Nov 24 00:17:36.221794 systemd[1]: session-36.scope: Deactivated successfully. Nov 24 00:17:36.222006 systemd[1]: session-36.scope: Consumed 1.430s CPU time, 14M memory peak. Nov 24 00:17:36.222476 systemd-logind[1874]: Session 36 logged out. Waiting for processes to exit. Nov 24 00:17:36.223383 systemd-logind[1874]: Removed session 36.