Nov 6 23:37:20.229384 kernel: Linux version 6.6.113-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT_DYNAMIC Thu Nov 6 22:02:38 -00 2025 Nov 6 23:37:20.229412 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=1a4810aa24298684dd9efd264f1d9b812e4e16f32429f4615db9ff284dd4ac25 Nov 6 23:37:20.229425 kernel: BIOS-provided physical RAM map: Nov 6 23:37:20.229433 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 6 23:37:20.229439 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 6 23:37:20.229446 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 6 23:37:20.229454 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 6 23:37:20.229464 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 6 23:37:20.229471 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 6 23:37:20.229477 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 6 23:37:20.229484 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 6 23:37:20.229491 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 6 23:37:20.229498 kernel: printk: bootconsole [earlyser0] enabled Nov 6 23:37:20.229505 kernel: NX (Execute Disable) protection: active Nov 6 23:37:20.229516 kernel: APIC: Static calls initialized Nov 6 23:37:20.229523 kernel: efi: EFI v2.7 by Microsoft Nov 6 23:37:20.229531 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Nov 6 23:37:20.229539 kernel: random: crng init done Nov 6 23:37:20.229547 kernel: secureboot: Secure boot disabled Nov 6 23:37:20.229554 kernel: SMBIOS 3.1.0 present. Nov 6 23:37:20.229562 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 6 23:37:20.229570 kernel: Hypervisor detected: Microsoft Hyper-V Nov 6 23:37:20.229578 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 6 23:37:20.229586 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 6 23:37:20.229595 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 6 23:37:20.229603 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 6 23:37:20.229611 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 6 23:37:20.229619 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 6 23:37:20.229627 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 6 23:37:20.229635 kernel: tsc: Detected 2593.993 MHz processor Nov 6 23:37:20.229643 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 6 23:37:20.229651 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 6 23:37:20.229658 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 6 23:37:20.229668 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 6 23:37:20.229676 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 6 23:37:20.229684 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 6 23:37:20.229691 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 6 23:37:20.229699 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 6 23:37:20.229706 kernel: Using GB pages for direct mapping Nov 6 23:37:20.229714 kernel: ACPI: Early table checksum verification disabled Nov 6 23:37:20.229725 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 6 23:37:20.229736 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229744 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229752 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 6 23:37:20.229760 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 6 23:37:20.229768 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229777 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229787 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229795 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229803 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229812 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229820 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 6 23:37:20.229828 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 6 23:37:20.229836 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 6 23:37:20.229844 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 6 23:37:20.229854 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 6 23:37:20.229862 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 6 23:37:20.229870 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 6 23:37:20.229879 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 6 23:37:20.229887 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 6 23:37:20.229895 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 6 23:37:20.229903 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 6 23:37:20.229911 kernel: SRAT: PXM 0 -> APIC 0x00 -> Node 0 Nov 6 23:37:20.229919 kernel: SRAT: PXM 0 -> APIC 0x01 -> Node 0 Nov 6 23:37:20.229929 kernel: SRAT: PXM 0 -> APIC 0x02 -> Node 0 Nov 6 23:37:20.229937 kernel: SRAT: PXM 0 -> APIC 0x03 -> Node 0 Nov 6 23:37:20.229945 kernel: SRAT: PXM 0 -> APIC 0x04 -> Node 0 Nov 6 23:37:20.229953 kernel: SRAT: PXM 0 -> APIC 0x05 -> Node 0 Nov 6 23:37:20.229961 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 6 23:37:20.229970 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 6 23:37:20.229978 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 6 23:37:20.229986 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 6 23:37:20.230005 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 6 23:37:20.230071 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 6 23:37:20.230082 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 6 23:37:20.230090 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 6 23:37:20.230127 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00000000-0xfdfffffff] Nov 6 23:37:20.230141 kernel: NUMA: Node 0 [mem 0x00000000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00000000-0x5cbfffffff] Nov 6 23:37:20.230149 kernel: NODE_DATA(0) allocated [mem 0x5cbfff9000-0x5cbfffefff] Nov 6 23:37:20.230158 kernel: Zone ranges: Nov 6 23:37:20.230166 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 6 23:37:20.230174 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 6 23:37:20.230187 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 6 23:37:20.230195 kernel: Movable zone start for each node Nov 6 23:37:20.230203 kernel: Early memory node ranges Nov 6 23:37:20.230211 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 6 23:37:20.230219 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 6 23:37:20.230227 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 6 23:37:20.230236 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 6 23:37:20.230244 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 6 23:37:20.230252 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 6 23:37:20.230263 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 6 23:37:20.230271 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 6 23:37:20.230279 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 6 23:37:20.230287 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 6 23:37:20.230295 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 6 23:37:20.230303 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 6 23:37:20.230311 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 6 23:37:20.230319 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 6 23:37:20.230328 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 6 23:37:20.230338 kernel: smpboot: Allowing 6 CPUs, 0 hotplug CPUs Nov 6 23:37:20.230346 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 6 23:37:20.230354 kernel: Booting paravirtualized kernel on Hyper-V Nov 6 23:37:20.230363 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 6 23:37:20.230371 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 6 23:37:20.230379 kernel: percpu: Embedded 58 pages/cpu s196712 r8192 d32664 u262144 Nov 6 23:37:20.230387 kernel: pcpu-alloc: s196712 r8192 d32664 u262144 alloc=1*2097152 Nov 6 23:37:20.230395 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 6 23:37:20.230403 kernel: Hyper-V: PV spinlocks enabled Nov 6 23:37:20.230414 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 6 23:37:20.230424 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=1a4810aa24298684dd9efd264f1d9b812e4e16f32429f4615db9ff284dd4ac25 Nov 6 23:37:20.230432 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 6 23:37:20.230440 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 6 23:37:20.230449 kernel: Fallback order for Node 0: 0 Nov 6 23:37:20.230457 kernel: Built 1 zonelists, mobility grouping on. Total pages: 28899610 Nov 6 23:37:20.230465 kernel: Policy zone: Normal Nov 6 23:37:20.230473 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 6 23:37:20.230484 kernel: software IO TLB: area num 8. Nov 6 23:37:20.230500 kernel: Memory: 115335988K/117439364K available (14336K kernel code, 2288K rwdata, 22872K rodata, 43520K init, 1560K bss, 2103120K reserved, 0K cma-reserved) Nov 6 23:37:20.230509 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 6 23:37:20.230520 kernel: Kernel/User page tables isolation: enabled Nov 6 23:37:20.230528 kernel: ftrace: allocating 37954 entries in 149 pages Nov 6 23:37:20.230537 kernel: ftrace: allocated 149 pages with 4 groups Nov 6 23:37:20.230545 kernel: Dynamic Preempt: voluntary Nov 6 23:37:20.230554 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 6 23:37:20.230563 kernel: rcu: RCU event tracing is enabled. Nov 6 23:37:20.230572 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 6 23:37:20.230581 kernel: Trampoline variant of Tasks RCU enabled. Nov 6 23:37:20.230592 kernel: Rude variant of Tasks RCU enabled. Nov 6 23:37:20.230600 kernel: Tracing variant of Tasks RCU enabled. Nov 6 23:37:20.230609 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 6 23:37:20.230618 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 6 23:37:20.230626 kernel: Using NULL legacy PIC Nov 6 23:37:20.230635 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 6 23:37:20.230646 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 6 23:37:20.230654 kernel: Console: colour dummy device 80x25 Nov 6 23:37:20.230663 kernel: printk: console [tty1] enabled Nov 6 23:37:20.230672 kernel: printk: console [ttyS0] enabled Nov 6 23:37:20.230680 kernel: printk: bootconsole [earlyser0] disabled Nov 6 23:37:20.230689 kernel: ACPI: Core revision 20230628 Nov 6 23:37:20.230697 kernel: Failed to register legacy timer interrupt Nov 6 23:37:20.230706 kernel: APIC: Switch to symmetric I/O mode setup Nov 6 23:37:20.230714 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 6 23:37:20.230725 kernel: Hyper-V: Using IPI hypercalls Nov 6 23:37:20.230734 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 6 23:37:20.230742 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 6 23:37:20.230751 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 6 23:37:20.230759 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 6 23:37:20.230768 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 6 23:37:20.230777 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 6 23:37:20.230785 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 6 23:37:20.230794 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 6 23:37:20.230805 kernel: APIC: read() replaced with hv_apic_read() Nov 6 23:37:20.230813 kernel: APIC: write() replaced with hv_apic_write() Nov 6 23:37:20.230822 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 6 23:37:20.230830 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 6 23:37:20.230839 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Nov 6 23:37:20.230848 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 6 23:37:20.230856 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 6 23:37:20.230865 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 6 23:37:20.230873 kernel: Spectre V2 : Mitigation: Retpolines Nov 6 23:37:20.230884 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 6 23:37:20.230892 kernel: Speculative Store Bypass: Vulnerable Nov 6 23:37:20.230900 kernel: MDS: Mitigation: Clear CPU buffers Nov 6 23:37:20.230909 kernel: TAA: Mitigation: Clear CPU buffers Nov 6 23:37:20.230917 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 6 23:37:20.230925 kernel: active return thunk: its_return_thunk Nov 6 23:37:20.230933 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 6 23:37:20.230942 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 6 23:37:20.230950 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 6 23:37:20.230959 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 6 23:37:20.230967 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 6 23:37:20.230978 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 6 23:37:20.231014 kernel: Freeing SMP alternatives memory: 32K Nov 6 23:37:20.231024 kernel: pid_max: default: 32768 minimum: 301 Nov 6 23:37:20.231033 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Nov 6 23:37:20.231041 kernel: landlock: Up and running. Nov 6 23:37:20.231049 kernel: SELinux: Initializing. Nov 6 23:37:20.231058 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 6 23:37:20.231066 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 6 23:37:20.231075 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 6 23:37:20.231083 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 6 23:37:20.231092 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 6 23:37:20.231104 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 6 23:37:20.231113 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 6 23:37:20.231122 kernel: signal: max sigframe size: 1776 Nov 6 23:37:20.231130 kernel: rcu: Hierarchical SRCU implementation. Nov 6 23:37:20.231139 kernel: rcu: Max phase no-delay instances is 400. Nov 6 23:37:20.231147 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 6 23:37:20.231156 kernel: smp: Bringing up secondary CPUs ... Nov 6 23:37:20.231164 kernel: smpboot: x86: Booting SMP configuration: Nov 6 23:37:20.231173 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 6 23:37:20.231184 kernel: smp: Brought up 1 node, 6 CPUs Nov 6 23:37:20.231192 kernel: smpboot: Max logical packages: 1 Nov 6 23:37:20.231201 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Nov 6 23:37:20.231209 kernel: devtmpfs: initialized Nov 6 23:37:20.231218 kernel: x86/mm: Memory block size: 1024MB Nov 6 23:37:20.231227 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 6 23:37:20.231235 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 6 23:37:20.231244 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 6 23:37:20.231252 kernel: pinctrl core: initialized pinctrl subsystem Nov 6 23:37:20.231282 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 6 23:37:20.231291 kernel: audit: initializing netlink subsys (disabled) Nov 6 23:37:20.231300 kernel: audit: type=2000 audit(1762472238.029:1): state=initialized audit_enabled=0 res=1 Nov 6 23:37:20.231308 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 6 23:37:20.231317 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 6 23:37:20.231325 kernel: cpuidle: using governor menu Nov 6 23:37:20.231334 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 6 23:37:20.231342 kernel: dca service started, version 1.12.1 Nov 6 23:37:20.231351 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 6 23:37:20.231362 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 6 23:37:20.231371 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 6 23:37:20.231379 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 6 23:37:20.231388 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 6 23:37:20.231397 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 6 23:37:20.231405 kernel: ACPI: Added _OSI(Module Device) Nov 6 23:37:20.231414 kernel: ACPI: Added _OSI(Processor Device) Nov 6 23:37:20.231423 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 6 23:37:20.231431 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 6 23:37:20.231442 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Nov 6 23:37:20.231451 kernel: ACPI: Interpreter enabled Nov 6 23:37:20.231459 kernel: ACPI: PM: (supports S0 S5) Nov 6 23:37:20.231468 kernel: ACPI: Using IOAPIC for interrupt routing Nov 6 23:37:20.231477 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 6 23:37:20.231485 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 6 23:37:20.231494 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 6 23:37:20.231502 kernel: iommu: Default domain type: Translated Nov 6 23:37:20.231511 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 6 23:37:20.231522 kernel: efivars: Registered efivars operations Nov 6 23:37:20.231530 kernel: PCI: Using ACPI for IRQ routing Nov 6 23:37:20.231539 kernel: PCI: System does not support PCI Nov 6 23:37:20.231548 kernel: vgaarb: loaded Nov 6 23:37:20.231556 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 6 23:37:20.231565 kernel: VFS: Disk quotas dquot_6.6.0 Nov 6 23:37:20.231573 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 6 23:37:20.231582 kernel: pnp: PnP ACPI init Nov 6 23:37:20.231590 kernel: pnp: PnP ACPI: found 3 devices Nov 6 23:37:20.231601 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 6 23:37:20.231610 kernel: NET: Registered PF_INET protocol family Nov 6 23:37:20.231619 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 6 23:37:20.231628 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 6 23:37:20.231636 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 6 23:37:20.231645 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 6 23:37:20.231654 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 6 23:37:20.231662 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 6 23:37:20.231671 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 6 23:37:20.231682 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 6 23:37:20.231691 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 6 23:37:20.231699 kernel: NET: Registered PF_XDP protocol family Nov 6 23:37:20.231708 kernel: PCI: CLS 0 bytes, default 64 Nov 6 23:37:20.231716 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 6 23:37:20.231725 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Nov 6 23:37:20.231734 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 6 23:37:20.231742 kernel: Initialise system trusted keyrings Nov 6 23:37:20.231751 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 6 23:37:20.231762 kernel: Key type asymmetric registered Nov 6 23:37:20.231770 kernel: Asymmetric key parser 'x509' registered Nov 6 23:37:20.231778 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 6 23:37:20.231787 kernel: io scheduler mq-deadline registered Nov 6 23:37:20.231795 kernel: io scheduler kyber registered Nov 6 23:37:20.231804 kernel: io scheduler bfq registered Nov 6 23:37:20.231812 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 6 23:37:20.231821 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 6 23:37:20.231830 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 6 23:37:20.231841 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 6 23:37:20.231849 kernel: i8042: PNP: No PS/2 controller found. Nov 6 23:37:20.232032 kernel: rtc_cmos 00:02: registered as rtc0 Nov 6 23:37:20.232146 kernel: rtc_cmos 00:02: setting system clock to 2025-11-06T23:37:19 UTC (1762472239) Nov 6 23:37:20.232250 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 6 23:37:20.232263 kernel: intel_pstate: CPU model not supported Nov 6 23:37:20.232272 kernel: efifb: probing for efifb Nov 6 23:37:20.232285 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 6 23:37:20.232294 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 6 23:37:20.232302 kernel: efifb: scrolling: redraw Nov 6 23:37:20.232311 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 6 23:37:20.232320 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 23:37:20.232328 kernel: fb0: EFI VGA frame buffer device Nov 6 23:37:20.232337 kernel: pstore: Using crash dump compression: deflate Nov 6 23:37:20.232345 kernel: pstore: Registered efi_pstore as persistent store backend Nov 6 23:37:20.232354 kernel: NET: Registered PF_INET6 protocol family Nov 6 23:37:20.232365 kernel: Segment Routing with IPv6 Nov 6 23:37:20.232374 kernel: In-situ OAM (IOAM) with IPv6 Nov 6 23:37:20.232382 kernel: NET: Registered PF_PACKET protocol family Nov 6 23:37:20.232391 kernel: Key type dns_resolver registered Nov 6 23:37:20.232399 kernel: IPI shorthand broadcast: enabled Nov 6 23:37:20.232408 kernel: sched_clock: Marking stable (1662003800, 103617900)->(2120422800, -354801100) Nov 6 23:37:20.232416 kernel: registered taskstats version 1 Nov 6 23:37:20.232425 kernel: Loading compiled-in X.509 certificates Nov 6 23:37:20.232433 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.113-flatcar: d06f6bc77ef9183fbb55ec1fc021fe2cce974996' Nov 6 23:37:20.232442 kernel: Key type .fscrypt registered Nov 6 23:37:20.232452 kernel: Key type fscrypt-provisioning registered Nov 6 23:37:20.232461 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 6 23:37:20.232469 kernel: ima: Allocated hash algorithm: sha1 Nov 6 23:37:20.232478 kernel: ima: No architecture policies found Nov 6 23:37:20.232486 kernel: clk: Disabling unused clocks Nov 6 23:37:20.232495 kernel: Freeing unused kernel image (initmem) memory: 43520K Nov 6 23:37:20.232503 kernel: Write protecting the kernel read-only data: 38912k Nov 6 23:37:20.232512 kernel: Freeing unused kernel image (rodata/data gap) memory: 1704K Nov 6 23:37:20.232521 kernel: Run /init as init process Nov 6 23:37:20.232531 kernel: with arguments: Nov 6 23:37:20.232540 kernel: /init Nov 6 23:37:20.232548 kernel: with environment: Nov 6 23:37:20.232556 kernel: HOME=/ Nov 6 23:37:20.232565 kernel: TERM=linux Nov 6 23:37:20.232574 systemd[1]: Successfully made /usr/ read-only. Nov 6 23:37:20.232586 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 23:37:20.232598 systemd[1]: Detected virtualization microsoft. Nov 6 23:37:20.232607 systemd[1]: Detected architecture x86-64. Nov 6 23:37:20.232616 systemd[1]: Running in initrd. Nov 6 23:37:20.232625 systemd[1]: No hostname configured, using default hostname. Nov 6 23:37:20.232634 systemd[1]: Hostname set to . Nov 6 23:37:20.232643 systemd[1]: Initializing machine ID from random generator. Nov 6 23:37:20.232652 systemd[1]: Queued start job for default target initrd.target. Nov 6 23:37:20.232661 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:37:20.232670 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:37:20.232682 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 6 23:37:20.232691 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 23:37:20.232700 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 6 23:37:20.232710 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 6 23:37:20.232720 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 6 23:37:20.232730 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 6 23:37:20.232741 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:37:20.232750 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:37:20.232759 systemd[1]: Reached target paths.target - Path Units. Nov 6 23:37:20.232768 systemd[1]: Reached target slices.target - Slice Units. Nov 6 23:37:20.232778 systemd[1]: Reached target swap.target - Swaps. Nov 6 23:37:20.232787 systemd[1]: Reached target timers.target - Timer Units. Nov 6 23:37:20.232796 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 23:37:20.232805 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 23:37:20.232814 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 6 23:37:20.232826 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 6 23:37:20.232835 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:37:20.232844 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 23:37:20.232853 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:37:20.232862 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 23:37:20.232871 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 6 23:37:20.232880 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 23:37:20.232889 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 6 23:37:20.232901 systemd[1]: Starting systemd-fsck-usr.service... Nov 6 23:37:20.232910 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 23:37:20.232919 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 23:37:20.232948 systemd-journald[205]: Collecting audit messages is disabled. Nov 6 23:37:20.232973 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:37:20.232983 systemd-journald[205]: Journal started Nov 6 23:37:20.233019 systemd-journald[205]: Runtime Journal (/run/log/journal/08d00b1c83844b1db768a501083fc94a) is 8M, max 2.2G, 2.1G free. Nov 6 23:37:20.241007 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 23:37:20.244549 systemd-modules-load[207]: Inserted module 'overlay' Nov 6 23:37:20.244975 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 6 23:37:20.245788 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:37:20.246524 systemd[1]: Finished systemd-fsck-usr.service. Nov 6 23:37:20.248949 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 23:37:20.249941 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 23:37:20.292363 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:37:20.297690 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:37:20.309101 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:37:20.324603 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 23:37:20.325026 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:37:20.350125 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:37:20.355008 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 6 23:37:20.367466 systemd-modules-load[207]: Inserted module 'br_netfilter' Nov 6 23:37:20.368004 kernel: Bridge firewalling registered Nov 6 23:37:20.368390 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 23:37:20.369946 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 23:37:20.393416 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:37:20.394872 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 23:37:20.403580 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:37:20.414331 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 6 23:37:20.433407 dracut-cmdline[243]: dracut-dracut-053 Nov 6 23:37:20.438898 dracut-cmdline[243]: Using kernel command line parameters: rd.driver.pre=btrfs 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=1a4810aa24298684dd9efd264f1d9b812e4e16f32429f4615db9ff284dd4ac25 Nov 6 23:37:20.447403 systemd-resolved[241]: Positive Trust Anchors: Nov 6 23:37:20.447411 systemd-resolved[241]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 23:37:20.447441 systemd-resolved[241]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 6 23:37:20.449941 systemd-resolved[241]: Defaulting to hostname 'linux'. Nov 6 23:37:20.451010 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 23:37:20.471682 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:37:20.608050 kernel: SCSI subsystem initialized Nov 6 23:37:20.620010 kernel: Loading iSCSI transport class v2.0-870. Nov 6 23:37:20.632010 kernel: iscsi: registered transport (tcp) Nov 6 23:37:20.655222 kernel: iscsi: registered transport (qla4xxx) Nov 6 23:37:20.655249 kernel: QLogic iSCSI HBA Driver Nov 6 23:37:20.695509 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 6 23:37:20.709186 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 6 23:37:20.739055 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 6 23:37:20.739102 kernel: device-mapper: uevent: version 1.0.3 Nov 6 23:37:20.743886 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Nov 6 23:37:20.786015 kernel: raid6: avx2x4 gen() 31095 MB/s Nov 6 23:37:20.806012 kernel: raid6: avx2x2 gen() 30559 MB/s Nov 6 23:37:20.827272 kernel: raid6: avx2x1 gen() 27386 MB/s Nov 6 23:37:20.827309 kernel: raid6: using algorithm avx2x4 gen() 31095 MB/s Nov 6 23:37:20.849257 kernel: raid6: .... xor() 10230 MB/s, rmw enabled Nov 6 23:37:20.849290 kernel: raid6: using avx2x2 recovery algorithm Nov 6 23:37:20.876013 kernel: xor: automatically using best checksumming function avx Nov 6 23:37:21.036021 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 6 23:37:21.046937 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 6 23:37:21.064116 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:37:21.086875 systemd-udevd[426]: Using default interface naming scheme 'v255'. Nov 6 23:37:21.092407 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:37:21.109128 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 6 23:37:21.125401 dracut-pre-trigger[432]: rd.md=0: removing MD RAID activation Nov 6 23:37:21.158630 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 23:37:21.181162 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 23:37:21.251245 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:37:21.268190 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 6 23:37:21.278324 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 6 23:37:21.323701 kernel: cryptd: max_cpu_qlen set to 1000 Nov 6 23:37:21.280129 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 23:37:21.280428 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:37:21.281177 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 23:37:21.285148 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 6 23:37:21.298591 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 6 23:37:21.312552 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 23:37:21.312723 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:37:21.323829 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:37:21.328521 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:37:21.328745 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:37:21.389107 kernel: hv_vmbus: Vmbus version:4.0 Nov 6 23:37:21.338811 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:37:21.389355 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:37:21.408596 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 6 23:37:21.408630 kernel: AVX2 version of gcm_enc/dec engaged. Nov 6 23:37:21.409002 kernel: AES CTR mode by8 optimization enabled Nov 6 23:37:21.413454 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 6 23:37:21.413485 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 6 23:37:21.413498 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Nov 6 23:37:21.419153 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 6 23:37:21.435011 kernel: hv_vmbus: registering driver hv_netvsc Nov 6 23:37:21.440004 kernel: PTP clock support registered Nov 6 23:37:21.440037 kernel: hv_vmbus: registering driver hv_storvsc Nov 6 23:37:21.441967 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:37:21.458012 kernel: scsi host1: storvsc_host_t Nov 6 23:37:21.462017 kernel: hv_vmbus: registering driver hid_hyperv Nov 6 23:37:21.462048 kernel: scsi host0: storvsc_host_t Nov 6 23:37:21.462077 kernel: hv_vmbus: registering driver hv_pci Nov 6 23:37:21.462090 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 6 23:37:21.463007 kernel: hv_utils: Registering HyperV Utility Driver Nov 6 23:37:21.463051 kernel: hv_vmbus: registering driver hv_utils Nov 6 23:37:21.467019 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 6 23:37:21.467110 kernel: hv_utils: Heartbeat IC version 3.0 Nov 6 23:37:21.467127 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 6 23:37:21.467139 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 6 23:37:21.467152 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 6 23:37:21.510143 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 6 23:37:21.510349 kernel: hv_utils: Shutdown IC version 3.2 Nov 6 23:37:21.510364 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 6 23:37:21.510526 kernel: hv_utils: TimeSync IC version 4.0 Nov 6 23:37:22.278907 systemd-resolved[241]: Clock change detected. Flushing caches. Nov 6 23:37:22.289629 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 6 23:37:22.289862 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 6 23:37:22.290080 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 6 23:37:22.299218 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 6 23:37:22.299819 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:37:22.327591 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 Nov 6 23:37:22.327642 kernel: pci 0001:00:00.0: reg 0x10: [mem 0x41000000-0x41ffffff] Nov 6 23:37:22.327666 kernel: pci 0001:00:00.0: reg 0x14: [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 6 23:37:22.327685 kernel: pci 0001:00:00.0: reg 0x1c: [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 6 23:37:22.327703 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 6 23:37:22.337572 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 6 23:37:22.337785 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 6 23:37:22.339952 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 6 23:37:22.351030 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 6 23:37:22.351202 kernel: pci 0001:00:00.0: BAR 1: assigned [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 6 23:37:22.357975 kernel: pci 0001:00:00.0: BAR 3: assigned [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 6 23:37:22.358150 kernel: pci 0001:00:00.0: BAR 0: assigned [mem 0x41000000-0x41ffffff] Nov 6 23:37:22.371596 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 6 23:37:22.371791 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 6 23:37:22.372015 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Nov 6 23:37:22.372178 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 6 23:37:22.376720 kernel: sd 0:0:0:1: [sdb] Write Protect is off Nov 6 23:37:22.376897 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 6 23:37:22.381489 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 6 23:37:22.381701 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 6 23:37:22.381852 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 6 23:37:22.382039 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 6 23:37:22.385909 kernel: sdb: sdb1 Nov 6 23:37:22.390936 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:37:22.390963 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Nov 6 23:37:22.394936 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 6 23:37:22.891949 kernel: BTRFS: device fsid 7e63b391-7474-48b8-9614-cf161680d90d devid 1 transid 36 /dev/sda3 scanned by (udev-worker) (485) Nov 6 23:37:22.904966 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (474) Nov 6 23:37:22.920412 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 6 23:37:22.920570 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 6 23:37:22.942219 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 23:37:22.954517 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 6 23:37:22.969996 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 6 23:37:22.991052 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 6 23:37:23.008942 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:37:24.028941 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 6 23:37:24.029057 disk-uuid[638]: The operation has completed successfully. Nov 6 23:37:26.122027 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 6 23:37:26.122135 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 6 23:37:26.147040 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 6 23:37:26.156446 sh[725]: Success Nov 6 23:37:26.189954 kernel: device-mapper: verity: sha256 using implementation "sha256-avx2" Nov 6 23:37:26.443893 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 6 23:37:26.462439 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 6 23:37:26.464412 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 6 23:37:26.507654 kernel: BTRFS info (device dm-0): first mount of filesystem 7e63b391-7474-48b8-9614-cf161680d90d Nov 6 23:37:26.507688 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 6 23:37:26.512909 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Nov 6 23:37:26.516837 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 6 23:37:26.520161 kernel: BTRFS info (device dm-0): using free space tree Nov 6 23:37:26.779363 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 6 23:37:26.780184 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 6 23:37:26.808064 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 6 23:37:26.816209 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 6 23:37:26.855587 kernel: BTRFS info (device sda6): first mount of filesystem c2193637-3855-459d-ac6d-9b4591136350 Nov 6 23:37:26.855635 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 23:37:26.859050 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:37:26.879941 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:37:26.886943 kernel: BTRFS info (device sda6): last unmount of filesystem c2193637-3855-459d-ac6d-9b4591136350 Nov 6 23:37:26.892760 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 6 23:37:26.905107 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 6 23:37:26.914642 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 23:37:26.916518 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 23:37:26.954313 systemd-networkd[907]: lo: Link UP Nov 6 23:37:26.954324 systemd-networkd[907]: lo: Gained carrier Nov 6 23:37:26.955494 systemd-networkd[907]: Enumeration completed Nov 6 23:37:26.955580 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 23:37:26.955868 systemd-networkd[907]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:37:26.955873 systemd-networkd[907]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:37:26.956662 systemd-networkd[907]: eth0: Link UP Nov 6 23:37:26.957182 systemd-networkd[907]: eth0: Gained carrier Nov 6 23:37:26.957190 systemd-networkd[907]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:37:26.957290 systemd[1]: Reached target network.target - Network. Nov 6 23:37:27.018958 systemd-networkd[907]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 6 23:37:27.612768 ignition[883]: Ignition 2.20.0 Nov 6 23:37:27.612781 ignition[883]: Stage: fetch-offline Nov 6 23:37:27.612822 ignition[883]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:27.616841 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 23:37:27.612832 ignition[883]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:27.640073 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 6 23:37:27.612964 ignition[883]: parsed url from cmdline: "" Nov 6 23:37:27.612969 ignition[883]: no config URL provided Nov 6 23:37:27.612975 ignition[883]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 23:37:27.612986 ignition[883]: no config at "/usr/lib/ignition/user.ign" Nov 6 23:37:27.612992 ignition[883]: failed to fetch config: resource requires networking Nov 6 23:37:27.613164 ignition[883]: Ignition finished successfully Nov 6 23:37:27.658007 ignition[919]: Ignition 2.20.0 Nov 6 23:37:27.658016 ignition[919]: Stage: fetch Nov 6 23:37:27.658271 ignition[919]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:27.658307 ignition[919]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:27.658434 ignition[919]: parsed url from cmdline: "" Nov 6 23:37:27.658445 ignition[919]: no config URL provided Nov 6 23:37:27.658452 ignition[919]: reading system config file "/usr/lib/ignition/user.ign" Nov 6 23:37:27.658465 ignition[919]: no config at "/usr/lib/ignition/user.ign" Nov 6 23:37:27.658494 ignition[919]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 6 23:37:27.780875 ignition[919]: GET result: OK Nov 6 23:37:27.780932 ignition[919]: config has been read from IMDS userdata Nov 6 23:37:27.780946 ignition[919]: parsing config with SHA512: 0d07dc116546be33bf70207e94ad37ad474511520bb6b00c9b135523a96d732c72aaae74cfc82bbe36931c7885497d75d42bd61a700c35f829293f5c781736c4 Nov 6 23:37:27.784288 unknown[919]: fetched base config from "system" Nov 6 23:37:27.784653 ignition[919]: fetch: fetch complete Nov 6 23:37:27.784297 unknown[919]: fetched base config from "system" Nov 6 23:37:27.784658 ignition[919]: fetch: fetch passed Nov 6 23:37:27.784481 unknown[919]: fetched user config from "azure" Nov 6 23:37:27.784703 ignition[919]: Ignition finished successfully Nov 6 23:37:27.794592 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 6 23:37:27.819053 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 6 23:37:27.840211 ignition[928]: Ignition 2.20.0 Nov 6 23:37:27.840223 ignition[928]: Stage: kargs Nov 6 23:37:27.840391 ignition[928]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:27.840405 ignition[928]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:27.840972 ignition[928]: kargs: kargs passed Nov 6 23:37:27.850282 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 6 23:37:27.841020 ignition[928]: Ignition finished successfully Nov 6 23:37:27.870068 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 6 23:37:27.883465 ignition[939]: Ignition 2.20.0 Nov 6 23:37:27.883478 ignition[939]: Stage: disks Nov 6 23:37:27.883645 ignition[939]: no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:27.887155 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 6 23:37:27.883659 ignition[939]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:27.888627 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 6 23:37:27.885117 ignition[939]: disks: disks passed Nov 6 23:37:27.895892 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 6 23:37:27.885255 ignition[939]: Ignition finished successfully Nov 6 23:37:27.909226 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 23:37:27.917670 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 23:37:27.925945 systemd[1]: Reached target basic.target - Basic System. Nov 6 23:37:27.954042 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 6 23:37:28.015510 systemd-fsck[951]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Nov 6 23:37:28.027470 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 6 23:37:28.045055 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 6 23:37:28.158937 kernel: EXT4-fs (sda9): mounted filesystem 2abcf372-764b-46c0-a870-42c779c5f871 r/w with ordered data mode. Quota mode: none. Nov 6 23:37:28.159210 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 6 23:37:28.159971 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 6 23:37:28.206989 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 23:37:28.222777 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 6 23:37:28.230436 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by mount (962) Nov 6 23:37:28.224811 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 6 23:37:28.230531 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 6 23:37:28.251416 kernel: BTRFS info (device sda6): first mount of filesystem c2193637-3855-459d-ac6d-9b4591136350 Nov 6 23:37:28.251438 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 23:37:28.251451 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:37:28.230568 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 23:37:28.272264 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:37:28.269549 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 23:37:28.275907 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 6 23:37:28.295052 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 6 23:37:28.576031 systemd-networkd[907]: eth0: Gained IPv6LL Nov 6 23:37:28.784241 coreos-metadata[964]: Nov 06 23:37:28.784 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 23:37:28.792708 coreos-metadata[964]: Nov 06 23:37:28.792 INFO Fetch successful Nov 6 23:37:28.796654 coreos-metadata[964]: Nov 06 23:37:28.792 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 6 23:37:28.805237 coreos-metadata[964]: Nov 06 23:37:28.800 INFO Fetch successful Nov 6 23:37:28.824718 coreos-metadata[964]: Nov 06 23:37:28.824 INFO wrote hostname ci-4230.2.4-n-4142df7abb to /sysroot/etc/hostname Nov 6 23:37:28.826556 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 23:37:28.842328 initrd-setup-root[992]: cut: /sysroot/etc/passwd: No such file or directory Nov 6 23:37:28.878251 initrd-setup-root[1000]: cut: /sysroot/etc/group: No such file or directory Nov 6 23:37:28.883271 initrd-setup-root[1007]: cut: /sysroot/etc/shadow: No such file or directory Nov 6 23:37:28.895978 initrd-setup-root[1014]: cut: /sysroot/etc/gshadow: No such file or directory Nov 6 23:37:29.775291 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 6 23:37:29.795995 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 6 23:37:29.796778 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 6 23:37:29.815526 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 6 23:37:29.825077 kernel: BTRFS info (device sda6): last unmount of filesystem c2193637-3855-459d-ac6d-9b4591136350 Nov 6 23:37:29.838689 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 6 23:37:29.849075 ignition[1085]: INFO : Ignition 2.20.0 Nov 6 23:37:29.849075 ignition[1085]: INFO : Stage: mount Nov 6 23:37:29.855091 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:29.855091 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:29.855091 ignition[1085]: INFO : mount: mount passed Nov 6 23:37:29.855091 ignition[1085]: INFO : Ignition finished successfully Nov 6 23:37:29.871782 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 6 23:37:29.886045 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 6 23:37:29.897291 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 6 23:37:29.919944 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (1097) Nov 6 23:37:29.928749 kernel: BTRFS info (device sda6): first mount of filesystem c2193637-3855-459d-ac6d-9b4591136350 Nov 6 23:37:29.928782 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 6 23:37:29.931860 kernel: BTRFS info (device sda6): using free space tree Nov 6 23:37:29.938936 kernel: BTRFS info (device sda6): auto enabling async discard Nov 6 23:37:29.940676 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 6 23:37:29.964427 ignition[1114]: INFO : Ignition 2.20.0 Nov 6 23:37:29.964427 ignition[1114]: INFO : Stage: files Nov 6 23:37:29.970962 ignition[1114]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:29.970962 ignition[1114]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:29.970962 ignition[1114]: DEBUG : files: compiled without relabeling support, skipping Nov 6 23:37:29.985973 ignition[1114]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 6 23:37:29.985973 ignition[1114]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 6 23:37:30.050623 ignition[1114]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 6 23:37:30.059048 ignition[1114]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 6 23:37:30.059048 ignition[1114]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 6 23:37:30.051591 unknown[1114]: wrote ssh authorized keys file for user: core Nov 6 23:37:30.078704 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 23:37:30.086391 ignition[1114]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 6 23:37:30.086391 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 6 23:37:30.086391 ignition[1114]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 6 23:37:30.086391 ignition[1114]: INFO : files: files passed Nov 6 23:37:30.086391 ignition[1114]: INFO : Ignition finished successfully Nov 6 23:37:30.087738 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 6 23:37:30.120053 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 6 23:37:30.124273 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 6 23:37:30.132541 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 6 23:37:30.132630 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 6 23:37:30.154387 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 23:37:30.165349 initrd-setup-root-after-ignition[1146]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:37:30.165349 initrd-setup-root-after-ignition[1146]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:37:30.160584 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 6 23:37:30.187004 initrd-setup-root-after-ignition[1150]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 6 23:37:30.186062 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 6 23:37:30.211373 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 6 23:37:30.211471 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 6 23:37:30.216917 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 6 23:37:30.220549 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 6 23:37:30.229875 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 6 23:37:30.230658 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 6 23:37:30.264962 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 23:37:30.266260 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 6 23:37:30.284231 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:37:30.289270 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:37:30.293857 systemd[1]: Stopped target timers.target - Timer Units. Nov 6 23:37:30.306950 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 6 23:37:30.307068 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 6 23:37:30.319147 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 6 23:37:30.323899 systemd[1]: Stopped target basic.target - Basic System. Nov 6 23:37:30.331898 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 6 23:37:30.338950 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 6 23:37:30.352816 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 6 23:37:30.362250 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 6 23:37:30.367697 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 6 23:37:30.376046 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 6 23:37:30.385792 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 6 23:37:30.393805 systemd[1]: Stopped target swap.target - Swaps. Nov 6 23:37:30.401602 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 6 23:37:30.401730 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 6 23:37:30.416065 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:37:30.420268 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:37:30.428725 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 6 23:37:30.428830 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:37:30.438277 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 6 23:37:30.438420 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 6 23:37:30.456997 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 6 23:37:30.457130 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 6 23:37:30.460841 systemd[1]: ignition-files.service: Deactivated successfully. Nov 6 23:37:30.460972 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 6 23:37:30.470791 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 6 23:37:30.470909 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 6 23:37:30.503066 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 6 23:37:30.507495 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 6 23:37:30.518303 ignition[1171]: INFO : Ignition 2.20.0 Nov 6 23:37:30.518303 ignition[1171]: INFO : Stage: umount Nov 6 23:37:30.518303 ignition[1171]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 6 23:37:30.518303 ignition[1171]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 6 23:37:30.521745 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 6 23:37:30.554289 ignition[1171]: INFO : umount: umount passed Nov 6 23:37:30.554289 ignition[1171]: INFO : Ignition finished successfully Nov 6 23:37:30.521894 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:37:30.524932 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 6 23:37:30.525050 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 6 23:37:30.542713 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 6 23:37:30.542811 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 6 23:37:30.547562 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 6 23:37:30.550619 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 6 23:37:30.550710 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 6 23:37:30.554841 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 6 23:37:30.554906 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 6 23:37:30.562395 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 6 23:37:30.562441 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 6 23:37:30.574034 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 6 23:37:30.574082 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 6 23:37:30.582489 systemd[1]: Stopped target network.target - Network. Nov 6 23:37:30.590094 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 6 23:37:30.590159 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 6 23:37:30.594737 systemd[1]: Stopped target paths.target - Path Units. Nov 6 23:37:30.603220 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 6 23:37:30.613975 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:37:30.614143 systemd[1]: Stopped target slices.target - Slice Units. Nov 6 23:37:30.622886 systemd[1]: Stopped target sockets.target - Socket Units. Nov 6 23:37:30.631181 systemd[1]: iscsid.socket: Deactivated successfully. Nov 6 23:37:30.631234 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 6 23:37:30.638739 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 6 23:37:30.638789 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 6 23:37:30.647121 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 6 23:37:30.647172 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 6 23:37:30.654975 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 6 23:37:30.655021 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 6 23:37:30.662723 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 6 23:37:30.670524 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 6 23:37:30.678499 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 6 23:37:30.678601 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 6 23:37:30.685899 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 6 23:37:30.685994 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 6 23:37:30.702498 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 6 23:37:30.702617 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 6 23:37:30.714202 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 6 23:37:30.714306 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 6 23:37:30.729259 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 6 23:37:30.729308 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:37:30.761009 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 6 23:37:30.763250 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 6 23:37:30.763307 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 6 23:37:30.771062 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 6 23:37:30.771109 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:37:30.784968 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 6 23:37:30.785015 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 6 23:37:30.789334 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 6 23:37:30.789379 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:37:30.805991 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:37:30.815506 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 6 23:37:30.815569 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 6 23:37:30.815612 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 6 23:37:30.815654 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:37:30.850095 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 6 23:37:30.850252 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:37:30.853830 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 6 23:37:30.853968 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 6 23:37:30.867334 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 6 23:37:30.867389 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 6 23:37:30.872342 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 6 23:37:30.872380 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:37:30.880325 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 6 23:37:30.880375 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 6 23:37:30.897600 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 6 23:37:30.897647 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 6 23:37:30.916035 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 6 23:37:30.916084 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 6 23:37:30.954066 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 6 23:37:30.959994 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 6 23:37:30.960049 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:37:30.969543 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 6 23:37:30.969595 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:37:30.978462 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 6 23:37:30.978509 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:37:30.987645 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:37:30.987690 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:37:31.001294 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 6 23:37:31.001354 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:37:31.001741 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 6 23:37:31.001836 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 6 23:37:31.117012 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). Nov 6 23:37:31.004971 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 6 23:37:31.026116 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 6 23:37:31.033078 systemd[1]: Switching root. Nov 6 23:37:31.128142 systemd-journald[205]: Journal stopped Nov 6 23:37:35.185695 kernel: SELinux: policy capability network_peer_controls=1 Nov 6 23:37:35.185729 kernel: SELinux: policy capability open_perms=1 Nov 6 23:37:35.185743 kernel: SELinux: policy capability extended_socket_class=1 Nov 6 23:37:35.185754 kernel: SELinux: policy capability always_check_network=0 Nov 6 23:37:35.185764 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 6 23:37:35.185775 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 6 23:37:35.185787 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 6 23:37:35.185802 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 6 23:37:35.185813 kernel: audit: type=1403 audit(1762472251.790:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 6 23:37:35.185827 systemd[1]: Successfully loaded SELinux policy in 133.407ms. Nov 6 23:37:35.185840 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 15.444ms. Nov 6 23:37:35.185854 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 6 23:37:35.185866 systemd[1]: Detected virtualization microsoft. Nov 6 23:37:35.185882 systemd[1]: Detected architecture x86-64. Nov 6 23:37:35.185900 systemd[1]: Detected first boot. Nov 6 23:37:35.185913 systemd[1]: Hostname set to . Nov 6 23:37:35.185949 systemd[1]: Initializing machine ID from random generator. Nov 6 23:37:35.185964 zram_generator::config[1219]: No configuration found. Nov 6 23:37:35.185977 kernel: Guest personality initialized and is inactive Nov 6 23:37:35.185994 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Nov 6 23:37:35.186013 kernel: Initialized host personality Nov 6 23:37:35.186025 kernel: NET: Registered PF_VSOCK protocol family Nov 6 23:37:35.186036 systemd[1]: Populated /etc with preset unit settings. Nov 6 23:37:35.186050 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 6 23:37:35.186063 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 6 23:37:35.186075 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 6 23:37:35.186087 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 6 23:37:35.186103 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 6 23:37:35.186117 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 6 23:37:35.186130 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 6 23:37:35.186143 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 6 23:37:35.186156 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 6 23:37:35.186168 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 6 23:37:35.186181 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 6 23:37:35.186197 systemd[1]: Created slice user.slice - User and Session Slice. Nov 6 23:37:35.186210 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 6 23:37:35.186222 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 6 23:37:35.186235 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 6 23:37:35.186248 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 6 23:37:35.186260 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 6 23:37:35.186278 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 6 23:37:35.186295 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 6 23:37:35.186308 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 6 23:37:35.186324 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 6 23:37:35.186337 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 6 23:37:35.186350 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 6 23:37:35.186363 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 6 23:37:35.186375 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 6 23:37:35.186388 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 6 23:37:35.186401 systemd[1]: Reached target slices.target - Slice Units. Nov 6 23:37:35.186417 systemd[1]: Reached target swap.target - Swaps. Nov 6 23:37:35.186430 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 6 23:37:35.186443 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 6 23:37:35.186455 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 6 23:37:35.186469 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 6 23:37:35.186485 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 6 23:37:35.186498 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 6 23:37:35.186511 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 6 23:37:35.186524 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 6 23:37:35.186537 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 6 23:37:35.186550 systemd[1]: Mounting media.mount - External Media Directory... Nov 6 23:37:35.186563 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:35.186579 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 6 23:37:35.186596 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 6 23:37:35.186609 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 6 23:37:35.186622 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 6 23:37:35.186635 systemd[1]: Reached target machines.target - Containers. Nov 6 23:37:35.186649 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 6 23:37:35.186662 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:37:35.186675 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 6 23:37:35.186688 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 6 23:37:35.186704 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:37:35.186717 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 23:37:35.186730 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:37:35.186743 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 6 23:37:35.186756 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:37:35.186769 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 6 23:37:35.186782 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 6 23:37:35.186796 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 6 23:37:35.186808 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 6 23:37:35.186825 systemd[1]: Stopped systemd-fsck-usr.service. Nov 6 23:37:35.186838 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:37:35.186851 kernel: fuse: init (API version 7.39) Nov 6 23:37:35.186868 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 6 23:37:35.186880 kernel: loop: module loaded Nov 6 23:37:35.186893 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 6 23:37:35.186906 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 6 23:37:35.186934 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 6 23:37:35.186949 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 6 23:37:35.186962 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 6 23:37:35.186975 systemd[1]: verity-setup.service: Deactivated successfully. Nov 6 23:37:35.186988 systemd[1]: Stopped verity-setup.service. Nov 6 23:37:35.187001 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:35.187014 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 6 23:37:35.187027 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 6 23:37:35.187040 systemd[1]: Mounted media.mount - External Media Directory. Nov 6 23:37:35.187058 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 6 23:37:35.187071 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 6 23:37:35.187084 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 6 23:37:35.187097 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 6 23:37:35.187110 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 6 23:37:35.187123 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 6 23:37:35.187136 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 6 23:37:35.187149 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:37:35.187169 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:37:35.187183 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:37:35.187196 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:37:35.187209 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 6 23:37:35.187222 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 6 23:37:35.187235 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:37:35.187248 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:37:35.187261 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 6 23:37:35.187274 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 6 23:37:35.187313 systemd-journald[1305]: Collecting audit messages is disabled. Nov 6 23:37:35.187341 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 23:37:35.187354 systemd-journald[1305]: Journal started Nov 6 23:37:35.187382 systemd-journald[1305]: Runtime Journal (/run/log/journal/2ec99905d06340089b452fc60836213c) is 8M, max 2.2G, 2.1G free. Nov 6 23:37:34.336120 systemd[1]: Queued start job for default target multi-user.target. Nov 6 23:37:34.348460 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 6 23:37:34.348938 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 6 23:37:35.197937 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 6 23:37:35.203935 systemd[1]: Started systemd-journald.service - Journal Service. Nov 6 23:37:35.205352 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 6 23:37:35.206055 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 6 23:37:35.207982 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 6 23:37:35.216366 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 6 23:37:35.274942 kernel: ACPI: bus type drm_connector registered Nov 6 23:37:35.278600 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 6 23:37:35.284734 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 23:37:35.284949 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 23:37:35.291665 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 6 23:37:35.297457 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 6 23:37:35.304409 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 6 23:37:35.311533 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 6 23:37:35.316443 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 6 23:37:35.316480 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 6 23:37:35.322273 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 6 23:37:35.338099 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 6 23:37:35.338293 systemd-tmpfiles[1337]: ACLs are not supported, ignoring. Nov 6 23:37:35.338314 systemd-tmpfiles[1337]: ACLs are not supported, ignoring. Nov 6 23:37:35.343802 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 6 23:37:35.347907 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:37:35.349402 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 6 23:37:35.355997 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 6 23:37:35.360820 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 23:37:35.361792 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 6 23:37:35.368153 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 6 23:37:35.374128 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Nov 6 23:37:35.379793 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 6 23:37:35.385443 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 6 23:37:35.390388 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 6 23:37:35.400498 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 6 23:37:35.401197 systemd-journald[1305]: Time spent on flushing to /var/log/journal/2ec99905d06340089b452fc60836213c is 13.827ms for 943 entries. Nov 6 23:37:35.401197 systemd-journald[1305]: System Journal (/var/log/journal/2ec99905d06340089b452fc60836213c) is 8M, max 2.6G, 2.6G free. Nov 6 23:37:35.455168 systemd-journald[1305]: Received client request to flush runtime journal. Nov 6 23:37:35.409179 udevadm[1369]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Nov 6 23:37:35.429739 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 6 23:37:35.434701 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 6 23:37:35.450085 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 6 23:37:35.456627 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 6 23:37:35.520570 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 6 23:37:35.521314 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 6 23:37:35.531939 kernel: loop0: detected capacity change from 0 to 28272 Nov 6 23:37:35.606880 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 6 23:37:35.629074 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 6 23:37:35.643801 systemd-tmpfiles[1381]: ACLs are not supported, ignoring. Nov 6 23:37:35.643826 systemd-tmpfiles[1381]: ACLs are not supported, ignoring. Nov 6 23:37:35.648870 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 6 23:37:36.167951 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 6 23:37:36.247946 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 6 23:37:36.267147 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 6 23:37:36.291884 systemd-udevd[1387]: Using default interface naming scheme 'v255'. Nov 6 23:37:36.299940 kernel: loop1: detected capacity change from 0 to 147912 Nov 6 23:37:36.519556 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 6 23:37:36.535255 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 6 23:37:36.556789 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 6 23:37:36.654950 kernel: hv_vmbus: registering driver hv_balloon Nov 6 23:37:36.655036 kernel: mousedev: PS/2 mouse device common for all mice Nov 6 23:37:36.655060 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 6 23:37:36.666958 kernel: hv_vmbus: registering driver hyperv_fb Nov 6 23:37:36.667098 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:37:36.674960 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 6 23:37:36.675008 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 6 23:37:36.682038 kernel: Console: switching to colour dummy device 80x25 Nov 6 23:37:36.683491 kernel: Console: switching to colour frame buffer device 128x48 Nov 6 23:37:36.688955 kernel: EDAC MC: Ver: 3.0.0 Nov 6 23:37:36.691281 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 6 23:37:36.691490 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:37:36.697248 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 6 23:37:36.715121 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 6 23:37:36.720275 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 6 23:37:36.772517 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 6 23:37:36.832010 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1391) Nov 6 23:37:36.839946 kernel: loop2: detected capacity change from 0 to 138176 Nov 6 23:37:36.893686 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 6 23:37:36.903377 systemd-networkd[1403]: lo: Link UP Nov 6 23:37:36.903389 systemd-networkd[1403]: lo: Gained carrier Nov 6 23:37:36.905128 systemd-networkd[1403]: Enumeration completed Nov 6 23:37:36.912058 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 6 23:37:36.912239 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 6 23:37:36.912430 systemd-networkd[1403]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:37:36.912435 systemd-networkd[1403]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:37:36.913048 systemd-networkd[1403]: eth0: Link UP Nov 6 23:37:36.913052 systemd-networkd[1403]: eth0: Gained carrier Nov 6 23:37:36.913066 systemd-networkd[1403]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:37:36.914016 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 6 23:37:36.915331 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 6 23:37:36.973981 systemd-networkd[1403]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 6 23:37:37.002529 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 6 23:37:37.003052 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 6 23:37:37.180696 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 6 23:37:37.338039 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Nov 6 23:37:37.354079 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Nov 6 23:37:37.368938 kernel: loop3: detected capacity change from 0 to 28272 Nov 6 23:37:37.385938 kernel: loop4: detected capacity change from 0 to 147912 Nov 6 23:37:37.406948 kernel: loop5: detected capacity change from 0 to 138176 Nov 6 23:37:37.413538 lvm[1527]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 6 23:37:37.425809 (sd-merge)[1528]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Nov 6 23:37:37.426321 (sd-merge)[1528]: Merged extensions into '/usr'. Nov 6 23:37:37.430714 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 6 23:37:37.446226 systemd[1]: Starting ensure-sysext.service... Nov 6 23:37:37.450129 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 6 23:37:37.459513 systemd[1]: Reload requested from client PID 1530 ('systemctl') (unit ensure-sysext.service)... Nov 6 23:37:37.459533 systemd[1]: Reloading... Nov 6 23:37:37.506088 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 6 23:37:37.506383 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 6 23:37:37.507297 systemd-tmpfiles[1531]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 6 23:37:37.507572 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Nov 6 23:37:37.507647 systemd-tmpfiles[1531]: ACLs are not supported, ignoring. Nov 6 23:37:37.512942 zram_generator::config[1563]: No configuration found. Nov 6 23:37:37.540247 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 23:37:37.540262 systemd-tmpfiles[1531]: Skipping /boot Nov 6 23:37:37.551989 systemd-tmpfiles[1531]: Detected autofs mount point /boot during canonicalization of boot. Nov 6 23:37:37.552003 systemd-tmpfiles[1531]: Skipping /boot Nov 6 23:37:37.648492 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:37:37.784578 systemd[1]: Reloading finished in 324 ms. Nov 6 23:37:37.806980 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Nov 6 23:37:37.830989 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 6 23:37:37.848931 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 6 23:37:37.853723 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:37.869179 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 6 23:37:37.908138 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 6 23:37:37.913246 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:37:37.914421 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Nov 6 23:37:37.920305 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:37:37.925873 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:37:37.931766 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:37:37.936670 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:37:37.936801 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:37:37.938017 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 6 23:37:37.944843 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 6 23:37:37.949671 lvm[1628]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 6 23:37:37.950179 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 6 23:37:37.955075 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:37.957527 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:37:37.957723 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:37:37.963102 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:37:37.963292 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:37:37.968811 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:37:37.969006 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:37:37.978602 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:37.978814 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:37:37.983133 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:37:37.988655 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:37:37.994548 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:37:37.998724 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:37:37.998847 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:37:37.998971 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:38.002210 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:37:38.002396 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:37:38.007567 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:37:38.007757 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:37:38.013439 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Nov 6 23:37:38.019212 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:37:38.019387 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:37:38.027482 augenrules[1660]: No rules Nov 6 23:37:38.029894 systemd[1]: audit-rules.service: Deactivated successfully. Nov 6 23:37:38.030122 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 6 23:37:38.040064 systemd[1]: Finished ensure-sysext.service. Nov 6 23:37:38.043820 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 6 23:37:38.051344 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:38.051603 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 6 23:37:38.067057 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 6 23:37:38.072541 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 6 23:37:38.077783 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 6 23:37:38.083471 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 6 23:37:38.087974 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 6 23:37:38.088021 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 6 23:37:38.088087 systemd[1]: Reached target time-set.target - System Time Set. Nov 6 23:37:38.092470 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 6 23:37:38.093210 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 6 23:37:38.093395 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 6 23:37:38.099121 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 6 23:37:38.099304 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 6 23:37:38.104334 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 6 23:37:38.104509 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 6 23:37:38.107830 systemd-resolved[1639]: Positive Trust Anchors: Nov 6 23:37:38.107849 systemd-resolved[1639]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 6 23:37:38.107880 systemd-resolved[1639]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 6 23:37:38.110853 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 6 23:37:38.111177 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 6 23:37:38.118586 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 6 23:37:38.118653 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 6 23:37:38.149736 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 6 23:37:38.158486 systemd-resolved[1639]: Using system hostname 'ci-4230.2.4-n-4142df7abb'. Nov 6 23:37:38.160390 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 6 23:37:38.165174 systemd[1]: Reached target network.target - Network. Nov 6 23:37:38.168849 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 6 23:37:38.432051 systemd-networkd[1403]: eth0: Gained IPv6LL Nov 6 23:37:38.434345 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 6 23:37:38.440048 systemd[1]: Reached target network-online.target - Network is Online. Nov 6 23:37:38.572170 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 6 23:37:38.577695 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 6 23:37:41.481696 ldconfig[1364]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 23:37:41.527778 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 6 23:37:41.543093 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 6 23:37:41.552110 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 6 23:37:41.557049 systemd[1]: Reached target sysinit.target - System Initialization. Nov 6 23:37:41.561664 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 6 23:37:41.566763 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 6 23:37:41.572399 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 6 23:37:41.576963 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 6 23:37:41.582228 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 6 23:37:41.588636 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 6 23:37:41.588672 systemd[1]: Reached target paths.target - Path Units. Nov 6 23:37:41.592828 systemd[1]: Reached target timers.target - Timer Units. Nov 6 23:37:41.611960 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 6 23:37:41.618046 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 6 23:37:41.624808 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 6 23:37:41.630244 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 6 23:37:41.635556 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 6 23:37:41.642033 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 6 23:37:41.647025 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 6 23:37:41.652902 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 6 23:37:41.657511 systemd[1]: Reached target sockets.target - Socket Units. Nov 6 23:37:41.661573 systemd[1]: Reached target basic.target - Basic System. Nov 6 23:37:41.665496 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 6 23:37:41.665529 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 6 23:37:41.680011 systemd[1]: Starting chronyd.service - NTP client/server... Nov 6 23:37:41.684470 systemd[1]: Starting containerd.service - containerd container runtime... Nov 6 23:37:41.690043 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 6 23:37:41.695146 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 6 23:37:41.702709 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 6 23:37:41.707800 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 6 23:37:41.712131 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 6 23:37:41.712177 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Nov 6 23:37:41.713216 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 6 23:37:41.717584 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 6 23:37:41.718713 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 6 23:37:41.720574 KVP[1697]: KVP starting; pid is:1697 Nov 6 23:37:41.723675 jq[1695]: false Nov 6 23:37:41.723743 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 6 23:37:41.726004 KVP[1697]: KVP LIC Version: 3.1 Nov 6 23:37:41.728946 kernel: hv_utils: KVP IC version 4.0 Nov 6 23:37:41.730114 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 6 23:37:41.735847 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 6 23:37:41.741802 (chronyd)[1688]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Nov 6 23:37:41.742576 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 6 23:37:41.747344 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 6 23:37:41.747589 chronyd[1709]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Nov 6 23:37:41.747862 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 6 23:37:41.748832 systemd[1]: Starting update-engine.service - Update Engine... Nov 6 23:37:41.753776 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 6 23:37:41.760854 jq[1717]: true Nov 6 23:37:41.760647 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 6 23:37:41.760857 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 6 23:37:41.761183 systemd[1]: motdgen.service: Deactivated successfully. Nov 6 23:37:41.761385 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 6 23:37:41.766343 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 6 23:37:41.766578 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 6 23:37:41.767946 extend-filesystems[1696]: Found loop3 Nov 6 23:37:41.767946 extend-filesystems[1696]: Found loop4 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found loop5 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda1 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda2 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda3 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found usr Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda4 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda6 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda7 Nov 6 23:37:41.777638 extend-filesystems[1696]: Found sda9 Nov 6 23:37:41.777638 extend-filesystems[1696]: Checking size of /dev/sda9 Nov 6 23:37:41.828991 extend-filesystems[1696]: Old size kept for /dev/sda9 Nov 6 23:37:41.828991 extend-filesystems[1696]: Found sdb Nov 6 23:37:41.828991 extend-filesystems[1696]: Found sdb1 Nov 6 23:37:41.828991 extend-filesystems[1696]: Found sr0 Nov 6 23:37:41.873126 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (1764) Nov 6 23:37:41.779642 systemd[1]: Started chronyd.service - NTP client/server. Nov 6 23:37:41.778150 chronyd[1709]: Timezone right/UTC failed leap second check, ignoring Nov 6 23:37:41.874396 coreos-metadata[1690]: Nov 06 23:37:41.865 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 6 23:37:41.878482 update_engine[1710]: I20251106 23:37:41.845515 1710 main.cc:92] Flatcar Update Engine starting Nov 6 23:37:41.878482 update_engine[1710]: I20251106 23:37:41.849138 1710 update_check_scheduler.cc:74] Next update check in 4m38s Nov 6 23:37:41.785160 (ntainerd)[1721]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 6 23:37:41.778324 chronyd[1709]: Loaded seccomp filter (level 2) Nov 6 23:37:41.879115 jq[1720]: true Nov 6 23:37:41.879288 coreos-metadata[1690]: Nov 06 23:37:41.874 INFO Fetch successful Nov 6 23:37:41.879288 coreos-metadata[1690]: Nov 06 23:37:41.875 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 6 23:37:41.796721 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 6 23:37:41.796548 dbus-daemon[1691]: [system] SELinux support is enabled Nov 6 23:37:41.821360 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 6 23:37:41.821592 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 6 23:37:41.829940 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 6 23:37:41.829975 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 6 23:37:41.837832 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 6 23:37:41.880445 coreos-metadata[1690]: Nov 06 23:37:41.880 INFO Fetch successful Nov 6 23:37:41.837857 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 6 23:37:41.839684 systemd-logind[1705]: New seat seat0. Nov 6 23:37:41.842787 systemd-logind[1705]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 6 23:37:41.843389 systemd[1]: Started systemd-logind.service - User Login Management. Nov 6 23:37:41.849311 systemd[1]: Started update-engine.service - Update Engine. Nov 6 23:37:41.880858 coreos-metadata[1690]: Nov 06 23:37:41.880 INFO Fetching http://168.63.129.16/machine/caf8b7a2-b4ed-4ac7-92af-f8344e99cb2b/c8ad253b%2D6fb2%2D4d5b%2Db819%2Deca5d68508ad.%5Fci%2D4230.2.4%2Dn%2D4142df7abb?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 6 23:37:41.857665 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 6 23:37:41.882975 bash[1747]: Updated "/home/core/.ssh/authorized_keys" Nov 6 23:37:41.884261 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 6 23:37:41.884816 coreos-metadata[1690]: Nov 06 23:37:41.884 INFO Fetch successful Nov 6 23:37:41.884816 coreos-metadata[1690]: Nov 06 23:37:41.884 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 6 23:37:41.889249 setup-nvidia[1699]: Downloading Flatcar Container Linux Developer Container for version: 4230.2.4+nightly-20251106-2100 Nov 6 23:37:41.893980 coreos-metadata[1690]: Nov 06 23:37:41.893 INFO Fetch successful Nov 6 23:37:41.922224 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 6 23:37:41.943253 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 6 23:37:41.943730 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 6 23:37:41.977475 locksmithd[1765]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 6 23:37:42.029933 sshd_keygen[1711]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 6 23:37:42.051333 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 6 23:37:42.067235 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 6 23:37:42.074057 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 6 23:37:42.078866 systemd[1]: issuegen.service: Deactivated successfully. Nov 6 23:37:42.079124 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 6 23:37:42.087618 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 6 23:37:42.098217 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 6 23:37:42.119985 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 6 23:37:42.139202 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 6 23:37:42.144266 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 6 23:37:42.149236 systemd[1]: Reached target getty.target - Login Prompts. Nov 6 23:37:42.560798 setup-nvidia[1855]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 6 23:37:42.561150 setup-nvidia[1855]: Dload Upload Total Spent Left Speed Nov 6 23:37:42.689397 containerd[1721]: time="2025-11-06T23:37:42.689225800Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Nov 6 23:37:42.708435 containerd[1721]: time="2025-11-06T23:37:42.708391500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710022 containerd[1721]: time="2025-11-06T23:37:42.709981900Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.113-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710022 containerd[1721]: time="2025-11-06T23:37:42.710011700Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Nov 6 23:37:42.710092 containerd[1721]: time="2025-11-06T23:37:42.710028000Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Nov 6 23:37:42.710191 containerd[1721]: time="2025-11-06T23:37:42.710169300Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Nov 6 23:37:42.710257 containerd[1721]: time="2025-11-06T23:37:42.710192700Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710284 containerd[1721]: time="2025-11-06T23:37:42.710255100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710284 containerd[1721]: time="2025-11-06T23:37:42.710268900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710473 containerd[1721]: time="2025-11-06T23:37:42.710450100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710473 containerd[1721]: time="2025-11-06T23:37:42.710471200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710524 containerd[1721]: time="2025-11-06T23:37:42.710485100Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710524 containerd[1721]: time="2025-11-06T23:37:42.710494900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710595 containerd[1721]: time="2025-11-06T23:37:42.710576500Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710817 containerd[1721]: time="2025-11-06T23:37:42.710797900Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Nov 6 23:37:42.710961 containerd[1721]: time="2025-11-06T23:37:42.710939200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Nov 6 23:37:42.711001 containerd[1721]: time="2025-11-06T23:37:42.710959400Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Nov 6 23:37:42.711096 containerd[1721]: time="2025-11-06T23:37:42.711076600Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Nov 6 23:37:42.711154 containerd[1721]: time="2025-11-06T23:37:42.711136600Z" level=info msg="metadata content store policy set" policy=shared Nov 6 23:37:42.748789 containerd[1721]: time="2025-11-06T23:37:42.748752000Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Nov 6 23:37:42.748852 containerd[1721]: time="2025-11-06T23:37:42.748813400Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Nov 6 23:37:42.748852 containerd[1721]: time="2025-11-06T23:37:42.748833600Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Nov 6 23:37:42.748936 containerd[1721]: time="2025-11-06T23:37:42.748851800Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Nov 6 23:37:42.748936 containerd[1721]: time="2025-11-06T23:37:42.748867000Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Nov 6 23:37:42.749044 containerd[1721]: time="2025-11-06T23:37:42.749020200Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Nov 6 23:37:42.749280 containerd[1721]: time="2025-11-06T23:37:42.749251900Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Nov 6 23:37:42.749433 containerd[1721]: time="2025-11-06T23:37:42.749386500Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Nov 6 23:37:42.749433 containerd[1721]: time="2025-11-06T23:37:42.749411600Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Nov 6 23:37:42.749433 containerd[1721]: time="2025-11-06T23:37:42.749427100Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Nov 6 23:37:42.749518 containerd[1721]: time="2025-11-06T23:37:42.749442000Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.749599 containerd[1721]: time="2025-11-06T23:37:42.749564800Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.749736 containerd[1721]: time="2025-11-06T23:37:42.749717300Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.749821 containerd[1721]: time="2025-11-06T23:37:42.749804900Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.749898 containerd[1721]: time="2025-11-06T23:37:42.749882500Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.750017 containerd[1721]: time="2025-11-06T23:37:42.749983300Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.750071 containerd[1721]: time="2025-11-06T23:37:42.750015200Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.750221 containerd[1721]: time="2025-11-06T23:37:42.750172600Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750287800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750318100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750336700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750350800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750364500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750376800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750388800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750401000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750413500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750428400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750440100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750451600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750463300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750476600Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Nov 6 23:37:42.750940 containerd[1721]: time="2025-11-06T23:37:42.750504400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750526200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750571700Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750634700Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750654000Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750664400Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750676500Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750685900Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750698600Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750709500Z" level=info msg="NRI interface is disabled by configuration." Nov 6 23:37:42.751291 containerd[1721]: time="2025-11-06T23:37:42.750718800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Nov 6 23:37:42.751500 containerd[1721]: time="2025-11-06T23:37:42.750999700Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Nov 6 23:37:42.751500 containerd[1721]: time="2025-11-06T23:37:42.751046800Z" level=info msg="Connect containerd service" Nov 6 23:37:42.751500 containerd[1721]: time="2025-11-06T23:37:42.751086800Z" level=info msg="using legacy CRI server" Nov 6 23:37:42.751500 containerd[1721]: time="2025-11-06T23:37:42.751098700Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 6 23:37:42.751500 containerd[1721]: time="2025-11-06T23:37:42.751197500Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Nov 6 23:37:42.751768 containerd[1721]: time="2025-11-06T23:37:42.751740500Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 6 23:37:42.751931 containerd[1721]: time="2025-11-06T23:37:42.751882800Z" level=info msg="Start subscribing containerd event" Nov 6 23:37:42.752029 containerd[1721]: time="2025-11-06T23:37:42.751995900Z" level=info msg="Start recovering state" Nov 6 23:37:42.752138 containerd[1721]: time="2025-11-06T23:37:42.752103100Z" level=info msg="Start event monitor" Nov 6 23:37:42.752138 containerd[1721]: time="2025-11-06T23:37:42.752136700Z" level=info msg="Start snapshots syncer" Nov 6 23:37:42.752207 containerd[1721]: time="2025-11-06T23:37:42.752103200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 6 23:37:42.752207 containerd[1721]: time="2025-11-06T23:37:42.752150000Z" level=info msg="Start cni network conf syncer for default" Nov 6 23:37:42.752258 containerd[1721]: time="2025-11-06T23:37:42.752214000Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 6 23:37:42.752258 containerd[1721]: time="2025-11-06T23:37:42.752213800Z" level=info msg="Start streaming server" Nov 6 23:37:42.752335 containerd[1721]: time="2025-11-06T23:37:42.752315800Z" level=info msg="containerd successfully booted in 0.064064s" Nov 6 23:37:42.752408 systemd[1]: Started containerd.service - containerd container runtime. Nov 6 23:37:43.937729 waagent[1846]: 2025-11-06T23:37:43.937629Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Nov 6 23:37:43.942198 waagent[1846]: 2025-11-06T23:37:43.942144Z INFO Daemon Daemon OS: flatcar 4230.2.4 Nov 6 23:37:43.945811 waagent[1846]: 2025-11-06T23:37:43.945762Z INFO Daemon Daemon Python: 3.11.11 Nov 6 23:37:43.949299 waagent[1846]: 2025-11-06T23:37:43.949227Z INFO Daemon Daemon Run daemon Nov 6 23:37:43.952590 waagent[1846]: 2025-11-06T23:37:43.952538Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.2.4' Nov 6 23:37:43.959218 waagent[1846]: 2025-11-06T23:37:43.959163Z INFO Daemon Daemon Using waagent for provisioning Nov 6 23:37:43.963338 waagent[1846]: 2025-11-06T23:37:43.963292Z INFO Daemon Daemon Activate resource disk Nov 6 23:37:43.967023 waagent[1846]: 2025-11-06T23:37:43.966976Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 6 23:37:43.974829 waagent[1846]: 2025-11-06T23:37:43.974776Z INFO Daemon Daemon Found device: sdb Nov 6 23:37:43.984073 waagent[1846]: 2025-11-06T23:37:43.984012Z INFO Daemon Daemon Examining partition table Nov 6 23:37:44.016908 waagent[1846]: 2025-11-06T23:37:44.016828Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 6 23:37:44.067542 waagent[1846]: 2025-11-06T23:37:44.067462Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 6 23:37:44.072507 waagent[1846]: 2025-11-06T23:37:44.072446Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 6 23:37:44.500947 kernel: sdb: sdb1 Nov 6 23:37:44.525603 waagent[1846]: 2025-11-06T23:37:44.525512Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 6 23:37:44.540462 waagent[1846]: 2025-11-06T23:37:44.540395Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 6 23:37:44.540935 kernel: sdb: sdb1 Nov 6 23:37:50.205138 login[1848]: pam_lastlog(login:session): file /var/log/lastlog is locked/read, retrying Nov 6 23:37:50.207225 login[1849]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 6 23:37:50.219464 systemd-logind[1705]: New session 2 of user core. Nov 6 23:37:50.220592 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 6 23:37:50.230189 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 6 23:37:50.282247 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 6 23:37:50.297166 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 6 23:37:50.313632 waagent[1846]: 2025-11-06T23:37:50.313539Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 6 23:37:50.325122 (systemd)[1925]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 6 23:37:50.327535 systemd-logind[1705]: New session c1 of user core. Nov 6 23:37:50.333754 waagent[1846]: 2025-11-06T23:37:50.333680Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 6 23:37:50.333952 kernel: EXT4-fs (sdb1): mounted filesystem cca4840e-f163-48c8-b96a-952e61f1f6e4 r/w with ordered data mode. Quota mode: none. Nov 6 23:37:50.334529 waagent[1846]: 2025-11-06T23:37:50.334478Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 23:37:50.334761 waagent[1846]: 2025-11-06T23:37:50.334720Z INFO Daemon Daemon Running default provisioning handler Nov 6 23:37:50.372175 waagent[1846]: 2025-11-06T23:37:50.370968Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 6 23:37:50.372909 waagent[1846]: 2025-11-06T23:37:50.372858Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 6 23:37:50.373187 waagent[1846]: 2025-11-06T23:37:50.373146Z INFO Daemon Daemon cloud-init is enabled: False Nov 6 23:37:50.373889 waagent[1846]: 2025-11-06T23:37:50.373770Z INFO Daemon Daemon Copying ovf-env.xml Nov 6 23:37:50.474333 waagent[1846]: 2025-11-06T23:37:50.474202Z INFO Daemon Daemon Successfully mounted dvd Nov 6 23:37:50.499342 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 6 23:37:50.500167 waagent[1846]: 2025-11-06T23:37:50.500105Z INFO Daemon Daemon Detect protocol endpoint Nov 6 23:37:50.500408 waagent[1846]: 2025-11-06T23:37:50.500361Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 6 23:37:50.501875 waagent[1846]: 2025-11-06T23:37:50.501831Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 6 23:37:50.502580 waagent[1846]: 2025-11-06T23:37:50.502542Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 6 23:37:50.504217 waagent[1846]: 2025-11-06T23:37:50.504146Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 6 23:37:50.504804 waagent[1846]: 2025-11-06T23:37:50.504755Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 6 23:37:50.545043 waagent[1846]: 2025-11-06T23:37:50.544981Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 6 23:37:50.545433 waagent[1846]: 2025-11-06T23:37:50.545389Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 6 23:37:50.546762 waagent[1846]: 2025-11-06T23:37:50.546707Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 6 23:37:50.584553 systemd[1925]: Queued start job for default target default.target. Nov 6 23:37:50.596488 systemd[1925]: Created slice app.slice - User Application Slice. Nov 6 23:37:50.596520 systemd[1925]: Reached target paths.target - Paths. Nov 6 23:37:50.596571 systemd[1925]: Reached target timers.target - Timers. Nov 6 23:37:50.597897 systemd[1925]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 23:37:50.608504 systemd[1925]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 23:37:50.608631 systemd[1925]: Reached target sockets.target - Sockets. Nov 6 23:37:50.608689 systemd[1925]: Reached target basic.target - Basic System. Nov 6 23:37:50.608737 systemd[1925]: Reached target default.target - Main User Target. Nov 6 23:37:50.608771 systemd[1925]: Startup finished in 275ms. Nov 6 23:37:50.608847 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 6 23:37:50.610238 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 6 23:37:50.637767 waagent[1846]: 2025-11-06T23:37:50.637638Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 6 23:37:50.637983 waagent[1846]: 2025-11-06T23:37:50.637932Z INFO Daemon Daemon Forcing an update of the goal state. Nov 6 23:37:50.642599 waagent[1846]: 2025-11-06T23:37:50.642546Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 23:37:50.667097 waagent[1846]: 2025-11-06T23:37:50.667048Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 6 23:37:50.667721 waagent[1846]: 2025-11-06T23:37:50.667668Z INFO Daemon Nov 6 23:37:50.668849 waagent[1846]: 2025-11-06T23:37:50.668798Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: d85a98ef-76e4-47ad-88ee-b59d0bf410b2 eTag: 11884770920870045997 source: Fabric] Nov 6 23:37:50.670586 waagent[1846]: 2025-11-06T23:37:50.670541Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 6 23:37:50.672371 waagent[1846]: 2025-11-06T23:37:50.672324Z INFO Daemon Nov 6 23:37:50.672582 waagent[1846]: 2025-11-06T23:37:50.672544Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 6 23:37:50.677685 waagent[1846]: 2025-11-06T23:37:50.677642Z INFO Daemon Daemon Downloading artifacts profile blob Nov 6 23:37:50.806302 waagent[1846]: 2025-11-06T23:37:50.806174Z INFO Daemon Downloaded certificate {'thumbprint': '1CED7A46ADBDF10ABB5C5ED98DF70347DB1C6B62', 'hasPrivateKey': True} Nov 6 23:37:50.806962 waagent[1846]: 2025-11-06T23:37:50.806897Z INFO Daemon Fetch goal state completed Nov 6 23:37:50.846839 waagent[1846]: 2025-11-06T23:37:50.846781Z INFO Daemon Daemon Starting provisioning Nov 6 23:37:50.847063 waagent[1846]: 2025-11-06T23:37:50.847009Z INFO Daemon Daemon Handle ovf-env.xml. Nov 6 23:37:50.848614 waagent[1846]: 2025-11-06T23:37:50.848505Z INFO Daemon Daemon Set hostname [ci-4230.2.4-n-4142df7abb] Nov 6 23:37:50.871804 waagent[1846]: 2025-11-06T23:37:50.871754Z INFO Daemon Daemon Publish hostname [ci-4230.2.4-n-4142df7abb] Nov 6 23:37:50.872168 waagent[1846]: 2025-11-06T23:37:50.872118Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 6 23:37:50.872900 waagent[1846]: 2025-11-06T23:37:50.872857Z INFO Daemon Daemon Primary interface is [eth0] Nov 6 23:37:50.892329 systemd-networkd[1403]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 6 23:37:50.892340 systemd-networkd[1403]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 6 23:37:50.892364 systemd-networkd[1403]: eth0: DHCP lease lost Nov 6 23:37:50.892777 waagent[1846]: 2025-11-06T23:37:50.892722Z INFO Daemon Daemon Create user account if not exists Nov 6 23:37:50.897217 waagent[1846]: 2025-11-06T23:37:50.897166Z INFO Daemon Daemon User core already exists, skip useradd Nov 6 23:37:50.897430 waagent[1846]: 2025-11-06T23:37:50.897382Z INFO Daemon Daemon Configure sudoer Nov 6 23:37:50.899110 waagent[1846]: 2025-11-06T23:37:50.899059Z INFO Daemon Daemon Configure sshd Nov 6 23:37:50.899769 waagent[1846]: 2025-11-06T23:37:50.899722Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 6 23:37:50.901068 waagent[1846]: 2025-11-06T23:37:50.901007Z INFO Daemon Daemon Deploy ssh public key. Nov 6 23:37:50.948965 systemd-networkd[1403]: eth0: DHCPv4 address 10.200.8.10/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 6 23:37:51.205631 login[1848]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 6 23:37:51.212868 systemd-logind[1705]: New session 1 of user core. Nov 6 23:37:51.229048 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 6 23:37:52.032613 waagent[1846]: 2025-11-06T23:37:52.032543Z INFO Daemon Daemon Provisioning complete Nov 6 23:37:52.041211 waagent[1846]: 2025-11-06T23:37:52.041159Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 6 23:37:52.041507 waagent[1846]: 2025-11-06T23:37:52.041459Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 6 23:37:52.043002 waagent[1846]: 2025-11-06T23:37:52.042955Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Nov 6 23:37:52.208149 waagent[1982]: 2025-11-06T23:37:52.208064Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Nov 6 23:37:52.208477 waagent[1982]: 2025-11-06T23:37:52.208262Z INFO ExtHandler ExtHandler OS: flatcar 4230.2.4 Nov 6 23:37:52.208477 waagent[1982]: 2025-11-06T23:37:52.208343Z INFO ExtHandler ExtHandler Python: 3.11.11 Nov 6 23:37:52.244562 waagent[1982]: 2025-11-06T23:37:52.244506Z INFO ExtHandler ExtHandler Distro: flatcar-4230.2.4; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Nov 6 23:37:52.244743 waagent[1982]: 2025-11-06T23:37:52.244699Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:37:52.244828 waagent[1982]: 2025-11-06T23:37:52.244788Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:37:52.252163 waagent[1982]: 2025-11-06T23:37:52.252100Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 6 23:37:52.257158 waagent[1982]: 2025-11-06T23:37:52.257110Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 6 23:37:52.257621 waagent[1982]: 2025-11-06T23:37:52.257575Z INFO ExtHandler Nov 6 23:37:52.257708 waagent[1982]: 2025-11-06T23:37:52.257670Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 00dd3aa4-6b25-4fa8-bf35-e6ac7073b151 eTag: 11884770920870045997 source: Fabric] Nov 6 23:37:52.258063 waagent[1982]: 2025-11-06T23:37:52.258017Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 6 23:37:52.258732 waagent[1982]: 2025-11-06T23:37:52.258682Z INFO ExtHandler Nov 6 23:37:52.258812 waagent[1982]: 2025-11-06T23:37:52.258775Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 6 23:37:52.262493 waagent[1982]: 2025-11-06T23:37:52.262448Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 6 23:37:52.323338 waagent[1982]: 2025-11-06T23:37:52.323170Z INFO ExtHandler Downloaded certificate {'thumbprint': '1CED7A46ADBDF10ABB5C5ED98DF70347DB1C6B62', 'hasPrivateKey': True} Nov 6 23:37:52.323814 waagent[1982]: 2025-11-06T23:37:52.323765Z INFO ExtHandler Fetch goal state completed Nov 6 23:37:52.334896 waagent[1982]: 2025-11-06T23:37:52.334836Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1982 Nov 6 23:37:52.335056 waagent[1982]: 2025-11-06T23:37:52.335010Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 6 23:37:52.336829 waagent[1982]: 2025-11-06T23:37:52.336781Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.2.4', '', 'Flatcar Container Linux by Kinvolk'] Nov 6 23:37:52.337222 waagent[1982]: 2025-11-06T23:37:52.337176Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 6 23:37:52.369806 waagent[1982]: 2025-11-06T23:37:52.369764Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 6 23:37:52.370005 waagent[1982]: 2025-11-06T23:37:52.369960Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 6 23:37:52.376375 waagent[1982]: 2025-11-06T23:37:52.376336Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 6 23:37:52.383786 systemd[1]: Reload requested from client PID 1995 ('systemctl') (unit waagent.service)... Nov 6 23:37:52.383803 systemd[1]: Reloading... Nov 6 23:37:52.452984 zram_generator::config[2035]: No configuration found. Nov 6 23:37:52.583681 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:37:52.729374 systemd[1]: Reloading finished in 345 ms. Nov 6 23:37:52.743580 waagent[1982]: 2025-11-06T23:37:52.743493Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Nov 6 23:37:52.750593 systemd[1]: Reload requested from client PID 2092 ('systemctl') (unit waagent.service)... Nov 6 23:37:52.750609 systemd[1]: Reloading... Nov 6 23:37:52.813951 zram_generator::config[2132]: No configuration found. Nov 6 23:37:52.945275 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Nov 6 23:37:53.093639 systemd[1]: Reloading finished in 342 ms. Nov 6 23:37:53.111599 waagent[1982]: 2025-11-06T23:37:53.111509Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 6 23:37:53.111736 waagent[1982]: 2025-11-06T23:37:53.111690Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 6 23:37:53.878193 waagent[1982]: 2025-11-06T23:37:53.878095Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 6 23:37:53.878737 waagent[1982]: 2025-11-06T23:37:53.878679Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Nov 6 23:37:53.879836 waagent[1982]: 2025-11-06T23:37:53.879513Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 6 23:37:53.880464 waagent[1982]: 2025-11-06T23:37:53.880409Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 6 23:37:53.881111 waagent[1982]: 2025-11-06T23:37:53.881043Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 6 23:37:53.881171 waagent[1982]: 2025-11-06T23:37:53.881115Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:37:53.881284 waagent[1982]: 2025-11-06T23:37:53.881234Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 6 23:37:53.881516 waagent[1982]: 2025-11-06T23:37:53.881471Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:37:53.881648 waagent[1982]: 2025-11-06T23:37:53.881596Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 6 23:37:53.881779 waagent[1982]: 2025-11-06T23:37:53.881736Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 6 23:37:53.882099 waagent[1982]: 2025-11-06T23:37:53.882043Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 6 23:37:53.882474 waagent[1982]: 2025-11-06T23:37:53.882423Z INFO EnvHandler ExtHandler Configure routes Nov 6 23:37:53.882740 waagent[1982]: 2025-11-06T23:37:53.882675Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 6 23:37:53.882812 waagent[1982]: 2025-11-06T23:37:53.882763Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 6 23:37:53.883470 waagent[1982]: 2025-11-06T23:37:53.883428Z INFO EnvHandler ExtHandler Gateway:None Nov 6 23:37:53.883614 waagent[1982]: 2025-11-06T23:37:53.883566Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 6 23:37:53.883726 waagent[1982]: 2025-11-06T23:37:53.883677Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 6 23:37:53.883726 waagent[1982]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 6 23:37:53.883726 waagent[1982]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Nov 6 23:37:53.883726 waagent[1982]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 6 23:37:53.883726 waagent[1982]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:37:53.883726 waagent[1982]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:37:53.883726 waagent[1982]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 6 23:37:53.884327 waagent[1982]: 2025-11-06T23:37:53.884279Z INFO EnvHandler ExtHandler Routes:None Nov 6 23:37:53.888856 waagent[1982]: 2025-11-06T23:37:53.888806Z INFO ExtHandler ExtHandler Nov 6 23:37:53.889414 waagent[1982]: 2025-11-06T23:37:53.889354Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 7e5485f2-3540-4c90-9dc4-d87d88b5543a correlation e92f1151-5260-4811-a2b5-a568ee62b293 created: 2025-11-06T23:36:41.000282Z] Nov 6 23:37:53.890821 waagent[1982]: 2025-11-06T23:37:53.890764Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 6 23:37:53.893063 waagent[1982]: 2025-11-06T23:37:53.893010Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 4 ms] Nov 6 23:37:53.976786 waagent[1982]: 2025-11-06T23:37:53.976728Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 3C280004-09C6-4F42-A343-C8C121F8B3DF;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Nov 6 23:37:54.024634 waagent[1982]: 2025-11-06T23:37:54.024570Z INFO MonitorHandler ExtHandler Network interfaces: Nov 6 23:37:54.024634 waagent[1982]: Executing ['ip', '-a', '-o', 'link']: Nov 6 23:37:54.024634 waagent[1982]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 6 23:37:54.024634 waagent[1982]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:41:6d:4f brd ff:ff:ff:ff:ff:ff Nov 6 23:37:54.024634 waagent[1982]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 6 23:37:54.024634 waagent[1982]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 6 23:37:54.024634 waagent[1982]: 2: eth0 inet 10.200.8.10/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 6 23:37:54.024634 waagent[1982]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 6 23:37:54.024634 waagent[1982]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 6 23:37:54.024634 waagent[1982]: 2: eth0 inet6 fe80::7eed:8dff:fe41:6d4f/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 6 23:37:54.242390 waagent[1982]: 2025-11-06T23:37:54.242253Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Nov 6 23:37:54.242390 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:37:54.242390 waagent[1982]: pkts bytes target prot opt in out source destination Nov 6 23:37:54.242390 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:37:54.242390 waagent[1982]: pkts bytes target prot opt in out source destination Nov 6 23:37:54.242390 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:37:54.242390 waagent[1982]: pkts bytes target prot opt in out source destination Nov 6 23:37:54.242390 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 23:37:54.242390 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 23:37:54.242390 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 23:37:54.245963 waagent[1982]: 2025-11-06T23:37:54.245883Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 6 23:37:54.245963 waagent[1982]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:37:54.245963 waagent[1982]: pkts bytes target prot opt in out source destination Nov 6 23:37:54.245963 waagent[1982]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:37:54.245963 waagent[1982]: pkts bytes target prot opt in out source destination Nov 6 23:37:54.245963 waagent[1982]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 6 23:37:54.245963 waagent[1982]: pkts bytes target prot opt in out source destination Nov 6 23:37:54.245963 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 6 23:37:54.245963 waagent[1982]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 6 23:37:54.245963 waagent[1982]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 6 23:37:54.246236 waagent[1982]: 2025-11-06T23:37:54.246196Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 6 23:37:54.246484 waagent[1982]: 2025-11-06T23:37:54.246408Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 6 23:37:57.124260 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 6 23:37:57.141158 systemd[1]: Started sshd@0-10.200.8.10:22-10.200.16.10:40578.service - OpenSSH per-connection server daemon (10.200.16.10:40578). Nov 6 23:37:57.953516 sshd[2223]: Accepted publickey for core from 10.200.16.10 port 40578 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:37:57.954868 sshd-session[2223]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:37:57.959095 systemd-logind[1705]: New session 3 of user core. Nov 6 23:37:57.969037 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 6 23:37:58.506577 systemd[1]: Started sshd@1-10.200.8.10:22-10.200.16.10:40580.service - OpenSSH per-connection server daemon (10.200.16.10:40580). Nov 6 23:37:59.138680 sshd[2228]: Accepted publickey for core from 10.200.16.10 port 40580 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:37:59.140025 sshd-session[2228]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:37:59.144468 systemd-logind[1705]: New session 4 of user core. Nov 6 23:37:59.156039 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 6 23:37:59.585737 sshd[2230]: Connection closed by 10.200.16.10 port 40580 Nov 6 23:37:59.586116 sshd-session[2228]: pam_unix(sshd:session): session closed for user core Nov 6 23:37:59.590397 systemd[1]: sshd@1-10.200.8.10:22-10.200.16.10:40580.service: Deactivated successfully. Nov 6 23:37:59.592029 systemd[1]: session-4.scope: Deactivated successfully. Nov 6 23:37:59.592649 systemd-logind[1705]: Session 4 logged out. Waiting for processes to exit. Nov 6 23:37:59.593481 systemd-logind[1705]: Removed session 4. Nov 6 23:37:59.717365 systemd[1]: Started sshd@2-10.200.8.10:22-10.200.16.10:40592.service - OpenSSH per-connection server daemon (10.200.16.10:40592). Nov 6 23:38:00.357153 sshd[2236]: Accepted publickey for core from 10.200.16.10 port 40592 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:00.358520 sshd-session[2236]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:00.363638 systemd-logind[1705]: New session 5 of user core. Nov 6 23:38:00.373054 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 6 23:38:00.808574 sshd[2238]: Connection closed by 10.200.16.10 port 40592 Nov 6 23:38:00.808917 sshd-session[2236]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:00.812166 systemd[1]: sshd@2-10.200.8.10:22-10.200.16.10:40592.service: Deactivated successfully. Nov 6 23:38:00.813802 systemd[1]: session-5.scope: Deactivated successfully. Nov 6 23:38:00.814471 systemd-logind[1705]: Session 5 logged out. Waiting for processes to exit. Nov 6 23:38:00.815301 systemd-logind[1705]: Removed session 5. Nov 6 23:38:05.558544 chronyd[1709]: Selected source PHC0 Nov 6 23:38:07.020754 setup-nvidia[1855]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 534M 0 848k 0 0 645k 0 0:14:08 0:00:01 0:14:07 645k\u000d 3 534M 3 20.4M 0 0 9198k 0 0:00:59 0:00:02 0:00:57 9195k\u000d 8 534M 8 45.9M 0 0 13.5M 0 0:00:39 0:00:03 0:00:36 13.5M\u000d 12 534M 12 68.4M 0 0 15.7M 0 0:00:34 0:00:04 0:00:30 15.7M\u000d 17 534M 17 92.0M 0 0 17.2M 0 0:00:30 0:00:05 0:00:25 18.5M\u000d 21 534M 21 115M 0 0 18.3M 0 0:00:29 0:00:06 0:00:23 22.9M\u000d 25 534M 25 137M 0 0 18.5M 0 0:00:28 0:00:07 0:00:21 22.8M\u000d 30 534M 30 160M 0 0 19.2M 0 0:00:27 0:00:08 0:00:19 23.1M\u000d 33 534M 33 179M 0 0 19.2M 0 0:00:27 0:00:09 0:00:18 22.3M\u000d 37 534M 37 202M 0 0 19.6M 0 0:00:27 0:00:10 0:00:17 22.2M\u000d 42 534M 42 227M 0 0 19.9M 0 0:00:26 0:00:11 0:00:15 21.9M\u000d 45 534M 45 245M 0 0 19.8M 0 0:00:26 0:00:12 0:00:14 21.7M\u000d 50 534M 50 269M 0 0 20.2M 0 0:00:26 0:00:13 0:00:13 21.7M\u000d 54 534M 54 292M 0 0 20.4M 0 0:00:26 0:00:14 0:00:12 22.6M\u000d 59 534M 59 316M 0 0 20.7M 0 0:00:25 0:00:15 0:00:10 22.9M\u000d 63 534M 63 342M 0 0 20.8M 0 0:00:25 0:00:16 0:00:09 23.0M\u000d 68 534M 68 365M 0 0 21.0M 0 0:00:25 0:00:17 0:00:08 23.9M\u000d 72 534M 72 389M 0 0 21.2M 0 0:00:25 0:00:18 0:00:07 23.8M\u000d 76 534M 76 411M 0 0 21.2M 0 0:00:25 0:00:19 0:00:06 23.6M\u000d 81 534M 81 434M 0 0 21.4M 0 0:00:24 0:00:20 0:00:04 23.5M\u000d 86 534M 86 460M 0 0 21.5M 0 0:00:24 0:00:21 0:00:03 23.6M\u000d 90 534M 90 483M 0 0 21.6M 0 0:00:24 0:00:22 0:00:02 23.6M\u000d 94 534M 94 506M 0 0 21.7M 0 0:00:24 0:00:23 0:00:01 23.5M\u000d 99 534M 99 530M 0 0 21.8M 0 0:00:24 0:00:24 --:--:-- 23.8M\u000d100 534M 100 534M 0 0 21.8M 0 0:00:24 0:00:24 --:--:-- 24.0M Nov 6 23:38:10.932209 systemd[1]: Started sshd@3-10.200.8.10:22-10.200.16.10:50758.service - OpenSSH per-connection server daemon (10.200.16.10:50758). Nov 6 23:38:11.802936 sshd[2254]: Accepted publickey for core from 10.200.16.10 port 50758 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:11.801546 sshd-session[2254]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:11.808629 systemd-logind[1705]: New session 6 of user core. Nov 6 23:38:11.817054 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 6 23:38:12.252791 sshd[2256]: Connection closed by 10.200.16.10 port 50758 Nov 6 23:38:12.251790 sshd-session[2254]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:12.255237 systemd[1]: sshd@3-10.200.8.10:22-10.200.16.10:50758.service: Deactivated successfully. Nov 6 23:38:12.256892 systemd[1]: session-6.scope: Deactivated successfully. Nov 6 23:38:12.260094 systemd-logind[1705]: Session 6 logged out. Waiting for processes to exit. Nov 6 23:38:12.261093 systemd-logind[1705]: Removed session 6. Nov 6 23:38:12.377856 systemd[1]: Started sshd@4-10.200.8.10:22-10.200.16.10:50770.service - OpenSSH per-connection server daemon (10.200.16.10:50770). Nov 6 23:38:13.011230 sshd[2262]: Accepted publickey for core from 10.200.16.10 port 50770 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:13.012589 sshd-session[2262]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:13.023764 systemd-logind[1705]: New session 7 of user core. Nov 6 23:38:13.032107 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 6 23:38:13.462048 sshd[2264]: Connection closed by 10.200.16.10 port 50770 Nov 6 23:38:13.463130 sshd-session[2262]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:13.467342 systemd-logind[1705]: Session 7 logged out. Waiting for processes to exit. Nov 6 23:38:13.472947 systemd[1]: sshd@4-10.200.8.10:22-10.200.16.10:50770.service: Deactivated successfully. Nov 6 23:38:13.474626 systemd[1]: session-7.scope: Deactivated successfully. Nov 6 23:38:13.475802 systemd-logind[1705]: Removed session 7. Nov 6 23:38:18.768432 setup-nvidia[1699]: Downloading NVIDIA 535.230.02 Driver Nov 6 23:38:18.772967 setup-nvidia[2270]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 6 23:38:18.773222 setup-nvidia[2270]: Dload Upload Total Spent Left Speed Nov 6 23:38:23.574473 systemd[1]: Started sshd@5-10.200.8.10:22-10.200.16.10:56522.service - OpenSSH per-connection server daemon (10.200.16.10:56522). Nov 6 23:38:24.204734 sshd[2272]: Accepted publickey for core from 10.200.16.10 port 56522 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:24.206110 sshd-session[2272]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:24.210322 systemd-logind[1705]: New session 8 of user core. Nov 6 23:38:24.228055 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 6 23:38:24.655720 sshd[2274]: Connection closed by 10.200.16.10 port 56522 Nov 6 23:38:24.656111 sshd-session[2272]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:24.659211 systemd[1]: sshd@5-10.200.8.10:22-10.200.16.10:56522.service: Deactivated successfully. Nov 6 23:38:24.660807 systemd[1]: session-8.scope: Deactivated successfully. Nov 6 23:38:24.661449 systemd-logind[1705]: Session 8 logged out. Waiting for processes to exit. Nov 6 23:38:24.662248 systemd-logind[1705]: Removed session 8. Nov 6 23:38:24.750800 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 6 23:38:24.767475 systemd[1]: Started sshd@6-10.200.8.10:22-10.200.16.10:56536.service - OpenSSH per-connection server daemon (10.200.16.10:56536). Nov 6 23:38:25.396795 sshd[2280]: Accepted publickey for core from 10.200.16.10 port 56536 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:25.398105 sshd-session[2280]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:25.402297 systemd-logind[1705]: New session 9 of user core. Nov 6 23:38:25.412053 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 6 23:38:25.842440 sshd[2282]: Connection closed by 10.200.16.10 port 56536 Nov 6 23:38:25.842717 sshd-session[2280]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:25.847196 systemd[1]: sshd@6-10.200.8.10:22-10.200.16.10:56536.service: Deactivated successfully. Nov 6 23:38:25.848821 systemd[1]: session-9.scope: Deactivated successfully. Nov 6 23:38:25.849479 systemd-logind[1705]: Session 9 logged out. Waiting for processes to exit. Nov 6 23:38:25.850277 systemd-logind[1705]: Removed session 9. Nov 6 23:38:26.696615 update_engine[1710]: I20251106 23:38:26.696520 1710 update_attempter.cc:509] Updating boot flags... Nov 6 23:38:26.757950 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2301) Nov 6 23:38:26.841950 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2291) Nov 6 23:38:26.903947 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 36 scanned by (udev-worker) (2291) Nov 6 23:38:35.957461 systemd[1]: Started sshd@7-10.200.8.10:22-10.200.16.10:52098.service - OpenSSH per-connection server daemon (10.200.16.10:52098). Nov 6 23:38:36.587987 sshd[2453]: Accepted publickey for core from 10.200.16.10 port 52098 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:36.589258 sshd-session[2453]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:36.593383 systemd-logind[1705]: New session 10 of user core. Nov 6 23:38:36.607040 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 6 23:38:37.036092 sshd[2455]: Connection closed by 10.200.16.10 port 52098 Nov 6 23:38:37.036463 sshd-session[2453]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:37.039553 systemd[1]: sshd@7-10.200.8.10:22-10.200.16.10:52098.service: Deactivated successfully. Nov 6 23:38:37.041144 systemd[1]: session-10.scope: Deactivated successfully. Nov 6 23:38:37.041790 systemd-logind[1705]: Session 10 logged out. Waiting for processes to exit. Nov 6 23:38:37.042703 systemd-logind[1705]: Removed session 10. Nov 6 23:38:37.151402 systemd[1]: Started sshd@8-10.200.8.10:22-10.200.16.10:52110.service - OpenSSH per-connection server daemon (10.200.16.10:52110). Nov 6 23:38:37.778642 sshd[2461]: Accepted publickey for core from 10.200.16.10 port 52110 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:37.780012 sshd-session[2461]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:37.784159 systemd-logind[1705]: New session 11 of user core. Nov 6 23:38:37.799048 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 6 23:38:38.222023 sshd[2463]: Connection closed by 10.200.16.10 port 52110 Nov 6 23:38:38.222427 sshd-session[2461]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:38.225624 systemd[1]: sshd@8-10.200.8.10:22-10.200.16.10:52110.service: Deactivated successfully. Nov 6 23:38:38.227237 systemd[1]: session-11.scope: Deactivated successfully. Nov 6 23:38:38.227879 systemd-logind[1705]: Session 11 logged out. Waiting for processes to exit. Nov 6 23:38:38.228677 systemd-logind[1705]: Removed session 11. Nov 6 23:38:48.335416 systemd[1]: Started sshd@9-10.200.8.10:22-10.200.16.10:33876.service - OpenSSH per-connection server daemon (10.200.16.10:33876). Nov 6 23:38:48.965626 sshd[2469]: Accepted publickey for core from 10.200.16.10 port 33876 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:48.966961 sshd-session[2469]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:48.971271 systemd-logind[1705]: New session 12 of user core. Nov 6 23:38:48.980037 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 6 23:38:49.412317 sshd[2471]: Connection closed by 10.200.16.10 port 33876 Nov 6 23:38:49.412711 sshd-session[2469]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:49.415977 systemd[1]: sshd@9-10.200.8.10:22-10.200.16.10:33876.service: Deactivated successfully. Nov 6 23:38:49.417556 systemd[1]: session-12.scope: Deactivated successfully. Nov 6 23:38:49.418224 systemd-logind[1705]: Session 12 logged out. Waiting for processes to exit. Nov 6 23:38:49.419057 systemd-logind[1705]: Removed session 12. Nov 6 23:38:49.523191 systemd[1]: Started sshd@10-10.200.8.10:22-10.200.16.10:33886.service - OpenSSH per-connection server daemon (10.200.16.10:33886). Nov 6 23:38:50.151234 sshd[2477]: Accepted publickey for core from 10.200.16.10 port 33886 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:38:50.152541 sshd-session[2477]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:38:50.156745 systemd-logind[1705]: New session 13 of user core. Nov 6 23:38:50.174045 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 6 23:38:50.595589 sshd[2479]: Connection closed by 10.200.16.10 port 33886 Nov 6 23:38:50.595898 sshd-session[2477]: pam_unix(sshd:session): session closed for user core Nov 6 23:38:50.599049 systemd[1]: sshd@10-10.200.8.10:22-10.200.16.10:33886.service: Deactivated successfully. Nov 6 23:38:50.600705 systemd[1]: session-13.scope: Deactivated successfully. Nov 6 23:38:50.601430 systemd-logind[1705]: Session 13 logged out. Waiting for processes to exit. Nov 6 23:38:50.602201 systemd-logind[1705]: Removed session 13. Nov 6 23:39:00.711543 systemd[1]: Started sshd@11-10.200.8.10:22-10.200.16.10:56406.service - OpenSSH per-connection server daemon (10.200.16.10:56406). Nov 6 23:39:01.342319 sshd[2485]: Accepted publickey for core from 10.200.16.10 port 56406 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:01.343636 sshd-session[2485]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:01.347788 systemd-logind[1705]: New session 14 of user core. Nov 6 23:39:01.359045 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 6 23:39:01.793231 sshd[2487]: Connection closed by 10.200.16.10 port 56406 Nov 6 23:39:01.793622 sshd-session[2485]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:01.796833 systemd[1]: sshd@11-10.200.8.10:22-10.200.16.10:56406.service: Deactivated successfully. Nov 6 23:39:01.798529 systemd[1]: session-14.scope: Deactivated successfully. Nov 6 23:39:01.799209 systemd-logind[1705]: Session 14 logged out. Waiting for processes to exit. Nov 6 23:39:01.800067 systemd-logind[1705]: Removed session 14. Nov 6 23:39:01.904231 systemd[1]: Started sshd@12-10.200.8.10:22-10.200.16.10:56416.service - OpenSSH per-connection server daemon (10.200.16.10:56416). Nov 6 23:39:02.532000 sshd[2494]: Accepted publickey for core from 10.200.16.10 port 56416 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:02.533307 sshd-session[2494]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:02.537438 systemd-logind[1705]: New session 15 of user core. Nov 6 23:39:02.556047 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 6 23:39:02.982839 sshd[2496]: Connection closed by 10.200.16.10 port 56416 Nov 6 23:39:02.983234 sshd-session[2494]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:02.986367 systemd[1]: sshd@12-10.200.8.10:22-10.200.16.10:56416.service: Deactivated successfully. Nov 6 23:39:02.987980 systemd[1]: session-15.scope: Deactivated successfully. Nov 6 23:39:02.988609 systemd-logind[1705]: Session 15 logged out. Waiting for processes to exit. Nov 6 23:39:02.993805 systemd-logind[1705]: Removed session 15. Nov 6 23:39:13.095845 systemd[1]: Started sshd@13-10.200.8.10:22-10.200.16.10:35330.service - OpenSSH per-connection server daemon (10.200.16.10:35330). Nov 6 23:39:13.731247 sshd[2502]: Accepted publickey for core from 10.200.16.10 port 35330 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:13.732558 sshd-session[2502]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:13.737149 systemd-logind[1705]: New session 16 of user core. Nov 6 23:39:13.752737 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 6 23:39:14.177003 sshd[2504]: Connection closed by 10.200.16.10 port 35330 Nov 6 23:39:14.177409 sshd-session[2502]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:14.182051 systemd[1]: sshd@13-10.200.8.10:22-10.200.16.10:35330.service: Deactivated successfully. Nov 6 23:39:14.183880 systemd[1]: session-16.scope: Deactivated successfully. Nov 6 23:39:14.184691 systemd-logind[1705]: Session 16 logged out. Waiting for processes to exit. Nov 6 23:39:14.185480 systemd-logind[1705]: Removed session 16. Nov 6 23:39:14.288147 systemd[1]: Started sshd@14-10.200.8.10:22-10.200.16.10:35346.service - OpenSSH per-connection server daemon (10.200.16.10:35346). Nov 6 23:39:14.918165 sshd[2510]: Accepted publickey for core from 10.200.16.10 port 35346 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:14.919504 sshd-session[2510]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:14.923972 systemd-logind[1705]: New session 17 of user core. Nov 6 23:39:14.931091 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 6 23:39:15.364340 sshd[2512]: Connection closed by 10.200.16.10 port 35346 Nov 6 23:39:15.364672 sshd-session[2510]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:15.367885 systemd[1]: sshd@14-10.200.8.10:22-10.200.16.10:35346.service: Deactivated successfully. Nov 6 23:39:15.369479 systemd[1]: session-17.scope: Deactivated successfully. Nov 6 23:39:15.370150 systemd-logind[1705]: Session 17 logged out. Waiting for processes to exit. Nov 6 23:39:15.370974 systemd-logind[1705]: Removed session 17. Nov 6 23:39:23.323052 setup-nvidia[2270]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 111k 0 0 103k 0 0:53:30 0:00:01 0:53:29 103k\u000d 1 326M 1 4097k 0 0 1828k 0 0:03:02 0:00:02 0:03:00 1828k\u000d 2 326M 2 8193k 0 0 2555k 0 0:02:10 0:00:03 0:02:07 2554k\u000d 3 326M 3 12.0M 0 0 2771k 0 0:02:00 0:00:04 0:01:56 2771k\u000d 4 326M 4 16.0M 0 0 3052k 0 0:01:49 0:00:05 0:01:44 3091k\u000d 6 326M 6 20.0M 0 0 3251k 0 0:01:42 0:00:06 0:01:36 3894k\u000d 7 326M 7 24.0M 0 0 3406k 0 0:01:38 0:00:07 0:01:31 4116k\u000d 8 326M 8 28.0M 0 0 3506k 0 0:01:35 0:00:08 0:01:27 4119k\u000d 10 326M 10 34.0M 0 0 3747k 0 0:01:29 0:00:09 0:01:20 4641k\u000d 11 326M 11 38.3M 0 0 3905k 0 0:01:25 0:00:10 0:01:15 4878k\u000d 13 326M 13 44.0M 0 0 4016k 0 0:01:23 0:00:11 0:01:12 4994k\u000d 15 326M 15 50.0M 0 0 4155k 0 0:01:20 0:00:12 0:01:08 5213k\u000d 16 326M 16 54.0M 0 0 4224k 0 0:01:19 0:00:13 0:01:06 5416k\u000d 19 326M 19 62.0M 0 0 4468k 0 0:01:14 0:00:14 0:01:00 5834k\u000d 20 326M 20 67.9M 0 0 4615k 0 0:01:12 0:00:15 0:00:57 6040k\u000d 22 326M 22 72.0M 0 0 4558k 0 0:01:13 0:00:16 0:00:57 5785k\u000d 23 326M 23 78.0M 0 0 4620k 0 0:01:12 0:00:17 0:00:55 5776k\u000d 25 326M 25 82.0M 0 0 4641k 0 0:01:11 0:00:18 0:00:53 5734k\u000d 26 326M 26 88.0M 0 0 4676k 0 0:01:11 0:00:19 0:00:52 5259k\u000d 28 326M 28 93.9M 0 0 4793k 0 0:01:09 0:00:20 0:00:49 5332k\u000d 30 326M 30 98.0M 0 0 4728k 0 0:01:10 0:00:21 0:00:49 5275k\u000d 31 326M 31 102M 0 0 4598k 0 0:01:12 0:00:22 0:00:50 4531k\u000d 33 326M 33 108M 0 0 4793k 0 0:01:09 0:00:23 0:00:46 5342k\u000d 34 326M 34 114M 0 0 4814k 0 0:01:09 0:00:24 0:00:45 5351k\u000d 36 326M 36 118M 0 0 4845k 0 0:01:08 0:00:25 0:00:43 5051k\u000d 38 326M 38 124M 0 0 4830k 0 0:01:09 0:00:26 0:00:43 5255k\u000d 38 326M 38 126M 0 0 4631k 0 0:01:12 0:00:27 0:00:45 4777k\u000d 41 326M 41 134M 0 0 4856k 0 0:01:08 0:00:28 0:00:40 5137k\u000d 42 326M 42 140M 0 0 4926k 0 0:01:07 0:00:29 0:00:38 5481k\u000d 45 326M 45 147M 0 0 5010k 0 0:01:06 0:00:30 0:00:36 5837k\u000d 46 326M 46 152M 0 0 4979k 0 0:01:07 0:00:31 0:00:36 5765k\u000d 48 326M 48 157M 0 0 5041k 0 0:01:06 0:00:32 0:00:34 7750k\u000d 49 326M 49 162M 0 0 5000k 0 0:01:06 0:00:33 0:00:33 5826k\u000d 51 326M 51 168M 0 0 5003k 0 0:01:06 0:00:34 0:00:32 5429k\u000d 52 326M 52 172M 0 0 5011k 0 0:01:06 0:00:35 0:00:31 5019k\u000d 54 326M 54 178M 0 0 5020k 0 0:01:06 0:00:36 0:00:30 5276k\u000d 55 326M 55 182M 0 0 5037k 0 0:01:06 0:00:37 0:00:29 5014k\u000d 57 326M 57 188M 0 0 5043k 0 0:01:06 0:00:38 0:00:28 5329k\u000d 59 326M 59 194M 0 0 5049k 0 0:01:06 0:00:39 0:00:27 5370k\u000d 60 326M 60 198M 0 0 5050k 0 0:01:06 0:00:40 0:00:26 5324k\u000d 62 326M 62 204M 0 0 5063k 0 0:01:05 0:00:41 0:00:24 5379k\u000d 63 326M 63 208M 0 0 5071k 0 0:01:05 0:00:42 0:00:23 5318k\u000d 65 326M 65 214M 0 0 5063k 0 0:01:05 0:00:43 0:00:22 5217k\u000d 66 326M 66 218M 0 0 5063k 0 0:01:05 0:00:44 0:00:21 5174k\u000d 68 326M 68 224M 0 0 5068k 0 0:01:05 0:00:45 0:00:20 5209k\u000d 70 326M 70 230M 0 0 5111k 0 0:01:05 0:00:46 0:00:19 5523k\u000d 71 326M 71 234M 0 0 5080k 0 0:01:05 0:00:47 0:00:18 5162k\u000d 73 326M 73 240M 0 0 5085k 0 0:01:05 0:00:48 0:00:17 5271k\u000d 74 326M 74 244M 0 0 5085k 0 0:01:05 0:00:49 0:00:16 5279k\u000d 76 326M 76 250M 0 0 5090k 0 0:01:05 0:00:50 0:00:15 5289k\u000d 77 326M 77 254M 0 0 5091k 0 0:01:05 0:00:51 0:00:14 4903k\u000d 79 326M 79 260M 0 0 5089k 0 0:01:05 0:00:52 0:00:13 5171k\u000d 80 326M 80 264M 0 0 5088k 0 0:01:05 0:00:53 0:00:12 5125k\u000d 82 326M 82 270M 0 0 5088k 0 0:01:05 0:00:54 0:00:11 5121k\u000d 84 326M 84 276M 0 0 5108k 0 0:01:05 0:00:55 0:00:10 5292k\u000d 85 326M 85 280M 0 0 5111k 0 0:01:05 0:00:56 0:00:09 5318k\u000d 87 326M 87 286M 0 0 5118k 0 0:01:05 0:00:57 0:00:08 5425k\u000d 89 326M 89 292M 0 0 5123k 0 0:01:05 0:00:58 0:00:07 5468k\u000d 90 326M 90 296M 0 0 5126k 0 0:01:05 0:00:59 0:00:06 5545k\u000d 92 326M 92 301M 0 0 5148k 0 0:01:04 0:01:00 0:00:04 5610k\u000d 94 326M 94 307M 0 0 5162k 0 0:01:04 0:01:01 0:00:03 5730k\u000d 95 326M 95 312M 0 0 5144k 0 0:01:04 0:01:02 0:00:02 5447k\u000d 97 326M 97 318M 0 0 5154k 0 0:01:04 0:01:03 0:00:01 5540k\u000d 98 326M 98 322M 0 0 5152k 0 0:01:04 0:01:04 --:--:-- 5467k\u000d100 326M 100 326M 0 0 5172k 0 0:01:04 0:01:04 --:--:-- 5494k Nov 6 23:39:23.502073 setup-nvidia[1699]: Extract the NVIDIA Driver Installer 535.230.02 Nov 6 23:39:23.502073 setup-nvidia[1699]: /opt/nvidia/workdir/nvidia-workdir / Nov 6 23:39:23.519932 setup-nvidia[2519]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Nov 6 23:39:23.818277 setup-nvidia[2519]: Verifying archive integrity... OK Nov 6 23:39:24.813850 setup-nvidia[2519]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Nov 6 23:39:25.481076 systemd[1]: Started sshd@15-10.200.8.10:22-10.200.16.10:50012.service - OpenSSH per-connection server daemon (10.200.16.10:50012). Nov 6 23:39:26.109940 sshd[2550]: Accepted publickey for core from 10.200.16.10 port 50012 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:26.111310 sshd-session[2550]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:26.115849 systemd-logind[1705]: New session 18 of user core. Nov 6 23:39:26.127138 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 6 23:39:26.560687 sshd[2552]: Connection closed by 10.200.16.10 port 50012 Nov 6 23:39:26.561104 sshd-session[2550]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:26.572148 systemd[1]: sshd@15-10.200.8.10:22-10.200.16.10:50012.service: Deactivated successfully. Nov 6 23:39:26.574475 systemd[1]: session-18.scope: Deactivated successfully. Nov 6 23:39:26.575186 systemd-logind[1705]: Session 18 logged out. Waiting for processes to exit. Nov 6 23:39:26.576338 systemd-logind[1705]: Removed session 18. Nov 6 23:39:26.675625 systemd[1]: Started sshd@16-10.200.8.10:22-10.200.16.10:50014.service - OpenSSH per-connection server daemon (10.200.16.10:50014). Nov 6 23:39:26.679515 setup-nvidia[2545]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 6 23:39:26.722650 setup-nvidia[1699]: / Nov 6 23:39:26.722650 setup-nvidia[1699]: Spawn system-nspawn container to install the NVIDIA drivers Nov 6 23:39:26.876720 sudo[2563]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4230.2.4+nightly-20251106-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.230.02 Nov 6 23:39:26.878611 sudo[2563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 6 23:39:26.886117 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 6 23:39:26.898189 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 6 23:39:26.910041 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 6 23:39:26.917707 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 6 23:39:26.924304 (systemd)[2566]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 6 23:39:26.926550 systemd-logind[1705]: New session c3 of user root. Nov 6 23:39:27.101141 systemd[2566]: Queued start job for default target default.target. Nov 6 23:39:27.111996 systemd[2566]: Created slice app.slice - User Application Slice. Nov 6 23:39:27.112027 systemd[2566]: Reached target paths.target - Paths. Nov 6 23:39:27.112074 systemd[2566]: Reached target timers.target - Timers. Nov 6 23:39:27.113324 systemd[2566]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 6 23:39:27.123678 systemd[2566]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 6 23:39:27.123737 systemd[2566]: Reached target sockets.target - Sockets. Nov 6 23:39:27.123784 systemd[2566]: Reached target basic.target - Basic System. Nov 6 23:39:27.123831 systemd[2566]: Reached target default.target - Main User Target. Nov 6 23:39:27.123862 systemd[2566]: Startup finished in 191ms. Nov 6 23:39:27.124063 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 6 23:39:27.130126 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 6 23:39:27.170938 kernel: loop6: detected capacity change from 0 to 12644352 Nov 6 23:39:27.214953 kernel: loop6: p9 Nov 6 23:39:27.229406 setup-nvidia[2575]: ░ Spawning container flatcardevelopercontainer-4230.2.4nightly-20251106-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4230.2.4+nightly-20251106-2100.bin. Nov 6 23:39:27.244943 kernel: EXT4-fs (loop6p9): mounted filesystem baa5ab7d-71da-43f0-b92b-08a1a6956bf6 ro without journal. Quota mode: none. Nov 6 23:39:27.307572 sshd[2558]: Accepted publickey for core from 10.200.16.10 port 50014 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:27.308872 sshd-session[2558]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:27.312982 systemd-logind[1705]: New session 19 of user core. Nov 6 23:39:27.324040 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 6 23:39:27.764544 sshd[2581]: Connection closed by 10.200.16.10 port 50014 Nov 6 23:39:27.764935 sshd-session[2558]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:27.768317 systemd[1]: sshd@16-10.200.8.10:22-10.200.16.10:50014.service: Deactivated successfully. Nov 6 23:39:27.770116 systemd[1]: session-19.scope: Deactivated successfully. Nov 6 23:39:27.770830 systemd-logind[1705]: Session 19 logged out. Waiting for processes to exit. Nov 6 23:39:27.771733 systemd-logind[1705]: Removed session 19. Nov 6 23:39:37.897208 systemd[1]: Started sshd@17-10.200.8.10:22-10.200.16.10:59754.service - OpenSSH per-connection server daemon (10.200.16.10:59754). Nov 6 23:39:38.527643 sshd[6034]: Accepted publickey for core from 10.200.16.10 port 59754 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:38.530551 sshd-session[6034]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:38.537259 systemd-logind[1705]: New session 20 of user core. Nov 6 23:39:38.554132 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 6 23:39:38.979552 sshd[6230]: Connection closed by 10.200.16.10 port 59754 Nov 6 23:39:38.981139 sshd-session[6034]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:38.986973 systemd[1]: sshd@17-10.200.8.10:22-10.200.16.10:59754.service: Deactivated successfully. Nov 6 23:39:38.988832 systemd[1]: session-20.scope: Deactivated successfully. Nov 6 23:39:38.990794 systemd-logind[1705]: Session 20 logged out. Waiting for processes to exit. Nov 6 23:39:38.992179 systemd-logind[1705]: Removed session 20. Nov 6 23:39:39.194414 systemd[1]: Started sshd@18-10.200.8.10:22-10.200.16.10:59766.service - OpenSSH per-connection server daemon (10.200.16.10:59766). Nov 6 23:39:39.832235 sshd[6394]: Accepted publickey for core from 10.200.16.10 port 59766 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:39.833660 sshd-session[6394]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:39.840586 systemd-logind[1705]: New session 21 of user core. Nov 6 23:39:39.849129 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 6 23:39:40.279290 sshd[6627]: Connection closed by 10.200.16.10 port 59766 Nov 6 23:39:40.279679 sshd-session[6394]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:40.287402 systemd[1]: sshd@18-10.200.8.10:22-10.200.16.10:59766.service: Deactivated successfully. Nov 6 23:39:40.289460 systemd-logind[1705]: Session 21 logged out. Waiting for processes to exit. Nov 6 23:39:40.290116 systemd[1]: session-21.scope: Deactivated successfully. Nov 6 23:39:40.291312 systemd-logind[1705]: Removed session 21. Nov 6 23:39:50.393189 systemd[1]: Started sshd@19-10.200.8.10:22-10.200.16.10:36742.service - OpenSSH per-connection server daemon (10.200.16.10:36742). Nov 6 23:39:51.033801 sshd[10464]: Accepted publickey for core from 10.200.16.10 port 36742 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:51.035601 sshd-session[10464]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:51.043507 systemd-logind[1705]: New session 22 of user core. Nov 6 23:39:51.052076 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 6 23:39:51.485228 sshd[10600]: Connection closed by 10.200.16.10 port 36742 Nov 6 23:39:51.485864 sshd-session[10464]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:51.489464 systemd[1]: sshd@19-10.200.8.10:22-10.200.16.10:36742.service: Deactivated successfully. Nov 6 23:39:51.494579 systemd[1]: session-22.scope: Deactivated successfully. Nov 6 23:39:51.495711 systemd-logind[1705]: Session 22 logged out. Waiting for processes to exit. Nov 6 23:39:51.496776 systemd-logind[1705]: Removed session 22. Nov 6 23:39:51.616914 systemd[1]: Started sshd@20-10.200.8.10:22-10.200.16.10:36756.service - OpenSSH per-connection server daemon (10.200.16.10:36756). Nov 6 23:39:52.249158 sshd[10775]: Accepted publickey for core from 10.200.16.10 port 36756 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:39:52.250963 sshd-session[10775]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:39:52.257630 systemd-logind[1705]: New session 23 of user core. Nov 6 23:39:52.266067 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 6 23:39:52.698391 sshd[11002]: Connection closed by 10.200.16.10 port 36756 Nov 6 23:39:52.698785 sshd-session[10775]: pam_unix(sshd:session): session closed for user core Nov 6 23:39:52.704035 systemd[1]: sshd@20-10.200.8.10:22-10.200.16.10:36756.service: Deactivated successfully. Nov 6 23:39:52.705907 systemd[1]: session-23.scope: Deactivated successfully. Nov 6 23:39:52.708392 systemd-logind[1705]: Session 23 logged out. Waiting for processes to exit. Nov 6 23:39:52.711969 systemd-logind[1705]: Removed session 23. Nov 6 23:40:02.815821 systemd[1]: Started sshd@21-10.200.8.10:22-10.200.16.10:34842.service - OpenSSH per-connection server daemon (10.200.16.10:34842). Nov 6 23:40:03.463901 sshd-session[12019]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:03.464433 sshd[12019]: Accepted publickey for core from 10.200.16.10 port 34842 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:03.473406 systemd-logind[1705]: New session 24 of user core. Nov 6 23:40:03.484159 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 6 23:40:03.903516 sshd[12026]: Connection closed by 10.200.16.10 port 34842 Nov 6 23:40:03.903953 sshd-session[12019]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:03.907845 systemd[1]: sshd@21-10.200.8.10:22-10.200.16.10:34842.service: Deactivated successfully. Nov 6 23:40:03.909696 systemd[1]: session-24.scope: Deactivated successfully. Nov 6 23:40:03.912100 systemd-logind[1705]: Session 24 logged out. Waiting for processes to exit. Nov 6 23:40:03.913204 systemd-logind[1705]: Removed session 24. Nov 6 23:40:04.044016 systemd[1]: Started sshd@22-10.200.8.10:22-10.200.16.10:34852.service - OpenSSH per-connection server daemon (10.200.16.10:34852). Nov 6 23:40:04.673314 sshd[12050]: Accepted publickey for core from 10.200.16.10 port 34852 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:04.675120 sshd-session[12050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:04.680370 systemd-logind[1705]: New session 25 of user core. Nov 6 23:40:04.686094 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 6 23:40:05.123133 sshd[12059]: Connection closed by 10.200.16.10 port 34852 Nov 6 23:40:05.123821 sshd-session[12050]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:05.127120 systemd-logind[1705]: Session 25 logged out. Waiting for processes to exit. Nov 6 23:40:05.128624 systemd[1]: sshd@22-10.200.8.10:22-10.200.16.10:34852.service: Deactivated successfully. Nov 6 23:40:05.130235 systemd[1]: session-25.scope: Deactivated successfully. Nov 6 23:40:05.132074 systemd-logind[1705]: Removed session 25. Nov 6 23:40:15.244398 systemd[1]: Started sshd@23-10.200.8.10:22-10.200.16.10:42016.service - OpenSSH per-connection server daemon (10.200.16.10:42016). Nov 6 23:40:15.876024 sshd[12400]: Accepted publickey for core from 10.200.16.10 port 42016 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:15.880012 sshd-session[12400]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:15.887166 systemd-logind[1705]: New session 26 of user core. Nov 6 23:40:15.896811 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 6 23:40:16.325638 sshd[12414]: Connection closed by 10.200.16.10 port 42016 Nov 6 23:40:16.327227 sshd-session[12400]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:16.330616 systemd[1]: sshd@23-10.200.8.10:22-10.200.16.10:42016.service: Deactivated successfully. Nov 6 23:40:16.332886 systemd[1]: session-26.scope: Deactivated successfully. Nov 6 23:40:16.333820 systemd-logind[1705]: Session 26 logged out. Waiting for processes to exit. Nov 6 23:40:16.335066 systemd-logind[1705]: Removed session 26. Nov 6 23:40:16.438603 systemd[1]: Started sshd@24-10.200.8.10:22-10.200.16.10:42018.service - OpenSSH per-connection server daemon (10.200.16.10:42018). Nov 6 23:40:17.075427 sshd[12444]: Accepted publickey for core from 10.200.16.10 port 42018 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:17.077032 sshd-session[12444]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:17.082325 systemd-logind[1705]: New session 27 of user core. Nov 6 23:40:17.091114 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 6 23:40:17.523662 sshd[12458]: Connection closed by 10.200.16.10 port 42018 Nov 6 23:40:17.525624 sshd-session[12444]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:17.530654 systemd[1]: sshd@24-10.200.8.10:22-10.200.16.10:42018.service: Deactivated successfully. Nov 6 23:40:17.532887 systemd[1]: session-27.scope: Deactivated successfully. Nov 6 23:40:17.534698 systemd-logind[1705]: Session 27 logged out. Waiting for processes to exit. Nov 6 23:40:17.536019 systemd-logind[1705]: Removed session 27. Nov 6 23:40:27.644263 systemd[1]: Started sshd@25-10.200.8.10:22-10.200.16.10:45300.service - OpenSSH per-connection server daemon (10.200.16.10:45300). Nov 6 23:40:28.286780 sshd[12806]: Accepted publickey for core from 10.200.16.10 port 45300 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:28.288217 sshd-session[12806]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:28.292502 systemd-logind[1705]: New session 28 of user core. Nov 6 23:40:28.302051 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 6 23:40:28.733653 sshd[12835]: Connection closed by 10.200.16.10 port 45300 Nov 6 23:40:28.734117 sshd-session[12806]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:28.737356 systemd[1]: sshd@25-10.200.8.10:22-10.200.16.10:45300.service: Deactivated successfully. Nov 6 23:40:28.738968 systemd[1]: session-28.scope: Deactivated successfully. Nov 6 23:40:28.739596 systemd-logind[1705]: Session 28 logged out. Waiting for processes to exit. Nov 6 23:40:28.740394 systemd-logind[1705]: Removed session 28. Nov 6 23:40:28.845662 systemd[1]: Started sshd@26-10.200.8.10:22-10.200.16.10:45306.service - OpenSSH per-connection server daemon (10.200.16.10:45306). Nov 6 23:40:29.480423 sshd[12841]: Accepted publickey for core from 10.200.16.10 port 45306 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:29.481751 sshd-session[12841]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:29.486022 systemd-logind[1705]: New session 29 of user core. Nov 6 23:40:29.498049 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 6 23:40:29.926100 sshd[12843]: Connection closed by 10.200.16.10 port 45306 Nov 6 23:40:29.926492 sshd-session[12841]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:29.929698 systemd[1]: sshd@26-10.200.8.10:22-10.200.16.10:45306.service: Deactivated successfully. Nov 6 23:40:29.931391 systemd[1]: session-29.scope: Deactivated successfully. Nov 6 23:40:29.932046 systemd-logind[1705]: Session 29 logged out. Waiting for processes to exit. Nov 6 23:40:29.932845 systemd-logind[1705]: Removed session 29. Nov 6 23:40:36.211081 setup-nvidia[2575]: \u000d Nov 6 23:40:36.211081 setup-nvidia[2575]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 6 23:40:36.211081 setup-nvidia[2575]: frequently when this kernel module was built against the wrong or Nov 6 23:40:36.211081 setup-nvidia[2575]: improperly configured kernel sources, with a version of gcc that differs Nov 6 23:40:36.211081 setup-nvidia[2575]: from the one used to build the target kernel, or if another driver, such Nov 6 23:40:36.211081 setup-nvidia[2575]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 6 23:40:36.211081 setup-nvidia[2575]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 6 23:40:36.211081 setup-nvidia[2575]: installed in this system is supported by this NVIDIA Linux graphics Nov 6 23:40:36.211081 setup-nvidia[2575]: driver release. Nov 6 23:40:36.211081 setup-nvidia[2575]: \u000d Nov 6 23:40:36.211081 setup-nvidia[2575]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 6 23:40:36.211081 setup-nvidia[2575]: messages' at the end of the file Nov 6 23:40:36.211081 setup-nvidia[2575]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Nov 6 23:40:36.211081 setup-nvidia[2575]: information. Nov 6 23:40:36.211081 setup-nvidia[2575]: \u000d Nov 6 23:40:36.231352 setup-nvidia[2575]: dmesg: read kernel buffer failed: Operation not permitted Nov 6 23:40:36.254772 setup-nvidia[2575]: \u000d Nov 6 23:40:36.254772 setup-nvidia[2575]: ERROR: Installation has failed. Please see the file Nov 6 23:40:36.254772 setup-nvidia[2575]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Nov 6 23:40:36.254772 setup-nvidia[2575]: details. You may find suggestions on fixing installation problems in Nov 6 23:40:36.254772 setup-nvidia[2575]: the README available on the Linux driver download page at Nov 6 23:40:36.254772 setup-nvidia[2575]: www.nvidia.com. Nov 6 23:40:36.254772 setup-nvidia[2575]: \u000d Nov 6 23:40:36.255191 setup-nvidia[2575]: Last 50 lines of nvidia-installer.log: Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038989rm+0x32: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in RETHUNK build Nov 6 23:40:36.256032 setup-nvidia[2575]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Nov 6 23:40:36.256032 setup-nvidia[2575]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Nov 6 23:40:36.256032 setup-nvidia[2575]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Nov 6 23:40:36.256032 setup-nvidia[2575]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Nov 6 23:40:36.256032 setup-nvidia[2575]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Nov 6 23:40:36.256032 setup-nvidia[2575]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Nov 6 23:40:36.256032 setup-nvidia[2575]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 6 23:40:36.256032 setup-nvidia[2575]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 6 23:40:36.256032 setup-nvidia[2575]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 6 23:40:36.256032 setup-nvidia[2575]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 6 23:40:36.256032 setup-nvidia[2575]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Nov 6 23:40:36.256032 setup-nvidia[2575]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Nov 6 23:40:36.256032 setup-nvidia[2575]: make[2]: Leaving directory '/usr/lib/modules/6.6.113-flatcar/build' Nov 6 23:40:36.256032 setup-nvidia[2575]: make[1]: Leaving directory '/usr/lib/modules/6.6.113-flatcar/source' Nov 6 23:40:36.256032 setup-nvidia[2575]: -> done. Nov 6 23:40:36.256032 setup-nvidia[2575]: -> Kernel module compilation complete. Nov 6 23:40:36.256032 setup-nvidia[2575]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 6 23:40:36.256032 setup-nvidia[2575]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Nov 6 23:40:36.256032 setup-nvidia[2575]: \u000d Nov 6 23:40:36.256032 setup-nvidia[2575]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Nov 6 23:40:36.256032 setup-nvidia[2575]: -> Kernel module load error: Operation not permitted Nov 6 23:40:36.256032 setup-nvidia[2575]: -> Kernel messages: Nov 6 23:40:36.256032 setup-nvidia[2575]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 6 23:40:40.038574 systemd[1]: Started sshd@27-10.200.8.10:22-10.200.16.10:54150.service - OpenSSH per-connection server daemon (10.200.16.10:54150). Nov 6 23:40:40.664741 sshd[13059]: Accepted publickey for core from 10.200.16.10 port 54150 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:40.666115 sshd-session[13059]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:40.670682 systemd-logind[1705]: New session 30 of user core. Nov 6 23:40:40.680103 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 6 23:40:41.114317 sshd[13063]: Connection closed by 10.200.16.10 port 54150 Nov 6 23:40:41.114700 sshd-session[13059]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:41.118005 systemd[1]: sshd@27-10.200.8.10:22-10.200.16.10:54150.service: Deactivated successfully. Nov 6 23:40:41.119678 systemd[1]: session-30.scope: Deactivated successfully. Nov 6 23:40:41.120539 systemd-logind[1705]: Session 30 logged out. Waiting for processes to exit. Nov 6 23:40:41.121530 systemd-logind[1705]: Removed session 30. Nov 6 23:40:41.228588 systemd[1]: Started sshd@28-10.200.8.10:22-10.200.16.10:54164.service - OpenSSH per-connection server daemon (10.200.16.10:54164). Nov 6 23:40:41.858382 sshd[13069]: Accepted publickey for core from 10.200.16.10 port 54164 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:41.859680 sshd-session[13069]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:41.864195 systemd-logind[1705]: New session 31 of user core. Nov 6 23:40:41.877048 systemd[1]: Started session-31.scope - Session 31 of User core. Nov 6 23:40:42.310180 sshd[13071]: Connection closed by 10.200.16.10 port 54164 Nov 6 23:40:42.310647 sshd-session[13069]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:42.313938 systemd[1]: sshd@28-10.200.8.10:22-10.200.16.10:54164.service: Deactivated successfully. Nov 6 23:40:42.315565 systemd[1]: session-31.scope: Deactivated successfully. Nov 6 23:40:42.316237 systemd-logind[1705]: Session 31 logged out. Waiting for processes to exit. Nov 6 23:40:42.317204 systemd-logind[1705]: Removed session 31. Nov 6 23:40:50.177949 kernel: EXT4-fs (loop6p9): unmounting filesystem baa5ab7d-71da-43f0-b92b-08a1a6956bf6. Nov 6 23:40:50.178357 setup-nvidia[2575]: Container flatcardevelopercontainer-4230.2.4nightly-20251106-2100.bin exited successfully. Nov 6 23:40:50.180826 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4230.2.4nightly\x2d20251106\x2d2100.bin.mount: Deactivated successfully. Nov 6 23:40:50.247826 sudo[2563]: pam_unix(sudo:session): session closed for user root Nov 6 23:40:50.249657 systemd[1]: session-c2.scope: Deactivated successfully. Nov 6 23:40:50.249955 systemd[1]: session-c2.scope: Consumed 5min 9.739s CPU time, 882.5M memory peak. Nov 6 23:40:50.662916 setup-nvidia[1699]: /opt/nvidia / Nov 6 23:40:50.663899 setup-nvidia[1699]: / Nov 6 23:40:50.681275 setup-nvidia[13094]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 6 23:40:50.719946 kernel: IPMI message handler: version 39.2 Nov 6 23:40:50.722942 kernel: ipmi device interface Nov 6 23:40:50.724360 setup-nvidia[1699]: /opt/nvidia/current/usr/lib/modules/6.6.113-flatcar/video / Nov 6 23:40:50.780593 kernel: nvidia: loading out-of-tree module taints kernel. Nov 6 23:40:50.780725 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 6 23:40:50.780750 kernel: Disabling lock debugging due to kernel taint Nov 6 23:40:50.787991 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 6 23:40:50.788040 kernel: nvidia: module license taints kernel. Nov 6 23:40:51.186946 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 245 Nov 6 23:40:51.579980 kernel: Nov 6 23:40:51.580119 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 6 23:40:51.787948 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Nov 6 23:40:51.808943 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Nov 6 23:40:51.828964 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 6 23:40:51.875586 setup-nvidia[1699]: / Nov 6 23:40:51.875969 kernel: nvidia-uvm: Loaded the UVM driver, major device number 243. Nov 6 23:40:52.427473 systemd[1]: Started sshd@29-10.200.8.10:22-10.200.16.10:58068.service - OpenSSH per-connection server daemon (10.200.16.10:58068). Nov 6 23:40:53.058654 sshd[13129]: Accepted publickey for core from 10.200.16.10 port 58068 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:53.060161 sshd-session[13129]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:53.064500 systemd-logind[1705]: New session 32 of user core. Nov 6 23:40:53.073052 systemd[1]: Started session-32.scope - Session 32 of User core. Nov 6 23:40:53.506431 sshd[13131]: Connection closed by 10.200.16.10 port 58068 Nov 6 23:40:53.506800 sshd-session[13129]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:53.510066 systemd[1]: sshd@29-10.200.8.10:22-10.200.16.10:58068.service: Deactivated successfully. Nov 6 23:40:53.511668 systemd[1]: session-32.scope: Deactivated successfully. Nov 6 23:40:53.512331 systemd-logind[1705]: Session 32 logged out. Waiting for processes to exit. Nov 6 23:40:53.513139 systemd-logind[1705]: Removed session 32. Nov 6 23:40:53.621269 systemd[1]: Started sshd@30-10.200.8.10:22-10.200.16.10:58072.service - OpenSSH per-connection server daemon (10.200.16.10:58072). Nov 6 23:40:53.649162 setup-nvidia[13124]: Thu Nov 6 23:40:53 2025 Nov 6 23:40:53.649162 setup-nvidia[13124]: +---------------------------------------------------------------------------------------+ Nov 6 23:40:53.649162 setup-nvidia[13124]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Nov 6 23:40:53.649162 setup-nvidia[13124]: |-----------------------------------------+----------------------+----------------------+ Nov 6 23:40:53.649162 setup-nvidia[13124]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 6 23:40:53.649162 setup-nvidia[13124]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 6 23:40:53.649162 setup-nvidia[13124]: | | | MIG M. | Nov 6 23:40:53.649162 setup-nvidia[13124]: |=========================================+======================+======================| Nov 6 23:40:53.652736 setup-nvidia[13124]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 6 23:40:53.652736 setup-nvidia[13124]: | N/A 25C P0 36W / 250W | 0MiB / 16384MiB | 0% Default | Nov 6 23:40:53.652736 setup-nvidia[13124]: | | | N/A | Nov 6 23:40:53.652736 setup-nvidia[13124]: +-----------------------------------------+----------------------+----------------------+ Nov 6 23:40:53.652859 setup-nvidia[13124]: Nov 6 23:40:53.652859 setup-nvidia[13124]: +---------------------------------------------------------------------------------------+ Nov 6 23:40:53.652859 setup-nvidia[13124]: | Processes: | Nov 6 23:40:53.652859 setup-nvidia[13124]: | GPU GI CI PID Type Process name GPU Memory | Nov 6 23:40:53.652859 setup-nvidia[13124]: | ID ID Usage | Nov 6 23:40:53.652859 setup-nvidia[13124]: |=======================================================================================| Nov 6 23:40:53.653011 setup-nvidia[13124]: | No running processes found | Nov 6 23:40:53.653011 setup-nvidia[13124]: +---------------------------------------------------------------------------------------+ Nov 6 23:40:54.023399 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 6 23:40:54.024043 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 6 23:40:54.024428 systemd[1]: Startup finished in 1.104s (firmware) + 15.351s (loader) + 1.845s (kernel) + 11.117s (initrd) + 3min 22.366s (userspace) = 3min 51.786s. Nov 6 23:40:54.250090 sshd[13137]: Accepted publickey for core from 10.200.16.10 port 58072 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:40:54.251546 sshd-session[13137]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:40:54.255997 systemd-logind[1705]: New session 33 of user core. Nov 6 23:40:54.266036 systemd[1]: Started session-33.scope - Session 33 of User core. Nov 6 23:40:54.696554 sshd[13141]: Connection closed by 10.200.16.10 port 58072 Nov 6 23:40:54.696914 sshd-session[13137]: pam_unix(sshd:session): session closed for user core Nov 6 23:40:54.699930 systemd[1]: sshd@30-10.200.8.10:22-10.200.16.10:58072.service: Deactivated successfully. Nov 6 23:40:54.701526 systemd[1]: session-33.scope: Deactivated successfully. Nov 6 23:40:54.702197 systemd-logind[1705]: Session 33 logged out. Waiting for processes to exit. Nov 6 23:40:54.703006 systemd-logind[1705]: Removed session 33. Nov 6 23:41:00.384805 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 6 23:41:00.385634 systemd[2566]: Activating special unit exit.target... Nov 6 23:41:00.385722 systemd[2566]: Stopped target default.target - Main User Target. Nov 6 23:41:00.385764 systemd[2566]: Stopped target basic.target - Basic System. Nov 6 23:41:00.385796 systemd[2566]: Stopped target paths.target - Paths. Nov 6 23:41:00.385825 systemd[2566]: Stopped target sockets.target - Sockets. Nov 6 23:41:00.385853 systemd[2566]: Stopped target timers.target - Timers. Nov 6 23:41:00.386231 systemd[2566]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 6 23:41:00.386442 systemd[2566]: Removed slice app.slice - User Application Slice. Nov 6 23:41:00.386476 systemd[2566]: Reached target shutdown.target - Shutdown. Nov 6 23:41:00.386631 systemd[2566]: Finished systemd-exit.service - Exit the Session. Nov 6 23:41:00.386670 systemd[2566]: Reached target exit.target - Exit the Session. Nov 6 23:41:00.415081 systemd[1]: user@0.service: Deactivated successfully. Nov 6 23:41:00.415435 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 6 23:41:00.431334 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 6 23:41:00.440910 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 6 23:41:00.441801 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 6 23:41:00.442008 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 6 23:41:00.443754 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 6 23:41:00.443837 systemd[1]: user-0.slice: Consumed 5min 9.968s CPU time, 890.2M memory peak. Nov 6 23:41:00.444256 systemd-logind[1705]: Removed session c3. Nov 6 23:41:04.812615 systemd[1]: Started sshd@31-10.200.8.10:22-10.200.16.10:58184.service - OpenSSH per-connection server daemon (10.200.16.10:58184). Nov 6 23:41:05.439899 sshd[13151]: Accepted publickey for core from 10.200.16.10 port 58184 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:41:05.441335 sshd-session[13151]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:41:05.445469 systemd-logind[1705]: New session 34 of user core. Nov 6 23:41:05.457048 systemd[1]: Started session-34.scope - Session 34 of User core. Nov 6 23:41:05.893964 sshd[13153]: Connection closed by 10.200.16.10 port 58184 Nov 6 23:41:05.894351 sshd-session[13151]: pam_unix(sshd:session): session closed for user core Nov 6 23:41:05.897562 systemd[1]: sshd@31-10.200.8.10:22-10.200.16.10:58184.service: Deactivated successfully. Nov 6 23:41:05.899336 systemd[1]: session-34.scope: Deactivated successfully. Nov 6 23:41:05.900086 systemd-logind[1705]: Session 34 logged out. Waiting for processes to exit. Nov 6 23:41:05.900886 systemd-logind[1705]: Removed session 34. Nov 6 23:41:06.009622 systemd[1]: Started sshd@32-10.200.8.10:22-10.200.16.10:58190.service - OpenSSH per-connection server daemon (10.200.16.10:58190). Nov 6 23:41:06.641544 sshd[13159]: Accepted publickey for core from 10.200.16.10 port 58190 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:41:06.642860 sshd-session[13159]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:41:06.647066 systemd-logind[1705]: New session 35 of user core. Nov 6 23:41:06.663054 systemd[1]: Started session-35.scope - Session 35 of User core. Nov 6 23:41:07.086903 sshd[13161]: Connection closed by 10.200.16.10 port 58190 Nov 6 23:41:07.087194 sshd-session[13159]: pam_unix(sshd:session): session closed for user core Nov 6 23:41:07.090560 systemd[1]: sshd@32-10.200.8.10:22-10.200.16.10:58190.service: Deactivated successfully. Nov 6 23:41:07.092344 systemd[1]: session-35.scope: Deactivated successfully. Nov 6 23:41:07.093051 systemd-logind[1705]: Session 35 logged out. Waiting for processes to exit. Nov 6 23:41:07.093863 systemd-logind[1705]: Removed session 35. Nov 6 23:41:07.201477 systemd[1]: Started sshd@33-10.200.8.10:22-10.200.16.10:58206.service - OpenSSH per-connection server daemon (10.200.16.10:58206). Nov 6 23:41:07.829664 sshd[13167]: Accepted publickey for core from 10.200.16.10 port 58206 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:41:07.831071 sshd-session[13167]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:41:07.835312 systemd-logind[1705]: New session 36 of user core. Nov 6 23:41:07.853052 systemd[1]: Started session-36.scope - Session 36 of User core. Nov 6 23:41:08.276352 sshd[13169]: Connection closed by 10.200.16.10 port 58206 Nov 6 23:41:08.276730 sshd-session[13167]: pam_unix(sshd:session): session closed for user core Nov 6 23:41:08.280018 systemd[1]: sshd@33-10.200.8.10:22-10.200.16.10:58206.service: Deactivated successfully. Nov 6 23:41:08.281772 systemd[1]: session-36.scope: Deactivated successfully. Nov 6 23:41:08.282486 systemd-logind[1705]: Session 36 logged out. Waiting for processes to exit. Nov 6 23:41:08.283315 systemd-logind[1705]: Removed session 36. Nov 6 23:41:08.388492 systemd[1]: Started sshd@34-10.200.8.10:22-10.200.16.10:58220.service - OpenSSH per-connection server daemon (10.200.16.10:58220). Nov 6 23:41:09.017523 sshd[13175]: Accepted publickey for core from 10.200.16.10 port 58220 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:41:09.018828 sshd-session[13175]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:41:09.023012 systemd-logind[1705]: New session 37 of user core. Nov 6 23:41:09.036048 systemd[1]: Started session-37.scope - Session 37 of User core. Nov 6 23:41:09.467050 sshd[13177]: Connection closed by 10.200.16.10 port 58220 Nov 6 23:41:09.467426 sshd-session[13175]: pam_unix(sshd:session): session closed for user core Nov 6 23:41:09.470582 systemd[1]: sshd@34-10.200.8.10:22-10.200.16.10:58220.service: Deactivated successfully. Nov 6 23:41:09.472398 systemd[1]: session-37.scope: Deactivated successfully. Nov 6 23:41:09.473102 systemd-logind[1705]: Session 37 logged out. Waiting for processes to exit. Nov 6 23:41:09.473871 systemd-logind[1705]: Removed session 37. Nov 6 23:41:09.581261 systemd[1]: Started sshd@35-10.200.8.10:22-10.200.16.10:58232.service - OpenSSH per-connection server daemon (10.200.16.10:58232). Nov 6 23:41:10.207388 sshd[13183]: Accepted publickey for core from 10.200.16.10 port 58232 ssh2: RSA SHA256:JK2oXiv3IyANHuebuw4n6A1DMGqGVfK/D7tsI1XRXMk Nov 6 23:41:10.208741 sshd-session[13183]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 6 23:41:10.213135 systemd-logind[1705]: New session 38 of user core. Nov 6 23:41:10.222051 systemd[1]: Started session-38.scope - Session 38 of User core. Nov 6 23:41:12.117900 sshd[13185]: Connection closed by 10.200.16.10 port 58232 Nov 6 23:41:12.118356 sshd-session[13183]: pam_unix(sshd:session): session closed for user core Nov 6 23:41:12.121716 systemd[1]: sshd@35-10.200.8.10:22-10.200.16.10:58232.service: Deactivated successfully. Nov 6 23:41:12.123979 systemd[1]: session-38.scope: Deactivated successfully. Nov 6 23:41:12.124223 systemd[1]: session-38.scope: Consumed 1.405s CPU time, 14.1M memory peak. Nov 6 23:41:12.124737 systemd-logind[1705]: Session 38 logged out. Waiting for processes to exit. Nov 6 23:41:12.125612 systemd-logind[1705]: Removed session 38.