Sep 9 05:35:44.207451 kernel: Linux version 6.12.45-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 Tue Sep 9 03:39:34 -00 2025 Sep 9 05:35:44.207479 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=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:35:44.207488 kernel: BIOS-provided physical RAM map: Sep 9 05:35:44.207495 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Sep 9 05:35:44.207502 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Sep 9 05:35:44.207508 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Sep 9 05:35:44.207516 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Sep 9 05:35:44.207525 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Sep 9 05:35:44.207532 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Sep 9 05:35:44.207539 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Sep 9 05:35:44.207546 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Sep 9 05:35:44.207552 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Sep 9 05:35:44.207559 kernel: printk: legacy bootconsole [earlyser0] enabled Sep 9 05:35:44.207566 kernel: NX (Execute Disable) protection: active Sep 9 05:35:44.207577 kernel: APIC: Static calls initialized Sep 9 05:35:44.207584 kernel: efi: EFI v2.7 by Microsoft Sep 9 05:35:44.207592 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f61e418 RNG=0x3ffd1018 Sep 9 05:35:44.207600 kernel: random: crng init done Sep 9 05:35:44.207611 kernel: secureboot: Secure boot disabled Sep 9 05:35:44.207619 kernel: SMBIOS 3.1.0 present. Sep 9 05:35:44.207626 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Sep 9 05:35:44.207634 kernel: DMI: Memory slots populated: 3/3 Sep 9 05:35:44.207642 kernel: Hypervisor detected: Microsoft Hyper-V Sep 9 05:35:44.207649 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Sep 9 05:35:44.207659 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Sep 9 05:35:44.207667 kernel: Hyper-V: Using hypercall for remote TLB flush Sep 9 05:35:44.207674 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 9 05:35:44.207682 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Sep 9 05:35:44.207690 kernel: tsc: Marking TSC unstable due to running on Hyper-V Sep 9 05:35:44.207698 kernel: tsc: Detected 2593.992 MHz processor Sep 9 05:35:44.207721 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 9 05:35:44.207730 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 9 05:35:44.207737 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Sep 9 05:35:44.207748 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Sep 9 05:35:44.207756 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 9 05:35:44.207763 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Sep 9 05:35:44.207770 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Sep 9 05:35:44.207778 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Sep 9 05:35:44.207785 kernel: Using GB pages for direct mapping Sep 9 05:35:44.207793 kernel: ACPI: Early table checksum verification disabled Sep 9 05:35:44.207805 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Sep 9 05:35:44.207815 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207824 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207832 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 9 05:35:44.207840 kernel: ACPI: FACS 0x000000003FFFE000 000040 Sep 9 05:35:44.207848 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207856 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207866 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207875 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207883 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207891 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207899 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 9 05:35:44.207907 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Sep 9 05:35:44.207915 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Sep 9 05:35:44.207923 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Sep 9 05:35:44.207931 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Sep 9 05:35:44.207941 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Sep 9 05:35:44.207949 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Sep 9 05:35:44.207957 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Sep 9 05:35:44.207965 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Sep 9 05:35:44.207973 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Sep 9 05:35:44.207981 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Sep 9 05:35:44.207989 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Sep 9 05:35:44.207997 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Sep 9 05:35:44.208007 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Sep 9 05:35:44.208015 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Sep 9 05:35:44.208023 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Sep 9 05:35:44.208031 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Sep 9 05:35:44.208039 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Sep 9 05:35:44.208047 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Sep 9 05:35:44.208056 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Sep 9 05:35:44.208064 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Sep 9 05:35:44.208072 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Sep 9 05:35:44.208082 kernel: Zone ranges: Sep 9 05:35:44.208090 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 9 05:35:44.208098 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 9 05:35:44.208106 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Sep 9 05:35:44.208114 kernel: Device empty Sep 9 05:35:44.208122 kernel: Movable zone start for each node Sep 9 05:35:44.208130 kernel: Early memory node ranges Sep 9 05:35:44.208138 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Sep 9 05:35:44.208146 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Sep 9 05:35:44.208157 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Sep 9 05:35:44.208165 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Sep 9 05:35:44.208173 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Sep 9 05:35:44.208181 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Sep 9 05:35:44.208189 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 05:35:44.208197 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Sep 9 05:35:44.208205 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Sep 9 05:35:44.208213 kernel: ACPI: PM-Timer IO Port: 0x408 Sep 9 05:35:44.208221 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Sep 9 05:35:44.208231 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Sep 9 05:35:44.208239 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 9 05:35:44.208247 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 9 05:35:44.208255 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Sep 9 05:35:44.208263 kernel: CPU topo: Max. logical packages: 1 Sep 9 05:35:44.208271 kernel: CPU topo: Max. logical dies: 1 Sep 9 05:35:44.208279 kernel: CPU topo: Max. dies per package: 1 Sep 9 05:35:44.208287 kernel: CPU topo: Max. threads per core: 1 Sep 9 05:35:44.208295 kernel: CPU topo: Num. cores per package: 6 Sep 9 05:35:44.208306 kernel: CPU topo: Num. threads per package: 6 Sep 9 05:35:44.208314 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Sep 9 05:35:44.208322 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Sep 9 05:35:44.208330 kernel: Booting paravirtualized kernel on Hyper-V Sep 9 05:35:44.208338 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 9 05:35:44.208346 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Sep 9 05:35:44.208354 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Sep 9 05:35:44.208362 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Sep 9 05:35:44.208370 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Sep 9 05:35:44.208380 kernel: Hyper-V: PV spinlocks enabled Sep 9 05:35:44.208389 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 9 05:35:44.208398 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=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:35:44.208406 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 05:35:44.208414 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Sep 9 05:35:44.208422 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Sep 9 05:35:44.208430 kernel: Fallback order for Node 0: 0 Sep 9 05:35:44.208438 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Sep 9 05:35:44.208449 kernel: Policy zone: Normal Sep 9 05:35:44.208465 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 05:35:44.208473 kernel: software IO TLB: area num 8. Sep 9 05:35:44.208484 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Sep 9 05:35:44.208492 kernel: Kernel/User page tables isolation: enabled Sep 9 05:35:44.208501 kernel: ftrace: allocating 40102 entries in 157 pages Sep 9 05:35:44.208509 kernel: ftrace: allocated 157 pages with 5 groups Sep 9 05:35:44.208518 kernel: Dynamic Preempt: voluntary Sep 9 05:35:44.208526 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 05:35:44.208538 kernel: rcu: RCU event tracing is enabled. Sep 9 05:35:44.208547 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Sep 9 05:35:44.208556 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 05:35:44.208567 kernel: Rude variant of Tasks RCU enabled. Sep 9 05:35:44.208576 kernel: Tracing variant of Tasks RCU enabled. Sep 9 05:35:44.208584 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 05:35:44.208593 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Sep 9 05:35:44.208601 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 9 05:35:44.208612 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 9 05:35:44.208621 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Sep 9 05:35:44.208630 kernel: Using NULL legacy PIC Sep 9 05:35:44.208638 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Sep 9 05:35:44.208647 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 05:35:44.208655 kernel: Console: colour dummy device 80x25 Sep 9 05:35:44.208663 kernel: printk: legacy console [tty1] enabled Sep 9 05:35:44.208672 kernel: printk: legacy console [ttyS0] enabled Sep 9 05:35:44.208680 kernel: printk: legacy bootconsole [earlyser0] disabled Sep 9 05:35:44.208691 kernel: ACPI: Core revision 20240827 Sep 9 05:35:44.208700 kernel: Failed to register legacy timer interrupt Sep 9 05:35:44.208716 kernel: APIC: Switch to symmetric I/O mode setup Sep 9 05:35:44.208725 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Sep 9 05:35:44.208734 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 9 05:35:44.208742 kernel: Hyper-V: Using IPI hypercalls Sep 9 05:35:44.208750 kernel: APIC: send_IPI() replaced with hv_send_ipi() Sep 9 05:35:44.208759 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Sep 9 05:35:44.208767 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Sep 9 05:35:44.208779 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Sep 9 05:35:44.208787 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Sep 9 05:35:44.208796 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Sep 9 05:35:44.208804 kernel: Hyper-V: Using enlightened APIC (xapic mode) Sep 9 05:35:44.208813 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Sep 9 05:35:44.208821 kernel: APIC: read() replaced with hv_apic_read() Sep 9 05:35:44.208830 kernel: APIC: write() replaced with hv_apic_write() Sep 9 05:35:44.208838 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Sep 9 05:35:44.208847 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Sep 9 05:35:44.208858 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Sep 9 05:35:44.208866 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Sep 9 05:35:44.208875 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Sep 9 05:35:44.208883 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 9 05:35:44.208892 kernel: Spectre V2 : Mitigation: Retpolines Sep 9 05:35:44.208900 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 9 05:35:44.208908 kernel: Speculative Store Bypass: Vulnerable Sep 9 05:35:44.208916 kernel: MDS: Mitigation: Clear CPU buffers Sep 9 05:35:44.208925 kernel: TAA: Mitigation: Clear CPU buffers Sep 9 05:35:44.208933 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 9 05:35:44.208943 kernel: active return thunk: its_return_thunk Sep 9 05:35:44.208952 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 9 05:35:44.208960 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 9 05:35:44.208968 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 9 05:35:44.208977 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 9 05:35:44.208985 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 9 05:35:44.208994 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 9 05:35:44.209002 kernel: Freeing SMP alternatives memory: 32K Sep 9 05:35:44.209010 kernel: pid_max: default: 32768 minimum: 301 Sep 9 05:35:44.209018 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 9 05:35:44.209027 kernel: landlock: Up and running. Sep 9 05:35:44.209037 kernel: SELinux: Initializing. Sep 9 05:35:44.209046 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 9 05:35:44.209054 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 9 05:35:44.209063 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Sep 9 05:35:44.209071 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Sep 9 05:35:44.209080 kernel: signal: max sigframe size: 1776 Sep 9 05:35:44.209088 kernel: rcu: Hierarchical SRCU implementation. Sep 9 05:35:44.209097 kernel: rcu: Max phase no-delay instances is 400. Sep 9 05:35:44.209106 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 9 05:35:44.209114 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 9 05:35:44.209125 kernel: smp: Bringing up secondary CPUs ... Sep 9 05:35:44.209134 kernel: smpboot: x86: Booting SMP configuration: Sep 9 05:35:44.209142 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Sep 9 05:35:44.209150 kernel: smp: Brought up 1 node, 6 CPUs Sep 9 05:35:44.209159 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Sep 9 05:35:44.209168 kernel: Memory: 115339092K/117439364K available (14336K kernel code, 2428K rwdata, 9988K rodata, 54076K init, 2892K bss, 2090004K reserved, 0K cma-reserved) Sep 9 05:35:44.209176 kernel: devtmpfs: initialized Sep 9 05:35:44.209185 kernel: x86/mm: Memory block size: 1024MB Sep 9 05:35:44.209194 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Sep 9 05:35:44.209205 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 05:35:44.209213 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Sep 9 05:35:44.209222 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 05:35:44.209230 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 05:35:44.209239 kernel: audit: initializing netlink subsys (disabled) Sep 9 05:35:44.209248 kernel: audit: type=2000 audit(1757396140.030:1): state=initialized audit_enabled=0 res=1 Sep 9 05:35:44.209256 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 05:35:44.209264 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 9 05:35:44.209273 kernel: cpuidle: using governor menu Sep 9 05:35:44.209284 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 05:35:44.209292 kernel: dca service started, version 1.12.1 Sep 9 05:35:44.209300 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Sep 9 05:35:44.209309 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 9 05:35:44.209317 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 05:35:44.209326 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 05:35:44.209334 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 05:35:44.209343 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 05:35:44.209351 kernel: ACPI: Added _OSI(Module Device) Sep 9 05:35:44.209362 kernel: ACPI: Added _OSI(Processor Device) Sep 9 05:35:44.209370 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 05:35:44.209379 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 9 05:35:44.209387 kernel: ACPI: Interpreter enabled Sep 9 05:35:44.209396 kernel: ACPI: PM: (supports S0 S5) Sep 9 05:35:44.209404 kernel: ACPI: Using IOAPIC for interrupt routing Sep 9 05:35:44.209413 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 9 05:35:44.209421 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 9 05:35:44.209430 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Sep 9 05:35:44.209440 kernel: iommu: Default domain type: Translated Sep 9 05:35:44.209449 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 9 05:35:44.209457 kernel: efivars: Registered efivars operations Sep 9 05:35:44.209466 kernel: PCI: Using ACPI for IRQ routing Sep 9 05:35:44.209474 kernel: PCI: System does not support PCI Sep 9 05:35:44.209483 kernel: vgaarb: loaded Sep 9 05:35:44.209491 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Sep 9 05:35:44.209500 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 05:35:44.209508 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 05:35:44.209519 kernel: pnp: PnP ACPI init Sep 9 05:35:44.209528 kernel: pnp: PnP ACPI: found 3 devices Sep 9 05:35:44.209536 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 9 05:35:44.209545 kernel: NET: Registered PF_INET protocol family Sep 9 05:35:44.209553 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 9 05:35:44.209562 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Sep 9 05:35:44.209570 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 05:35:44.209579 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 05:35:44.209588 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:35:44.209598 kernel: TCP: Hash tables configured (established 524288 bind 65536) Sep 9 05:35:44.209607 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:35:44.209615 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:35:44.209624 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 05:35:44.209632 kernel: NET: Registered PF_XDP protocol family Sep 9 05:35:44.209641 kernel: PCI: CLS 0 bytes, default 64 Sep 9 05:35:44.209649 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 9 05:35:44.209658 kernel: software IO TLB: mapped [mem 0x000000003ae5e000-0x000000003ee5e000] (64MB) Sep 9 05:35:44.209673 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 9 05:35:44.209685 kernel: Initialise system trusted keyrings Sep 9 05:35:44.209694 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Sep 9 05:35:44.209752 kernel: Key type asymmetric registered Sep 9 05:35:44.209763 kernel: Asymmetric key parser 'x509' registered Sep 9 05:35:44.209772 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 9 05:35:44.209780 kernel: io scheduler mq-deadline registered Sep 9 05:35:44.209789 kernel: io scheduler kyber registered Sep 9 05:35:44.209797 kernel: io scheduler bfq registered Sep 9 05:35:44.209806 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 9 05:35:44.209818 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 05:35:44.209826 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 05:35:44.209835 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 9 05:35:44.209843 kernel: i8042: PNP: No PS/2 controller found. Sep 9 05:35:44.209993 kernel: rtc_cmos 00:02: registered as rtc0 Sep 9 05:35:44.210095 kernel: rtc_cmos 00:02: setting system clock to 2025-09-09T05:35:43 UTC (1757396143) Sep 9 05:35:44.210189 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Sep 9 05:35:44.210205 kernel: intel_pstate: CPU model not supported Sep 9 05:35:44.210214 kernel: efifb: probing for efifb Sep 9 05:35:44.210223 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 9 05:35:44.210232 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 9 05:35:44.210264 kernel: efifb: scrolling: redraw Sep 9 05:35:44.210295 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 9 05:35:44.210306 kernel: Console: switching to colour frame buffer device 128x48 Sep 9 05:35:44.210314 kernel: fb0: EFI VGA frame buffer device Sep 9 05:35:44.210323 kernel: pstore: Using crash dump compression: deflate Sep 9 05:35:44.210336 kernel: pstore: Registered efi_pstore as persistent store backend Sep 9 05:35:44.210345 kernel: NET: Registered PF_INET6 protocol family Sep 9 05:35:44.210353 kernel: Segment Routing with IPv6 Sep 9 05:35:44.210362 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 05:35:44.210370 kernel: NET: Registered PF_PACKET protocol family Sep 9 05:35:44.210379 kernel: Key type dns_resolver registered Sep 9 05:35:44.210387 kernel: IPI shorthand broadcast: enabled Sep 9 05:35:44.210396 kernel: sched_clock: Marking stable (3735004000, 123187100)->(4218891300, -360700200) Sep 9 05:35:44.210404 kernel: registered taskstats version 1 Sep 9 05:35:44.210413 kernel: Loading compiled-in X.509 certificates Sep 9 05:35:44.210424 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.45-flatcar: 884b9ad6a330f59ae6e6488b20a5491e41ff24a3' Sep 9 05:35:44.210433 kernel: Demotion targets for Node 0: null Sep 9 05:35:44.210441 kernel: Key type .fscrypt registered Sep 9 05:35:44.210449 kernel: Key type fscrypt-provisioning registered Sep 9 05:35:44.210458 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 9 05:35:44.210466 kernel: ima: Allocated hash algorithm: sha1 Sep 9 05:35:44.210475 kernel: ima: No architecture policies found Sep 9 05:35:44.210483 kernel: clk: Disabling unused clocks Sep 9 05:35:44.210494 kernel: Warning: unable to open an initial console. Sep 9 05:35:44.210503 kernel: Freeing unused kernel image (initmem) memory: 54076K Sep 9 05:35:44.210511 kernel: Write protecting the kernel read-only data: 24576k Sep 9 05:35:44.210520 kernel: Freeing unused kernel image (rodata/data gap) memory: 252K Sep 9 05:35:44.210528 kernel: Run /init as init process Sep 9 05:35:44.210537 kernel: with arguments: Sep 9 05:35:44.210545 kernel: /init Sep 9 05:35:44.210553 kernel: with environment: Sep 9 05:35:44.210562 kernel: HOME=/ Sep 9 05:35:44.210573 kernel: TERM=linux Sep 9 05:35:44.210581 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 05:35:44.210591 systemd[1]: Successfully made /usr/ read-only. Sep 9 05:35:44.210602 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) Sep 9 05:35:44.210612 systemd[1]: Detected virtualization microsoft. Sep 9 05:35:44.210621 systemd[1]: Detected architecture x86-64. Sep 9 05:35:44.210630 systemd[1]: Running in initrd. Sep 9 05:35:44.210638 systemd[1]: No hostname configured, using default hostname. Sep 9 05:35:44.210650 systemd[1]: Hostname set to . Sep 9 05:35:44.210659 systemd[1]: Initializing machine ID from random generator. Sep 9 05:35:44.210668 systemd[1]: Queued start job for default target initrd.target. Sep 9 05:35:44.210677 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:35:44.210686 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:35:44.210696 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 05:35:44.210717 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 05:35:44.210727 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 05:35:44.210740 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 05:35:44.210750 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 05:35:44.210759 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 05:35:44.210768 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:35:44.210777 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:35:44.210786 systemd[1]: Reached target paths.target - Path Units. Sep 9 05:35:44.210798 systemd[1]: Reached target slices.target - Slice Units. Sep 9 05:35:44.210807 systemd[1]: Reached target swap.target - Swaps. Sep 9 05:35:44.210816 systemd[1]: Reached target timers.target - Timer Units. Sep 9 05:35:44.210825 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 05:35:44.210834 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 05:35:44.210843 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 05:35:44.210852 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 9 05:35:44.210861 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:35:44.210870 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 05:35:44.210882 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:35:44.210891 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 05:35:44.210900 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 05:35:44.210909 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 05:35:44.210918 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 05:35:44.210927 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). Sep 9 05:35:44.210937 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 05:35:44.210946 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 05:35:44.210957 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 05:35:44.210989 systemd-journald[232]: Collecting audit messages is disabled. Sep 9 05:35:44.211011 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:44.211021 systemd-journald[232]: Journal started Sep 9 05:35:44.211044 systemd-journald[232]: Runtime Journal (/run/log/journal/fe63d76aff7e4c039716b45e65c76b2c) is 8M, max 2.2G, 2.1G free. Sep 9 05:35:44.214174 systemd-modules-load[234]: Inserted module 'overlay' Sep 9 05:35:44.219570 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 05:35:44.219951 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 05:35:44.220081 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:35:44.220843 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 05:35:44.223041 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 05:35:44.224168 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 05:35:44.269652 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:44.273516 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 05:35:44.279619 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:35:44.290585 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:35:44.294594 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 05:35:44.308178 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 05:35:44.337726 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 05:35:44.345174 systemd-modules-load[234]: Inserted module 'br_netfilter' Sep 9 05:35:44.348787 kernel: Bridge firewalling registered Sep 9 05:35:44.350906 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 05:35:44.352619 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 05:35:44.367335 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 05:35:44.378468 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 05:35:44.383410 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:35:44.385963 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:35:44.405318 dracut-cmdline[271]: 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=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:35:44.399072 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 05:35:44.464279 systemd-resolved[289]: Positive Trust Anchors: Sep 9 05:35:44.464293 systemd-resolved[289]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 05:35:44.464323 systemd-resolved[289]: 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 Sep 9 05:35:44.467101 systemd-resolved[289]: Defaulting to hostname 'linux'. Sep 9 05:35:44.468131 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 05:35:44.478462 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:35:44.532721 kernel: SCSI subsystem initialized Sep 9 05:35:44.543728 kernel: Loading iSCSI transport class v2.0-870. Sep 9 05:35:44.556729 kernel: iscsi: registered transport (tcp) Sep 9 05:35:44.581089 kernel: iscsi: registered transport (qla4xxx) Sep 9 05:35:44.581116 kernel: QLogic iSCSI HBA Driver Sep 9 05:35:44.600769 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 05:35:44.624473 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:35:44.627885 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 05:35:44.688008 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 05:35:44.690794 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 05:35:44.761724 kernel: raid6: avx2x4 gen() 30848 MB/s Sep 9 05:35:44.780724 kernel: raid6: avx2x2 gen() 31154 MB/s Sep 9 05:35:44.801942 kernel: raid6: avx2x1 gen() 27637 MB/s Sep 9 05:35:44.801987 kernel: raid6: using algorithm avx2x2 gen() 31154 MB/s Sep 9 05:35:44.824880 kernel: raid6: .... xor() 16482 MB/s, rmw enabled Sep 9 05:35:44.824938 kernel: raid6: using avx2x2 recovery algorithm Sep 9 05:35:44.847727 kernel: xor: automatically using best checksumming function avx Sep 9 05:35:45.011735 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 05:35:45.019120 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 05:35:45.022264 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:35:45.071166 systemd-udevd[485]: Using default interface naming scheme 'v255'. Sep 9 05:35:45.077274 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:35:45.079613 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 05:35:45.121034 dracut-pre-trigger[493]: rd.md=0: removing MD RAID activation Sep 9 05:35:45.151368 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 05:35:45.161366 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 05:35:45.237861 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:35:45.253875 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 05:35:45.267743 kernel: cryptd: max_cpu_qlen set to 1000 Sep 9 05:35:45.279733 kernel: AES CTR mode by8 optimization enabled Sep 9 05:35:45.290729 kernel: hv_vmbus: Vmbus version:4.0 Sep 9 05:35:45.291507 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:35:45.295252 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:45.304451 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:45.310346 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:45.316602 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:35:45.331385 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 9 05:35:45.333277 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:35:45.351190 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 9 05:35:45.351220 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 9 05:35:45.333379 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:45.345603 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:35:45.368731 kernel: hv_vmbus: registering driver hv_storvsc Sep 9 05:35:45.371559 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 9 05:35:45.371589 kernel: hv_vmbus: registering driver hv_pci Sep 9 05:35:45.371602 kernel: scsi host0: storvsc_host_t Sep 9 05:35:45.371808 kernel: hv_vmbus: registering driver hv_netvsc Sep 9 05:35:45.374732 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 Sep 9 05:35:45.374771 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 9 05:35:45.386728 kernel: hv_vmbus: registering driver hid_hyperv Sep 9 05:35:45.386767 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Sep 9 05:35:45.397439 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 9 05:35:45.397471 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 9 05:35:45.397663 kernel: scsi host1: storvsc_host_t Sep 9 05:35:45.397691 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Sep 9 05:35:45.397841 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Sep 9 05:35:45.397979 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Sep 9 05:35:45.398096 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Sep 9 05:35:45.398209 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 9 05:35:45.398343 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Sep 9 05:35:45.398369 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Sep 9 05:35:45.398391 kernel: PTP clock support registered Sep 9 05:35:45.398402 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Sep 9 05:35:45.398824 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Sep 9 05:35:45.398877 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 9 05:35:45.400814 kernel: pci 0001:00:00.0: Enabling HDA controller Sep 9 05:35:45.423556 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Sep 9 05:35:45.427724 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 9 05:35:45.427901 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Sep 9 05:35:45.428056 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Sep 9 05:35:45.428195 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Sep 9 05:35:45.430731 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 9 05:35:45.430908 kernel: sd 0:0:0:1: [sdb] Write Protect is off Sep 9 05:35:45.431056 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Sep 9 05:35:45.436941 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 9 05:35:45.437123 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Sep 9 05:35:45.437275 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Sep 9 05:35:45.440722 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 9 05:35:45.440975 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#403 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:45.444726 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 9 05:35:45.444894 kernel: hv_utils: Registering HyperV Utility Driver Sep 9 05:35:45.444908 kernel: hv_vmbus: registering driver hv_utils Sep 9 05:35:45.452726 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#9 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:45.521887 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 9 05:35:45.522745 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#406 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:45.534725 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#413 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:45.539518 kernel: sdb: sdb1 Sep 9 05:35:45.539547 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Sep 9 05:35:45.541203 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:45.600245 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:35:45.600276 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 9 05:35:45.840146 kernel: hv_utils: Shutdown IC version 3.2 Sep 9 05:35:45.840211 kernel: hv_utils: Heartbeat IC version 3.0 Sep 9 05:35:45.842973 kernel: hv_utils: TimeSync IC version 4.0 Sep 9 05:35:45.566249 systemd-resolved[289]: Clock change detected. Flushing caches. Sep 9 05:35:45.581891 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 9 05:35:45.582060 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 9 05:35:45.582073 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 9 05:35:45.582208 systemd-journald[232]: Time jumped backwards, rotating. Sep 9 05:35:45.898037 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 9 05:35:45.903441 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 9 05:35:45.931500 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 9 05:35:45.955708 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 9 05:35:45.994369 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 9 05:35:46.008261 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 05:35:47.037788 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#364 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:35:47.055487 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:35:47.055732 disk-uuid[720]: The operation has completed successfully. Sep 9 05:35:48.370988 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 05:35:48.371106 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 05:35:48.379966 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 05:35:48.390055 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 05:35:48.398366 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:35:48.408004 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 05:35:48.424442 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 05:35:48.426394 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 05:35:48.453811 sh[891]: Success Sep 9 05:35:48.460085 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 05:35:48.491243 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 05:35:48.491280 kernel: device-mapper: uevent: version 1.0.3 Sep 9 05:35:48.498971 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 9 05:35:48.511485 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 9 05:35:48.819997 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 05:35:48.829956 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 05:35:48.851302 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 05:35:48.869525 kernel: BTRFS: device fsid 9ca60a92-6b53-4529-adc0-1f4392d2ad56 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (916) Sep 9 05:35:48.878642 kernel: BTRFS info (device dm-0): first mount of filesystem 9ca60a92-6b53-4529-adc0-1f4392d2ad56 Sep 9 05:35:48.878676 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:49.183460 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 05:35:49.183555 kernel: BTRFS info (device dm-0): enabling free space tree Sep 9 05:35:49.272891 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 05:35:49.274924 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 9 05:35:49.281807 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 05:35:49.282752 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 05:35:49.320753 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 05:35:49.373507 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (947) Sep 9 05:35:49.373571 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:49.382240 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:49.432604 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 05:35:49.435993 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 05:35:49.458807 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:35:49.458844 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:35:49.467486 kernel: BTRFS info (device sda6): last unmount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:49.470653 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 05:35:49.473092 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 05:35:49.500170 systemd-networkd[1079]: lo: Link UP Sep 9 05:35:49.500181 systemd-networkd[1079]: lo: Gained carrier Sep 9 05:35:49.501206 systemd-networkd[1079]: Enumeration completed Sep 9 05:35:49.501292 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 05:35:49.501590 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:35:49.501595 systemd-networkd[1079]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:35:49.502351 systemd-networkd[1079]: eth0: Link UP Sep 9 05:35:49.502525 systemd-networkd[1079]: eth0: Gained carrier Sep 9 05:35:49.502535 systemd-networkd[1079]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:35:49.507353 systemd[1]: Reached target network.target - Network. Sep 9 05:35:49.577536 systemd-networkd[1079]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 9 05:35:50.446223 ignition[1086]: Ignition 2.22.0 Sep 9 05:35:50.446237 ignition[1086]: Stage: fetch-offline Sep 9 05:35:50.446386 ignition[1086]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.450398 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 05:35:50.446397 ignition[1086]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.446532 ignition[1086]: parsed url from cmdline: "" Sep 9 05:35:50.464105 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 9 05:35:50.446536 ignition[1086]: no config URL provided Sep 9 05:35:50.446542 ignition[1086]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 05:35:50.446552 ignition[1086]: no config at "/usr/lib/ignition/user.ign" Sep 9 05:35:50.446557 ignition[1086]: failed to fetch config: resource requires networking Sep 9 05:35:50.448818 ignition[1086]: Ignition finished successfully Sep 9 05:35:50.519156 ignition[1098]: Ignition 2.22.0 Sep 9 05:35:50.519169 ignition[1098]: Stage: fetch Sep 9 05:35:50.519415 ignition[1098]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.519427 ignition[1098]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.519538 ignition[1098]: parsed url from cmdline: "" Sep 9 05:35:50.519543 ignition[1098]: no config URL provided Sep 9 05:35:50.519548 ignition[1098]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 05:35:50.519557 ignition[1098]: no config at "/usr/lib/ignition/user.ign" Sep 9 05:35:50.519576 ignition[1098]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 9 05:35:50.634589 ignition[1098]: GET result: OK Sep 9 05:35:50.634657 ignition[1098]: config has been read from IMDS userdata Sep 9 05:35:50.634670 ignition[1098]: parsing config with SHA512: 0f5a66d2b15dba92b0e07e697cb7dfec191bfcce9524a14536bc092285658b77478925d2f69f572a6aaad3de5cbde0042177c2d76b5d7042cc4002317c924129 Sep 9 05:35:50.641745 unknown[1098]: fetched base config from "system" Sep 9 05:35:50.641757 unknown[1098]: fetched base config from "system" Sep 9 05:35:50.641907 ignition[1098]: fetch: fetch complete Sep 9 05:35:50.641762 unknown[1098]: fetched user config from "azure" Sep 9 05:35:50.641913 ignition[1098]: fetch: fetch passed Sep 9 05:35:50.650965 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 9 05:35:50.641957 ignition[1098]: Ignition finished successfully Sep 9 05:35:50.653902 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 05:35:50.700915 ignition[1108]: Ignition 2.22.0 Sep 9 05:35:50.700931 ignition[1108]: Stage: kargs Sep 9 05:35:50.701167 ignition[1108]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.701182 ignition[1108]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.701714 ignition[1108]: kargs: kargs passed Sep 9 05:35:50.701763 ignition[1108]: Ignition finished successfully Sep 9 05:35:50.721735 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 05:35:50.730409 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 05:35:50.770590 ignition[1119]: Ignition 2.22.0 Sep 9 05:35:50.770604 ignition[1119]: Stage: disks Sep 9 05:35:50.770884 ignition[1119]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:50.770914 ignition[1119]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:50.777312 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 05:35:50.771786 ignition[1119]: disks: disks passed Sep 9 05:35:50.795497 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 05:35:50.771833 ignition[1119]: Ignition finished successfully Sep 9 05:35:50.796943 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 05:35:50.806451 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 05:35:50.818825 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 05:35:50.825705 systemd[1]: Reached target basic.target - Basic System. Sep 9 05:35:50.834994 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 05:35:50.913197 systemd-fsck[1131]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Sep 9 05:35:50.918285 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 05:35:50.921172 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 05:35:51.360625 systemd-networkd[1079]: eth0: Gained IPv6LL Sep 9 05:35:52.795480 kernel: EXT4-fs (sda9): mounted filesystem d2d7815e-fa16-4396-ab9d-ac540c1d8856 r/w with ordered data mode. Quota mode: none. Sep 9 05:35:52.795919 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 05:35:52.798681 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 05:35:52.845818 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 05:35:52.870956 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 05:35:52.872784 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 9 05:35:52.892497 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1145) Sep 9 05:35:52.879637 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 05:35:52.879678 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 05:35:52.921596 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:52.921618 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:52.931304 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:35:52.931332 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:35:52.933062 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 05:35:52.945767 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 05:35:52.948923 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 05:35:54.175667 coreos-metadata[1147]: Sep 09 05:35:54.175 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 9 05:35:54.183453 coreos-metadata[1147]: Sep 09 05:35:54.183 INFO Fetch successful Sep 9 05:35:54.187337 coreos-metadata[1147]: Sep 09 05:35:54.183 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 9 05:35:54.202723 coreos-metadata[1147]: Sep 09 05:35:54.202 INFO Fetch successful Sep 9 05:35:54.216210 coreos-metadata[1147]: Sep 09 05:35:54.216 INFO wrote hostname ci-4452.0.0-n-116516c315 to /sysroot/etc/hostname Sep 9 05:35:54.218570 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 9 05:35:54.894570 initrd-setup-root[1175]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 05:35:55.012522 initrd-setup-root[1182]: cut: /sysroot/etc/group: No such file or directory Sep 9 05:35:55.060420 initrd-setup-root[1189]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 05:35:55.067716 initrd-setup-root[1196]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 05:35:56.633425 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 05:35:56.637070 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 05:35:56.655169 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 05:35:56.660826 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 05:35:56.670952 kernel: BTRFS info (device sda6): last unmount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:56.691313 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 05:35:56.698806 ignition[1263]: INFO : Ignition 2.22.0 Sep 9 05:35:56.698806 ignition[1263]: INFO : Stage: mount Sep 9 05:35:56.698806 ignition[1263]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:56.698806 ignition[1263]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:56.698806 ignition[1263]: INFO : mount: mount passed Sep 9 05:35:56.698806 ignition[1263]: INFO : Ignition finished successfully Sep 9 05:35:56.703024 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 05:35:56.709614 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 05:35:56.745015 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 05:35:56.770493 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1280) Sep 9 05:35:56.770535 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:35:56.779083 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:35:56.788453 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:35:56.788490 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:35:56.790911 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 05:35:56.829209 ignition[1297]: INFO : Ignition 2.22.0 Sep 9 05:35:56.829209 ignition[1297]: INFO : Stage: files Sep 9 05:35:56.834982 ignition[1297]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:56.834982 ignition[1297]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:56.834982 ignition[1297]: DEBUG : files: compiled without relabeling support, skipping Sep 9 05:35:56.860863 ignition[1297]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 05:35:56.860863 ignition[1297]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 05:35:56.950616 ignition[1297]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 05:35:56.956123 ignition[1297]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 05:35:56.961427 ignition[1297]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 05:35:56.956634 unknown[1297]: wrote ssh authorized keys file for user: core Sep 9 05:35:56.991646 ignition[1297]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 05:35:56.998884 ignition[1297]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 05:35:56.998884 ignition[1297]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 05:35:56.998884 ignition[1297]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 05:35:56.998884 ignition[1297]: INFO : files: files passed Sep 9 05:35:56.998884 ignition[1297]: INFO : Ignition finished successfully Sep 9 05:35:57.007819 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 05:35:57.015730 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 05:35:57.052024 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 05:35:57.062478 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 05:35:57.062612 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 05:35:57.090983 initrd-setup-root-after-ignition[1330]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:35:57.090983 initrd-setup-root-after-ignition[1330]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:35:57.103921 initrd-setup-root-after-ignition[1334]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:35:57.104911 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 05:35:57.115288 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 05:35:57.126503 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 05:35:57.170542 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 05:35:57.170653 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 05:35:57.179838 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 05:35:57.189015 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 05:35:57.197588 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 05:35:57.198432 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 05:35:57.248222 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 05:35:57.251056 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 05:35:57.283569 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:35:57.284612 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:35:57.297422 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 05:35:57.299843 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 05:35:57.299980 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 05:35:57.313679 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 05:35:57.322323 systemd[1]: Stopped target basic.target - Basic System. Sep 9 05:35:57.330630 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 05:35:57.338906 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 05:35:57.347020 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 05:35:57.348492 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 9 05:35:57.349090 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 05:35:57.349828 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 05:35:57.350573 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 05:35:57.463929 ignition[1354]: INFO : Ignition 2.22.0 Sep 9 05:35:57.463929 ignition[1354]: INFO : Stage: umount Sep 9 05:35:57.463929 ignition[1354]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:35:57.463929 ignition[1354]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 9 05:35:57.463929 ignition[1354]: INFO : umount: umount passed Sep 9 05:35:57.463929 ignition[1354]: INFO : Ignition finished successfully Sep 9 05:35:57.351335 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 05:35:57.352050 systemd[1]: Stopped target swap.target - Swaps. Sep 9 05:35:57.352748 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 05:35:57.352874 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 05:35:57.354242 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:35:57.354890 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:35:57.355566 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 05:35:57.355696 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:35:57.356302 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 05:35:57.356414 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 05:35:57.357842 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 05:35:57.357962 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 05:35:57.358565 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 05:35:57.358676 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 05:35:57.359279 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 9 05:35:57.359380 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 9 05:35:57.360908 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 05:35:57.362139 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 05:35:57.362897 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 05:35:57.363013 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:35:57.363688 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 05:35:57.363797 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 05:35:57.368105 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 05:35:57.443678 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 05:35:57.467917 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 05:35:57.468034 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 05:35:57.473792 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 05:35:57.473844 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 05:35:57.481439 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 05:35:57.481500 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 05:35:57.490096 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 9 05:35:57.490143 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 9 05:35:57.497003 systemd[1]: Stopped target network.target - Network. Sep 9 05:35:57.503981 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 05:35:57.504045 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 05:35:57.505580 systemd[1]: Stopped target paths.target - Path Units. Sep 9 05:35:57.513850 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 05:35:57.517511 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:35:57.520342 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 05:35:57.528400 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 05:35:57.538970 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 05:35:57.539014 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 05:35:57.546995 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 05:35:57.547036 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 05:35:57.549231 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 05:35:57.549284 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 05:35:57.560645 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 05:35:57.560691 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 05:35:57.581902 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 05:35:57.589673 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 05:35:57.598585 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 05:35:57.599190 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 05:35:57.599290 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 05:35:57.605678 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 05:35:57.605779 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 05:35:57.623141 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 05:35:57.623258 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 05:35:57.636260 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 9 05:35:57.636321 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 9 05:35:57.637631 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 9 05:35:57.641500 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 05:35:57.641554 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:35:57.649539 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 05:35:57.649593 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 05:35:57.658549 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 05:35:57.666685 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 05:35:57.666745 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 05:35:57.674674 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 05:35:57.674727 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:35:57.690526 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 05:35:57.690573 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 05:35:57.692145 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 05:35:57.692195 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:35:57.712203 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:35:57.722158 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 9 05:35:57.722224 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:35:57.758071 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 05:35:57.758225 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:35:57.759915 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 05:35:57.759961 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 05:35:57.768823 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 05:35:57.768855 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:35:57.778864 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 05:35:57.778917 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 05:35:57.793111 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 05:35:57.793162 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 05:35:57.803984 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 05:35:57.804082 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 05:35:57.816933 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 05:35:58.101110 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Sep 9 05:35:57.824865 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 9 05:35:57.824922 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:35:57.837447 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 05:35:57.837518 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:35:57.850498 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 9 05:35:57.850544 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:35:57.863344 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 05:35:57.863389 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:35:57.871911 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:35:57.871956 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:35:57.884886 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 9 05:35:57.884940 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Sep 9 05:35:57.884979 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 9 05:35:57.885018 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:35:57.885384 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 05:35:57.885492 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 05:35:57.889704 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 05:35:57.889792 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 05:35:57.898099 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 05:35:57.906605 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 05:35:57.971156 systemd[1]: Switching root. Sep 9 05:35:58.115927 systemd-journald[232]: Journal stopped Sep 9 05:36:07.861636 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 05:36:07.861667 kernel: SELinux: policy capability open_perms=1 Sep 9 05:36:07.861679 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 05:36:07.861688 kernel: SELinux: policy capability always_check_network=0 Sep 9 05:36:07.861697 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 05:36:07.861705 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 05:36:07.861716 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 05:36:07.861728 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 05:36:07.861737 kernel: SELinux: policy capability userspace_initial_context=0 Sep 9 05:36:07.861747 kernel: audit: type=1403 audit(1757396159.547:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 05:36:07.861758 systemd[1]: Successfully loaded SELinux policy in 263.715ms. Sep 9 05:36:07.861770 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.902ms. Sep 9 05:36:07.861781 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) Sep 9 05:36:07.861791 systemd[1]: Detected virtualization microsoft. Sep 9 05:36:07.861810 systemd[1]: Detected architecture x86-64. Sep 9 05:36:07.861821 systemd[1]: Detected first boot. Sep 9 05:36:07.861832 systemd[1]: Hostname set to . Sep 9 05:36:07.861843 systemd[1]: Initializing machine ID from random generator. Sep 9 05:36:07.861853 zram_generator::config[1400]: No configuration found. Sep 9 05:36:07.861867 kernel: Guest personality initialized and is inactive Sep 9 05:36:07.861877 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Sep 9 05:36:07.861887 kernel: Initialized host personality Sep 9 05:36:07.861896 kernel: NET: Registered PF_VSOCK protocol family Sep 9 05:36:07.861907 systemd[1]: Populated /etc with preset unit settings. Sep 9 05:36:07.861918 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 9 05:36:07.861929 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 05:36:07.861942 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 05:36:07.861952 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 05:36:07.861963 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 05:36:07.861974 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 05:36:07.861985 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 05:36:07.861995 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 05:36:07.862006 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 05:36:07.862017 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 05:36:07.862030 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 05:36:07.862046 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 05:36:07.862057 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:36:07.862068 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:36:07.862079 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 05:36:07.862089 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 05:36:07.862104 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 05:36:07.862116 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 05:36:07.862132 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 9 05:36:07.862144 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:36:07.862154 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:36:07.862165 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 05:36:07.862176 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 05:36:07.862187 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 05:36:07.862197 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 05:36:07.862211 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:36:07.862222 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 05:36:07.862233 systemd[1]: Reached target slices.target - Slice Units. Sep 9 05:36:07.862244 systemd[1]: Reached target swap.target - Swaps. Sep 9 05:36:07.862254 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 05:36:07.862265 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 05:36:07.862279 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 9 05:36:07.862295 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:36:07.862306 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 05:36:07.862317 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:36:07.862376 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 05:36:07.862407 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 05:36:07.862420 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 05:36:07.862438 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 05:36:07.862450 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:07.862462 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 05:36:07.862488 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 05:36:07.862501 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 05:36:07.862513 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). Sep 9 05:36:07.862525 systemd[1]: Reached target machines.target - Containers. Sep 9 05:36:07.862537 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 05:36:07.862548 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:07.862564 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 05:36:07.862576 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 05:36:07.862587 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:36:07.862599 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 05:36:07.862610 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:36:07.862621 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 05:36:07.862639 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:36:07.862651 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 05:36:07.862665 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 05:36:07.862677 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 05:36:07.862688 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 05:36:07.862700 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 05:36:07.862712 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). Sep 9 05:36:07.862724 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 05:36:07.862735 kernel: fuse: init (API version 7.41) Sep 9 05:36:07.862746 kernel: loop: module loaded Sep 9 05:36:07.862760 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 05:36:07.862772 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 05:36:07.862783 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 05:36:07.862795 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 9 05:36:07.862806 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 05:36:07.862817 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 05:36:07.862829 systemd[1]: Stopped verity-setup.service. Sep 9 05:36:07.862870 systemd-journald[1507]: Collecting audit messages is disabled. Sep 9 05:36:07.862902 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:07.862915 systemd-journald[1507]: Journal started Sep 9 05:36:07.862941 systemd-journald[1507]: Runtime Journal (/run/log/journal/64d08bb0584e4d6ebf6966b02540aefd) is 8M, max 2.2G, 2.1G free. Sep 9 05:36:06.874359 systemd[1]: Queued start job for default target multi-user.target. Sep 9 05:36:06.899937 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 9 05:36:06.900451 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 05:36:07.881103 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 05:36:07.885624 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 05:36:07.890069 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 05:36:07.894650 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 05:36:07.898882 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 05:36:07.903498 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 05:36:07.908133 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 05:36:07.912533 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 05:36:07.917793 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:36:07.923175 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 05:36:07.923404 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 05:36:07.928601 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:36:07.928809 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:36:07.933797 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:36:07.934013 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:36:07.939566 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 05:36:07.939859 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 05:36:07.944826 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:36:07.945059 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:36:07.949911 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 05:36:07.955185 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:36:07.961480 kernel: ACPI: bus type drm_connector registered Sep 9 05:36:07.963397 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 05:36:07.969304 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 05:36:07.969563 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 05:36:07.975247 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 9 05:36:07.990234 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 05:36:07.997193 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 05:36:08.016678 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 05:36:08.021089 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 05:36:08.021171 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 05:36:08.023787 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 9 05:36:08.031760 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 05:36:08.036015 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:08.037200 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 05:36:08.042617 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 05:36:08.047422 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:36:08.048434 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 05:36:08.052881 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:36:08.055205 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 05:36:08.059984 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 05:36:08.065751 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 05:36:08.072789 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:36:08.078495 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 05:36:08.084442 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 05:36:08.096092 systemd-journald[1507]: Time spent on flushing to /var/log/journal/64d08bb0584e4d6ebf6966b02540aefd is 42.097ms for 955 entries. Sep 9 05:36:08.096092 systemd-journald[1507]: System Journal (/var/log/journal/64d08bb0584e4d6ebf6966b02540aefd) is 11.9M, max 2.6G, 2.6G free. Sep 9 05:36:08.323840 systemd-journald[1507]: Received client request to flush runtime journal. Sep 9 05:36:08.323927 systemd-journald[1507]: /var/log/journal/64d08bb0584e4d6ebf6966b02540aefd/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. Sep 9 05:36:08.323959 systemd-journald[1507]: Rotating system journal. Sep 9 05:36:08.323987 kernel: loop0: detected capacity change from 0 to 110984 Sep 9 05:36:08.138211 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 05:36:08.143764 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 05:36:08.162088 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 9 05:36:08.221621 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:36:08.255622 systemd-tmpfiles[1543]: ACLs are not supported, ignoring. Sep 9 05:36:08.255639 systemd-tmpfiles[1543]: ACLs are not supported, ignoring. Sep 9 05:36:08.259850 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:36:08.266412 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 05:36:08.325367 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 05:36:08.352478 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 05:36:08.353188 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 9 05:36:09.154397 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 05:36:09.161483 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 05:36:09.195414 systemd-tmpfiles[1563]: ACLs are not supported, ignoring. Sep 9 05:36:09.195688 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 05:36:09.195442 systemd-tmpfiles[1563]: ACLs are not supported, ignoring. Sep 9 05:36:09.199117 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:36:09.411496 kernel: loop1: detected capacity change from 0 to 27936 Sep 9 05:36:10.051647 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 05:36:10.058065 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:36:10.094703 systemd-udevd[1567]: Using default interface naming scheme 'v255'. Sep 9 05:36:10.648497 kernel: loop2: detected capacity change from 0 to 128016 Sep 9 05:36:10.917218 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:36:10.926776 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 05:36:10.949288 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 9 05:36:11.044494 kernel: mousedev: PS/2 mouse device common for all mice Sep 9 05:36:11.064409 kernel: hv_vmbus: registering driver hv_balloon Sep 9 05:36:11.064504 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 9 05:36:11.085137 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:36:11.085985 kernel: hv_vmbus: registering driver hyperv_fb Sep 9 05:36:11.102519 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 9 05:36:11.102589 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 9 05:36:11.106252 kernel: Console: switching to colour dummy device 80x25 Sep 9 05:36:11.111235 kernel: Console: switching to colour frame buffer device 128x48 Sep 9 05:36:11.136712 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:36:11.136949 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:36:11.142835 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:36:11.144823 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 05:36:11.149945 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:36:11.208286 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 05:36:11.275548 kernel: EDAC MC: Ver: 3.0.0 Sep 9 05:36:11.387662 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 9 05:36:11.392374 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 05:36:11.439730 systemd-networkd[1592]: lo: Link UP Sep 9 05:36:11.439739 systemd-networkd[1592]: lo: Gained carrier Sep 9 05:36:11.441124 systemd-networkd[1592]: Enumeration completed Sep 9 05:36:11.441238 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 05:36:11.441530 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:36:11.441540 systemd-networkd[1592]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:36:11.442304 systemd-networkd[1592]: eth0: Link UP Sep 9 05:36:11.442308 systemd-networkd[1592]: eth0: Gained carrier Sep 9 05:36:11.442323 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:36:11.444636 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 9 05:36:11.446308 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 05:36:11.479495 kernel: loop3: detected capacity change from 0 to 110984 Sep 9 05:36:11.493537 systemd-networkd[1592]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 9 05:36:11.496578 kernel: loop4: detected capacity change from 0 to 27936 Sep 9 05:36:11.506930 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 05:36:11.509732 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 9 05:36:11.522484 kernel: loop5: detected capacity change from 0 to 128016 Sep 9 05:36:11.535884 (sd-merge)[1723]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 9 05:36:11.536372 (sd-merge)[1723]: Merged extensions into '/usr'. Sep 9 05:36:11.540506 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 05:36:11.546680 systemd[1]: Starting ensure-sysext.service... Sep 9 05:36:11.549709 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 05:36:11.571160 systemd[1]: Reload requested from client PID 1727 ('systemctl') (unit ensure-sysext.service)... Sep 9 05:36:11.571179 systemd[1]: Reloading... Sep 9 05:36:11.614715 systemd-tmpfiles[1728]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 9 05:36:11.614774 systemd-tmpfiles[1728]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 9 05:36:11.615123 systemd-tmpfiles[1728]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 05:36:11.615587 systemd-tmpfiles[1728]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 05:36:11.616536 systemd-tmpfiles[1728]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 05:36:11.616823 systemd-tmpfiles[1728]: ACLs are not supported, ignoring. Sep 9 05:36:11.616888 systemd-tmpfiles[1728]: ACLs are not supported, ignoring. Sep 9 05:36:11.617518 zram_generator::config[1762]: No configuration found. Sep 9 05:36:11.700735 systemd-tmpfiles[1728]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 05:36:11.700749 systemd-tmpfiles[1728]: Skipping /boot Sep 9 05:36:11.709173 systemd-tmpfiles[1728]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 05:36:11.709188 systemd-tmpfiles[1728]: Skipping /boot Sep 9 05:36:11.901186 systemd[1]: Reloading finished in 329 ms. Sep 9 05:36:11.946617 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:36:11.962933 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 05:36:11.988386 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 05:36:11.990209 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:11.991421 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:36:11.997551 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:36:12.000155 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:36:12.000666 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:12.000792 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). Sep 9 05:36:12.002110 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 05:36:12.005437 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 05:36:12.006825 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 05:36:12.009117 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:36:12.009379 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:36:12.010774 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:36:12.010959 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:36:12.015993 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:12.017216 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:36:12.018656 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:36:12.019188 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:12.019361 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). Sep 9 05:36:12.023650 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:36:12.024797 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 05:36:12.025257 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:36:12.025372 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). Sep 9 05:36:12.025566 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 05:36:12.026533 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:36:12.026731 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:36:12.027778 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:36:12.027970 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:36:12.030777 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 05:36:12.030977 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 05:36:12.031526 systemd[1]: Finished ensure-sysext.service. Sep 9 05:36:12.034121 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:36:12.067382 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:36:12.067643 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:36:12.070100 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 05:36:12.071907 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:36:12.108900 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:12.108934 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:36:12.161775 systemd-resolved[1826]: Positive Trust Anchors: Sep 9 05:36:12.161794 systemd-resolved[1826]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 05:36:12.161826 systemd-resolved[1826]: 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 Sep 9 05:36:12.244682 systemd-resolved[1826]: Using system hostname 'ci-4452.0.0-n-116516c315'. Sep 9 05:36:12.253111 augenrules[1859]: No rules Sep 9 05:36:12.254732 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 05:36:12.254980 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 05:36:12.269015 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 05:36:12.270751 systemd[1]: Reached target network.target - Network. Sep 9 05:36:12.271298 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:36:12.343529 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 05:36:12.480596 systemd-networkd[1592]: eth0: Gained IPv6LL Sep 9 05:36:12.482876 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 05:36:12.484628 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 05:36:12.629349 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:36:15.578044 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 05:36:15.584003 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). Sep 9 05:36:20.748320 ldconfig[1536]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 05:36:20.759705 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 05:36:20.766739 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 05:36:20.811366 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 05:36:20.816391 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 05:36:20.821149 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 05:36:20.826885 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 05:36:20.832376 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 9 05:36:20.837533 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 05:36:20.842409 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 05:36:20.847745 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 05:36:20.853555 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 05:36:20.853619 systemd[1]: Reached target paths.target - Path Units. Sep 9 05:36:20.857962 systemd[1]: Reached target timers.target - Timer Units. Sep 9 05:36:20.876649 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 05:36:20.882916 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 05:36:20.889635 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 9 05:36:20.895475 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 9 05:36:20.901153 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 9 05:36:20.907399 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 05:36:20.911938 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 9 05:36:20.917425 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 05:36:20.922761 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 05:36:20.926456 systemd[1]: Reached target basic.target - Basic System. Sep 9 05:36:20.930261 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 05:36:20.930291 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 05:36:20.945286 systemd[1]: Starting chronyd.service - NTP client/server... Sep 9 05:36:20.949623 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 05:36:20.962316 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 9 05:36:20.967351 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 05:36:20.972543 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 05:36:20.978235 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 05:36:20.983748 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 05:36:20.987786 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). Sep 9 05:36:20.988924 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 9 05:36:20.993104 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). Sep 9 05:36:20.994064 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Sep 9 05:36:20.998414 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Sep 9 05:36:20.999439 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 05:36:21.000637 jq[1884]: false Sep 9 05:36:21.004512 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 05:36:21.009215 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 05:36:21.015061 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 05:36:21.021737 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 05:36:21.026713 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 9 05:36:21.027157 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 05:36:21.027715 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 05:36:21.032207 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 05:36:21.039735 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 05:36:21.039965 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 05:36:21.040279 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 05:36:21.040508 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 05:36:21.048859 jq[1900]: true Sep 9 05:36:21.055104 KVP[1887]: KVP starting; pid is:1887 Sep 9 05:36:21.057905 chronyd[1876]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Sep 9 05:36:21.058565 jq[1907]: true Sep 9 05:36:21.059121 KVP[1887]: KVP LIC Version: 3.1 Sep 9 05:36:21.059496 kernel: hv_utils: KVP IC version 4.0 Sep 9 05:36:21.067974 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Refreshing passwd entry cache Sep 9 05:36:21.067983 oslogin_cache_refresh[1886]: Refreshing passwd entry cache Sep 9 05:36:21.075834 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Failure getting users, quitting Sep 9 05:36:21.075834 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 05:36:21.075817 oslogin_cache_refresh[1886]: Failure getting users, quitting Sep 9 05:36:21.075954 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Refreshing group entry cache Sep 9 05:36:21.075834 oslogin_cache_refresh[1886]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 05:36:21.075877 oslogin_cache_refresh[1886]: Refreshing group entry cache Sep 9 05:36:21.095777 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Failure getting groups, quitting Sep 9 05:36:21.095777 google_oslogin_nss_cache[1886]: oslogin_cache_refresh[1886]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 05:36:21.095765 oslogin_cache_refresh[1886]: Failure getting groups, quitting Sep 9 05:36:21.095781 oslogin_cache_refresh[1886]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 05:36:21.097458 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 9 05:36:21.097734 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 9 05:36:21.106755 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 05:36:21.114075 extend-filesystems[1885]: Found /dev/sda6 Sep 9 05:36:21.122160 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 05:36:21.122407 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 05:36:21.133008 (ntainerd)[1932]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 05:36:21.136903 chronyd[1876]: Timezone right/UTC failed leap second check, ignoring Sep 9 05:36:21.137114 chronyd[1876]: Loaded seccomp filter (level 2) Sep 9 05:36:21.137213 systemd[1]: Started chronyd.service - NTP client/server. Sep 9 05:36:21.153618 systemd-logind[1897]: New seat seat0. Sep 9 05:36:21.155223 systemd-logind[1897]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 9 05:36:21.155456 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 05:36:21.162542 update_engine[1899]: I20250909 05:36:21.162459 1899 main.cc:92] Flatcar Update Engine starting Sep 9 05:36:21.167551 extend-filesystems[1885]: Found /dev/sda9 Sep 9 05:36:21.172168 extend-filesystems[1885]: Checking size of /dev/sda9 Sep 9 05:36:21.217226 extend-filesystems[1885]: Old size kept for /dev/sda9 Sep 9 05:36:21.218362 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 05:36:21.218637 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 05:36:21.311658 bash[1925]: Updated "/home/core/.ssh/authorized_keys" Sep 9 05:36:21.313231 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 05:36:21.320876 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 9 05:36:21.427003 setup-nvidia[1889]: Downloading Flatcar Container Linux Developer Container for version: 4452.0.0+nightly-20250908-2100 Sep 9 05:36:21.430846 sshd_keygen[1927]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 05:36:21.453024 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 05:36:21.458966 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 05:36:21.463529 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 9 05:36:21.467869 dbus-daemon[1879]: [system] SELinux support is enabled Sep 9 05:36:21.468033 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 05:36:21.470845 update_engine[1899]: I20250909 05:36:21.470795 1899 update_check_scheduler.cc:74] Next update check in 9m1s Sep 9 05:36:21.474702 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). Sep 9 05:36:21.474737 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 05:36:21.475504 dbus-daemon[1879]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 9 05:36:21.480088 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). Sep 9 05:36:21.480118 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 05:36:21.485456 systemd[1]: Started update-engine.service - Update Engine. Sep 9 05:36:21.491653 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 05:36:21.496418 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 05:36:21.496686 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 05:36:21.503846 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 05:36:21.519170 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 9 05:36:21.531961 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 05:36:21.538687 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 05:36:21.543681 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 9 05:36:21.545436 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 05:36:21.597274 coreos-metadata[1878]: Sep 09 05:36:21.597 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 9 05:36:21.599720 coreos-metadata[1878]: Sep 09 05:36:21.599 INFO Fetch successful Sep 9 05:36:21.599839 coreos-metadata[1878]: Sep 09 05:36:21.599 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 9 05:36:21.603986 coreos-metadata[1878]: Sep 09 05:36:21.603 INFO Fetch successful Sep 9 05:36:21.604244 coreos-metadata[1878]: Sep 09 05:36:21.604 INFO Fetching http://168.63.129.16/machine/ffaa976c-7a90-44fe-87f4-1cab0ca6bfcc/56b4d5cf%2Da432%2D4dfd%2Dbafd%2Dfd0f1a6c89c4.%5Fci%2D4452.0.0%2Dn%2D116516c315?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 9 05:36:21.606140 coreos-metadata[1878]: Sep 09 05:36:21.606 INFO Fetch successful Sep 9 05:36:21.606279 coreos-metadata[1878]: Sep 09 05:36:21.606 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 9 05:36:21.613834 coreos-metadata[1878]: Sep 09 05:36:21.613 INFO Fetch successful Sep 9 05:36:21.639546 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 9 05:36:21.645331 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 05:36:21.948686 locksmithd[2024]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 05:36:22.598143 containerd[1932]: time="2025-09-09T05:36: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 Sep 9 05:36:22.598831 containerd[1932]: time="2025-09-09T05:36:22.598798300Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 9 05:36:22.607738 containerd[1932]: time="2025-09-09T05:36:22.607688900Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.8µs" Sep 9 05:36:22.607738 containerd[1932]: time="2025-09-09T05:36:22.607727500Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 05:36:22.607819 containerd[1932]: time="2025-09-09T05:36:22.607746400Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 05:36:22.607910 containerd[1932]: time="2025-09-09T05:36:22.607886700Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 05:36:22.608189 containerd[1932]: time="2025-09-09T05:36:22.607909800Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 05:36:22.608189 containerd[1932]: time="2025-09-09T05:36:22.607983000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608189 containerd[1932]: time="2025-09-09T05:36:22.608079200Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608189 containerd[1932]: time="2025-09-09T05:36:22.608095000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608361 containerd[1932]: time="2025-09-09T05:36:22.608314400Z" 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 Sep 9 05:36:22.608361 containerd[1932]: time="2025-09-09T05:36:22.608330200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608414 containerd[1932]: time="2025-09-09T05:36:22.608353100Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608414 containerd[1932]: time="2025-09-09T05:36:22.608370500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608502 containerd[1932]: time="2025-09-09T05:36:22.608476300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608837 containerd[1932]: time="2025-09-09T05:36:22.608813400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 05:36:22.608865 containerd[1932]: time="2025-09-09T05:36:22.608854600Z" 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 Sep 9 05:36:22.608898 containerd[1932]: time="2025-09-09T05:36:22.608866900Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 05:36:22.608928 containerd[1932]: time="2025-09-09T05:36:22.608915300Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 05:36:22.609707 containerd[1932]: time="2025-09-09T05:36:22.609651300Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 05:36:22.609835 containerd[1932]: time="2025-09-09T05:36:22.609810600Z" level=info msg="metadata content store policy set" policy=shared Sep 9 05:36:22.624459 containerd[1932]: time="2025-09-09T05:36:22.624421200Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 05:36:22.624527 containerd[1932]: time="2025-09-09T05:36:22.624480600Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 05:36:22.624578 containerd[1932]: time="2025-09-09T05:36:22.624498600Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 05:36:22.624608 containerd[1932]: time="2025-09-09T05:36:22.624592200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 05:36:22.624631 containerd[1932]: time="2025-09-09T05:36:22.624614100Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 05:36:22.624631 containerd[1932]: time="2025-09-09T05:36:22.624626100Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 05:36:22.624699 containerd[1932]: time="2025-09-09T05:36:22.624639900Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 05:36:22.624699 containerd[1932]: time="2025-09-09T05:36:22.624653500Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 05:36:22.624699 containerd[1932]: time="2025-09-09T05:36:22.624665300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 05:36:22.624699 containerd[1932]: time="2025-09-09T05:36:22.624676600Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 05:36:22.624699 containerd[1932]: time="2025-09-09T05:36:22.624686300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 05:36:22.624699 containerd[1932]: time="2025-09-09T05:36:22.624698100Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 05:36:22.624833 containerd[1932]: time="2025-09-09T05:36:22.624816700Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 05:36:22.624866 containerd[1932]: time="2025-09-09T05:36:22.624840900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 05:36:22.624866 containerd[1932]: time="2025-09-09T05:36:22.624855500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 05:36:22.624906 containerd[1932]: time="2025-09-09T05:36:22.624867500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 05:36:22.624906 containerd[1932]: time="2025-09-09T05:36:22.624878300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 05:36:22.624906 containerd[1932]: time="2025-09-09T05:36:22.624888500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 05:36:22.624906 containerd[1932]: time="2025-09-09T05:36:22.624900600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 05:36:22.625024 containerd[1932]: time="2025-09-09T05:36:22.624924100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 05:36:22.625024 containerd[1932]: time="2025-09-09T05:36:22.624939000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 05:36:22.625024 containerd[1932]: time="2025-09-09T05:36:22.624951800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 05:36:22.625024 containerd[1932]: time="2025-09-09T05:36:22.624962200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 05:36:22.625024 containerd[1932]: time="2025-09-09T05:36:22.625019500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 05:36:22.625169 containerd[1932]: time="2025-09-09T05:36:22.625033100Z" level=info msg="Start snapshots syncer" Sep 9 05:36:22.625169 containerd[1932]: time="2025-09-09T05:36:22.625060200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 05:36:22.625365 containerd[1932]: time="2025-09-09T05:36:22.625321400Z" 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\"}" Sep 9 05:36:22.625499 containerd[1932]: time="2025-09-09T05:36:22.625379200Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 05:36:22.625499 containerd[1932]: time="2025-09-09T05:36:22.625456600Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 05:36:22.625609 containerd[1932]: time="2025-09-09T05:36:22.625582700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 05:36:22.625649 containerd[1932]: time="2025-09-09T05:36:22.625632800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 05:36:22.625684 containerd[1932]: time="2025-09-09T05:36:22.625649800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 05:36:22.625684 containerd[1932]: time="2025-09-09T05:36:22.625661200Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 05:36:22.625684 containerd[1932]: time="2025-09-09T05:36:22.625672800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 05:36:22.625755 containerd[1932]: time="2025-09-09T05:36:22.625683600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 05:36:22.625755 containerd[1932]: time="2025-09-09T05:36:22.625693700Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 05:36:22.625755 containerd[1932]: time="2025-09-09T05:36:22.625716500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 05:36:22.625755 containerd[1932]: time="2025-09-09T05:36:22.625729000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 05:36:22.625755 containerd[1932]: time="2025-09-09T05:36:22.625739900Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625787200Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625806100Z" 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 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625814600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625824200Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625832100Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625841700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 05:36:22.625860 containerd[1932]: time="2025-09-09T05:36:22.625851600Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 05:36:22.625997 containerd[1932]: time="2025-09-09T05:36:22.625868600Z" level=info msg="runtime interface created" Sep 9 05:36:22.625997 containerd[1932]: time="2025-09-09T05:36:22.625874600Z" level=info msg="created NRI interface" Sep 9 05:36:22.625997 containerd[1932]: time="2025-09-09T05:36:22.625882700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 05:36:22.625997 containerd[1932]: time="2025-09-09T05:36:22.625895400Z" level=info msg="Connect containerd service" Sep 9 05:36:22.625997 containerd[1932]: time="2025-09-09T05:36:22.625937200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 05:36:22.626655 containerd[1932]: time="2025-09-09T05:36:22.626626600Z" 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" Sep 9 05:36:23.502863 containerd[1932]: time="2025-09-09T05:36:23.502804600Z" level=info msg="Start subscribing containerd event" Sep 9 05:36:23.502984 containerd[1932]: time="2025-09-09T05:36:23.502866300Z" level=info msg="Start recovering state" Sep 9 05:36:23.502984 containerd[1932]: time="2025-09-09T05:36:23.502966400Z" level=info msg="Start event monitor" Sep 9 05:36:23.503058 containerd[1932]: time="2025-09-09T05:36:23.502987700Z" level=info msg="Start cni network conf syncer for default" Sep 9 05:36:23.503058 containerd[1932]: time="2025-09-09T05:36:23.502997700Z" level=info msg="Start streaming server" Sep 9 05:36:23.503058 containerd[1932]: time="2025-09-09T05:36:23.503007300Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 05:36:23.503058 containerd[1932]: time="2025-09-09T05:36:23.503015900Z" level=info msg="runtime interface starting up..." Sep 9 05:36:23.503058 containerd[1932]: time="2025-09-09T05:36:23.503025900Z" level=info msg="starting plugins..." Sep 9 05:36:23.503058 containerd[1932]: time="2025-09-09T05:36:23.503043800Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 05:36:23.503179 containerd[1932]: time="2025-09-09T05:36:23.503046200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 05:36:23.503179 containerd[1932]: time="2025-09-09T05:36:23.503163700Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 05:36:23.503261 containerd[1932]: time="2025-09-09T05:36:23.503243000Z" level=info msg="containerd successfully booted in 0.905493s" Sep 9 05:36:23.503356 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 05:36:25.356268 waagent[2033]: 2025-09-09T05:36:25.356172Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Sep 9 05:36:25.360732 waagent[2033]: 2025-09-09T05:36:25.360680Z INFO Daemon Daemon OS: flatcar 4452.0.0 Sep 9 05:36:25.364200 waagent[2033]: 2025-09-09T05:36:25.364154Z INFO Daemon Daemon Python: 3.11.13 Sep 9 05:36:25.367395 waagent[2033]: 2025-09-09T05:36:25.367349Z INFO Daemon Daemon Run daemon Sep 9 05:36:25.370428 waagent[2033]: 2025-09-09T05:36:25.370388Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4452.0.0' Sep 9 05:36:25.377187 waagent[2033]: 2025-09-09T05:36:25.377142Z INFO Daemon Daemon Using waagent for provisioning Sep 9 05:36:25.381185 waagent[2033]: 2025-09-09T05:36:25.381138Z INFO Daemon Daemon Activate resource disk Sep 9 05:36:25.384939 waagent[2033]: 2025-09-09T05:36:25.384892Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 9 05:36:25.393938 waagent[2033]: 2025-09-09T05:36:25.393878Z INFO Daemon Daemon Found device: sdb Sep 9 05:36:25.403004 waagent[2033]: 2025-09-09T05:36:25.402943Z INFO Daemon Daemon Examining partition table Sep 9 05:36:25.470144 waagent[2033]: 2025-09-09T05:36:25.470064Z INFO Daemon Daemon GPT not detected, determining filesystem Sep 9 05:36:25.579868 waagent[2033]: 2025-09-09T05:36:25.579777Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Sep 9 05:36:25.584748 waagent[2033]: 2025-09-09T05:36:25.584687Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Sep 9 05:36:25.931399 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#10 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:36:25.947494 kernel: sdb: sdb1 Sep 9 05:36:25.954652 waagent[2033]: 2025-09-09T05:36:25.954575Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Sep 9 05:36:25.974968 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#336 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Sep 9 05:36:25.989486 kernel: sdb: sdb1 Sep 9 05:36:25.989940 waagent[2033]: 2025-09-09T05:36:25.989846Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Sep 9 05:36:30.033697 login[2035]: pam_lastlog(login:session): file /var/log/lastlog is locked/read, retrying Sep 9 05:36:30.129503 login[2036]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 9 05:36:30.136016 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 05:36:30.137092 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 05:36:30.143176 systemd-logind[1897]: New session 2 of user core. Sep 9 05:36:30.202562 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 05:36:30.205159 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 05:36:30.241246 (systemd)[2140]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 05:36:30.243795 systemd-logind[1897]: New session c1 of user core. Sep 9 05:36:31.117780 login[2035]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Sep 9 05:36:31.122545 systemd-logind[1897]: New session 1 of user core. Sep 9 05:36:31.530613 systemd[2140]: Queued start job for default target default.target. Sep 9 05:36:31.542505 systemd[2140]: Created slice app.slice - User Application Slice. Sep 9 05:36:31.542534 systemd[2140]: Reached target paths.target - Paths. Sep 9 05:36:31.542583 systemd[2140]: Reached target timers.target - Timers. Sep 9 05:36:31.543845 systemd[2140]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 05:36:31.555196 systemd[2140]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:36:31.555326 systemd[2140]: Reached target sockets.target - Sockets. Sep 9 05:36:31.555379 systemd[2140]: Reached target basic.target - Basic System. Sep 9 05:36:31.555483 systemd[2140]: Reached target default.target - Main User Target. Sep 9 05:36:31.555532 systemd[2140]: Startup finished in 1.304s. Sep 9 05:36:31.555628 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 05:36:31.556961 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 05:36:31.557811 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 05:36:31.619485 waagent[2033]: 2025-09-09T05:36:31.619388Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Sep 9 05:36:31.737235 waagent[2033]: 2025-09-09T05:36:31.737144Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Sep 9 05:36:31.737520 kernel: EXT4-fs (sdb1): mounted filesystem ff979e82-79f8-47e9-81b7-9a1a0add93e3 r/w with ordered data mode. Quota mode: none. Sep 9 05:36:31.740372 waagent[2033]: 2025-09-09T05:36:31.740321Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 9 05:36:31.742039 waagent[2033]: 2025-09-09T05:36:31.741882Z INFO Daemon Daemon Running default provisioning handler Sep 9 05:36:31.754116 waagent[2033]: 2025-09-09T05:36:31.753953Z 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. Sep 9 05:36:31.764444 waagent[2033]: 2025-09-09T05:36:31.764394Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 9 05:36:31.766073 waagent[2033]: 2025-09-09T05:36:31.766033Z INFO Daemon Daemon cloud-init is enabled: False Sep 9 05:36:31.767609 waagent[2033]: 2025-09-09T05:36:31.767564Z INFO Daemon Daemon Copying ovf-env.xml Sep 9 05:36:31.893526 waagent[2033]: 2025-09-09T05:36:31.893371Z INFO Daemon Daemon Successfully mounted dvd Sep 9 05:36:31.946659 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 9 05:36:31.946837 waagent[2033]: 2025-09-09T05:36:31.946658Z INFO Daemon Daemon Detect protocol endpoint Sep 9 05:36:31.948411 waagent[2033]: 2025-09-09T05:36:31.948352Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 9 05:36:31.949158 waagent[2033]: 2025-09-09T05:36:31.949118Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 9 05:36:31.950600 waagent[2033]: 2025-09-09T05:36:31.950565Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 9 05:36:31.951337 waagent[2033]: 2025-09-09T05:36:31.951300Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 9 05:36:31.952666 waagent[2033]: 2025-09-09T05:36:31.952630Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 9 05:36:31.963999 waagent[2033]: 2025-09-09T05:36:31.963948Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 9 05:36:31.965431 waagent[2033]: 2025-09-09T05:36:31.965403Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 9 05:36:31.966048 waagent[2033]: 2025-09-09T05:36:31.966018Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 9 05:36:32.167863 waagent[2033]: 2025-09-09T05:36:32.167705Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 9 05:36:32.169610 waagent[2033]: 2025-09-09T05:36:32.169551Z INFO Daemon Daemon Forcing an update of the goal state. Sep 9 05:36:32.173670 waagent[2033]: 2025-09-09T05:36:32.173615Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 9 05:36:32.202067 waagent[2033]: 2025-09-09T05:36:32.202011Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Sep 9 05:36:32.204297 waagent[2033]: 2025-09-09T05:36:32.204241Z INFO Daemon Sep 9 05:36:32.205392 waagent[2033]: 2025-09-09T05:36:32.205348Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 4ea8a3f4-d04a-4aca-aa95-d40c370bef73 eTag: 5125223882270780519 source: Fabric] Sep 9 05:36:32.207149 waagent[2033]: 2025-09-09T05:36:32.207104Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 9 05:36:32.208693 waagent[2033]: 2025-09-09T05:36:32.208645Z INFO Daemon Sep 9 05:36:32.209183 waagent[2033]: 2025-09-09T05:36:32.209143Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 9 05:36:32.214477 waagent[2033]: 2025-09-09T05:36:32.214437Z INFO Daemon Daemon Downloading artifacts profile blob Sep 9 05:36:32.347143 waagent[2033]: 2025-09-09T05:36:32.347049Z INFO Daemon Downloaded certificate {'thumbprint': '1F31B4425B4629FFACC47E774F40F07EE7CC4DFF', 'hasPrivateKey': True} Sep 9 05:36:32.348670 waagent[2033]: 2025-09-09T05:36:32.348613Z INFO Daemon Fetch goal state completed Sep 9 05:36:32.391257 waagent[2033]: 2025-09-09T05:36:32.391187Z INFO Daemon Daemon Starting provisioning Sep 9 05:36:32.393045 waagent[2033]: 2025-09-09T05:36:32.392993Z INFO Daemon Daemon Handle ovf-env.xml. Sep 9 05:36:32.394494 waagent[2033]: 2025-09-09T05:36:32.394442Z INFO Daemon Daemon Set hostname [ci-4452.0.0-n-116516c315] Sep 9 05:36:32.417271 waagent[2033]: 2025-09-09T05:36:32.417219Z INFO Daemon Daemon Publish hostname [ci-4452.0.0-n-116516c315] Sep 9 05:36:32.419192 waagent[2033]: 2025-09-09T05:36:32.419112Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 9 05:36:32.420693 waagent[2033]: 2025-09-09T05:36:32.420585Z INFO Daemon Daemon Primary interface is [eth0] Sep 9 05:36:32.432138 systemd-networkd[1592]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:36:32.432149 systemd-networkd[1592]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:36:32.432183 systemd-networkd[1592]: eth0: DHCP lease lost Sep 9 05:36:32.432613 waagent[2033]: 2025-09-09T05:36:32.432563Z INFO Daemon Daemon Create user account if not exists Sep 9 05:36:32.434429 waagent[2033]: 2025-09-09T05:36:32.434385Z INFO Daemon Daemon User core already exists, skip useradd Sep 9 05:36:32.435112 waagent[2033]: 2025-09-09T05:36:32.435075Z INFO Daemon Daemon Configure sudoer Sep 9 05:36:32.448076 waagent[2033]: 2025-09-09T05:36:32.448025Z INFO Daemon Daemon Configure sshd Sep 9 05:36:32.454555 waagent[2033]: 2025-09-09T05:36:32.454489Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 9 05:36:32.456265 waagent[2033]: 2025-09-09T05:36:32.456218Z INFO Daemon Daemon Deploy ssh public key. Sep 9 05:36:32.528523 systemd-networkd[1592]: eth0: DHCPv4 address 10.200.8.11/24, gateway 10.200.8.1 acquired from 168.63.129.16 Sep 9 05:36:33.627430 waagent[2033]: 2025-09-09T05:36:33.627357Z INFO Daemon Daemon Provisioning complete Sep 9 05:36:33.639728 waagent[2033]: 2025-09-09T05:36:33.639672Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 9 05:36:33.641389 waagent[2033]: 2025-09-09T05:36:33.641339Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 9 05:36:33.642812 waagent[2033]: 2025-09-09T05:36:33.642773Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Sep 9 05:36:33.825596 waagent[2202]: 2025-09-09T05:36:33.825492Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Sep 9 05:36:33.825935 waagent[2202]: 2025-09-09T05:36:33.825645Z INFO ExtHandler ExtHandler OS: flatcar 4452.0.0 Sep 9 05:36:33.825935 waagent[2202]: 2025-09-09T05:36:33.825713Z INFO ExtHandler ExtHandler Python: 3.11.13 Sep 9 05:36:33.825935 waagent[2202]: 2025-09-09T05:36:33.825780Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Sep 9 05:36:33.874395 waagent[2202]: 2025-09-09T05:36:33.874317Z INFO ExtHandler ExtHandler Distro: flatcar-4452.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Sep 9 05:36:33.874620 waagent[2202]: 2025-09-09T05:36:33.874582Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 05:36:33.874704 waagent[2202]: 2025-09-09T05:36:33.874671Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 05:36:33.881686 waagent[2202]: 2025-09-09T05:36:33.881603Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 9 05:36:33.886679 waagent[2202]: 2025-09-09T05:36:33.886636Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Sep 9 05:36:33.887129 waagent[2202]: 2025-09-09T05:36:33.887089Z INFO ExtHandler Sep 9 05:36:33.887208 waagent[2202]: 2025-09-09T05:36:33.887176Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 27302c1e-1877-4b9b-bcdc-45de3a23adf2 eTag: 5125223882270780519 source: Fabric] Sep 9 05:36:33.887544 waagent[2202]: 2025-09-09T05:36:33.887506Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 9 05:36:33.888172 waagent[2202]: 2025-09-09T05:36:33.888133Z INFO ExtHandler Sep 9 05:36:33.888240 waagent[2202]: 2025-09-09T05:36:33.888210Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 9 05:36:33.891787 waagent[2202]: 2025-09-09T05:36:33.891749Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 9 05:36:33.966376 waagent[2202]: 2025-09-09T05:36:33.966302Z INFO ExtHandler Downloaded certificate {'thumbprint': '1F31B4425B4629FFACC47E774F40F07EE7CC4DFF', 'hasPrivateKey': True} Sep 9 05:36:33.966889 waagent[2202]: 2025-09-09T05:36:33.966838Z INFO ExtHandler Fetch goal state completed Sep 9 05:36:33.978145 waagent[2202]: 2025-09-09T05:36:33.978099Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Sep 9 05:36:33.983202 waagent[2202]: 2025-09-09T05:36:33.983142Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2202 Sep 9 05:36:33.983358 waagent[2202]: 2025-09-09T05:36:33.983300Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 9 05:36:33.983709 waagent[2202]: 2025-09-09T05:36:33.983669Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Sep 9 05:36:33.985362 waagent[2202]: 2025-09-09T05:36:33.985312Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4452.0.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 9 05:36:33.985802 waagent[2202]: 2025-09-09T05:36:33.985763Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4452.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Sep 9 05:36:33.985957 waagent[2202]: 2025-09-09T05:36:33.985922Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Sep 9 05:36:33.986615 waagent[2202]: 2025-09-09T05:36:33.986575Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 9 05:36:34.045573 waagent[2202]: 2025-09-09T05:36:34.045523Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 9 05:36:34.045763 waagent[2202]: 2025-09-09T05:36:34.045725Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 9 05:36:34.052228 waagent[2202]: 2025-09-09T05:36:34.052196Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 9 05:36:34.058896 systemd[1]: Reload requested from client PID 2217 ('systemctl') (unit waagent.service)... Sep 9 05:36:34.058915 systemd[1]: Reloading... Sep 9 05:36:34.140500 zram_generator::config[2260]: No configuration found. Sep 9 05:36:34.444493 systemd[1]: Reloading finished in 385 ms. Sep 9 05:36:34.471918 waagent[2202]: 2025-09-09T05:36:34.471837Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 9 05:36:34.472028 waagent[2202]: 2025-09-09T05:36:34.471990Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 9 05:36:35.029751 waagent[2202]: 2025-09-09T05:36:35.029665Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 9 05:36:35.030164 waagent[2202]: 2025-09-09T05:36:35.030013Z 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] Sep 9 05:36:35.030915 waagent[2202]: 2025-09-09T05:36:35.030813Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 9 05:36:35.031319 waagent[2202]: 2025-09-09T05:36:35.031270Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 05:36:35.031512 waagent[2202]: 2025-09-09T05:36:35.031454Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 9 05:36:35.032005 waagent[2202]: 2025-09-09T05:36:35.031959Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 9 05:36:35.032126 waagent[2202]: 2025-09-09T05:36:35.032070Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 9 05:36:35.032230 waagent[2202]: 2025-09-09T05:36:35.032190Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 05:36:35.032293 waagent[2202]: 2025-09-09T05:36:35.032247Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 9 05:36:35.032480 waagent[2202]: 2025-09-09T05:36:35.032428Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 9 05:36:35.032798 waagent[2202]: 2025-09-09T05:36:35.032752Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 9 05:36:35.033208 waagent[2202]: 2025-09-09T05:36:35.033160Z INFO EnvHandler ExtHandler Configure routes Sep 9 05:36:35.033640 waagent[2202]: 2025-09-09T05:36:35.033594Z INFO EnvHandler ExtHandler Gateway:None Sep 9 05:36:35.033775 waagent[2202]: 2025-09-09T05:36:35.033715Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 9 05:36:35.033904 waagent[2202]: 2025-09-09T05:36:35.033820Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 9 05:36:35.034107 waagent[2202]: 2025-09-09T05:36:35.034059Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 9 05:36:35.034107 waagent[2202]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 9 05:36:35.034107 waagent[2202]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Sep 9 05:36:35.034107 waagent[2202]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 9 05:36:35.034107 waagent[2202]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 9 05:36:35.034107 waagent[2202]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 9 05:36:35.034107 waagent[2202]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 9 05:36:35.034291 waagent[2202]: 2025-09-09T05:36:35.034250Z INFO EnvHandler ExtHandler Routes:None Sep 9 05:36:35.034636 waagent[2202]: 2025-09-09T05:36:35.034590Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 9 05:36:35.044079 waagent[2202]: 2025-09-09T05:36:35.044022Z INFO ExtHandler ExtHandler Sep 9 05:36:35.044473 waagent[2202]: 2025-09-09T05:36:35.044416Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 0a7e4a07-437c-40e1-a65d-61ff78889afd correlation 1271b7b7-88bf-49af-89e3-61914ec2894e created: 2025-09-09T05:34:42.970276Z] Sep 9 05:36:35.045619 waagent[2202]: 2025-09-09T05:36:35.045573Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 9 05:36:35.047502 waagent[2202]: 2025-09-09T05:36:35.047447Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Sep 9 05:36:35.105092 waagent[2202]: 2025-09-09T05:36:35.105015Z 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 Sep 9 05:36:35.105092 waagent[2202]: Try `iptables -h' or 'iptables --help' for more information.) Sep 9 05:36:35.105653 waagent[2202]: 2025-09-09T05:36:35.105609Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 7ACF3066-7476-4497-B9CB-2693CCCE4D51;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Sep 9 05:36:35.185118 waagent[2202]: 2025-09-09T05:36:35.185045Z INFO MonitorHandler ExtHandler Network interfaces: Sep 9 05:36:35.185118 waagent[2202]: Executing ['ip', '-a', '-o', 'link']: Sep 9 05:36:35.185118 waagent[2202]: 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 Sep 9 05:36:35.185118 waagent[2202]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:db:f4:68 brd ff:ff:ff:ff:ff:ff Sep 9 05:36:35.185118 waagent[2202]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 9 05:36:35.185118 waagent[2202]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 9 05:36:35.185118 waagent[2202]: 2: eth0 inet 10.200.8.11/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 9 05:36:35.185118 waagent[2202]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 9 05:36:35.185118 waagent[2202]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 9 05:36:35.185118 waagent[2202]: 2: eth0 inet6 fe80::20d:3aff:fedb:f468/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 9 05:36:35.307599 waagent[2202]: 2025-09-09T05:36:35.307414Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Sep 9 05:36:35.307599 waagent[2202]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.307599 waagent[2202]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.307599 waagent[2202]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.307599 waagent[2202]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.307599 waagent[2202]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.307599 waagent[2202]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.307599 waagent[2202]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 9 05:36:35.307599 waagent[2202]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 9 05:36:35.307599 waagent[2202]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 9 05:36:35.310886 waagent[2202]: 2025-09-09T05:36:35.310841Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 9 05:36:35.310886 waagent[2202]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.310886 waagent[2202]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.310886 waagent[2202]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.310886 waagent[2202]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.310886 waagent[2202]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 9 05:36:35.310886 waagent[2202]: pkts bytes target prot opt in out source destination Sep 9 05:36:35.310886 waagent[2202]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 9 05:36:35.310886 waagent[2202]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 9 05:36:35.310886 waagent[2202]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 9 05:36:35.311145 waagent[2202]: 2025-09-09T05:36:35.311106Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Sep 9 05:36:35.311298 waagent[2202]: 2025-09-09T05:36:35.311265Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 9 05:36:44.921572 chronyd[1876]: Selected source PHC0 Sep 9 05:36:59.198803 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Sep 9 05:37:00.052252 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 05:37:00.055758 systemd[1]: Started sshd@0-10.200.8.11:22-10.200.16.10:52424.service - OpenSSH per-connection server daemon (10.200.16.10:52424). Sep 9 05:37:01.201675 sshd[2350]: Accepted publickey for core from 10.200.16.10 port 52424 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:01.202946 sshd-session[2350]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:01.207074 systemd-logind[1897]: New session 3 of user core. Sep 9 05:37:01.228586 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 05:37:01.937394 systemd[1]: Started sshd@1-10.200.8.11:22-10.200.16.10:52428.service - OpenSSH per-connection server daemon (10.200.16.10:52428). Sep 9 05:37:02.612653 sshd[2356]: Accepted publickey for core from 10.200.16.10 port 52428 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:02.614098 sshd-session[2356]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:02.618379 systemd-logind[1897]: New session 4 of user core. Sep 9 05:37:02.632585 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 05:37:03.114709 sshd[2359]: Connection closed by 10.200.16.10 port 52428 Sep 9 05:37:03.115069 sshd-session[2356]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:03.117908 systemd[1]: sshd@1-10.200.8.11:22-10.200.16.10:52428.service: Deactivated successfully. Sep 9 05:37:03.119787 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 05:37:03.121056 systemd-logind[1897]: Session 4 logged out. Waiting for processes to exit. Sep 9 05:37:03.122890 systemd-logind[1897]: Removed session 4. Sep 9 05:37:03.230900 systemd[1]: Started sshd@2-10.200.8.11:22-10.200.16.10:52430.service - OpenSSH per-connection server daemon (10.200.16.10:52430). Sep 9 05:37:04.117560 sshd[2365]: Accepted publickey for core from 10.200.16.10 port 52430 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:04.118857 sshd-session[2365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:04.123137 systemd-logind[1897]: New session 5 of user core. Sep 9 05:37:04.145594 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 05:37:04.569268 sshd[2368]: Connection closed by 10.200.16.10 port 52430 Sep 9 05:37:04.569657 sshd-session[2365]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:04.573188 systemd[1]: sshd@2-10.200.8.11:22-10.200.16.10:52430.service: Deactivated successfully. Sep 9 05:37:04.574848 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 05:37:04.575565 systemd-logind[1897]: Session 5 logged out. Waiting for processes to exit. Sep 9 05:37:04.576479 systemd-logind[1897]: Removed session 5. Sep 9 05:37:06.573578 update_engine[1899]: I20250909 05:37:06.573490 1899 update_attempter.cc:509] Updating boot flags... Sep 9 05:37:14.707850 systemd[1]: Started sshd@3-10.200.8.11:22-10.200.16.10:52200.service - OpenSSH per-connection server daemon (10.200.16.10:52200). Sep 9 05:37:15.348988 sshd[2490]: Accepted publickey for core from 10.200.16.10 port 52200 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:15.350288 sshd-session[2490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:15.354672 systemd-logind[1897]: New session 6 of user core. Sep 9 05:37:15.364664 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 9 05:37:15.798955 sshd[2493]: Connection closed by 10.200.16.10 port 52200 Sep 9 05:37:15.799342 sshd-session[2490]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:15.802884 systemd[1]: sshd@3-10.200.8.11:22-10.200.16.10:52200.service: Deactivated successfully. Sep 9 05:37:15.804423 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 05:37:15.805103 systemd-logind[1897]: Session 6 logged out. Waiting for processes to exit. Sep 9 05:37:15.806128 systemd-logind[1897]: Removed session 6. Sep 9 05:37:15.915711 systemd[1]: Started sshd@4-10.200.8.11:22-10.200.16.10:52214.service - OpenSSH per-connection server daemon (10.200.16.10:52214). Sep 9 05:37:16.553562 sshd[2499]: Accepted publickey for core from 10.200.16.10 port 52214 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:16.554844 sshd-session[2499]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:16.559187 systemd-logind[1897]: New session 7 of user core. Sep 9 05:37:16.576581 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 9 05:37:17.005459 sshd[2502]: Connection closed by 10.200.16.10 port 52214 Sep 9 05:37:17.005853 sshd-session[2499]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:17.009289 systemd[1]: sshd@4-10.200.8.11:22-10.200.16.10:52214.service: Deactivated successfully. Sep 9 05:37:17.010951 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 05:37:17.011651 systemd-logind[1897]: Session 7 logged out. Waiting for processes to exit. Sep 9 05:37:17.012682 systemd-logind[1897]: Removed session 7. Sep 9 05:37:22.055312 setup-nvidia[2507]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 9 05:37:22.055763 setup-nvidia[2507]: Dload Upload Total Spent Left Speed Sep 9 05:37:27.142861 systemd[1]: Started sshd@5-10.200.8.11:22-10.200.16.10:48262.service - OpenSSH per-connection server daemon (10.200.16.10:48262). Sep 9 05:37:27.782600 sshd[2509]: Accepted publickey for core from 10.200.16.10 port 48262 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:27.784022 sshd-session[2509]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:27.788922 systemd-logind[1897]: New session 8 of user core. Sep 9 05:37:27.810656 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 9 05:37:28.237899 sshd[2512]: Connection closed by 10.200.16.10 port 48262 Sep 9 05:37:28.238208 sshd-session[2509]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:28.243841 systemd[1]: sshd@5-10.200.8.11:22-10.200.16.10:48262.service: Deactivated successfully. Sep 9 05:37:28.245659 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 05:37:28.246509 systemd-logind[1897]: Session 8 logged out. Waiting for processes to exit. Sep 9 05:37:28.247686 systemd-logind[1897]: Removed session 8. Sep 9 05:37:28.468222 systemd[1]: Started sshd@6-10.200.8.11:22-10.200.16.10:48264.service - OpenSSH per-connection server daemon (10.200.16.10:48264). Sep 9 05:37:29.129698 sshd[2519]: Accepted publickey for core from 10.200.16.10 port 48264 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:29.130956 sshd-session[2519]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:29.135229 systemd-logind[1897]: New session 9 of user core. Sep 9 05:37:29.143589 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 9 05:37:29.581264 sshd[2522]: Connection closed by 10.200.16.10 port 48264 Sep 9 05:37:29.581653 sshd-session[2519]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:29.585122 systemd[1]: sshd@6-10.200.8.11:22-10.200.16.10:48264.service: Deactivated successfully. Sep 9 05:37:29.586798 systemd[1]: session-9.scope: Deactivated successfully. Sep 9 05:37:29.587485 systemd-logind[1897]: Session 9 logged out. Waiting for processes to exit. Sep 9 05:37:29.588540 systemd-logind[1897]: Removed session 9. Sep 9 05:37:39.770325 systemd[1]: Started sshd@7-10.200.8.11:22-10.200.16.10:41818.service - OpenSSH per-connection server daemon (10.200.16.10:41818). Sep 9 05:37:40.408746 sshd[2528]: Accepted publickey for core from 10.200.16.10 port 41818 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:40.410079 sshd-session[2528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:40.416622 systemd-logind[1897]: New session 10 of user core. Sep 9 05:37:40.432652 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 9 05:37:40.859240 sshd[2531]: Connection closed by 10.200.16.10 port 41818 Sep 9 05:37:40.859646 sshd-session[2528]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:40.863360 systemd[1]: sshd@7-10.200.8.11:22-10.200.16.10:41818.service: Deactivated successfully. Sep 9 05:37:40.865356 systemd[1]: session-10.scope: Deactivated successfully. Sep 9 05:37:40.866135 systemd-logind[1897]: Session 10 logged out. Waiting for processes to exit. Sep 9 05:37:40.867256 systemd-logind[1897]: Removed session 10. Sep 9 05:37:40.976263 systemd[1]: Started sshd@8-10.200.8.11:22-10.200.16.10:54556.service - OpenSSH per-connection server daemon (10.200.16.10:54556). Sep 9 05:37:41.618423 sshd[2537]: Accepted publickey for core from 10.200.16.10 port 54556 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:41.619706 sshd-session[2537]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:41.623955 systemd-logind[1897]: New session 11 of user core. Sep 9 05:37:41.645604 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 9 05:37:42.070339 sshd[2540]: Connection closed by 10.200.16.10 port 54556 Sep 9 05:37:42.070743 sshd-session[2537]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:42.074284 systemd[1]: sshd@8-10.200.8.11:22-10.200.16.10:54556.service: Deactivated successfully. Sep 9 05:37:42.075935 systemd[1]: session-11.scope: Deactivated successfully. Sep 9 05:37:42.076646 systemd-logind[1897]: Session 11 logged out. Waiting for processes to exit. Sep 9 05:37:42.077552 systemd-logind[1897]: Removed session 11. Sep 9 05:37:44.596265 setup-nvidia[2507]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 530M 0 98304 0 0 117k 0 1:17:17 --:--:-- 1:17:17 117k\u000d 1 530M 1 10.3M 0 0 6064k 0 0:01:29 0:00:01 0:01:28 6063k\u000d 6 530M 6 35.3M 0 0 13.0M 0 0:00:40 0:00:02 0:00:38 13.0M\u000d 11 530M 11 61.1M 0 0 16.1M 0 0:00:32 0:00:03 0:00:29 16.1M\u000d 16 530M 16 85.4M 0 0 17.9M 0 0:00:29 0:00:04 0:00:25 17.9M\u000d 20 530M 20 110M 0 0 19.2M 0 0:00:27 0:00:05 0:00:22 22.4M\u000d 25 530M 25 135M 0 0 19.8M 0 0:00:26 0:00:06 0:00:20 24.6M\u000d 29 530M 29 158M 0 0 20.3M 0 0:00:26 0:00:07 0:00:19 24.2M\u000d 34 530M 34 182M 0 0 20.9M 0 0:00:25 0:00:08 0:00:17 24.6M\u000d 39 530M 39 211M 0 0 21.5M 0 0:00:24 0:00:09 0:00:15 24.8M\u000d 44 530M 44 237M 0 0 21.9M 0 0:00:24 0:00:10 0:00:14 25.1M\u000d 49 530M 49 260M 0 0 22.1M 0 0:00:23 0:00:11 0:00:12 25.3M\u000d 54 530M 54 287M 0 0 22.4M 0 0:00:23 0:00:12 0:00:11 25.6M\u000d 58 530M 58 312M 0 0 22.6M 0 0:00:23 0:00:13 0:00:10 25.6M\u000d 63 530M 63 337M 0 0 22.8M 0 0:00:23 0:00:14 0:00:09 25.6M\u000d 68 530M 68 361M 0 0 23.0M 0 0:00:23 0:00:15 0:00:08 25.2M\u000d 73 530M 73 388M 0 0 23.1M 0 0:00:22 0:00:16 0:00:06 25.4M\u000d 77 530M 77 411M 0 0 23.1M 0 0:00:22 0:00:17 0:00:05 25.0M\u000d 81 530M 81 434M 0 0 23.2M 0 0:00:22 0:00:18 0:00:04 24.7M\u000d 86 530M 86 461M 0 0 23.2M 0 0:00:22 0:00:19 0:00:03 24.3M\u000d 91 530M 91 485M 0 0 23.3M 0 0:00:22 0:00:20 0:00:02 24.5M\u000d 96 530M 96 509M 0 0 23.4M 0 0:00:22 0:00:21 0:00:01 24.5M\u000d100 530M 100 530M 0 0 23.5M 0 0:00:22 0:00:22 --:--:-- 24.8M Sep 9 05:37:52.188758 systemd[1]: Started sshd@9-10.200.8.11:22-10.200.16.10:37910.service - OpenSSH per-connection server daemon (10.200.16.10:37910). Sep 9 05:37:52.849812 sshd[2556]: Accepted publickey for core from 10.200.16.10 port 37910 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:52.851105 sshd-session[2556]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:52.860580 systemd-logind[1897]: New session 12 of user core. Sep 9 05:37:52.870708 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 9 05:37:53.304600 sshd[2559]: Connection closed by 10.200.16.10 port 37910 Sep 9 05:37:53.305009 sshd-session[2556]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:53.310777 systemd[1]: sshd@9-10.200.8.11:22-10.200.16.10:37910.service: Deactivated successfully. Sep 9 05:37:53.312684 systemd-logind[1897]: Session 12 logged out. Waiting for processes to exit. Sep 9 05:37:53.313718 systemd[1]: session-12.scope: Deactivated successfully. Sep 9 05:37:53.316554 systemd-logind[1897]: Removed session 12. Sep 9 05:37:53.420223 systemd[1]: Started sshd@10-10.200.8.11:22-10.200.16.10:37924.service - OpenSSH per-connection server daemon (10.200.16.10:37924). Sep 9 05:37:54.082774 sshd[2565]: Accepted publickey for core from 10.200.16.10 port 37924 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:37:54.084041 sshd-session[2565]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:37:54.091959 systemd-logind[1897]: New session 13 of user core. Sep 9 05:37:54.100724 systemd[1]: Started session-13.scope - Session 13 of User core. Sep 9 05:37:54.538972 sshd[2568]: Connection closed by 10.200.16.10 port 37924 Sep 9 05:37:54.541637 sshd-session[2565]: pam_unix(sshd:session): session closed for user core Sep 9 05:37:54.545324 systemd[1]: sshd@10-10.200.8.11:22-10.200.16.10:37924.service: Deactivated successfully. Sep 9 05:37:54.548263 systemd[1]: session-13.scope: Deactivated successfully. Sep 9 05:37:54.552154 systemd-logind[1897]: Session 13 logged out. Waiting for processes to exit. Sep 9 05:37:54.553398 systemd-logind[1897]: Removed session 13. Sep 9 05:37:56.376536 setup-nvidia[1889]: Downloading NVIDIA 535.261.03 Driver Sep 9 05:37:56.380768 setup-nvidia[2574]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 9 05:37:56.380992 setup-nvidia[2574]: Dload Upload Total Spent Left Speed Sep 9 05:37:57.819234 setup-nvidia[2574]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 778k 0 0 2036k 0 0:02:43 --:--:-- 0:02:43 2038k\u000d 94 325M 94 308M 0 0 223M 0 0:00:01 0:00:01 --:--:-- 222M\u000d100 325M 100 325M 0 0 226M 0 0:00:01 0:00:01 --:--:-- 226M Sep 9 05:37:57.890974 setup-nvidia[1889]: Extract the NVIDIA Driver Installer 535.261.03 Sep 9 05:37:57.890974 setup-nvidia[1889]: /opt/nvidia/workdir/nvidia-workdir / Sep 9 05:37:57.931252 setup-nvidia[2577]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Sep 9 05:37:58.214535 setup-nvidia[2577]: Verifying archive integrity... OK Sep 9 05:37:59.011064 setup-nvidia[2577]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Sep 9 05:38:00.857544 setup-nvidia[2603]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Sep 9 05:38:00.949859 setup-nvidia[1889]: / Sep 9 05:38:00.950132 setup-nvidia[1889]: Spawn system-nspawn container to install the NVIDIA drivers Sep 9 05:38:01.195539 sudo[2610]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4452.0.0+nightly-20250908-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Sep 9 05:38:01.197256 sudo[2610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 9 05:38:01.203273 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Sep 9 05:38:01.205946 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 9 05:38:01.236825 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 9 05:38:01.240595 systemd[1]: Starting user@0.service - User Manager for UID 0... Sep 9 05:38:01.260548 (systemd)[2613]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Sep 9 05:38:01.262874 systemd-logind[1897]: New session c3 of user root. Sep 9 05:38:01.451899 systemd[2613]: Queued start job for default target default.target. Sep 9 05:38:01.474736 systemd[2613]: Created slice app.slice - User Application Slice. Sep 9 05:38:01.474766 systemd[2613]: Reached target paths.target - Paths. Sep 9 05:38:01.474812 systemd[2613]: Reached target timers.target - Timers. Sep 9 05:38:01.476000 systemd[2613]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 05:38:01.486706 systemd[2613]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:38:01.486822 systemd[2613]: Reached target sockets.target - Sockets. Sep 9 05:38:01.486875 systemd[2613]: Reached target basic.target - Basic System. Sep 9 05:38:01.486918 systemd[2613]: Reached target default.target - Main User Target. Sep 9 05:38:01.486947 systemd[2613]: Startup finished in 218ms. Sep 9 05:38:01.487001 systemd[1]: Started user@0.service - User Manager for UID 0. Sep 9 05:38:01.491019 systemd[1]: Started session-c2.scope - Session c2 of User root. Sep 9 05:38:01.578491 kernel: loop6: detected capacity change from 0 to 12644352 Sep 9 05:38:01.588488 kernel: loop6: p9 Sep 9 05:38:01.656895 setup-nvidia[2622]: ░ Spawning container flatcardevelopercontainer-4452.0.0nightly-20250908-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4452.0.0+nightly-20250908-2100.bin. Sep 9 05:38:02.473951 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Sep 9 05:38:02.474074 kernel: EXT4-fs (loop6p9): mounted filesystem 70a79864-9d90-4624-98c5-2d7a4a92e0f3 ro without journal. Quota mode: none. Sep 9 05:38:04.665188 systemd[1]: Started sshd@11-10.200.8.11:22-10.200.16.10:60600.service - OpenSSH per-connection server daemon (10.200.16.10:60600). Sep 9 05:38:05.306703 sshd[2688]: Accepted publickey for core from 10.200.16.10 port 60600 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:05.307940 sshd-session[2688]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:05.312313 systemd-logind[1897]: New session 14 of user core. Sep 9 05:38:05.326647 systemd[1]: Started session-14.scope - Session 14 of User core. Sep 9 05:38:05.760662 sshd[2728]: Connection closed by 10.200.16.10 port 60600 Sep 9 05:38:05.761060 sshd-session[2688]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:05.764736 systemd[1]: sshd@11-10.200.8.11:22-10.200.16.10:60600.service: Deactivated successfully. Sep 9 05:38:05.766412 systemd[1]: session-14.scope: Deactivated successfully. Sep 9 05:38:05.767126 systemd-logind[1897]: Session 14 logged out. Waiting for processes to exit. Sep 9 05:38:05.768062 systemd-logind[1897]: Removed session 14. Sep 9 05:38:05.876973 systemd[1]: Started sshd@12-10.200.8.11:22-10.200.16.10:60606.service - OpenSSH per-connection server daemon (10.200.16.10:60606). Sep 9 05:38:06.532557 sshd[2762]: Accepted publickey for core from 10.200.16.10 port 60606 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:06.533848 sshd-session[2762]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:06.538125 systemd-logind[1897]: New session 15 of user core. Sep 9 05:38:06.548688 systemd[1]: Started session-15.scope - Session 15 of User core. Sep 9 05:38:06.986906 sshd[2951]: Connection closed by 10.200.16.10 port 60606 Sep 9 05:38:06.987198 sshd-session[2762]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:06.990698 systemd[1]: sshd@12-10.200.8.11:22-10.200.16.10:60606.service: Deactivated successfully. Sep 9 05:38:06.992335 systemd[1]: session-15.scope: Deactivated successfully. Sep 9 05:38:06.993075 systemd-logind[1897]: Session 15 logged out. Waiting for processes to exit. Sep 9 05:38:06.994183 systemd-logind[1897]: Removed session 15. Sep 9 05:38:17.104094 systemd[1]: Started sshd@13-10.200.8.11:22-10.200.16.10:48992.service - OpenSSH per-connection server daemon (10.200.16.10:48992). Sep 9 05:38:17.743608 sshd[3602]: Accepted publickey for core from 10.200.16.10 port 48992 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:17.744887 sshd-session[3602]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:17.749314 systemd-logind[1897]: New session 16 of user core. Sep 9 05:38:17.762605 systemd[1]: Started session-16.scope - Session 16 of User core. Sep 9 05:38:18.196114 sshd[3636]: Connection closed by 10.200.16.10 port 48992 Sep 9 05:38:18.196517 sshd-session[3602]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:18.200205 systemd[1]: sshd@13-10.200.8.11:22-10.200.16.10:48992.service: Deactivated successfully. Sep 9 05:38:18.201793 systemd[1]: session-16.scope: Deactivated successfully. Sep 9 05:38:18.202489 systemd-logind[1897]: Session 16 logged out. Waiting for processes to exit. Sep 9 05:38:18.203423 systemd-logind[1897]: Removed session 16. Sep 9 05:38:18.313888 systemd[1]: Started sshd@14-10.200.8.11:22-10.200.16.10:49002.service - OpenSSH per-connection server daemon (10.200.16.10:49002). Sep 9 05:38:18.982926 sshd[3642]: Accepted publickey for core from 10.200.16.10 port 49002 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:18.985298 sshd-session[3642]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:18.989605 systemd-logind[1897]: New session 17 of user core. Sep 9 05:38:19.001660 systemd[1]: Started session-17.scope - Session 17 of User core. Sep 9 05:38:19.438433 sshd[3645]: Connection closed by 10.200.16.10 port 49002 Sep 9 05:38:19.438841 sshd-session[3642]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:19.442391 systemd[1]: sshd@14-10.200.8.11:22-10.200.16.10:49002.service: Deactivated successfully. Sep 9 05:38:19.444237 systemd[1]: session-17.scope: Deactivated successfully. Sep 9 05:38:19.445033 systemd-logind[1897]: Session 17 logged out. Waiting for processes to exit. Sep 9 05:38:19.445935 systemd-logind[1897]: Removed session 17. Sep 9 05:38:29.568325 systemd[1]: Started sshd@15-10.200.8.11:22-10.200.16.10:33264.service - OpenSSH per-connection server daemon (10.200.16.10:33264). Sep 9 05:38:30.215541 sshd-session[6455]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:30.220635 systemd-logind[1897]: New session 18 of user core. Sep 9 05:38:30.268084 sshd[6455]: Accepted publickey for core from 10.200.16.10 port 33264 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:30.237586 systemd[1]: Started session-18.scope - Session 18 of User core. Sep 9 05:38:30.674894 sshd[6459]: Connection closed by 10.200.16.10 port 33264 Sep 9 05:38:30.677643 sshd-session[6455]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:30.683514 systemd-logind[1897]: Session 18 logged out. Waiting for processes to exit. Sep 9 05:38:30.684131 systemd[1]: sshd@15-10.200.8.11:22-10.200.16.10:33264.service: Deactivated successfully. Sep 9 05:38:30.686825 systemd[1]: session-18.scope: Deactivated successfully. Sep 9 05:38:30.691962 systemd-logind[1897]: Removed session 18. Sep 9 05:38:30.796543 systemd[1]: Started sshd@16-10.200.8.11:22-10.200.16.10:57060.service - OpenSSH per-connection server daemon (10.200.16.10:57060). Sep 9 05:38:31.453673 sshd[6668]: Accepted publickey for core from 10.200.16.10 port 57060 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:31.455367 sshd-session[6668]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:31.462846 systemd-logind[1897]: New session 19 of user core. Sep 9 05:38:31.470957 systemd[1]: Started session-19.scope - Session 19 of User core. Sep 9 05:38:31.905844 sshd[6838]: Connection closed by 10.200.16.10 port 57060 Sep 9 05:38:31.906218 sshd-session[6668]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:31.909190 systemd[1]: sshd@16-10.200.8.11:22-10.200.16.10:57060.service: Deactivated successfully. Sep 9 05:38:31.910838 systemd[1]: session-19.scope: Deactivated successfully. Sep 9 05:38:31.912018 systemd-logind[1897]: Session 19 logged out. Waiting for processes to exit. Sep 9 05:38:31.913012 systemd-logind[1897]: Removed session 19. Sep 9 05:38:42.024785 systemd[1]: Started sshd@17-10.200.8.11:22-10.200.16.10:53084.service - OpenSSH per-connection server daemon (10.200.16.10:53084). Sep 9 05:38:42.667922 sshd[9109]: Accepted publickey for core from 10.200.16.10 port 53084 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:42.669257 sshd-session[9109]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:42.673948 systemd-logind[1897]: New session 20 of user core. Sep 9 05:38:42.686637 systemd[1]: Started session-20.scope - Session 20 of User core. Sep 9 05:38:43.120990 sshd[9121]: Connection closed by 10.200.16.10 port 53084 Sep 9 05:38:43.121408 sshd-session[9109]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:43.125086 systemd[1]: sshd@17-10.200.8.11:22-10.200.16.10:53084.service: Deactivated successfully. Sep 9 05:38:43.126673 systemd[1]: session-20.scope: Deactivated successfully. Sep 9 05:38:43.127349 systemd-logind[1897]: Session 20 logged out. Waiting for processes to exit. Sep 9 05:38:43.128543 systemd-logind[1897]: Removed session 20. Sep 9 05:38:43.237953 systemd[1]: Started sshd@18-10.200.8.11:22-10.200.16.10:53088.service - OpenSSH per-connection server daemon (10.200.16.10:53088). Sep 9 05:38:43.876424 sshd[9203]: Accepted publickey for core from 10.200.16.10 port 53088 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:43.877705 sshd-session[9203]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:43.882379 systemd-logind[1897]: New session 21 of user core. Sep 9 05:38:43.891800 systemd[1]: Started session-21.scope - Session 21 of User core. Sep 9 05:38:44.329571 sshd[9335]: Connection closed by 10.200.16.10 port 53088 Sep 9 05:38:44.329921 sshd-session[9203]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:44.333036 systemd-logind[1897]: Session 21 logged out. Waiting for processes to exit. Sep 9 05:38:44.333302 systemd[1]: sshd@18-10.200.8.11:22-10.200.16.10:53088.service: Deactivated successfully. Sep 9 05:38:44.335048 systemd[1]: session-21.scope: Deactivated successfully. Sep 9 05:38:44.337079 systemd-logind[1897]: Removed session 21. Sep 9 05:38:54.448786 systemd[1]: Started sshd@19-10.200.8.11:22-10.200.16.10:41338.service - OpenSSH per-connection server daemon (10.200.16.10:41338). Sep 9 05:38:55.106729 sshd[13038]: Accepted publickey for core from 10.200.16.10 port 41338 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:55.108544 sshd-session[13038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:55.114834 systemd-logind[1897]: New session 22 of user core. Sep 9 05:38:55.119813 systemd[1]: Started session-22.scope - Session 22 of User core. Sep 9 05:38:55.561627 sshd[13325]: Connection closed by 10.200.16.10 port 41338 Sep 9 05:38:55.564029 sshd-session[13038]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:55.567530 systemd-logind[1897]: Session 22 logged out. Waiting for processes to exit. Sep 9 05:38:55.569279 systemd[1]: sshd@19-10.200.8.11:22-10.200.16.10:41338.service: Deactivated successfully. Sep 9 05:38:55.570947 systemd[1]: session-22.scope: Deactivated successfully. Sep 9 05:38:55.574633 systemd-logind[1897]: Removed session 22. Sep 9 05:38:55.677370 systemd[1]: Started sshd@20-10.200.8.11:22-10.200.16.10:41354.service - OpenSSH per-connection server daemon (10.200.16.10:41354). Sep 9 05:38:56.323815 sshd[13543]: Accepted publickey for core from 10.200.16.10 port 41354 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:38:56.325508 sshd-session[13543]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:56.330257 systemd-logind[1897]: New session 23 of user core. Sep 9 05:38:56.338644 systemd[1]: Started session-23.scope - Session 23 of User core. Sep 9 05:38:56.775861 sshd[13884]: Connection closed by 10.200.16.10 port 41354 Sep 9 05:38:56.776213 sshd-session[13543]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:56.779603 systemd[1]: sshd@20-10.200.8.11:22-10.200.16.10:41354.service: Deactivated successfully. Sep 9 05:38:56.781354 systemd[1]: session-23.scope: Deactivated successfully. Sep 9 05:38:56.782035 systemd-logind[1897]: Session 23 logged out. Waiting for processes to exit. Sep 9 05:38:56.782934 systemd-logind[1897]: Removed session 23. Sep 9 05:39:06.890993 systemd[1]: Started sshd@21-10.200.8.11:22-10.200.16.10:56188.service - OpenSSH per-connection server daemon (10.200.16.10:56188). Sep 9 05:39:07.544933 sshd[14487]: Accepted publickey for core from 10.200.16.10 port 56188 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:07.546707 sshd-session[14487]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:07.552245 systemd-logind[1897]: New session 24 of user core. Sep 9 05:39:07.562929 systemd[1]: Started session-24.scope - Session 24 of User core. Sep 9 05:39:08.001807 sshd[14506]: Connection closed by 10.200.16.10 port 56188 Sep 9 05:39:08.004064 sshd-session[14487]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:08.007908 systemd[1]: sshd@21-10.200.8.11:22-10.200.16.10:56188.service: Deactivated successfully. Sep 9 05:39:08.011080 systemd[1]: session-24.scope: Deactivated successfully. Sep 9 05:39:08.012403 systemd-logind[1897]: Session 24 logged out. Waiting for processes to exit. Sep 9 05:39:08.013905 systemd-logind[1897]: Removed session 24. Sep 9 05:39:08.123013 systemd[1]: Started sshd@22-10.200.8.11:22-10.200.16.10:56202.service - OpenSSH per-connection server daemon (10.200.16.10:56202). Sep 9 05:39:08.777957 sshd[14530]: Accepted publickey for core from 10.200.16.10 port 56202 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:08.779339 sshd-session[14530]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:08.785542 systemd-logind[1897]: New session 25 of user core. Sep 9 05:39:08.796628 systemd[1]: Started session-25.scope - Session 25 of User core. Sep 9 05:39:09.232061 sshd[14557]: Connection closed by 10.200.16.10 port 56202 Sep 9 05:39:09.232495 sshd-session[14530]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:09.236188 systemd[1]: sshd@22-10.200.8.11:22-10.200.16.10:56202.service: Deactivated successfully. Sep 9 05:39:09.237987 systemd[1]: session-25.scope: Deactivated successfully. Sep 9 05:39:09.238357 systemd-logind[1897]: Session 25 logged out. Waiting for processes to exit. Sep 9 05:39:09.241110 systemd-logind[1897]: Removed session 25. Sep 9 05:39:19.348963 systemd[1]: Started sshd@23-10.200.8.11:22-10.200.16.10:49900.service - OpenSSH per-connection server daemon (10.200.16.10:49900). Sep 9 05:39:20.000210 sshd[14899]: Accepted publickey for core from 10.200.16.10 port 49900 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:20.002036 sshd-session[14899]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:20.008703 systemd-logind[1897]: New session 26 of user core. Sep 9 05:39:20.015669 systemd[1]: Started session-26.scope - Session 26 of User core. Sep 9 05:39:20.456326 sshd[14908]: Connection closed by 10.200.16.10 port 49900 Sep 9 05:39:20.457341 sshd-session[14899]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:20.466576 systemd-logind[1897]: Session 26 logged out. Waiting for processes to exit. Sep 9 05:39:20.467996 systemd[1]: sshd@23-10.200.8.11:22-10.200.16.10:49900.service: Deactivated successfully. Sep 9 05:39:20.470423 systemd[1]: session-26.scope: Deactivated successfully. Sep 9 05:39:20.474501 systemd-logind[1897]: Removed session 26. Sep 9 05:39:20.576848 systemd[1]: Started sshd@24-10.200.8.11:22-10.200.16.10:48738.service - OpenSSH per-connection server daemon (10.200.16.10:48738). Sep 9 05:39:21.235278 sshd[14926]: Accepted publickey for core from 10.200.16.10 port 48738 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:21.238392 sshd-session[14926]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:21.244701 systemd-logind[1897]: New session 27 of user core. Sep 9 05:39:21.251618 systemd[1]: Started session-27.scope - Session 27 of User core. Sep 9 05:39:21.691665 sshd[14961]: Connection closed by 10.200.16.10 port 48738 Sep 9 05:39:21.693647 sshd-session[14926]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:21.697438 systemd[1]: sshd@24-10.200.8.11:22-10.200.16.10:48738.service: Deactivated successfully. Sep 9 05:39:21.699100 systemd[1]: session-27.scope: Deactivated successfully. Sep 9 05:39:21.701220 systemd-logind[1897]: Session 27 logged out. Waiting for processes to exit. Sep 9 05:39:21.702934 systemd-logind[1897]: Removed session 27. Sep 9 05:39:31.810825 systemd[1]: Started sshd@25-10.200.8.11:22-10.200.16.10:33624.service - OpenSSH per-connection server daemon (10.200.16.10:33624). Sep 9 05:39:32.450324 sshd[15286]: Accepted publickey for core from 10.200.16.10 port 33624 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:32.451646 sshd-session[15286]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:32.455911 systemd-logind[1897]: New session 28 of user core. Sep 9 05:39:32.466595 systemd[1]: Started session-28.scope - Session 28 of User core. Sep 9 05:39:32.901174 sshd[15289]: Connection closed by 10.200.16.10 port 33624 Sep 9 05:39:32.901591 sshd-session[15286]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:32.905265 systemd[1]: sshd@25-10.200.8.11:22-10.200.16.10:33624.service: Deactivated successfully. Sep 9 05:39:32.906900 systemd[1]: session-28.scope: Deactivated successfully. Sep 9 05:39:32.907660 systemd-logind[1897]: Session 28 logged out. Waiting for processes to exit. Sep 9 05:39:32.908590 systemd-logind[1897]: Removed session 28. Sep 9 05:39:33.018683 systemd[1]: Started sshd@26-10.200.8.11:22-10.200.16.10:33636.service - OpenSSH per-connection server daemon (10.200.16.10:33636). Sep 9 05:39:33.658488 sshd[15295]: Accepted publickey for core from 10.200.16.10 port 33636 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:33.659805 sshd-session[15295]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:33.664056 systemd-logind[1897]: New session 29 of user core. Sep 9 05:39:33.680651 systemd[1]: Started session-29.scope - Session 29 of User core. Sep 9 05:39:34.111095 sshd[15300]: Connection closed by 10.200.16.10 port 33636 Sep 9 05:39:34.111488 sshd-session[15295]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:34.115059 systemd[1]: sshd@26-10.200.8.11:22-10.200.16.10:33636.service: Deactivated successfully. Sep 9 05:39:34.116686 systemd[1]: session-29.scope: Deactivated successfully. Sep 9 05:39:34.117382 systemd-logind[1897]: Session 29 logged out. Waiting for processes to exit. Sep 9 05:39:34.118321 systemd-logind[1897]: Removed session 29. Sep 9 05:39:39.834754 setup-nvidia[2622]: \u000d Sep 9 05:39:39.834754 setup-nvidia[2622]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Sep 9 05:39:39.834754 setup-nvidia[2622]: frequently when this kernel module was built against the wrong or Sep 9 05:39:39.834754 setup-nvidia[2622]: improperly configured kernel sources, with a version of gcc that differs Sep 9 05:39:39.834754 setup-nvidia[2622]: from the one used to build the target kernel, or if another driver, such Sep 9 05:39:39.834754 setup-nvidia[2622]: as nouveau, is present and prevents the NVIDIA kernel module from Sep 9 05:39:39.834754 setup-nvidia[2622]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Sep 9 05:39:39.834754 setup-nvidia[2622]: installed in this system is supported by this NVIDIA Linux graphics Sep 9 05:39:39.834754 setup-nvidia[2622]: driver release. Sep 9 05:39:39.834754 setup-nvidia[2622]: \u000d Sep 9 05:39:39.834754 setup-nvidia[2622]: Please see the log entries 'Kernel module load error' and 'Kernel Sep 9 05:39:39.834754 setup-nvidia[2622]: messages' at the end of the file Sep 9 05:39:39.834754 setup-nvidia[2622]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Sep 9 05:39:39.834754 setup-nvidia[2622]: information. Sep 9 05:39:39.834754 setup-nvidia[2622]: \u000d Sep 9 05:39:39.853019 setup-nvidia[2622]: dmesg: read kernel buffer failed: Operation not permitted Sep 9 05:39:39.874686 setup-nvidia[2622]: \u000d Sep 9 05:39:39.874686 setup-nvidia[2622]: ERROR: Installation has failed. Please see the file Sep 9 05:39:39.874686 setup-nvidia[2622]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Sep 9 05:39:39.874686 setup-nvidia[2622]: details. You may find suggestions on fixing installation problems in Sep 9 05:39:39.874686 setup-nvidia[2622]: the README available on the Linux driver download page at Sep 9 05:39:39.874686 setup-nvidia[2622]: www.nvidia.com. Sep 9 05:39:39.874686 setup-nvidia[2622]: \u000d Sep 9 05:39:39.875032 setup-nvidia[2622]: Last 50 lines of nvidia-installer.log: Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Sep 9 05:39:39.876266 setup-nvidia[2622]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Sep 9 05:39:39.876266 setup-nvidia[2622]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Sep 9 05:39:39.876266 setup-nvidia[2622]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Sep 9 05:39:39.876266 setup-nvidia[2622]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Sep 9 05:39:39.876266 setup-nvidia[2622]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Sep 9 05:39:39.876266 setup-nvidia[2622]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Sep 9 05:39:39.876266 setup-nvidia[2622]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Sep 9 05:39:39.876266 setup-nvidia[2622]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Sep 9 05:39:39.876266 setup-nvidia[2622]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Sep 9 05:39:39.876266 setup-nvidia[2622]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Sep 9 05:39:39.876266 setup-nvidia[2622]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Sep 9 05:39:39.876266 setup-nvidia[2622]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Sep 9 05:39:39.876266 setup-nvidia[2622]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Sep 9 05:39:39.876266 setup-nvidia[2622]: make[2]: Leaving directory '/usr/lib/modules/6.12.45-flatcar/build' Sep 9 05:39:39.876266 setup-nvidia[2622]: make[1]: Leaving directory '/usr/lib/modules/6.12.45-flatcar/source' Sep 9 05:39:39.876266 setup-nvidia[2622]: -> done. Sep 9 05:39:39.876266 setup-nvidia[2622]: -> Kernel module compilation complete. Sep 9 05:39:39.876266 setup-nvidia[2622]: -> Unable to determine if Secure Boot is enabled: No such file or directory Sep 9 05:39:39.877428 setup-nvidia[2622]: 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. Sep 9 05:39:39.877428 setup-nvidia[2622]: \u000d Sep 9 05:39:39.877428 setup-nvidia[2622]: 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. Sep 9 05:39:39.877428 setup-nvidia[2622]: -> Kernel module load error: Operation not permitted Sep 9 05:39:39.877428 setup-nvidia[2622]: -> Kernel messages: Sep 9 05:39:39.877428 setup-nvidia[2622]: 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. Sep 9 05:39:44.223352 systemd[1]: Started sshd@27-10.200.8.11:22-10.200.16.10:53980.service - OpenSSH per-connection server daemon (10.200.16.10:53980). Sep 9 05:39:44.869040 sshd[15486]: Accepted publickey for core from 10.200.16.10 port 53980 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:44.870316 sshd-session[15486]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:44.874876 systemd-logind[1897]: New session 30 of user core. Sep 9 05:39:44.891589 systemd[1]: Started session-30.scope - Session 30 of User core. Sep 9 05:39:45.321281 sshd[15490]: Connection closed by 10.200.16.10 port 53980 Sep 9 05:39:45.321659 sshd-session[15486]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:45.325449 systemd[1]: sshd@27-10.200.8.11:22-10.200.16.10:53980.service: Deactivated successfully. Sep 9 05:39:45.327208 systemd[1]: session-30.scope: Deactivated successfully. Sep 9 05:39:45.328042 systemd-logind[1897]: Session 30 logged out. Waiting for processes to exit. Sep 9 05:39:45.329010 systemd-logind[1897]: Removed session 30. Sep 9 05:39:45.436779 systemd[1]: Started sshd@28-10.200.8.11:22-10.200.16.10:53992.service - OpenSSH per-connection server daemon (10.200.16.10:53992). Sep 9 05:39:46.092231 sshd[15496]: Accepted publickey for core from 10.200.16.10 port 53992 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:46.093493 sshd-session[15496]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:46.098061 systemd-logind[1897]: New session 31 of user core. Sep 9 05:39:46.106592 systemd[1]: Started session-31.scope - Session 31 of User core. Sep 9 05:39:46.544761 sshd[15499]: Connection closed by 10.200.16.10 port 53992 Sep 9 05:39:46.545136 sshd-session[15496]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:46.549043 systemd[1]: sshd@28-10.200.8.11:22-10.200.16.10:53992.service: Deactivated successfully. Sep 9 05:39:46.550754 systemd[1]: session-31.scope: Deactivated successfully. Sep 9 05:39:46.551452 systemd-logind[1897]: Session 31 logged out. Waiting for processes to exit. Sep 9 05:39:46.552482 systemd-logind[1897]: Removed session 31. Sep 9 05:39:56.658795 systemd[1]: Started sshd@29-10.200.8.11:22-10.200.16.10:48304.service - OpenSSH per-connection server daemon (10.200.16.10:48304). Sep 9 05:39:57.322200 sshd[15505]: Accepted publickey for core from 10.200.16.10 port 48304 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:57.323419 sshd-session[15505]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:57.327751 systemd-logind[1897]: New session 32 of user core. Sep 9 05:39:57.341593 systemd[1]: Started session-32.scope - Session 32 of User core. Sep 9 05:39:57.775921 sshd[15508]: Connection closed by 10.200.16.10 port 48304 Sep 9 05:39:57.776288 sshd-session[15505]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:57.779848 systemd[1]: sshd@29-10.200.8.11:22-10.200.16.10:48304.service: Deactivated successfully. Sep 9 05:39:57.781573 systemd[1]: session-32.scope: Deactivated successfully. Sep 9 05:39:57.782271 systemd-logind[1897]: Session 32 logged out. Waiting for processes to exit. Sep 9 05:39:57.783152 systemd-logind[1897]: Removed session 32. Sep 9 05:39:57.892685 systemd[1]: Started sshd@30-10.200.8.11:22-10.200.16.10:48310.service - OpenSSH per-connection server daemon (10.200.16.10:48310). Sep 9 05:39:58.552285 sshd[15514]: Accepted publickey for core from 10.200.16.10 port 48310 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:39:58.553549 sshd-session[15514]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:39:58.558578 systemd-logind[1897]: New session 33 of user core. Sep 9 05:39:58.570583 systemd[1]: Started session-33.scope - Session 33 of User core. Sep 9 05:39:59.004871 sshd[15517]: Connection closed by 10.200.16.10 port 48310 Sep 9 05:39:59.005265 sshd-session[15514]: pam_unix(sshd:session): session closed for user core Sep 9 05:39:59.008880 systemd[1]: sshd@30-10.200.8.11:22-10.200.16.10:48310.service: Deactivated successfully. Sep 9 05:39:59.010637 systemd[1]: session-33.scope: Deactivated successfully. Sep 9 05:39:59.011308 systemd-logind[1897]: Session 33 logged out. Waiting for processes to exit. Sep 9 05:39:59.012283 systemd-logind[1897]: Removed session 33. Sep 9 05:40:06.786494 kernel: EXT4-fs (loop6p9): unmounting filesystem 70a79864-9d90-4624-98c5-2d7a4a92e0f3. Sep 9 05:40:06.787146 setup-nvidia[2622]: Container flatcardevelopercontainer-4452.0.0nightly-20250908-2100.bin exited successfully. Sep 9 05:40:06.789761 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4452.0.0nightly\x2d20250908\x2d2100.bin.mount: Deactivated successfully. Sep 9 05:40:06.845323 sudo[2610]: pam_unix(sudo:session): session closed for user root Sep 9 05:40:06.847221 systemd[1]: session-c2.scope: Deactivated successfully. Sep 9 05:40:06.847545 systemd[1]: session-c2.scope: Consumed 5min 24.589s CPU time, 889.2M memory peak. Sep 9 05:40:07.281210 setup-nvidia[1889]: /opt/nvidia / Sep 9 05:40:07.282226 setup-nvidia[1889]: / Sep 9 05:40:07.296487 kernel: IPMI message handler: version 39.2 Sep 9 05:40:07.299482 kernel: ipmi device interface Sep 9 05:40:07.311898 setup-nvidia[1889]: /opt/nvidia/current/usr/lib/modules/6.12.45-flatcar/video / Sep 9 05:40:07.368900 kernel: nvidia: loading out-of-tree module taints kernel. Sep 9 05:40:07.369035 kernel: nvidia: module license 'NVIDIA' taints kernel. Sep 9 05:40:07.369081 kernel: Disabling lock debugging due to kernel taint Sep 9 05:40:07.376460 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Sep 9 05:40:07.376508 kernel: nvidia: module license taints kernel. Sep 9 05:40:07.769488 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Sep 9 05:40:08.182406 kernel: Sep 9 05:40:08.182565 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Sep 9 05:40:08.391493 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Sep 9 05:40:08.413487 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Sep 9 05:40:08.432507 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Sep 9 05:40:08.482040 setup-nvidia[1889]: / Sep 9 05:40:08.482488 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Sep 9 05:40:08.496944 setup-nvidia[15557]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 05:40:09.122953 systemd[1]: Started sshd@31-10.200.8.11:22-10.200.16.10:60204.service - OpenSSH per-connection server daemon (10.200.16.10:60204). Sep 9 05:40:09.785731 sshd[15579]: Accepted publickey for core from 10.200.16.10 port 60204 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:09.786972 sshd-session[15579]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:09.791263 systemd-logind[1897]: New session 34 of user core. Sep 9 05:40:09.805605 systemd[1]: Started session-34.scope - Session 34 of User core. Sep 9 05:40:10.239993 sshd[15582]: Connection closed by 10.200.16.10 port 60204 Sep 9 05:40:10.240298 sshd-session[15579]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:10.244191 systemd[1]: sshd@31-10.200.8.11:22-10.200.16.10:60204.service: Deactivated successfully. Sep 9 05:40:10.245994 systemd[1]: session-34.scope: Deactivated successfully. Sep 9 05:40:10.246747 systemd-logind[1897]: Session 34 logged out. Waiting for processes to exit. Sep 9 05:40:10.247669 systemd-logind[1897]: Removed session 34. Sep 9 05:40:10.256776 setup-nvidia[15574]: Tue Sep 9 05:40:10 2025 Sep 9 05:40:10.256776 setup-nvidia[15574]: +---------------------------------------------------------------------------------------+ Sep 9 05:40:10.256776 setup-nvidia[15574]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Sep 9 05:40:10.256776 setup-nvidia[15574]: |-----------------------------------------+----------------------+----------------------+ Sep 9 05:40:10.256776 setup-nvidia[15574]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Sep 9 05:40:10.256776 setup-nvidia[15574]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Sep 9 05:40:10.256776 setup-nvidia[15574]: | | | MIG M. | Sep 9 05:40:10.256776 setup-nvidia[15574]: |=========================================+======================+======================| Sep 9 05:40:10.261681 setup-nvidia[15574]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Sep 9 05:40:10.261681 setup-nvidia[15574]: | N/A 26C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Sep 9 05:40:10.261681 setup-nvidia[15574]: | | | N/A | Sep 9 05:40:10.261681 setup-nvidia[15574]: +-----------------------------------------+----------------------+----------------------+ Sep 9 05:40:10.261798 setup-nvidia[15574]: Sep 9 05:40:10.261798 setup-nvidia[15574]: +---------------------------------------------------------------------------------------+ Sep 9 05:40:10.261798 setup-nvidia[15574]: | Processes: | Sep 9 05:40:10.261798 setup-nvidia[15574]: | GPU GI CI PID Type Process name GPU Memory | Sep 9 05:40:10.261798 setup-nvidia[15574]: | ID ID Usage | Sep 9 05:40:10.261798 setup-nvidia[15574]: |=======================================================================================| Sep 9 05:40:10.261932 setup-nvidia[15574]: | No running processes found | Sep 9 05:40:10.261932 setup-nvidia[15574]: +---------------------------------------------------------------------------------------+ Sep 9 05:40:10.353741 systemd[1]: Started sshd@32-10.200.8.11:22-10.200.16.10:38392.service - OpenSSH per-connection server daemon (10.200.16.10:38392). Sep 9 05:40:10.635349 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 05:40:10.637376 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 05:40:10.637795 systemd[1]: Startup finished in 1.366s (firmware) + 32.402s (loader) + 3.867s (kernel) + 15.845s (initrd) + 4min 11.353s (userspace) = 5min 4.834s. Sep 9 05:40:11.247987 sshd[15588]: Accepted publickey for core from 10.200.16.10 port 38392 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:11.254372 sshd-session[15588]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:11.259020 systemd-logind[1897]: New session 35 of user core. Sep 9 05:40:11.268638 systemd[1]: Started session-35.scope - Session 35 of User core. Sep 9 05:40:11.699985 sshd[15593]: Connection closed by 10.200.16.10 port 38392 Sep 9 05:40:11.700308 sshd-session[15588]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:11.704160 systemd[1]: sshd@32-10.200.8.11:22-10.200.16.10:38392.service: Deactivated successfully. Sep 9 05:40:11.705982 systemd[1]: session-35.scope: Deactivated successfully. Sep 9 05:40:11.706750 systemd-logind[1897]: Session 35 logged out. Waiting for processes to exit. Sep 9 05:40:11.707664 systemd-logind[1897]: Removed session 35. Sep 9 05:40:16.998798 systemd[1]: Stopping user@0.service - User Manager for UID 0... Sep 9 05:40:17.016056 (sd-pam)[2614]: pam_unix(systemd-user:session): session closed for user root Sep 9 05:40:16.999615 systemd[2613]: Activating special unit exit.target... Sep 9 05:40:16.999685 systemd[2613]: Stopped target default.target - Main User Target. Sep 9 05:40:16.999725 systemd[2613]: Stopped target basic.target - Basic System. Sep 9 05:40:16.999754 systemd[2613]: Stopped target paths.target - Paths. Sep 9 05:40:16.999779 systemd[2613]: Stopped target sockets.target - Sockets. Sep 9 05:40:16.999803 systemd[2613]: Stopped target timers.target - Timers. Sep 9 05:40:17.000147 systemd[2613]: Closed dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:40:17.000354 systemd[2613]: Removed slice app.slice - User Application Slice. Sep 9 05:40:17.000382 systemd[2613]: Reached target shutdown.target - Shutdown. Sep 9 05:40:17.000544 systemd[2613]: Finished systemd-exit.service - Exit the Session. Sep 9 05:40:17.000576 systemd[2613]: Reached target exit.target - Exit the Session. Sep 9 05:40:17.017409 systemd[1]: user@0.service: Deactivated successfully. Sep 9 05:40:17.017899 systemd[1]: Stopped user@0.service - User Manager for UID 0. Sep 9 05:40:17.023964 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Sep 9 05:40:17.047753 systemd[1]: run-user-0.mount: Deactivated successfully. Sep 9 05:40:17.048879 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Sep 9 05:40:17.049062 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Sep 9 05:40:17.050173 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Sep 9 05:40:17.050250 systemd[1]: user-0.slice: Consumed 5min 24.819s CPU time, 895.4M memory peak. Sep 9 05:40:17.050579 systemd-logind[1897]: Removed session c3. Sep 9 05:40:21.817058 systemd[1]: Started sshd@33-10.200.8.11:22-10.200.16.10:52030.service - OpenSSH per-connection server daemon (10.200.16.10:52030). Sep 9 05:40:22.457251 sshd[15603]: Accepted publickey for core from 10.200.16.10 port 52030 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:22.458577 sshd-session[15603]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:22.462896 systemd-logind[1897]: New session 36 of user core. Sep 9 05:40:22.473375 systemd[1]: Started session-36.scope - Session 36 of User core. Sep 9 05:40:22.900649 sshd[15606]: Connection closed by 10.200.16.10 port 52030 Sep 9 05:40:22.901066 sshd-session[15603]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:22.905049 systemd[1]: sshd@33-10.200.8.11:22-10.200.16.10:52030.service: Deactivated successfully. Sep 9 05:40:22.906735 systemd[1]: session-36.scope: Deactivated successfully. Sep 9 05:40:22.907420 systemd-logind[1897]: Session 36 logged out. Waiting for processes to exit. Sep 9 05:40:22.908322 systemd-logind[1897]: Removed session 36. Sep 9 05:40:23.021754 systemd[1]: Started sshd@34-10.200.8.11:22-10.200.16.10:52036.service - OpenSSH per-connection server daemon (10.200.16.10:52036). Sep 9 05:40:23.675185 sshd[15612]: Accepted publickey for core from 10.200.16.10 port 52036 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:23.676523 sshd-session[15612]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:23.680995 systemd-logind[1897]: New session 37 of user core. Sep 9 05:40:23.696593 systemd[1]: Started session-37.scope - Session 37 of User core. Sep 9 05:40:24.116459 sshd[15615]: Connection closed by 10.200.16.10 port 52036 Sep 9 05:40:24.116836 sshd-session[15612]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:24.120398 systemd[1]: sshd@34-10.200.8.11:22-10.200.16.10:52036.service: Deactivated successfully. Sep 9 05:40:24.122148 systemd[1]: session-37.scope: Deactivated successfully. Sep 9 05:40:24.122846 systemd-logind[1897]: Session 37 logged out. Waiting for processes to exit. Sep 9 05:40:24.123775 systemd-logind[1897]: Removed session 37. Sep 9 05:40:24.234001 systemd[1]: Started sshd@35-10.200.8.11:22-10.200.16.10:52046.service - OpenSSH per-connection server daemon (10.200.16.10:52046). Sep 9 05:40:24.885285 sshd[15621]: Accepted publickey for core from 10.200.16.10 port 52046 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:24.886769 sshd-session[15621]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:24.891163 systemd-logind[1897]: New session 38 of user core. Sep 9 05:40:24.900604 systemd[1]: Started session-38.scope - Session 38 of User core. Sep 9 05:40:25.331009 sshd[15624]: Connection closed by 10.200.16.10 port 52046 Sep 9 05:40:25.331370 sshd-session[15621]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:25.334932 systemd[1]: sshd@35-10.200.8.11:22-10.200.16.10:52046.service: Deactivated successfully. Sep 9 05:40:25.336651 systemd[1]: session-38.scope: Deactivated successfully. Sep 9 05:40:25.337337 systemd-logind[1897]: Session 38 logged out. Waiting for processes to exit. Sep 9 05:40:25.338235 systemd-logind[1897]: Removed session 38. Sep 9 05:40:25.445641 systemd[1]: Started sshd@36-10.200.8.11:22-10.200.16.10:52060.service - OpenSSH per-connection server daemon (10.200.16.10:52060). Sep 9 05:40:26.071191 sshd[15630]: Accepted publickey for core from 10.200.16.10 port 52060 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:26.072723 sshd-session[15630]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:26.077199 systemd-logind[1897]: New session 39 of user core. Sep 9 05:40:26.088578 systemd[1]: Started session-39.scope - Session 39 of User core. Sep 9 05:40:26.519702 sshd[15633]: Connection closed by 10.200.16.10 port 52060 Sep 9 05:40:26.520070 sshd-session[15630]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:26.523689 systemd[1]: sshd@36-10.200.8.11:22-10.200.16.10:52060.service: Deactivated successfully. Sep 9 05:40:26.525368 systemd[1]: session-39.scope: Deactivated successfully. Sep 9 05:40:26.526115 systemd-logind[1897]: Session 39 logged out. Waiting for processes to exit. Sep 9 05:40:26.527225 systemd-logind[1897]: Removed session 39. Sep 9 05:40:26.645621 systemd[1]: Started sshd@37-10.200.8.11:22-10.200.16.10:52062.service - OpenSSH per-connection server daemon (10.200.16.10:52062). Sep 9 05:40:27.271920 sshd[15639]: Accepted publickey for core from 10.200.16.10 port 52062 ssh2: RSA SHA256:AA4nFAvaKYY/e32TwOsm5ZiQrnpspW3WZ0HgrUzz9fI Sep 9 05:40:27.273167 sshd-session[15639]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:40:27.277481 systemd-logind[1897]: New session 40 of user core. Sep 9 05:40:27.298587 systemd[1]: Started session-40.scope - Session 40 of User core. Sep 9 05:40:29.185346 sshd[15642]: Connection closed by 10.200.16.10 port 52062 Sep 9 05:40:29.185770 sshd-session[15639]: pam_unix(sshd:session): session closed for user core Sep 9 05:40:29.189443 systemd[1]: sshd@37-10.200.8.11:22-10.200.16.10:52062.service: Deactivated successfully. Sep 9 05:40:29.191071 systemd[1]: session-40.scope: Deactivated successfully. Sep 9 05:40:29.191275 systemd[1]: session-40.scope: Consumed 1.408s CPU time, 14M memory peak. Sep 9 05:40:29.191830 systemd-logind[1897]: Session 40 logged out. Waiting for processes to exit. Sep 9 05:40:29.192789 systemd-logind[1897]: Removed session 40.