Oct 30 00:03:41.209807 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Wed Oct 29 22:07:32 -00 2025 Oct 30 00:03:41.209837 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=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13 Oct 30 00:03:41.209847 kernel: BIOS-provided physical RAM map: Oct 30 00:03:41.209854 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Oct 30 00:03:41.209860 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Oct 30 00:03:41.209867 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Oct 30 00:03:41.209875 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Oct 30 00:03:41.209884 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Oct 30 00:03:41.209891 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Oct 30 00:03:41.209897 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Oct 30 00:03:41.209904 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Oct 30 00:03:41.209911 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Oct 30 00:03:41.209917 kernel: printk: legacy bootconsole [earlyser0] enabled Oct 30 00:03:41.209924 kernel: NX (Execute Disable) protection: active Oct 30 00:03:41.209935 kernel: APIC: Static calls initialized Oct 30 00:03:41.209942 kernel: efi: EFI v2.7 by Microsoft Oct 30 00:03:41.209950 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Oct 30 00:03:41.209957 kernel: random: crng init done Oct 30 00:03:41.209965 kernel: secureboot: Secure boot disabled Oct 30 00:03:41.209972 kernel: SMBIOS 3.1.0 present. Oct 30 00:03:41.209979 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Oct 30 00:03:41.209987 kernel: DMI: Memory slots populated: 3/3 Oct 30 00:03:41.209994 kernel: Hypervisor detected: Microsoft Hyper-V Oct 30 00:03:41.210001 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Oct 30 00:03:41.210011 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Oct 30 00:03:41.210018 kernel: Hyper-V: Using hypercall for remote TLB flush Oct 30 00:03:41.210026 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 30 00:03:41.210033 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Oct 30 00:03:41.210041 kernel: tsc: Marking TSC unstable due to running on Hyper-V Oct 30 00:03:41.210049 kernel: tsc: Detected 2593.990 MHz processor Oct 30 00:03:41.210057 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 30 00:03:41.210065 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 30 00:03:41.210084 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Oct 30 00:03:41.210096 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Oct 30 00:03:41.210103 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 30 00:03:41.210111 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Oct 30 00:03:41.210118 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Oct 30 00:03:41.210126 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Oct 30 00:03:41.210133 kernel: Using GB pages for direct mapping Oct 30 00:03:41.210141 kernel: ACPI: Early table checksum verification disabled Oct 30 00:03:41.210153 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Oct 30 00:03:41.210163 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210171 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210179 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Oct 30 00:03:41.210187 kernel: ACPI: FACS 0x000000003FFFE000 000040 Oct 30 00:03:41.210195 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210203 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210214 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210222 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210230 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210238 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210246 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Oct 30 00:03:41.210254 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Oct 30 00:03:41.210262 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Oct 30 00:03:41.210270 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Oct 30 00:03:41.210278 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Oct 30 00:03:41.210289 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Oct 30 00:03:41.210297 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Oct 30 00:03:41.210305 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Oct 30 00:03:41.210313 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Oct 30 00:03:41.210321 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Oct 30 00:03:41.210329 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Oct 30 00:03:41.210336 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Oct 30 00:03:41.210344 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Oct 30 00:03:41.210355 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Oct 30 00:03:41.210363 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Oct 30 00:03:41.210371 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Oct 30 00:03:41.210379 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Oct 30 00:03:41.210387 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Oct 30 00:03:41.210395 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Oct 30 00:03:41.210403 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Oct 30 00:03:41.210411 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Oct 30 00:03:41.210419 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Oct 30 00:03:41.210429 kernel: Zone ranges: Oct 30 00:03:41.210437 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 30 00:03:41.210445 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Oct 30 00:03:41.210453 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Oct 30 00:03:41.210461 kernel: Device empty Oct 30 00:03:41.210469 kernel: Movable zone start for each node Oct 30 00:03:41.210477 kernel: Early memory node ranges Oct 30 00:03:41.210485 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Oct 30 00:03:41.210493 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Oct 30 00:03:41.210503 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Oct 30 00:03:41.210511 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Oct 30 00:03:41.210518 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Oct 30 00:03:41.210526 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Oct 30 00:03:41.210534 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 30 00:03:41.210542 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Oct 30 00:03:41.210550 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Oct 30 00:03:41.210558 kernel: ACPI: PM-Timer IO Port: 0x408 Oct 30 00:03:41.210566 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Oct 30 00:03:41.210576 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Oct 30 00:03:41.210584 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 30 00:03:41.210592 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 30 00:03:41.210600 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Oct 30 00:03:41.210608 kernel: CPU topo: Max. logical packages: 1 Oct 30 00:03:41.210616 kernel: CPU topo: Max. logical dies: 1 Oct 30 00:03:41.210623 kernel: CPU topo: Max. dies per package: 1 Oct 30 00:03:41.210634 kernel: CPU topo: Max. threads per core: 1 Oct 30 00:03:41.210642 kernel: CPU topo: Num. cores per package: 6 Oct 30 00:03:41.210653 kernel: CPU topo: Num. threads per package: 6 Oct 30 00:03:41.210661 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Oct 30 00:03:41.210669 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Oct 30 00:03:41.210676 kernel: Booting paravirtualized kernel on Hyper-V Oct 30 00:03:41.210684 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 30 00:03:41.210692 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Oct 30 00:03:41.210700 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Oct 30 00:03:41.210708 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Oct 30 00:03:41.210716 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Oct 30 00:03:41.210727 kernel: Hyper-V: PV spinlocks enabled Oct 30 00:03:41.210735 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Oct 30 00:03:41.210743 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=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13 Oct 30 00:03:41.210754 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Oct 30 00:03:41.210763 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Oct 30 00:03:41.210770 kernel: Fallback order for Node 0: 0 Oct 30 00:03:41.210779 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Oct 30 00:03:41.210786 kernel: Policy zone: Normal Oct 30 00:03:41.210799 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 30 00:03:41.210815 kernel: software IO TLB: area num 8. Oct 30 00:03:41.210823 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Oct 30 00:03:41.210837 kernel: Kernel/User page tables isolation: enabled Oct 30 00:03:41.210849 kernel: ftrace: allocating 40021 entries in 157 pages Oct 30 00:03:41.210862 kernel: ftrace: allocated 157 pages with 5 groups Oct 30 00:03:41.210873 kernel: Dynamic Preempt: voluntary Oct 30 00:03:41.210881 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 30 00:03:41.210891 kernel: rcu: RCU event tracing is enabled. Oct 30 00:03:41.210899 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Oct 30 00:03:41.210908 kernel: Trampoline variant of Tasks RCU enabled. Oct 30 00:03:41.210919 kernel: Rude variant of Tasks RCU enabled. Oct 30 00:03:41.210927 kernel: Tracing variant of Tasks RCU enabled. Oct 30 00:03:41.210936 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 30 00:03:41.210944 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Oct 30 00:03:41.210953 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 30 00:03:41.210961 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 30 00:03:41.210972 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Oct 30 00:03:41.210981 kernel: Using NULL legacy PIC Oct 30 00:03:41.210989 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Oct 30 00:03:41.210997 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 30 00:03:41.211006 kernel: Console: colour dummy device 80x25 Oct 30 00:03:41.211014 kernel: printk: legacy console [tty1] enabled Oct 30 00:03:41.211023 kernel: printk: legacy console [ttyS0] enabled Oct 30 00:03:41.211031 kernel: printk: legacy bootconsole [earlyser0] disabled Oct 30 00:03:41.211039 kernel: ACPI: Core revision 20240827 Oct 30 00:03:41.211050 kernel: Failed to register legacy timer interrupt Oct 30 00:03:41.211058 kernel: APIC: Switch to symmetric I/O mode setup Oct 30 00:03:41.211067 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Oct 30 00:03:41.211087 kernel: Hyper-V: enabling crash_kexec_post_notifiers Oct 30 00:03:41.211095 kernel: Hyper-V: Using IPI hypercalls Oct 30 00:03:41.211104 kernel: APIC: send_IPI() replaced with hv_send_ipi() Oct 30 00:03:41.211112 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Oct 30 00:03:41.211121 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Oct 30 00:03:41.211129 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Oct 30 00:03:41.211141 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Oct 30 00:03:41.211149 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Oct 30 00:03:41.211164 kernel: Hyper-V: Using enlightened APIC (xapic mode) Oct 30 00:03:41.211178 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Oct 30 00:03:41.211186 kernel: APIC: read() replaced with hv_apic_read() Oct 30 00:03:41.211195 kernel: APIC: write() replaced with hv_apic_write() Oct 30 00:03:41.211203 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Oct 30 00:03:41.211211 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Oct 30 00:03:41.211220 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593990) Oct 30 00:03:41.211232 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Oct 30 00:03:41.211240 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Oct 30 00:03:41.211249 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 30 00:03:41.211257 kernel: Spectre V2 : Mitigation: Retpolines Oct 30 00:03:41.211265 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Oct 30 00:03:41.211274 kernel: Speculative Store Bypass: Vulnerable Oct 30 00:03:41.211282 kernel: MDS: Mitigation: Clear CPU buffers Oct 30 00:03:41.211290 kernel: TAA: Mitigation: Clear CPU buffers Oct 30 00:03:41.211298 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Oct 30 00:03:41.211306 kernel: active return thunk: its_return_thunk Oct 30 00:03:41.211315 kernel: ITS: Mitigation: Aligned branch/return thunks Oct 30 00:03:41.211325 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Oct 30 00:03:41.211333 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Oct 30 00:03:41.211342 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Oct 30 00:03:41.211350 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Oct 30 00:03:41.211358 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Oct 30 00:03:41.211367 kernel: Freeing SMP alternatives memory: 32K Oct 30 00:03:41.211375 kernel: pid_max: default: 32768 minimum: 301 Oct 30 00:03:41.211383 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Oct 30 00:03:41.211391 kernel: landlock: Up and running. Oct 30 00:03:41.211399 kernel: SELinux: Initializing. Oct 30 00:03:41.211408 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 30 00:03:41.211418 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 30 00:03:41.211430 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Oct 30 00:03:41.211438 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Oct 30 00:03:41.211447 kernel: signal: max sigframe size: 1776 Oct 30 00:03:41.211455 kernel: rcu: Hierarchical SRCU implementation. Oct 30 00:03:41.211464 kernel: rcu: Max phase no-delay instances is 400. Oct 30 00:03:41.211473 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Oct 30 00:03:41.211481 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Oct 30 00:03:41.211489 kernel: smp: Bringing up secondary CPUs ... Oct 30 00:03:41.211500 kernel: smpboot: x86: Booting SMP configuration: Oct 30 00:03:41.211509 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Oct 30 00:03:41.211517 kernel: smp: Brought up 1 node, 6 CPUs Oct 30 00:03:41.211526 kernel: smpboot: Total of 6 processors activated (31127.88 BogoMIPS) Oct 30 00:03:41.211534 kernel: Memory: 115332948K/117439364K available (14336K kernel code, 2436K rwdata, 26048K rodata, 45544K init, 1184K bss, 2096140K reserved, 0K cma-reserved) Oct 30 00:03:41.211543 kernel: devtmpfs: initialized Oct 30 00:03:41.211551 kernel: x86/mm: Memory block size: 1024MB Oct 30 00:03:41.211560 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Oct 30 00:03:41.211569 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 30 00:03:41.211580 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Oct 30 00:03:41.211588 kernel: pinctrl core: initialized pinctrl subsystem Oct 30 00:03:41.211597 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 30 00:03:41.211605 kernel: audit: initializing netlink subsys (disabled) Oct 30 00:03:41.211613 kernel: audit: type=2000 audit(1761782617.028:1): state=initialized audit_enabled=0 res=1 Oct 30 00:03:41.211622 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 30 00:03:41.211630 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 30 00:03:41.211638 kernel: cpuidle: using governor menu Oct 30 00:03:41.211647 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 30 00:03:41.211658 kernel: dca service started, version 1.12.1 Oct 30 00:03:41.211666 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Oct 30 00:03:41.211675 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 30 00:03:41.211683 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Oct 30 00:03:41.211692 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Oct 30 00:03:41.211700 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 30 00:03:41.211708 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 30 00:03:41.211717 kernel: ACPI: Added _OSI(Module Device) Oct 30 00:03:41.211725 kernel: ACPI: Added _OSI(Processor Device) Oct 30 00:03:41.211736 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 30 00:03:41.211744 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 30 00:03:41.211753 kernel: ACPI: Interpreter enabled Oct 30 00:03:41.211761 kernel: ACPI: PM: (supports S0 S5) Oct 30 00:03:41.211769 kernel: ACPI: Using IOAPIC for interrupt routing Oct 30 00:03:41.211778 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 30 00:03:41.211786 kernel: PCI: Ignoring E820 reservations for host bridge windows Oct 30 00:03:41.211795 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Oct 30 00:03:41.211803 kernel: iommu: Default domain type: Translated Oct 30 00:03:41.211814 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 30 00:03:41.211822 kernel: efivars: Registered efivars operations Oct 30 00:03:41.211831 kernel: PCI: Using ACPI for IRQ routing Oct 30 00:03:41.211839 kernel: PCI: System does not support PCI Oct 30 00:03:41.211848 kernel: vgaarb: loaded Oct 30 00:03:41.211856 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Oct 30 00:03:41.211864 kernel: VFS: Disk quotas dquot_6.6.0 Oct 30 00:03:41.211873 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 30 00:03:41.211881 kernel: pnp: PnP ACPI init Oct 30 00:03:41.211892 kernel: pnp: PnP ACPI: found 3 devices Oct 30 00:03:41.211900 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 30 00:03:41.211909 kernel: NET: Registered PF_INET protocol family Oct 30 00:03:41.211917 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 30 00:03:41.211926 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Oct 30 00:03:41.211934 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 30 00:03:41.211943 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 30 00:03:41.211951 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 30 00:03:41.211960 kernel: TCP: Hash tables configured (established 524288 bind 65536) Oct 30 00:03:41.211971 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 30 00:03:41.211979 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Oct 30 00:03:41.211987 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 30 00:03:41.211996 kernel: NET: Registered PF_XDP protocol family Oct 30 00:03:41.212004 kernel: PCI: CLS 0 bytes, default 64 Oct 30 00:03:41.212013 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Oct 30 00:03:41.212021 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Oct 30 00:03:41.212030 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Oct 30 00:03:41.212038 kernel: Initialise system trusted keyrings Oct 30 00:03:41.212049 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Oct 30 00:03:41.212057 kernel: Key type asymmetric registered Oct 30 00:03:41.212065 kernel: Asymmetric key parser 'x509' registered Oct 30 00:03:41.212082 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 30 00:03:41.212091 kernel: io scheduler mq-deadline registered Oct 30 00:03:41.212099 kernel: io scheduler kyber registered Oct 30 00:03:41.212108 kernel: io scheduler bfq registered Oct 30 00:03:41.212116 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 30 00:03:41.212124 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 30 00:03:41.212136 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 30 00:03:41.212144 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Oct 30 00:03:41.212153 kernel: i8042: PNP: No PS/2 controller found. Oct 30 00:03:41.212318 kernel: rtc_cmos 00:02: registered as rtc0 Oct 30 00:03:41.212439 kernel: rtc_cmos 00:02: setting system clock to 2025-10-30T00:03:40 UTC (1761782620) Oct 30 00:03:41.212539 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Oct 30 00:03:41.212552 kernel: intel_pstate: CPU model not supported Oct 30 00:03:41.212566 kernel: efifb: probing for efifb Oct 30 00:03:41.212575 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Oct 30 00:03:41.212583 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Oct 30 00:03:41.212592 kernel: efifb: scrolling: redraw Oct 30 00:03:41.212600 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Oct 30 00:03:41.212608 kernel: Console: switching to colour frame buffer device 128x48 Oct 30 00:03:41.212617 kernel: fb0: EFI VGA frame buffer device Oct 30 00:03:41.212625 kernel: pstore: Using crash dump compression: deflate Oct 30 00:03:41.212634 kernel: pstore: Registered efi_pstore as persistent store backend Oct 30 00:03:41.212645 kernel: NET: Registered PF_INET6 protocol family Oct 30 00:03:41.212653 kernel: Segment Routing with IPv6 Oct 30 00:03:41.212661 kernel: In-situ OAM (IOAM) with IPv6 Oct 30 00:03:41.212670 kernel: NET: Registered PF_PACKET protocol family Oct 30 00:03:41.212678 kernel: Key type dns_resolver registered Oct 30 00:03:41.212686 kernel: IPI shorthand broadcast: enabled Oct 30 00:03:41.212695 kernel: sched_clock: Marking stable (3601004800, 109600600)->(4064622200, -354016800) Oct 30 00:03:41.212703 kernel: registered taskstats version 1 Oct 30 00:03:41.212712 kernel: Loading compiled-in X.509 certificates Oct 30 00:03:41.212720 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 815fc40077fbc06b8d9e8a6016fea83aecff0a2a' Oct 30 00:03:41.212731 kernel: Demotion targets for Node 0: null Oct 30 00:03:41.212739 kernel: Key type .fscrypt registered Oct 30 00:03:41.212747 kernel: Key type fscrypt-provisioning registered Oct 30 00:03:41.212756 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 30 00:03:41.212764 kernel: ima: Allocated hash algorithm: sha1 Oct 30 00:03:41.212772 kernel: ima: No architecture policies found Oct 30 00:03:41.212781 kernel: clk: Disabling unused clocks Oct 30 00:03:41.212789 kernel: Warning: unable to open an initial console. Oct 30 00:03:41.212798 kernel: Freeing unused kernel image (initmem) memory: 45544K Oct 30 00:03:41.212809 kernel: Write protecting the kernel read-only data: 40960k Oct 30 00:03:41.212817 kernel: Freeing unused kernel image (rodata/data gap) memory: 576K Oct 30 00:03:41.212825 kernel: Run /init as init process Oct 30 00:03:41.212834 kernel: with arguments: Oct 30 00:03:41.212842 kernel: /init Oct 30 00:03:41.212851 kernel: with environment: Oct 30 00:03:41.212859 kernel: HOME=/ Oct 30 00:03:41.212867 kernel: TERM=linux Oct 30 00:03:41.212877 systemd[1]: Successfully made /usr/ read-only. Oct 30 00:03:41.212890 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) Oct 30 00:03:41.212900 systemd[1]: Detected virtualization microsoft. Oct 30 00:03:41.212909 systemd[1]: Detected architecture x86-64. Oct 30 00:03:41.212917 systemd[1]: Running in initrd. Oct 30 00:03:41.212926 systemd[1]: No hostname configured, using default hostname. Oct 30 00:03:41.212935 systemd[1]: Hostname set to . Oct 30 00:03:41.212944 systemd[1]: Initializing machine ID from random generator. Oct 30 00:03:41.212955 systemd[1]: Queued start job for default target initrd.target. Oct 30 00:03:41.212964 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 00:03:41.212973 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 00:03:41.212983 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 30 00:03:41.212992 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 30 00:03:41.213001 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 30 00:03:41.213011 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 30 00:03:41.213023 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 30 00:03:41.213032 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 30 00:03:41.213041 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 00:03:41.213050 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 30 00:03:41.213059 systemd[1]: Reached target paths.target - Path Units. Oct 30 00:03:41.213068 systemd[1]: Reached target slices.target - Slice Units. Oct 30 00:03:41.213149 systemd[1]: Reached target swap.target - Swaps. Oct 30 00:03:41.213158 systemd[1]: Reached target timers.target - Timer Units. Oct 30 00:03:41.213171 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 30 00:03:41.213180 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 30 00:03:41.213189 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 30 00:03:41.213198 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Oct 30 00:03:41.213208 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 30 00:03:41.213217 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 30 00:03:41.213226 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 00:03:41.213235 systemd[1]: Reached target sockets.target - Socket Units. Oct 30 00:03:41.213243 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 30 00:03:41.213255 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 30 00:03:41.213264 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 30 00:03:41.213273 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Oct 30 00:03:41.213283 systemd[1]: Starting systemd-fsck-usr.service... Oct 30 00:03:41.213292 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 30 00:03:41.213300 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 30 00:03:41.213309 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:03:41.213340 systemd-journald[214]: Collecting audit messages is disabled. Oct 30 00:03:41.213366 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 30 00:03:41.213380 systemd-journald[214]: Journal started Oct 30 00:03:41.213400 systemd-journald[214]: Runtime Journal (/run/log/journal/f9cad5ff3ba8424bb9fc86d4a1532bf3) is 8M, max 2.2G, 2.1G free. Oct 30 00:03:41.211200 systemd-modules-load[216]: Inserted module 'overlay' Oct 30 00:03:41.223091 systemd[1]: Started systemd-journald.service - Journal Service. Oct 30 00:03:41.223097 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 00:03:41.223632 systemd[1]: Finished systemd-fsck-usr.service. Oct 30 00:03:41.226097 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 30 00:03:41.227174 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 30 00:03:41.278221 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:03:41.280635 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 30 00:03:41.301372 systemd-tmpfiles[225]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Oct 30 00:03:41.310724 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 30 00:03:41.305704 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 30 00:03:41.310882 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 00:03:41.312415 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 30 00:03:41.348903 systemd-modules-load[216]: Inserted module 'br_netfilter' Oct 30 00:03:41.350482 kernel: Bridge firewalling registered Oct 30 00:03:41.359242 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 30 00:03:41.360313 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 30 00:03:41.373840 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 30 00:03:41.386597 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 30 00:03:41.405222 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 00:03:41.421451 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 30 00:03:41.424273 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 30 00:03:41.439042 dracut-cmdline[250]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=e5fe4ef982f4bbc75df9f63e805c4ec086c6d95878919f55fe8c638c4d2b3b13 Oct 30 00:03:41.490339 systemd-resolved[269]: Positive Trust Anchors: Oct 30 00:03:41.490353 systemd-resolved[269]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 00:03:41.490384 systemd-resolved[269]: 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 Oct 30 00:03:41.492840 systemd-resolved[269]: Defaulting to hostname 'linux'. Oct 30 00:03:41.493822 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 30 00:03:41.503604 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 30 00:03:41.551092 kernel: SCSI subsystem initialized Oct 30 00:03:41.562092 kernel: Loading iSCSI transport class v2.0-870. Oct 30 00:03:41.574097 kernel: iscsi: registered transport (tcp) Oct 30 00:03:41.598030 kernel: iscsi: registered transport (qla4xxx) Oct 30 00:03:41.598060 kernel: QLogic iSCSI HBA Driver Oct 30 00:03:41.616448 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 30 00:03:41.647473 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 00:03:41.650021 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 30 00:03:41.710229 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 30 00:03:41.713013 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 30 00:03:41.787097 kernel: raid6: avx2x4 gen() 29373 MB/s Oct 30 00:03:41.806084 kernel: raid6: avx2x2 gen() 31122 MB/s Oct 30 00:03:41.827283 kernel: raid6: avx2x1 gen() 26578 MB/s Oct 30 00:03:41.827311 kernel: raid6: using algorithm avx2x2 gen() 31122 MB/s Oct 30 00:03:41.850365 kernel: raid6: .... xor() 18278 MB/s, rmw enabled Oct 30 00:03:41.850400 kernel: raid6: using avx2x2 recovery algorithm Oct 30 00:03:41.873095 kernel: xor: automatically using best checksumming function avx Oct 30 00:03:42.036103 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 30 00:03:42.043424 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 30 00:03:42.046330 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 00:03:42.090954 systemd-udevd[467]: Using default interface naming scheme 'v255'. Oct 30 00:03:42.097620 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 00:03:42.101186 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 30 00:03:42.142788 dracut-pre-trigger[474]: rd.md=0: removing MD RAID activation Oct 30 00:03:42.166645 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 30 00:03:42.170883 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 30 00:03:42.254490 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 00:03:42.256889 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 30 00:03:42.288091 kernel: cryptd: max_cpu_qlen set to 1000 Oct 30 00:03:42.296105 kernel: hv_vmbus: Vmbus version:4.0 Oct 30 00:03:42.309686 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:03:42.336602 kernel: AES CTR mode by8 optimization enabled Oct 30 00:03:42.336628 kernel: hv_vmbus: registering driver hyperv_keyboard Oct 30 00:03:42.336640 kernel: pps_core: LinuxPPS API ver. 1 registered Oct 30 00:03:42.336651 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Oct 30 00:03:42.336663 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 Oct 30 00:03:42.309814 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:03:42.341081 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:03:42.355093 kernel: hv_vmbus: registering driver hv_netvsc Oct 30 00:03:42.357524 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:03:42.365472 kernel: PTP clock support registered Oct 30 00:03:42.367511 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:03:42.378438 kernel: hv_utils: Registering HyperV Utility Driver Oct 30 00:03:42.378470 kernel: hv_vmbus: registering driver hv_utils Oct 30 00:03:42.788404 kernel: hv_vmbus: registering driver hv_storvsc Oct 30 00:03:42.788434 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 30 00:03:42.788447 kernel: hv_utils: Heartbeat IC version 3.0 Oct 30 00:03:42.788465 kernel: hv_utils: Shutdown IC version 3.2 Oct 30 00:03:42.788477 kernel: hv_utils: TimeSync IC version 4.0 Oct 30 00:03:42.787757 systemd-resolved[269]: Clock change detected. Flushing caches. Oct 30 00:03:42.823846 kernel: scsi host1: storvsc_host_t Oct 30 00:03:42.824074 kernel: scsi host0: storvsc_host_t Oct 30 00:03:42.824258 kernel: hv_vmbus: registering driver hv_pci Oct 30 00:03:42.824281 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 30 00:03:42.824318 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Oct 30 00:03:42.824472 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Oct 30 00:03:42.824600 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Oct 30 00:03:42.824737 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Oct 30 00:03:42.788617 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:03:42.867268 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Oct 30 00:03:42.867430 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Oct 30 00:03:42.867587 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Oct 30 00:03:42.867711 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Oct 30 00:03:42.867737 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Oct 30 00:03:42.867756 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Oct 30 00:03:42.867794 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Oct 30 00:03:42.788709 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:03:42.878677 kernel: pci 0001:00:00.0: Enabling HDA controller Oct 30 00:03:42.878712 kernel: hv_vmbus: registering driver hid_hyperv Oct 30 00:03:42.858911 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:03:42.899475 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Oct 30 00:03:42.899656 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Oct 30 00:03:42.899811 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Oct 30 00:03:42.899941 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Oct 30 00:03:42.900091 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Oct 30 00:03:42.900240 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Oct 30 00:03:42.903794 kernel: sd 0:0:0:1: [sda] Write Protect is off Oct 30 00:03:42.903949 kernel: sd 0:0:0:0: [sdb] Write Protect is off Oct 30 00:03:42.904083 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Oct 30 00:03:42.904214 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 30 00:03:42.904345 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Oct 30 00:03:42.905790 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Oct 30 00:03:42.908804 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Oct 30 00:03:42.908970 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#586 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:03:42.911795 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Oct 30 00:03:42.930930 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#267 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:03:42.942790 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#587 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:03:42.953966 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#594 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:03:42.959141 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:03:42.973178 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 30 00:03:42.973203 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Oct 30 00:03:42.976234 kernel: sda: sda1 Oct 30 00:03:42.976258 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Oct 30 00:03:43.034589 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Oct 30 00:03:43.034629 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Oct 30 00:03:43.042857 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Oct 30 00:03:43.043038 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Oct 30 00:03:43.044805 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Oct 30 00:03:43.566664 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Oct 30 00:03:43.598106 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 30 00:03:43.653965 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Oct 30 00:03:43.686583 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Oct 30 00:03:43.688118 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Oct 30 00:03:43.700078 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 30 00:03:43.748049 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#258 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:03:43.763788 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 30 00:03:44.710693 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 30 00:03:44.715062 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 30 00:03:44.720613 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 00:03:44.735442 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 30 00:03:44.745245 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 30 00:03:44.777284 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#75 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:03:44.787234 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 30 00:03:44.803644 disk-uuid[704]: The operation has completed successfully. Oct 30 00:03:44.807663 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Oct 30 00:03:44.870510 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 30 00:03:44.870644 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 30 00:03:44.927610 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 30 00:03:44.958957 sh[876]: Success Oct 30 00:03:45.040847 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 30 00:03:45.040894 kernel: device-mapper: uevent: version 1.0.3 Oct 30 00:03:45.045694 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Oct 30 00:03:45.057795 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Oct 30 00:03:45.401214 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 30 00:03:45.410735 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 30 00:03:45.431769 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 30 00:03:45.451802 kernel: BTRFS: device fsid ad8523d8-35e6-44b9-a685-e8d871101da4 devid 1 transid 35 /dev/mapper/usr (254:0) scanned by mount (901) Oct 30 00:03:45.460836 kernel: BTRFS info (device dm-0): first mount of filesystem ad8523d8-35e6-44b9-a685-e8d871101da4 Oct 30 00:03:45.460870 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:03:45.924444 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 30 00:03:45.924519 kernel: BTRFS info (device dm-0): enabling free space tree Oct 30 00:03:45.964960 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 30 00:03:45.969114 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Oct 30 00:03:45.977169 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 30 00:03:45.977987 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 30 00:03:46.012324 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 30 00:03:46.052299 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (934) Oct 30 00:03:46.052337 kernel: BTRFS info (device sdb6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:03:46.057132 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:03:46.107878 kernel: BTRFS info (device sdb6): turning on async discard Oct 30 00:03:46.107925 kernel: BTRFS info (device sdb6): enabling free space tree Oct 30 00:03:46.112854 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 30 00:03:46.117882 kernel: BTRFS info (device sdb6): last unmount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:03:46.127683 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 30 00:03:46.137472 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 30 00:03:46.152886 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 30 00:03:46.187329 systemd-networkd[1071]: lo: Link UP Oct 30 00:03:46.187340 systemd-networkd[1071]: lo: Gained carrier Oct 30 00:03:46.188336 systemd-networkd[1071]: Enumeration completed Oct 30 00:03:46.188408 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 30 00:03:46.188689 systemd-networkd[1071]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:03:46.188693 systemd-networkd[1071]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 00:03:46.189453 systemd-networkd[1071]: eth0: Link UP Oct 30 00:03:46.189647 systemd-networkd[1071]: eth0: Gained carrier Oct 30 00:03:46.189657 systemd-networkd[1071]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:03:46.194630 systemd[1]: Reached target network.target - Network. Oct 30 00:03:46.249810 systemd-networkd[1071]: eth0: DHCPv4 address 10.200.8.13/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 30 00:03:47.680900 systemd-networkd[1071]: eth0: Gained IPv6LL Oct 30 00:03:48.410686 ignition[1069]: Ignition 2.22.0 Oct 30 00:03:48.410701 ignition[1069]: Stage: fetch-offline Oct 30 00:03:48.414379 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 30 00:03:48.410851 ignition[1069]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:48.423197 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 30 00:03:48.410861 ignition[1069]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:48.410969 ignition[1069]: parsed url from cmdline: "" Oct 30 00:03:48.410973 ignition[1069]: no config URL provided Oct 30 00:03:48.410979 ignition[1069]: reading system config file "/usr/lib/ignition/user.ign" Oct 30 00:03:48.410988 ignition[1069]: no config at "/usr/lib/ignition/user.ign" Oct 30 00:03:48.410994 ignition[1069]: failed to fetch config: resource requires networking Oct 30 00:03:48.411143 ignition[1069]: Ignition finished successfully Oct 30 00:03:48.474482 ignition[1085]: Ignition 2.22.0 Oct 30 00:03:48.474495 ignition[1085]: Stage: fetch Oct 30 00:03:48.474783 ignition[1085]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:48.474867 ignition[1085]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:48.475225 ignition[1085]: parsed url from cmdline: "" Oct 30 00:03:48.475265 ignition[1085]: no config URL provided Oct 30 00:03:48.475304 ignition[1085]: reading system config file "/usr/lib/ignition/user.ign" Oct 30 00:03:48.475376 ignition[1085]: no config at "/usr/lib/ignition/user.ign" Oct 30 00:03:48.475428 ignition[1085]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Oct 30 00:03:48.583189 ignition[1085]: GET result: OK Oct 30 00:03:48.583246 ignition[1085]: config has been read from IMDS userdata Oct 30 00:03:48.583264 ignition[1085]: parsing config with SHA512: 5018be824b6ccfc332ecb826a8d11ffacdc1a332997c0f2c55ef067ee578d5457974d2208484600ebe53f69298a38bac6bd6e45f34026b6cd30b723ca18ab4cd Oct 30 00:03:48.589298 unknown[1085]: fetched base config from "system" Oct 30 00:03:48.589348 unknown[1085]: fetched base config from "system" Oct 30 00:03:48.589362 unknown[1085]: fetched user config from "azure" Oct 30 00:03:48.594090 ignition[1085]: fetch: fetch complete Oct 30 00:03:48.594096 ignition[1085]: fetch: fetch passed Oct 30 00:03:48.602238 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 30 00:03:48.594163 ignition[1085]: Ignition finished successfully Oct 30 00:03:48.605230 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 30 00:03:48.650894 ignition[1095]: Ignition 2.22.0 Oct 30 00:03:48.650909 ignition[1095]: Stage: kargs Oct 30 00:03:48.651127 ignition[1095]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:48.651141 ignition[1095]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:48.659978 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 30 00:03:48.651628 ignition[1095]: kargs: kargs passed Oct 30 00:03:48.668613 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 30 00:03:48.651678 ignition[1095]: Ignition finished successfully Oct 30 00:03:48.711594 ignition[1104]: Ignition 2.22.0 Oct 30 00:03:48.711608 ignition[1104]: Stage: disks Oct 30 00:03:48.711892 ignition[1104]: no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:48.711905 ignition[1104]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:48.717815 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 30 00:03:48.712513 ignition[1104]: disks: disks passed Oct 30 00:03:48.724734 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 30 00:03:48.712559 ignition[1104]: Ignition finished successfully Oct 30 00:03:48.726805 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 30 00:03:48.739114 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 30 00:03:48.753951 systemd[1]: Reached target sysinit.target - System Initialization. Oct 30 00:03:48.755373 systemd[1]: Reached target basic.target - Basic System. Oct 30 00:03:48.768585 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 30 00:03:48.873682 systemd-fsck[1115]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Oct 30 00:03:48.881883 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 30 00:03:48.884316 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 30 00:03:50.754791 kernel: EXT4-fs (sdb9): mounted filesystem 02607114-2ead-44bc-a76e-2d51f82b108e r/w with ordered data mode. Quota mode: none. Oct 30 00:03:50.755287 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 30 00:03:50.757960 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 30 00:03:50.786147 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 30 00:03:50.805544 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 30 00:03:50.813200 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Oct 30 00:03:50.818170 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 30 00:03:50.841468 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1129) Oct 30 00:03:50.841495 kernel: BTRFS info (device sdb6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:03:50.841508 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:03:50.818205 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 30 00:03:50.863874 kernel: BTRFS info (device sdb6): turning on async discard Oct 30 00:03:50.863897 kernel: BTRFS info (device sdb6): enabling free space tree Oct 30 00:03:50.857123 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 30 00:03:50.871503 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 30 00:03:50.878901 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 30 00:03:51.810684 coreos-metadata[1131]: Oct 30 00:03:51.810 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 30 00:03:51.816884 coreos-metadata[1131]: Oct 30 00:03:51.816 INFO Fetch successful Oct 30 00:03:51.816884 coreos-metadata[1131]: Oct 30 00:03:51.816 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Oct 30 00:03:51.829147 coreos-metadata[1131]: Oct 30 00:03:51.820 INFO Fetch successful Oct 30 00:03:51.829147 coreos-metadata[1131]: Oct 30 00:03:51.820 INFO wrote hostname ci-4459.1.0-n-802fc0fb2f to /sysroot/etc/hostname Oct 30 00:03:51.830288 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 30 00:03:52.265828 initrd-setup-root[1159]: cut: /sysroot/etc/passwd: No such file or directory Oct 30 00:03:52.343451 initrd-setup-root[1166]: cut: /sysroot/etc/group: No such file or directory Oct 30 00:03:52.370445 initrd-setup-root[1173]: cut: /sysroot/etc/shadow: No such file or directory Oct 30 00:03:52.391392 initrd-setup-root[1180]: cut: /sysroot/etc/gshadow: No such file or directory Oct 30 00:03:53.623186 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 30 00:03:53.626038 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 30 00:03:53.645431 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 30 00:03:53.657187 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 30 00:03:53.666121 kernel: BTRFS info (device sdb6): last unmount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:03:53.686149 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 30 00:03:53.702683 ignition[1250]: INFO : Ignition 2.22.0 Oct 30 00:03:53.702683 ignition[1250]: INFO : Stage: mount Oct 30 00:03:53.708701 ignition[1250]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:53.708701 ignition[1250]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:53.708701 ignition[1250]: INFO : mount: mount passed Oct 30 00:03:53.708701 ignition[1250]: INFO : Ignition finished successfully Oct 30 00:03:53.710841 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 30 00:03:53.714572 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 30 00:03:53.747516 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 30 00:03:53.774793 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1264) Oct 30 00:03:53.787039 kernel: BTRFS info (device sdb6): first mount of filesystem 20cadb25-62ee-49b8-9ff8-7ba27828b77e Oct 30 00:03:53.787069 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Oct 30 00:03:53.796900 kernel: BTRFS info (device sdb6): turning on async discard Oct 30 00:03:53.796930 kernel: BTRFS info (device sdb6): enabling free space tree Oct 30 00:03:53.799112 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 30 00:03:53.845335 ignition[1281]: INFO : Ignition 2.22.0 Oct 30 00:03:53.845335 ignition[1281]: INFO : Stage: files Oct 30 00:03:53.851167 ignition[1281]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:53.851167 ignition[1281]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:53.851167 ignition[1281]: DEBUG : files: compiled without relabeling support, skipping Oct 30 00:03:53.883053 ignition[1281]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 30 00:03:53.883053 ignition[1281]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 30 00:03:53.923558 ignition[1281]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 30 00:03:53.929372 ignition[1281]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 30 00:03:53.934587 ignition[1281]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 30 00:03:53.929989 unknown[1281]: wrote ssh authorized keys file for user: core Oct 30 00:03:54.013107 ignition[1281]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 30 00:03:54.020275 ignition[1281]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 30 00:03:54.020275 ignition[1281]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 30 00:03:54.020275 ignition[1281]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 30 00:03:54.020275 ignition[1281]: INFO : files: files passed Oct 30 00:03:54.020275 ignition[1281]: INFO : Ignition finished successfully Oct 30 00:03:54.035801 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 30 00:03:54.043572 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 30 00:03:54.058972 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 30 00:03:54.062218 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 30 00:03:54.062306 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 30 00:03:54.107612 initrd-setup-root-after-ignition[1314]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 30 00:03:54.107612 initrd-setup-root-after-ignition[1314]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 30 00:03:54.120495 initrd-setup-root-after-ignition[1318]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 30 00:03:54.127688 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 30 00:03:54.137985 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 30 00:03:54.139462 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 30 00:03:54.185594 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 30 00:03:54.185714 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 30 00:03:54.188003 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 30 00:03:54.200874 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 30 00:03:54.210822 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 30 00:03:54.212124 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 30 00:03:54.236453 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 30 00:03:54.247121 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 30 00:03:54.281357 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 30 00:03:54.282945 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 00:03:54.292250 systemd[1]: Stopped target timers.target - Timer Units. Oct 30 00:03:54.301295 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 30 00:03:54.301417 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 30 00:03:54.315668 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 30 00:03:54.324523 systemd[1]: Stopped target basic.target - Basic System. Oct 30 00:03:54.326031 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 30 00:03:54.326781 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 30 00:03:54.327555 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 30 00:03:54.328340 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Oct 30 00:03:54.329118 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 30 00:03:54.329871 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 30 00:03:54.330652 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 30 00:03:54.331382 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 30 00:03:54.475463 ignition[1338]: INFO : Ignition 2.22.0 Oct 30 00:03:54.475463 ignition[1338]: INFO : Stage: umount Oct 30 00:03:54.475463 ignition[1338]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 30 00:03:54.475463 ignition[1338]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Oct 30 00:03:54.475463 ignition[1338]: INFO : umount: umount passed Oct 30 00:03:54.475463 ignition[1338]: INFO : Ignition finished successfully Oct 30 00:03:54.332092 systemd[1]: Stopped target swap.target - Swaps. Oct 30 00:03:54.333616 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 30 00:03:54.333733 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 30 00:03:54.335013 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 30 00:03:54.335813 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 00:03:54.336533 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 30 00:03:54.336656 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 00:03:54.337319 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 30 00:03:54.337430 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 30 00:03:54.338877 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 30 00:03:54.338993 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 30 00:03:54.339648 systemd[1]: ignition-files.service: Deactivated successfully. Oct 30 00:03:54.339757 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 30 00:03:54.340437 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Oct 30 00:03:54.340542 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Oct 30 00:03:54.342069 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 30 00:03:54.343550 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 30 00:03:54.343668 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 00:03:54.345072 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 30 00:03:54.345877 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 30 00:03:54.345997 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 00:03:54.346725 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 30 00:03:54.346846 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 30 00:03:54.356753 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 30 00:03:54.446013 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 30 00:03:54.479174 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 30 00:03:54.479288 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 30 00:03:54.485534 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 30 00:03:54.485602 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 30 00:03:54.492928 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 30 00:03:54.492978 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 30 00:03:54.501600 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 30 00:03:54.501647 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 30 00:03:54.513830 systemd[1]: Stopped target network.target - Network. Oct 30 00:03:54.520277 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 30 00:03:54.520332 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 30 00:03:54.528755 systemd[1]: Stopped target paths.target - Path Units. Oct 30 00:03:54.536223 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 30 00:03:54.540839 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 00:03:54.541731 systemd[1]: Stopped target slices.target - Slice Units. Oct 30 00:03:54.554277 systemd[1]: Stopped target sockets.target - Socket Units. Oct 30 00:03:54.561858 systemd[1]: iscsid.socket: Deactivated successfully. Oct 30 00:03:54.561904 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 30 00:03:54.571064 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 30 00:03:54.571103 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 30 00:03:54.578763 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 30 00:03:54.578833 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 30 00:03:54.586673 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 30 00:03:54.586722 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 30 00:03:54.595360 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 30 00:03:54.597481 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 30 00:03:54.610254 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 30 00:03:54.610852 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 30 00:03:54.610955 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 30 00:03:54.624973 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Oct 30 00:03:54.625238 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 30 00:03:54.625345 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 30 00:03:54.637898 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Oct 30 00:03:54.638129 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 30 00:03:54.638218 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 30 00:03:54.645784 systemd[1]: Stopped target network-pre.target - Preparation for Network. Oct 30 00:03:54.654003 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 30 00:03:54.654080 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 30 00:03:54.661959 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 30 00:03:54.662039 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 30 00:03:54.665118 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 30 00:03:54.675791 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 30 00:03:54.675849 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 30 00:03:54.683048 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 30 00:03:54.683096 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 30 00:03:54.698582 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 30 00:03:54.698628 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 30 00:03:54.707861 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 30 00:03:54.707911 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 00:03:54.720206 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 00:03:54.722685 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Oct 30 00:03:54.722748 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:03:54.758485 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 30 00:03:54.758688 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 00:03:54.761340 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 30 00:03:54.761390 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 30 00:03:54.768648 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 30 00:03:54.768685 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 00:03:54.780724 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 30 00:03:54.780784 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 30 00:03:54.794247 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 30 00:03:54.794296 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 30 00:03:54.805000 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 30 00:03:54.805048 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 30 00:03:54.817686 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 30 00:03:54.819139 systemd[1]: systemd-network-generator.service: Deactivated successfully. Oct 30 00:03:54.819193 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 00:03:54.837112 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 30 00:03:54.837161 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 00:03:54.849856 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 00:03:55.080403 systemd-journald[214]: Received SIGTERM from PID 1 (systemd). Oct 30 00:03:54.849904 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:03:54.864275 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Oct 30 00:03:54.864330 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Oct 30 00:03:54.864376 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Oct 30 00:03:54.864660 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 30 00:03:54.871894 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 30 00:03:54.878043 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 30 00:03:54.878137 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 30 00:03:54.885021 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 30 00:03:54.894202 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 30 00:03:54.958826 systemd[1]: Switching root. Oct 30 00:03:55.091149 systemd-journald[214]: Journal stopped Oct 30 00:04:03.741744 kernel: SELinux: policy capability network_peer_controls=1 Oct 30 00:04:03.741846 kernel: SELinux: policy capability open_perms=1 Oct 30 00:04:03.741862 kernel: SELinux: policy capability extended_socket_class=1 Oct 30 00:04:03.741872 kernel: SELinux: policy capability always_check_network=0 Oct 30 00:04:03.741881 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 30 00:04:03.741890 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 30 00:04:03.741901 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 30 00:04:03.741914 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 30 00:04:03.741924 kernel: SELinux: policy capability userspace_initial_context=0 Oct 30 00:04:03.741934 kernel: audit: type=1403 audit(1761782636.198:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 30 00:04:03.741946 systemd[1]: Successfully loaded SELinux policy in 244.757ms. Oct 30 00:04:03.741958 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.050ms. Oct 30 00:04:03.741969 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) Oct 30 00:04:03.741980 systemd[1]: Detected virtualization microsoft. Oct 30 00:04:03.741994 systemd[1]: Detected architecture x86-64. Oct 30 00:04:03.742005 systemd[1]: Detected first boot. Oct 30 00:04:03.742016 systemd[1]: Hostname set to . Oct 30 00:04:03.742026 systemd[1]: Initializing machine ID from random generator. Oct 30 00:04:03.742037 zram_generator::config[1382]: No configuration found. Oct 30 00:04:03.742051 kernel: Guest personality initialized and is inactive Oct 30 00:04:03.742064 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Oct 30 00:04:03.742074 kernel: Initialized host personality Oct 30 00:04:03.742084 kernel: NET: Registered PF_VSOCK protocol family Oct 30 00:04:03.742095 systemd[1]: Populated /etc with preset unit settings. Oct 30 00:04:03.742107 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Oct 30 00:04:03.742117 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 30 00:04:03.742131 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 30 00:04:03.742142 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 30 00:04:03.742153 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 30 00:04:03.742164 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 30 00:04:03.742175 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 30 00:04:03.742185 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 30 00:04:03.742196 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 30 00:04:03.742207 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 30 00:04:03.742220 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 30 00:04:03.742231 systemd[1]: Created slice user.slice - User and Session Slice. Oct 30 00:04:03.742242 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 30 00:04:03.742253 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 30 00:04:03.742264 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 30 00:04:03.742275 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 30 00:04:03.742289 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 30 00:04:03.742304 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 30 00:04:03.742318 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 30 00:04:03.742330 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 30 00:04:03.742341 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 30 00:04:03.742352 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 30 00:04:03.742363 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 30 00:04:03.742374 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 30 00:04:03.742386 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 30 00:04:03.742399 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 30 00:04:03.742411 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 30 00:04:03.742422 systemd[1]: Reached target slices.target - Slice Units. Oct 30 00:04:03.742433 systemd[1]: Reached target swap.target - Swaps. Oct 30 00:04:03.742444 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 30 00:04:03.742455 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 30 00:04:03.742469 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Oct 30 00:04:03.742481 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 30 00:04:03.742492 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 30 00:04:03.742504 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 30 00:04:03.742515 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 30 00:04:03.742526 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 30 00:04:03.742537 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 30 00:04:03.742553 systemd[1]: Mounting media.mount - External Media Directory... Oct 30 00:04:03.742565 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:03.742576 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 30 00:04:03.742587 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 30 00:04:03.742599 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 30 00:04:03.742611 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). Oct 30 00:04:03.742622 systemd[1]: Reached target machines.target - Containers. Oct 30 00:04:03.742633 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 30 00:04:03.742644 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:04:03.742658 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 30 00:04:03.742670 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 30 00:04:03.742681 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:04:03.742692 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 30 00:04:03.742704 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:04:03.742715 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 30 00:04:03.742726 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 00:04:03.742737 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 30 00:04:03.742751 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 30 00:04:03.742762 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 30 00:04:03.742784 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 30 00:04:03.742796 systemd[1]: Stopped systemd-fsck-usr.service. Oct 30 00:04:03.742811 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). Oct 30 00:04:03.742822 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 30 00:04:03.742833 kernel: fuse: init (API version 7.41) Oct 30 00:04:03.742843 kernel: loop: module loaded Oct 30 00:04:03.742857 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 30 00:04:03.742869 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 30 00:04:03.742880 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 30 00:04:03.742892 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Oct 30 00:04:03.742903 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 30 00:04:03.742914 systemd[1]: verity-setup.service: Deactivated successfully. Oct 30 00:04:03.742949 systemd-journald[1489]: Collecting audit messages is disabled. Oct 30 00:04:03.742979 systemd[1]: Stopped verity-setup.service. Oct 30 00:04:03.742991 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:03.743003 systemd-journald[1489]: Journal started Oct 30 00:04:03.743029 systemd-journald[1489]: Runtime Journal (/run/log/journal/2c355be3022b4ba093580d35d9fe37ec) is 8M, max 2.2G, 2.1G free. Oct 30 00:04:02.850053 systemd[1]: Queued start job for default target multi-user.target. Oct 30 00:04:02.868198 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Oct 30 00:04:02.868699 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 30 00:04:03.759175 systemd[1]: Started systemd-journald.service - Journal Service. Oct 30 00:04:03.763481 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 30 00:04:03.768030 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 30 00:04:03.772856 systemd[1]: Mounted media.mount - External Media Directory. Oct 30 00:04:03.777116 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 30 00:04:03.781868 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 30 00:04:03.786579 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 30 00:04:03.790954 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 30 00:04:03.796121 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 30 00:04:03.801652 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 30 00:04:03.801853 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 30 00:04:03.807033 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:04:03.807214 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:04:03.812935 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:04:03.813116 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:04:03.818638 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 30 00:04:03.818837 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 30 00:04:03.823679 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 00:04:03.823875 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 00:04:03.828795 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 30 00:04:03.834791 kernel: ACPI: bus type drm_connector registered Oct 30 00:04:03.836881 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 30 00:04:03.841725 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 00:04:03.841950 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 30 00:04:03.846652 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 30 00:04:03.860836 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 30 00:04:03.866719 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 30 00:04:03.883986 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 30 00:04:03.888733 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 30 00:04:03.888768 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 30 00:04:03.893927 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Oct 30 00:04:03.904027 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 30 00:04:03.919723 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:04:03.945558 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 30 00:04:03.975645 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 30 00:04:03.980355 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 00:04:03.981487 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 30 00:04:03.985887 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 00:04:03.986868 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 30 00:04:03.991865 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 30 00:04:03.997633 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 30 00:04:04.003944 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Oct 30 00:04:04.009490 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 30 00:04:04.015351 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 30 00:04:04.020320 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 30 00:04:04.035876 systemd-journald[1489]: Time spent on flushing to /var/log/journal/2c355be3022b4ba093580d35d9fe37ec is 13.883ms for 951 entries. Oct 30 00:04:04.035876 systemd-journald[1489]: System Journal (/var/log/journal/2c355be3022b4ba093580d35d9fe37ec) is 8M, max 2.6G, 2.6G free. Oct 30 00:04:04.090302 systemd-journald[1489]: Received client request to flush runtime journal. Oct 30 00:04:04.038273 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 30 00:04:04.046217 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 30 00:04:04.052177 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Oct 30 00:04:04.091632 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 30 00:04:04.119803 kernel: loop0: detected capacity change from 0 to 110984 Oct 30 00:04:04.139082 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 30 00:04:04.139713 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Oct 30 00:04:04.162857 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 30 00:04:04.856245 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 30 00:04:04.861983 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 30 00:04:05.005848 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Oct 30 00:04:05.005870 systemd-tmpfiles[1541]: ACLs are not supported, ignoring. Oct 30 00:04:05.012799 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 30 00:04:05.022171 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 30 00:04:05.131797 kernel: loop1: detected capacity change from 0 to 128016 Oct 30 00:04:05.248273 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 30 00:04:05.254932 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 30 00:04:05.305506 systemd-udevd[1547]: Using default interface naming scheme 'v255'. Oct 30 00:04:06.064733 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 30 00:04:06.083281 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 30 00:04:06.126999 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 30 00:04:06.175808 kernel: hv_vmbus: registering driver hyperv_fb Oct 30 00:04:06.192977 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Oct 30 00:04:06.193036 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Oct 30 00:04:06.193066 kernel: loop2: detected capacity change from 0 to 27936 Oct 30 00:04:06.196794 kernel: Console: switching to colour dummy device 80x25 Oct 30 00:04:06.203801 kernel: hv_vmbus: registering driver hv_balloon Oct 30 00:04:06.203853 kernel: Console: switching to colour frame buffer device 128x48 Oct 30 00:04:06.203876 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Oct 30 00:04:06.216301 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 30 00:04:06.235795 kernel: mousedev: PS/2 mouse device common for all mice Oct 30 00:04:06.289119 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 30 00:04:06.319915 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 30 00:04:06.321906 kernel: EDAC MC: Ver: 3.0.0 Oct 30 00:04:06.496469 systemd-networkd[1576]: lo: Link UP Oct 30 00:04:06.496480 systemd-networkd[1576]: lo: Gained carrier Oct 30 00:04:06.497841 systemd-networkd[1576]: Enumeration completed Oct 30 00:04:06.497953 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 30 00:04:06.500662 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Oct 30 00:04:06.501850 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 30 00:04:06.511744 systemd-networkd[1576]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:04:06.511754 systemd-networkd[1576]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 00:04:06.512318 systemd-networkd[1576]: eth0: Link UP Oct 30 00:04:06.512326 systemd-networkd[1576]: eth0: Gained carrier Oct 30 00:04:06.512341 systemd-networkd[1576]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:04:06.545812 systemd-networkd[1576]: eth0: DHCPv4 address 10.200.8.13/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 30 00:04:06.613391 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Oct 30 00:04:06.633273 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Oct 30 00:04:06.635836 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 30 00:04:06.752473 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 30 00:04:07.037802 kernel: loop3: detected capacity change from 0 to 110984 Oct 30 00:04:07.052798 kernel: loop4: detected capacity change from 0 to 128016 Oct 30 00:04:07.070797 kernel: loop5: detected capacity change from 0 to 27936 Oct 30 00:04:07.083084 (sd-merge)[1701]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Oct 30 00:04:07.083544 (sd-merge)[1701]: Merged extensions into '/usr'. Oct 30 00:04:07.086967 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 30 00:04:07.093750 systemd[1]: Starting ensure-sysext.service... Oct 30 00:04:07.097740 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 30 00:04:07.133244 systemd[1]: Reload requested from client PID 1703 ('systemctl') (unit ensure-sysext.service)... Oct 30 00:04:07.133261 systemd[1]: Reloading... Oct 30 00:04:07.152675 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Oct 30 00:04:07.152733 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Oct 30 00:04:07.153137 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 30 00:04:07.153502 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 30 00:04:07.154516 systemd-tmpfiles[1704]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 30 00:04:07.154859 systemd-tmpfiles[1704]: ACLs are not supported, ignoring. Oct 30 00:04:07.154943 systemd-tmpfiles[1704]: ACLs are not supported, ignoring. Oct 30 00:04:07.178346 zram_generator::config[1735]: No configuration found. Oct 30 00:04:07.246398 systemd-tmpfiles[1704]: Detected autofs mount point /boot during canonicalization of boot. Oct 30 00:04:07.246415 systemd-tmpfiles[1704]: Skipping /boot Oct 30 00:04:07.255255 systemd-tmpfiles[1704]: Detected autofs mount point /boot during canonicalization of boot. Oct 30 00:04:07.255270 systemd-tmpfiles[1704]: Skipping /boot Oct 30 00:04:07.446276 systemd[1]: Reloading finished in 312 ms. Oct 30 00:04:07.487821 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 30 00:04:07.501308 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:07.502506 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 30 00:04:07.537903 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 30 00:04:07.539626 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:04:07.540736 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:04:07.541898 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:04:07.543243 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 00:04:07.543929 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:04:07.544044 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). Oct 30 00:04:07.546354 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 30 00:04:07.548552 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 30 00:04:07.550065 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 30 00:04:07.550559 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:07.552410 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:04:07.552597 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:04:07.553207 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:04:07.553381 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:04:07.553930 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 00:04:07.554104 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 00:04:07.556956 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 00:04:07.557099 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 00:04:07.575081 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 30 00:04:07.591180 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:07.591379 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:04:07.592908 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:04:07.595432 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:04:07.596936 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 30 00:04:07.597383 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:04:07.597680 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). Oct 30 00:04:07.597789 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:07.599102 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:04:07.599278 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:04:07.601470 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:04:07.601670 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:04:07.605219 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 30 00:04:07.605389 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 30 00:04:07.607865 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:07.608130 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 30 00:04:07.609148 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 30 00:04:07.610742 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 30 00:04:07.612217 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 30 00:04:07.612927 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 30 00:04:07.612964 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). Oct 30 00:04:07.613022 systemd[1]: Reached target time-set.target - System Time Set. Oct 30 00:04:07.613574 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 30 00:04:07.614052 systemd[1]: Finished ensure-sysext.service. Oct 30 00:04:07.615310 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 30 00:04:07.615486 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 30 00:04:07.617017 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 00:04:07.617182 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 30 00:04:07.618573 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 30 00:04:07.618753 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 30 00:04:07.620417 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 30 00:04:07.620465 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 30 00:04:07.771584 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 30 00:04:07.784968 systemd-resolved[1802]: Positive Trust Anchors: Oct 30 00:04:07.784986 systemd-resolved[1802]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 00:04:07.785018 systemd-resolved[1802]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 30 00:04:07.801489 systemd-resolved[1802]: Using system hostname 'ci-4459.1.0-n-802fc0fb2f'. Oct 30 00:04:07.803147 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 30 00:04:07.807857 systemd[1]: Reached target network.target - Network. Oct 30 00:04:07.811625 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 30 00:04:07.904889 systemd-networkd[1576]: eth0: Gained IPv6LL Oct 30 00:04:07.907018 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 30 00:04:07.912648 systemd[1]: Reached target network-online.target - Network is Online. Oct 30 00:04:07.972885 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 30 00:04:07.991245 augenrules[1847]: No rules Oct 30 00:04:07.992408 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 00:04:07.992648 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 30 00:04:10.241373 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 30 00:04:10.247244 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). Oct 30 00:04:14.497329 ldconfig[1517]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 30 00:04:14.511578 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 30 00:04:14.518309 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 30 00:04:14.564910 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 30 00:04:14.569870 systemd[1]: Reached target sysinit.target - System Initialization. Oct 30 00:04:14.574509 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 30 00:04:14.579626 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 30 00:04:14.584863 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Oct 30 00:04:14.589980 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 30 00:04:14.594740 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 30 00:04:14.600275 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 30 00:04:14.605517 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 30 00:04:14.605556 systemd[1]: Reached target paths.target - Path Units. Oct 30 00:04:14.611398 systemd[1]: Reached target timers.target - Timer Units. Oct 30 00:04:14.627928 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 30 00:04:14.633488 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 30 00:04:14.639363 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Oct 30 00:04:14.644541 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Oct 30 00:04:14.650163 systemd[1]: Reached target ssh-access.target - SSH Access Available. Oct 30 00:04:14.656679 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 30 00:04:14.661075 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Oct 30 00:04:14.666307 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 30 00:04:14.671616 systemd[1]: Reached target sockets.target - Socket Units. Oct 30 00:04:14.675586 systemd[1]: Reached target basic.target - Basic System. Oct 30 00:04:14.679382 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 30 00:04:14.679410 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 30 00:04:14.697579 systemd[1]: Starting chronyd.service - NTP client/server... Oct 30 00:04:14.702048 systemd[1]: Starting containerd.service - containerd container runtime... Oct 30 00:04:14.716625 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 30 00:04:14.721792 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 30 00:04:14.726278 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 30 00:04:14.732308 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 30 00:04:14.737430 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 30 00:04:14.741440 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). Oct 30 00:04:14.742374 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Oct 30 00:04:14.746684 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Oct 30 00:04:14.747630 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Oct 30 00:04:14.752234 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Oct 30 00:04:14.753239 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 30 00:04:14.755963 jq[1867]: false Oct 30 00:04:14.758297 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 30 00:04:14.763188 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 30 00:04:14.770916 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 30 00:04:14.777920 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 30 00:04:14.782860 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 30 00:04:14.783290 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 30 00:04:14.784935 systemd[1]: Starting update-engine.service - Update Engine... Oct 30 00:04:14.789333 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 30 00:04:14.796155 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 30 00:04:14.801468 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 30 00:04:14.801749 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 30 00:04:14.802070 KVP[1870]: KVP starting; pid is:1870 Oct 30 00:04:14.802180 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 30 00:04:14.802415 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 30 00:04:14.804194 jq[1883]: true Oct 30 00:04:14.813195 extend-filesystems[1868]: Found /dev/sdb6 Oct 30 00:04:14.816337 jq[1885]: true Oct 30 00:04:14.849477 extend-filesystems[1868]: Found /dev/sdb9 Oct 30 00:04:14.852375 extend-filesystems[1868]: Checking size of /dev/sdb9 Oct 30 00:04:14.882179 chronyd[1859]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Oct 30 00:04:14.884160 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Refreshing passwd entry cache Oct 30 00:04:14.883921 oslogin_cache_refresh[1869]: Refreshing passwd entry cache Oct 30 00:04:14.885185 (ntainerd)[1913]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 30 00:04:14.885861 KVP[1870]: KVP LIC Version: 3.1 Oct 30 00:04:14.886796 kernel: hv_utils: KVP IC version 4.0 Oct 30 00:04:14.887129 systemd[1]: motdgen.service: Deactivated successfully. Oct 30 00:04:14.887374 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 30 00:04:14.891024 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Failure getting users, quitting Oct 30 00:04:14.891024 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 30 00:04:14.891009 oslogin_cache_refresh[1869]: Failure getting users, quitting Oct 30 00:04:14.891152 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Refreshing group entry cache Oct 30 00:04:14.891024 oslogin_cache_refresh[1869]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Oct 30 00:04:14.891065 oslogin_cache_refresh[1869]: Refreshing group entry cache Oct 30 00:04:14.901153 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Failure getting groups, quitting Oct 30 00:04:14.901153 google_oslogin_nss_cache[1869]: oslogin_cache_refresh[1869]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 30 00:04:14.901142 oslogin_cache_refresh[1869]: Failure getting groups, quitting Oct 30 00:04:14.901317 update_engine[1882]: I20251030 00:04:14.901117 1882 main.cc:92] Flatcar Update Engine starting Oct 30 00:04:14.901154 oslogin_cache_refresh[1869]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Oct 30 00:04:14.908979 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Oct 30 00:04:14.909217 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Oct 30 00:04:14.930910 chronyd[1859]: Timezone right/UTC failed leap second check, ignoring Oct 30 00:04:14.931088 chronyd[1859]: Loaded seccomp filter (level 2) Oct 30 00:04:14.931199 systemd[1]: Started chronyd.service - NTP client/server. Oct 30 00:04:14.958301 extend-filesystems[1868]: Old size kept for /dev/sdb9 Oct 30 00:04:14.959338 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 30 00:04:14.959580 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 30 00:04:15.019871 systemd-logind[1880]: New seat seat0. Oct 30 00:04:15.023672 systemd-logind[1880]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 30 00:04:15.025582 systemd[1]: Started systemd-logind.service - User Login Management. Oct 30 00:04:15.153009 bash[1908]: Updated "/home/core/.ssh/authorized_keys" Oct 30 00:04:15.154606 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 30 00:04:15.160412 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Oct 30 00:04:15.171843 setup-nvidia[1872]: Downloading Flatcar Container Linux Developer Container for version: 4459.1.0+nightly-20251029-2100 Oct 30 00:04:15.276640 dbus-daemon[1862]: [system] SELinux support is enabled Oct 30 00:04:15.276877 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 30 00:04:15.283750 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). Oct 30 00:04:15.283799 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 30 00:04:15.283910 update_engine[1882]: I20251030 00:04:15.283783 1882 update_check_scheduler.cc:74] Next update check in 11m22s Oct 30 00:04:15.289025 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). Oct 30 00:04:15.289049 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 30 00:04:15.294257 systemd[1]: Started update-engine.service - Update Engine. Oct 30 00:04:15.298714 dbus-daemon[1862]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 30 00:04:15.299545 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 30 00:04:15.383885 coreos-metadata[1861]: Oct 30 00:04:15.383 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Oct 30 00:04:15.386404 coreos-metadata[1861]: Oct 30 00:04:15.386 INFO Fetch successful Oct 30 00:04:15.386572 coreos-metadata[1861]: Oct 30 00:04:15.386 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Oct 30 00:04:15.390684 coreos-metadata[1861]: Oct 30 00:04:15.390 INFO Fetch successful Oct 30 00:04:15.390994 coreos-metadata[1861]: Oct 30 00:04:15.390 INFO Fetching http://168.63.129.16/machine/2e6a7491-d17c-4a66-8894-0c87f3afe2d9/6315ddf4%2D6caf%2D4633%2D8431%2D9225031945d1.%5Fci%2D4459.1.0%2Dn%2D802fc0fb2f?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Oct 30 00:04:15.417692 coreos-metadata[1861]: Oct 30 00:04:15.417 INFO Fetch successful Oct 30 00:04:15.417898 coreos-metadata[1861]: Oct 30 00:04:15.417 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Oct 30 00:04:15.425353 coreos-metadata[1861]: Oct 30 00:04:15.425 INFO Fetch successful Oct 30 00:04:15.461209 locksmithd[1998]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 30 00:04:15.463659 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 30 00:04:15.468753 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 30 00:04:15.477295 sshd_keygen[1922]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 30 00:04:15.498910 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 30 00:04:15.504704 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 30 00:04:15.530105 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Oct 30 00:04:15.536399 systemd[1]: issuegen.service: Deactivated successfully. Oct 30 00:04:15.536627 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 30 00:04:15.544048 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 30 00:04:15.553989 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Oct 30 00:04:15.576890 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 30 00:04:15.583133 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 30 00:04:15.588073 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 30 00:04:15.593025 systemd[1]: Reached target getty.target - Login Prompts. Oct 30 00:04:15.695322 setup-nvidia[2031]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 30 00:04:15.695616 setup-nvidia[2031]: Dload Upload Total Spent Left Speed Oct 30 00:04:16.293319 containerd[1913]: time="2025-10-30T00:04:16Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Oct 30 00:04:16.293997 containerd[1913]: time="2025-10-30T00:04:16.293965800Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Oct 30 00:04:16.303979 containerd[1913]: time="2025-10-30T00:04:16.303937300Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.6µs" Oct 30 00:04:16.303979 containerd[1913]: time="2025-10-30T00:04:16.303967400Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Oct 30 00:04:16.304049 containerd[1913]: time="2025-10-30T00:04:16.303985300Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Oct 30 00:04:16.304150 containerd[1913]: time="2025-10-30T00:04:16.304125600Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Oct 30 00:04:16.304150 containerd[1913]: time="2025-10-30T00:04:16.304147300Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Oct 30 00:04:16.304211 containerd[1913]: time="2025-10-30T00:04:16.304171100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304246 containerd[1913]: time="2025-10-30T00:04:16.304229000Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304246 containerd[1913]: time="2025-10-30T00:04:16.304244200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304482 containerd[1913]: time="2025-10-30T00:04:16.304457600Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304482 containerd[1913]: time="2025-10-30T00:04:16.304477400Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304581 containerd[1913]: time="2025-10-30T00:04:16.304488300Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304581 containerd[1913]: time="2025-10-30T00:04:16.304497000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304632 containerd[1913]: time="2025-10-30T00:04:16.304582200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304845 containerd[1913]: time="2025-10-30T00:04:16.304821100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304881 containerd[1913]: time="2025-10-30T00:04:16.304855500Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Oct 30 00:04:16.304881 containerd[1913]: time="2025-10-30T00:04:16.304866800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Oct 30 00:04:16.304928 containerd[1913]: time="2025-10-30T00:04:16.304894900Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Oct 30 00:04:16.305112 containerd[1913]: time="2025-10-30T00:04:16.305087100Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Oct 30 00:04:16.305177 containerd[1913]: time="2025-10-30T00:04:16.305156500Z" level=info msg="metadata content store policy set" policy=shared Oct 30 00:04:16.331545 containerd[1913]: time="2025-10-30T00:04:16.331508500Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Oct 30 00:04:16.331601 containerd[1913]: time="2025-10-30T00:04:16.331564000Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Oct 30 00:04:16.331601 containerd[1913]: time="2025-10-30T00:04:16.331583900Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Oct 30 00:04:16.331601 containerd[1913]: time="2025-10-30T00:04:16.331597500Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Oct 30 00:04:16.331679 containerd[1913]: time="2025-10-30T00:04:16.331610200Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Oct 30 00:04:16.331679 containerd[1913]: time="2025-10-30T00:04:16.331622900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Oct 30 00:04:16.331679 containerd[1913]: time="2025-10-30T00:04:16.331638700Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Oct 30 00:04:16.331679 containerd[1913]: time="2025-10-30T00:04:16.331651300Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Oct 30 00:04:16.331679 containerd[1913]: time="2025-10-30T00:04:16.331662700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Oct 30 00:04:16.331801 containerd[1913]: time="2025-10-30T00:04:16.331680500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Oct 30 00:04:16.331801 containerd[1913]: time="2025-10-30T00:04:16.331692200Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Oct 30 00:04:16.331801 containerd[1913]: time="2025-10-30T00:04:16.331705200Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Oct 30 00:04:16.331873 containerd[1913]: time="2025-10-30T00:04:16.331850100Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Oct 30 00:04:16.331898 containerd[1913]: time="2025-10-30T00:04:16.331874700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Oct 30 00:04:16.331898 containerd[1913]: time="2025-10-30T00:04:16.331893400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Oct 30 00:04:16.331937 containerd[1913]: time="2025-10-30T00:04:16.331905700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Oct 30 00:04:16.331937 containerd[1913]: time="2025-10-30T00:04:16.331916800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Oct 30 00:04:16.331937 containerd[1913]: time="2025-10-30T00:04:16.331927600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Oct 30 00:04:16.331999 containerd[1913]: time="2025-10-30T00:04:16.331938300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Oct 30 00:04:16.331999 containerd[1913]: time="2025-10-30T00:04:16.331950000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Oct 30 00:04:16.331999 containerd[1913]: time="2025-10-30T00:04:16.331962400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Oct 30 00:04:16.331999 containerd[1913]: time="2025-10-30T00:04:16.331973600Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Oct 30 00:04:16.332084 containerd[1913]: time="2025-10-30T00:04:16.332002000Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Oct 30 00:04:16.332084 containerd[1913]: time="2025-10-30T00:04:16.332072700Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Oct 30 00:04:16.332134 containerd[1913]: time="2025-10-30T00:04:16.332090400Z" level=info msg="Start snapshots syncer" Oct 30 00:04:16.332134 containerd[1913]: time="2025-10-30T00:04:16.332121900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Oct 30 00:04:16.332390 containerd[1913]: time="2025-10-30T00:04:16.332347900Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Oct 30 00:04:16.332506 containerd[1913]: time="2025-10-30T00:04:16.332412100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Oct 30 00:04:16.332506 containerd[1913]: time="2025-10-30T00:04:16.332478600Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Oct 30 00:04:16.332614 containerd[1913]: time="2025-10-30T00:04:16.332586500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Oct 30 00:04:16.332650 containerd[1913]: time="2025-10-30T00:04:16.332631400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Oct 30 00:04:16.332677 containerd[1913]: time="2025-10-30T00:04:16.332648800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Oct 30 00:04:16.332677 containerd[1913]: time="2025-10-30T00:04:16.332661500Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Oct 30 00:04:16.332677 containerd[1913]: time="2025-10-30T00:04:16.332674400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Oct 30 00:04:16.332740 containerd[1913]: time="2025-10-30T00:04:16.332685400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Oct 30 00:04:16.332740 containerd[1913]: time="2025-10-30T00:04:16.332696500Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Oct 30 00:04:16.332740 containerd[1913]: time="2025-10-30T00:04:16.332718200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Oct 30 00:04:16.332740 containerd[1913]: time="2025-10-30T00:04:16.332731100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Oct 30 00:04:16.332848 containerd[1913]: time="2025-10-30T00:04:16.332742200Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Oct 30 00:04:16.332848 containerd[1913]: time="2025-10-30T00:04:16.332800900Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 30 00:04:16.332848 containerd[1913]: time="2025-10-30T00:04:16.332821000Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Oct 30 00:04:16.332848 containerd[1913]: time="2025-10-30T00:04:16.332831500Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 30 00:04:16.332848 containerd[1913]: time="2025-10-30T00:04:16.332841200Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Oct 30 00:04:16.332848 containerd[1913]: time="2025-10-30T00:04:16.332849100Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332858800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332885600Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332908900Z" level=info msg="runtime interface created" Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332915500Z" level=info msg="created NRI interface" Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332925600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332937700Z" level=info msg="Connect containerd service" Oct 30 00:04:16.332975 containerd[1913]: time="2025-10-30T00:04:16.332964100Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 30 00:04:16.333708 containerd[1913]: time="2025-10-30T00:04:16.333674800Z" 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" Oct 30 00:04:17.275706 containerd[1913]: time="2025-10-30T00:04:17.275631500Z" level=info msg="Start subscribing containerd event" Oct 30 00:04:17.275706 containerd[1913]: time="2025-10-30T00:04:17.275691400Z" level=info msg="Start recovering state" Oct 30 00:04:17.275877 containerd[1913]: time="2025-10-30T00:04:17.275808600Z" level=info msg="Start event monitor" Oct 30 00:04:17.275877 containerd[1913]: time="2025-10-30T00:04:17.275827500Z" level=info msg="Start cni network conf syncer for default" Oct 30 00:04:17.275877 containerd[1913]: time="2025-10-30T00:04:17.275835300Z" level=info msg="Start streaming server" Oct 30 00:04:17.275877 containerd[1913]: time="2025-10-30T00:04:17.275853200Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Oct 30 00:04:17.275877 containerd[1913]: time="2025-10-30T00:04:17.275862600Z" level=info msg="runtime interface starting up..." Oct 30 00:04:17.275877 containerd[1913]: time="2025-10-30T00:04:17.275869000Z" level=info msg="starting plugins..." Oct 30 00:04:17.276011 containerd[1913]: time="2025-10-30T00:04:17.275882500Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Oct 30 00:04:17.276168 containerd[1913]: time="2025-10-30T00:04:17.276135500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 30 00:04:17.276226 containerd[1913]: time="2025-10-30T00:04:17.276207300Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 30 00:04:17.276311 containerd[1913]: time="2025-10-30T00:04:17.276293800Z" level=info msg="containerd successfully booted in 0.983352s" Oct 30 00:04:17.276410 systemd[1]: Started containerd.service - containerd container runtime. Oct 30 00:04:18.308995 waagent[2027]: 2025-10-30T00:04:18.308905Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Oct 30 00:04:18.313585 waagent[2027]: 2025-10-30T00:04:18.313536Z INFO Daemon Daemon OS: flatcar 4459.1.0 Oct 30 00:04:18.317315 waagent[2027]: 2025-10-30T00:04:18.317265Z INFO Daemon Daemon Python: 3.11.13 Oct 30 00:04:18.320894 waagent[2027]: 2025-10-30T00:04:18.320849Z INFO Daemon Daemon Run daemon Oct 30 00:04:18.324134 waagent[2027]: 2025-10-30T00:04:18.324091Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.1.0' Oct 30 00:04:18.331008 waagent[2027]: 2025-10-30T00:04:18.330958Z INFO Daemon Daemon Using waagent for provisioning Oct 30 00:04:18.335259 waagent[2027]: 2025-10-30T00:04:18.335218Z INFO Daemon Daemon Activate resource disk Oct 30 00:04:18.339088 waagent[2027]: 2025-10-30T00:04:18.339047Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Oct 30 00:04:18.346634 waagent[2027]: 2025-10-30T00:04:18.346592Z INFO Daemon Daemon Found device: sda Oct 30 00:04:18.355740 waagent[2027]: 2025-10-30T00:04:18.355698Z INFO Daemon Daemon Examining partition table Oct 30 00:04:18.454633 waagent[2027]: 2025-10-30T00:04:18.454555Z INFO Daemon Daemon GPT not detected, determining filesystem Oct 30 00:04:18.576483 waagent[2027]: 2025-10-30T00:04:18.576360Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Oct 30 00:04:18.581581 waagent[2027]: 2025-10-30T00:04:18.581533Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Oct 30 00:04:19.138748 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#194 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:04:19.145836 kernel: sda: sda1 Oct 30 00:04:19.170715 waagent[2027]: 2025-10-30T00:04:19.170645Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Oct 30 00:04:19.188796 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#121 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Oct 30 00:04:19.201795 kernel: sda: sda1 Oct 30 00:04:19.201849 waagent[2027]: 2025-10-30T00:04:19.201806Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Oct 30 00:04:23.387009 login[2029]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Oct 30 00:04:23.444949 login[2030]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 30 00:04:23.451807 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 30 00:04:23.453025 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 30 00:04:23.459367 systemd-logind[1880]: New session 2 of user core. Oct 30 00:04:23.540054 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 30 00:04:23.542704 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 30 00:04:23.631222 (systemd)[2124]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 30 00:04:23.633810 systemd-logind[1880]: New session c1 of user core. Oct 30 00:04:24.387438 login[2029]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 30 00:04:24.392336 systemd-logind[1880]: New session 1 of user core. Oct 30 00:04:24.750222 waagent[2027]: 2025-10-30T00:04:24.750137Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Oct 30 00:04:24.816123 systemd[2124]: Queued start job for default target default.target. Oct 30 00:04:24.831010 systemd[2124]: Created slice app.slice - User Application Slice. Oct 30 00:04:24.831055 systemd[2124]: Reached target paths.target - Paths. Oct 30 00:04:24.831103 systemd[2124]: Reached target timers.target - Timers. Oct 30 00:04:24.832361 systemd[2124]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 30 00:04:24.843505 systemd[2124]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 30 00:04:24.843642 systemd[2124]: Reached target sockets.target - Sockets. Oct 30 00:04:24.843707 systemd[2124]: Reached target basic.target - Basic System. Oct 30 00:04:24.843763 systemd[2124]: Reached target default.target - Main User Target. Oct 30 00:04:24.843813 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 30 00:04:24.843826 systemd[2124]: Startup finished in 1.203s. Oct 30 00:04:24.845176 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 30 00:04:24.845891 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 30 00:04:24.871802 kernel: EXT4-fs (sda1): mounted filesystem 4e14ee9b-f40d-4967-8240-3fe16684f8b9 r/w with ordered data mode. Quota mode: none. Oct 30 00:04:24.872173 waagent[2027]: 2025-10-30T00:04:24.872103Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Oct 30 00:04:24.874018 waagent[2027]: 2025-10-30T00:04:24.873958Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 30 00:04:24.875598 waagent[2027]: 2025-10-30T00:04:24.875552Z INFO Daemon Daemon Running default provisioning handler Oct 30 00:04:24.883138 waagent[2027]: 2025-10-30T00:04:24.883088Z 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. Oct 30 00:04:24.884386 waagent[2027]: 2025-10-30T00:04:24.884347Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Oct 30 00:04:24.884790 waagent[2027]: 2025-10-30T00:04:24.884742Z INFO Daemon Daemon cloud-init is enabled: False Oct 30 00:04:24.885527 waagent[2027]: 2025-10-30T00:04:24.885493Z INFO Daemon Daemon Copying ovf-env.xml Oct 30 00:04:25.045913 waagent[2027]: 2025-10-30T00:04:25.045793Z INFO Daemon Daemon Successfully mounted dvd Oct 30 00:04:25.074887 waagent[2027]: 2025-10-30T00:04:25.074832Z INFO Daemon Daemon Detect protocol endpoint Oct 30 00:04:25.074867 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Oct 30 00:04:25.076505 waagent[2027]: 2025-10-30T00:04:25.076454Z INFO Daemon Daemon Clean protocol and wireserver endpoint Oct 30 00:04:25.077975 waagent[2027]: 2025-10-30T00:04:25.077925Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Oct 30 00:04:25.078668 waagent[2027]: 2025-10-30T00:04:25.078632Z INFO Daemon Daemon Test for route to 168.63.129.16 Oct 30 00:04:25.080270 waagent[2027]: 2025-10-30T00:04:25.080227Z INFO Daemon Daemon Route to 168.63.129.16 exists Oct 30 00:04:25.081625 waagent[2027]: 2025-10-30T00:04:25.081588Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Oct 30 00:04:25.092964 waagent[2027]: 2025-10-30T00:04:25.092918Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Oct 30 00:04:25.093699 waagent[2027]: 2025-10-30T00:04:25.093671Z INFO Daemon Daemon Wire protocol version:2012-11-30 Oct 30 00:04:25.095090 waagent[2027]: 2025-10-30T00:04:25.095045Z INFO Daemon Daemon Server preferred version:2015-04-05 Oct 30 00:04:25.269316 waagent[2027]: 2025-10-30T00:04:25.269227Z INFO Daemon Daemon Initializing goal state during protocol detection Oct 30 00:04:25.270888 waagent[2027]: 2025-10-30T00:04:25.270833Z INFO Daemon Daemon Forcing an update of the goal state. Oct 30 00:04:25.280936 waagent[2027]: 2025-10-30T00:04:25.280889Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 30 00:04:25.310433 waagent[2027]: 2025-10-30T00:04:25.310354Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Oct 30 00:04:25.312369 waagent[2027]: 2025-10-30T00:04:25.312328Z INFO Daemon Oct 30 00:04:25.312732 waagent[2027]: 2025-10-30T00:04:25.312699Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 90d2660e-5816-43cd-a6fa-867c5ee759b7 eTag: 16152976717575181576 source: Fabric] Oct 30 00:04:25.313732 waagent[2027]: 2025-10-30T00:04:25.313692Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Oct 30 00:04:25.314471 waagent[2027]: 2025-10-30T00:04:25.314432Z INFO Daemon Oct 30 00:04:25.315696 waagent[2027]: 2025-10-30T00:04:25.315660Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Oct 30 00:04:25.320720 waagent[2027]: 2025-10-30T00:04:25.320678Z INFO Daemon Daemon Downloading artifacts profile blob Oct 30 00:04:25.447440 waagent[2027]: 2025-10-30T00:04:25.447374Z INFO Daemon Downloaded certificate {'thumbprint': 'D9B081858CE76E09FA5237BAE04D7D9B7A569193', 'hasPrivateKey': True} Oct 30 00:04:25.449319 waagent[2027]: 2025-10-30T00:04:25.449277Z INFO Daemon Fetch goal state completed Oct 30 00:04:25.493057 waagent[2027]: 2025-10-30T00:04:25.493014Z INFO Daemon Daemon Starting provisioning Oct 30 00:04:25.495624 waagent[2027]: 2025-10-30T00:04:25.495573Z INFO Daemon Daemon Handle ovf-env.xml. Oct 30 00:04:25.498221 waagent[2027]: 2025-10-30T00:04:25.498174Z INFO Daemon Daemon Set hostname [ci-4459.1.0-n-802fc0fb2f] Oct 30 00:04:25.525058 waagent[2027]: 2025-10-30T00:04:25.525019Z INFO Daemon Daemon Publish hostname [ci-4459.1.0-n-802fc0fb2f] Oct 30 00:04:25.526904 waagent[2027]: 2025-10-30T00:04:25.526856Z INFO Daemon Daemon Examine /proc/net/route for primary interface Oct 30 00:04:25.528645 waagent[2027]: 2025-10-30T00:04:25.528602Z INFO Daemon Daemon Primary interface is [eth0] Oct 30 00:04:25.556077 systemd-networkd[1576]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 30 00:04:25.556088 systemd-networkd[1576]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 30 00:04:25.556114 systemd-networkd[1576]: eth0: DHCP lease lost Oct 30 00:04:25.556935 waagent[2027]: 2025-10-30T00:04:25.556874Z INFO Daemon Daemon Create user account if not exists Oct 30 00:04:25.557916 waagent[2027]: 2025-10-30T00:04:25.557871Z INFO Daemon Daemon User core already exists, skip useradd Oct 30 00:04:25.558055 waagent[2027]: 2025-10-30T00:04:25.557999Z INFO Daemon Daemon Configure sudoer Oct 30 00:04:25.562840 waagent[2027]: 2025-10-30T00:04:25.562755Z INFO Daemon Daemon Configure sshd Oct 30 00:04:25.566951 waagent[2027]: 2025-10-30T00:04:25.566912Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Oct 30 00:04:25.567095 waagent[2027]: 2025-10-30T00:04:25.567062Z INFO Daemon Daemon Deploy ssh public key. Oct 30 00:04:25.647817 systemd-networkd[1576]: eth0: DHCPv4 address 10.200.8.13/24, gateway 10.200.8.1 acquired from 168.63.129.16 Oct 30 00:04:26.921766 waagent[2027]: 2025-10-30T00:04:26.921696Z INFO Daemon Daemon Provisioning complete Oct 30 00:04:26.931786 waagent[2027]: 2025-10-30T00:04:26.931737Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Oct 30 00:04:26.933455 waagent[2027]: 2025-10-30T00:04:26.933415Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Oct 30 00:04:26.934912 waagent[2027]: 2025-10-30T00:04:26.934878Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Oct 30 00:04:27.085955 waagent[2182]: 2025-10-30T00:04:27.085857Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Oct 30 00:04:27.086299 waagent[2182]: 2025-10-30T00:04:27.086005Z INFO ExtHandler ExtHandler OS: flatcar 4459.1.0 Oct 30 00:04:27.086299 waagent[2182]: 2025-10-30T00:04:27.086074Z INFO ExtHandler ExtHandler Python: 3.11.13 Oct 30 00:04:27.086299 waagent[2182]: 2025-10-30T00:04:27.086142Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Oct 30 00:04:27.178889 waagent[2182]: 2025-10-30T00:04:27.178729Z INFO ExtHandler ExtHandler Distro: flatcar-4459.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Oct 30 00:04:27.179039 waagent[2182]: 2025-10-30T00:04:27.179000Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 30 00:04:27.179171 waagent[2182]: 2025-10-30T00:04:27.179085Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 30 00:04:27.185870 waagent[2182]: 2025-10-30T00:04:27.185815Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Oct 30 00:04:27.190517 waagent[2182]: 2025-10-30T00:04:27.190476Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Oct 30 00:04:27.190973 waagent[2182]: 2025-10-30T00:04:27.190933Z INFO ExtHandler Oct 30 00:04:27.191057 waagent[2182]: 2025-10-30T00:04:27.191025Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 96d6e8ab-5203-4d3c-8a4a-c3db5ce6ec20 eTag: 16152976717575181576 source: Fabric] Oct 30 00:04:27.191369 waagent[2182]: 2025-10-30T00:04:27.191333Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Oct 30 00:04:27.192000 waagent[2182]: 2025-10-30T00:04:27.191962Z INFO ExtHandler Oct 30 00:04:27.192074 waagent[2182]: 2025-10-30T00:04:27.192043Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Oct 30 00:04:27.195495 waagent[2182]: 2025-10-30T00:04:27.195464Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Oct 30 00:04:27.258979 waagent[2182]: 2025-10-30T00:04:27.258919Z INFO ExtHandler Downloaded certificate {'thumbprint': 'D9B081858CE76E09FA5237BAE04D7D9B7A569193', 'hasPrivateKey': True} Oct 30 00:04:27.259487 waagent[2182]: 2025-10-30T00:04:27.259439Z INFO ExtHandler Fetch goal state completed Oct 30 00:04:27.270822 waagent[2182]: 2025-10-30T00:04:27.270754Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Oct 30 00:04:27.276651 waagent[2182]: 2025-10-30T00:04:27.276605Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2182 Oct 30 00:04:27.276801 waagent[2182]: 2025-10-30T00:04:27.276751Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Oct 30 00:04:27.277142 waagent[2182]: 2025-10-30T00:04:27.277105Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Oct 30 00:04:27.279585 waagent[2182]: 2025-10-30T00:04:27.279536Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] Oct 30 00:04:27.280051 waagent[2182]: 2025-10-30T00:04:27.280013Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.1.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Oct 30 00:04:27.280208 waagent[2182]: 2025-10-30T00:04:27.280172Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Oct 30 00:04:27.282797 waagent[2182]: 2025-10-30T00:04:27.282706Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Oct 30 00:04:27.413457 waagent[2182]: 2025-10-30T00:04:27.413411Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Oct 30 00:04:27.413654 waagent[2182]: 2025-10-30T00:04:27.413617Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Oct 30 00:04:27.420210 waagent[2182]: 2025-10-30T00:04:27.420183Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Oct 30 00:04:27.426849 systemd[1]: Reload requested from client PID 2197 ('systemctl') (unit waagent.service)... Oct 30 00:04:27.426865 systemd[1]: Reloading... Oct 30 00:04:27.491799 zram_generator::config[2239]: No configuration found. Oct 30 00:04:27.780264 systemd[1]: Reloading finished in 353 ms. Oct 30 00:04:27.809682 waagent[2182]: 2025-10-30T00:04:27.809611Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Oct 30 00:04:27.809806 waagent[2182]: 2025-10-30T00:04:27.809755Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Oct 30 00:04:28.208405 waagent[2182]: 2025-10-30T00:04:28.208330Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Oct 30 00:04:28.208751 waagent[2182]: 2025-10-30T00:04:28.208663Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Oct 30 00:04:28.209470 waagent[2182]: 2025-10-30T00:04:28.209423Z INFO ExtHandler ExtHandler Starting env monitor service. Oct 30 00:04:28.209879 waagent[2182]: 2025-10-30T00:04:28.209826Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 30 00:04:28.210059 waagent[2182]: 2025-10-30T00:04:28.210017Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Oct 30 00:04:28.210296 waagent[2182]: 2025-10-30T00:04:28.210258Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 30 00:04:28.210354 waagent[2182]: 2025-10-30T00:04:28.210311Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Oct 30 00:04:28.210682 waagent[2182]: 2025-10-30T00:04:28.210635Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Oct 30 00:04:28.210767 waagent[2182]: 2025-10-30T00:04:28.210726Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Oct 30 00:04:28.211194 waagent[2182]: 2025-10-30T00:04:28.211138Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Oct 30 00:04:28.211536 waagent[2182]: 2025-10-30T00:04:28.211493Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Oct 30 00:04:28.211874 waagent[2182]: 2025-10-30T00:04:28.211827Z INFO EnvHandler ExtHandler Configure routes Oct 30 00:04:28.212019 waagent[2182]: 2025-10-30T00:04:28.211983Z INFO EnvHandler ExtHandler Gateway:None Oct 30 00:04:28.212209 waagent[2182]: 2025-10-30T00:04:28.212164Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Oct 30 00:04:28.212209 waagent[2182]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Oct 30 00:04:28.212209 waagent[2182]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Oct 30 00:04:28.212209 waagent[2182]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Oct 30 00:04:28.212209 waagent[2182]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Oct 30 00:04:28.212209 waagent[2182]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 30 00:04:28.212209 waagent[2182]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Oct 30 00:04:28.212365 waagent[2182]: 2025-10-30T00:04:28.212231Z INFO EnvHandler ExtHandler Routes:None Oct 30 00:04:28.214352 waagent[2182]: 2025-10-30T00:04:28.214293Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Oct 30 00:04:28.214411 waagent[2182]: 2025-10-30T00:04:28.214352Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Oct 30 00:04:28.214970 waagent[2182]: 2025-10-30T00:04:28.214926Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Oct 30 00:04:28.222189 waagent[2182]: 2025-10-30T00:04:28.221707Z INFO ExtHandler ExtHandler Oct 30 00:04:28.222189 waagent[2182]: 2025-10-30T00:04:28.221908Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 2be39e56-710c-4883-8368-2fed55c24aad correlation 0b0f719a-096d-4588-bd6a-fc3ffed7f5df created: 2025-10-30T00:02:45.055794Z] Oct 30 00:04:28.223170 waagent[2182]: 2025-10-30T00:04:28.223126Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Oct 30 00:04:28.225145 waagent[2182]: 2025-10-30T00:04:28.225093Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Oct 30 00:04:28.265242 waagent[2182]: 2025-10-30T00:04:28.265197Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Oct 30 00:04:28.265242 waagent[2182]: Try `iptables -h' or 'iptables --help' for more information.) Oct 30 00:04:28.265624 waagent[2182]: 2025-10-30T00:04:28.265585Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: D591DA36-9DB2-426A-AE04-E627B270B5F1;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Oct 30 00:04:28.293313 waagent[2182]: 2025-10-30T00:04:28.293259Z INFO MonitorHandler ExtHandler Network interfaces: Oct 30 00:04:28.293313 waagent[2182]: Executing ['ip', '-a', '-o', 'link']: Oct 30 00:04:28.293313 waagent[2182]: 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 Oct 30 00:04:28.293313 waagent[2182]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:fc:75:70 brd ff:ff:ff:ff:ff:ff Oct 30 00:04:28.293313 waagent[2182]: Executing ['ip', '-4', '-a', '-o', 'address']: Oct 30 00:04:28.293313 waagent[2182]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Oct 30 00:04:28.293313 waagent[2182]: 2: eth0 inet 10.200.8.13/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Oct 30 00:04:28.293313 waagent[2182]: Executing ['ip', '-6', '-a', '-o', 'address']: Oct 30 00:04:28.293313 waagent[2182]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Oct 30 00:04:28.293313 waagent[2182]: 2: eth0 inet6 fe80::7e1e:52ff:fefc:7570/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Oct 30 00:04:28.396666 waagent[2182]: 2025-10-30T00:04:28.396604Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Oct 30 00:04:28.396666 waagent[2182]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:04:28.396666 waagent[2182]: pkts bytes target prot opt in out source destination Oct 30 00:04:28.396666 waagent[2182]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:04:28.396666 waagent[2182]: pkts bytes target prot opt in out source destination Oct 30 00:04:28.396666 waagent[2182]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:04:28.396666 waagent[2182]: pkts bytes target prot opt in out source destination Oct 30 00:04:28.396666 waagent[2182]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 30 00:04:28.396666 waagent[2182]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 30 00:04:28.396666 waagent[2182]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 30 00:04:28.400073 waagent[2182]: 2025-10-30T00:04:28.400031Z INFO EnvHandler ExtHandler Current Firewall rules: Oct 30 00:04:28.400073 waagent[2182]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:04:28.400073 waagent[2182]: pkts bytes target prot opt in out source destination Oct 30 00:04:28.400073 waagent[2182]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:04:28.400073 waagent[2182]: pkts bytes target prot opt in out source destination Oct 30 00:04:28.400073 waagent[2182]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Oct 30 00:04:28.400073 waagent[2182]: pkts bytes target prot opt in out source destination Oct 30 00:04:28.400073 waagent[2182]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Oct 30 00:04:28.400073 waagent[2182]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Oct 30 00:04:28.400073 waagent[2182]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Oct 30 00:04:28.400316 waagent[2182]: 2025-10-30T00:04:28.400283Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Oct 30 00:04:28.400463 waagent[2182]: 2025-10-30T00:04:28.400433Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Oct 30 00:04:38.711104 chronyd[1859]: Selected source PHC0 Oct 30 00:04:42.037359 setup-nvidia[2031]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 531M 0 128k 0 0 132k 0 1:08:27 --:--:-- 1:08:27 132k\u000d 1 531M 1 9920k 0 0 5050k 0 0:01:47 0:00:01 0:01:46 5050k\u000d 5 531M 5 29.4M 0 0 9.9M 0 0:00:53 0:00:02 0:00:51 9.9M\u000d 9 531M 9 48.7M 0 0 12.6M 0 0:00:42 0:00:03 0:00:39 12.6M\u000d 13 531M 13 69.7M 0 0 14.2M 0 0:00:37 0:00:04 0:00:33 14.2M\u000d 17 531M 17 91.5M 0 0 15.5M 0 0:00:34 0:00:05 0:00:29 18.5M\u000d 21 531M 21 112M 0 0 16.3M 0 0:00:32 0:00:06 0:00:26 20.8M\u000d 25 531M 25 135M 0 0 17.1M 0 0:00:31 0:00:07 0:00:24 21.4M\u000d 29 531M 29 155M 0 0 17.3M 0 0:00:30 0:00:08 0:00:22 20.9M\u000d 33 531M 33 180M 0 0 18.0M 0 0:00:29 0:00:09 0:00:20 21.6M\u000d 38 531M 38 202M 0 0 18.6M 0 0:00:28 0:00:10 0:00:18 22.2M\u000d 42 531M 42 227M 0 0 19.1M 0 0:00:27 0:00:11 0:00:16 23.0M\u000d 46 531M 46 249M 0 0 19.1M 0 0:00:27 0:00:13 0:00:14 22.3M\u000d 49 531M 49 263M 0 0 18.9M 0 0:00:27 0:00:13 0:00:14 22.0M\u000d 53 531M 53 284M 0 0 19.0M 0 0:00:27 0:00:14 0:00:13 21.1M\u000d 57 531M 57 305M 0 0 19.2M 0 0:00:27 0:00:15 0:00:12 20.5M\u000d 61 531M 61 327M 0 0 19.3M 0 0:00:27 0:00:16 0:00:11 19.9M\u000d 65 531M 65 348M 0 0 19.4M 0 0:00:27 0:00:17 0:00:10 20.0M\u000d 69 531M 69 369M 0 0 19.5M 0 0:00:27 0:00:18 0:00:09 20.9M\u000d 73 531M 73 389M 0 0 19.5M 0 0:00:27 0:00:19 0:00:08 20.8M\u000d 77 531M 77 412M 0 0 19.6M 0 0:00:26 0:00:20 0:00:06 21.1M\u000d 81 531M 81 434M 0 0 19.7M 0 0:00:26 0:00:21 0:00:05 21.1M\u000d 85 531M 85 455M 0 0 19.8M 0 0:00:26 0:00:22 0:00:04 21.2M\u000d 89 531M 89 476M 0 0 19.9M 0 0:00:26 0:00:23 0:00:03 21.7M\u000d 93 531M 93 497M 0 0 20.0M 0 0:00:26 0:00:24 0:00:02 21.8M\u000d 97 531M 97 520M 0 0 20.0M 0 0:00:26 0:00:25 0:00:01 21.8M\u000d100 531M 100 531M 0 0 20.1M 0 0:00:26 0:00:26 --:--:-- 22.1M Oct 30 00:04:53.832267 setup-nvidia[1872]: Downloading NVIDIA 535.261.03 Driver Oct 30 00:04:53.836488 setup-nvidia[2340]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 30 00:04:53.836716 setup-nvidia[2340]: Dload Upload Total Spent Left Speed Oct 30 00:04:54.353790 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Oct 30 00:04:56.732953 setup-nvidia[2340]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 325M 0 49152 0 0 58412 0 1:37:31 --:--:-- 1:37:31 58375\u000d 41 325M 41 135M 0 0 78.7M 0 0:00:04 0:00:01 0:00:03 78.7M\u000d 90 325M 90 293M 0 0 107M 0 0:00:03 0:00:02 0:00:01 107M\u000d100 325M 100 325M 0 0 112M 0 0:00:02 0:00:02 --:--:-- 112M Oct 30 00:04:56.903483 setup-nvidia[1872]: Extract the NVIDIA Driver Installer 535.261.03 Oct 30 00:04:56.903483 setup-nvidia[1872]: /opt/nvidia/workdir/nvidia-workdir / Oct 30 00:04:57.068090 setup-nvidia[2343]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Oct 30 00:04:57.369133 setup-nvidia[2343]: Verifying archive integrity... OK Oct 30 00:04:58.192000 setup-nvidia[2343]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Oct 30 00:04:59.989214 setup-nvidia[2369]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Oct 30 00:05:00.093203 setup-nvidia[1872]: / Oct 30 00:05:00.093455 setup-nvidia[1872]: Spawn system-nspawn container to install the NVIDIA drivers Oct 30 00:05:00.410952 update_engine[1882]: I20251030 00:05:00.410826 1882 update_attempter.cc:509] Updating boot flags... Oct 30 00:05:00.525959 sudo[2376]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.1.0+nightly-20251029-2100.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Oct 30 00:05:00.527656 sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 30 00:05:00.536983 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Oct 30 00:05:00.541356 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 30 00:05:00.567688 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 30 00:05:00.576625 systemd[1]: Starting user@0.service - User Manager for UID 0... Oct 30 00:05:00.594847 (systemd)[2419]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Oct 30 00:05:00.600452 systemd-logind[1880]: New session c3 of user root. Oct 30 00:05:00.783702 systemd[2419]: Queued start job for default target default.target. Oct 30 00:05:00.830542 systemd[2419]: Created slice app.slice - User Application Slice. Oct 30 00:05:00.830706 systemd[2419]: Reached target paths.target - Paths. Oct 30 00:05:00.830754 systemd[2419]: Reached target timers.target - Timers. Oct 30 00:05:00.831918 systemd[2419]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 30 00:05:00.862657 systemd[2419]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 30 00:05:00.862800 systemd[2419]: Reached target sockets.target - Sockets. Oct 30 00:05:00.862855 systemd[2419]: Reached target basic.target - Basic System. Oct 30 00:05:00.862898 systemd[2419]: Reached target default.target - Main User Target. Oct 30 00:05:00.862939 systemd[2419]: Startup finished in 256ms. Oct 30 00:05:00.862972 systemd[1]: Started user@0.service - User Manager for UID 0. Oct 30 00:05:00.878917 systemd[1]: Started session-c2.scope - Session c2 of User root. Oct 30 00:05:01.037799 kernel: loop6: detected capacity change from 0 to 12644352 Oct 30 00:05:01.071795 kernel: loop6: p9 Oct 30 00:05:01.411376 setup-nvidia[2504]: ░ Spawning container flatcardevelopercontainer-4459.1.0nightly-20251029-2100.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.1.0+nightly-20251029-2100.bin. Oct 30 00:05:01.471180 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 30 00:05:01.476982 systemd[1]: Started sshd@0-10.200.8.13:22-10.200.16.10:39198.service - OpenSSH per-connection server daemon (10.200.16.10:39198). Oct 30 00:05:02.473264 sshd[2508]: Accepted publickey for core from 10.200.16.10 port 39198 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:02.474478 sshd-session[2508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:02.478670 systemd-logind[1880]: New session 3 of user core. Oct 30 00:05:02.489896 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 30 00:05:03.035365 systemd[1]: Started sshd@1-10.200.8.13:22-10.200.16.10:39204.service - OpenSSH per-connection server daemon (10.200.16.10:39204). Oct 30 00:05:03.664498 sshd[2514]: Accepted publickey for core from 10.200.16.10 port 39204 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:03.665795 sshd-session[2514]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:03.670050 systemd-logind[1880]: New session 4 of user core. Oct 30 00:05:03.681911 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 30 00:05:04.111660 sshd[2517]: Connection closed by 10.200.16.10 port 39204 Oct 30 00:05:04.112041 sshd-session[2514]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:04.115524 systemd[1]: sshd@1-10.200.8.13:22-10.200.16.10:39204.service: Deactivated successfully. Oct 30 00:05:04.117215 systemd[1]: session-4.scope: Deactivated successfully. Oct 30 00:05:04.118584 systemd-logind[1880]: Session 4 logged out. Waiting for processes to exit. Oct 30 00:05:04.119576 systemd-logind[1880]: Removed session 4. Oct 30 00:05:04.224984 systemd[1]: Started sshd@2-10.200.8.13:22-10.200.16.10:39214.service - OpenSSH per-connection server daemon (10.200.16.10:39214). Oct 30 00:05:04.853160 sshd[2523]: Accepted publickey for core from 10.200.16.10 port 39214 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:04.854360 sshd-session[2523]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:04.858894 systemd-logind[1880]: New session 5 of user core. Oct 30 00:05:04.871901 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 30 00:05:05.302562 sshd[2526]: Connection closed by 10.200.16.10 port 39214 Oct 30 00:05:05.302927 sshd-session[2523]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:05.306319 systemd[1]: sshd@2-10.200.8.13:22-10.200.16.10:39214.service: Deactivated successfully. Oct 30 00:05:05.307907 systemd[1]: session-5.scope: Deactivated successfully. Oct 30 00:05:05.308569 systemd-logind[1880]: Session 5 logged out. Waiting for processes to exit. Oct 30 00:05:05.309522 systemd-logind[1880]: Removed session 5. Oct 30 00:05:05.826501 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Oct 30 00:05:05.826623 kernel: EXT4-fs (loop6p9): mounted filesystem ff60a145-de44-4789-82a5-6d59a2c3f56a ro without journal. Quota mode: none. Oct 30 00:05:15.421522 systemd[1]: Started sshd@3-10.200.8.13:22-10.200.16.10:36086.service - OpenSSH per-connection server daemon (10.200.16.10:36086). Oct 30 00:05:16.048323 sshd[2590]: Accepted publickey for core from 10.200.16.10 port 36086 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:16.049552 sshd-session[2590]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:16.053938 systemd-logind[1880]: New session 6 of user core. Oct 30 00:05:16.062909 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 30 00:05:16.498156 sshd[2593]: Connection closed by 10.200.16.10 port 36086 Oct 30 00:05:16.498533 sshd-session[2590]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:16.502247 systemd[1]: sshd@3-10.200.8.13:22-10.200.16.10:36086.service: Deactivated successfully. Oct 30 00:05:16.503945 systemd[1]: session-6.scope: Deactivated successfully. Oct 30 00:05:16.504677 systemd-logind[1880]: Session 6 logged out. Waiting for processes to exit. Oct 30 00:05:16.505639 systemd-logind[1880]: Removed session 6. Oct 30 00:05:16.621312 systemd[1]: Started sshd@4-10.200.8.13:22-10.200.16.10:36096.service - OpenSSH per-connection server daemon (10.200.16.10:36096). Oct 30 00:05:18.008407 sshd[2599]: Accepted publickey for core from 10.200.16.10 port 36096 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:18.008818 sshd-session[2599]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:18.013141 systemd-logind[1880]: New session 7 of user core. Oct 30 00:05:18.022909 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 30 00:05:18.373036 sshd[2602]: Connection closed by 10.200.16.10 port 36096 Oct 30 00:05:18.373333 sshd-session[2599]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:18.376975 systemd[1]: sshd@4-10.200.8.13:22-10.200.16.10:36096.service: Deactivated successfully. Oct 30 00:05:18.378536 systemd[1]: session-7.scope: Deactivated successfully. Oct 30 00:05:18.379257 systemd-logind[1880]: Session 7 logged out. Waiting for processes to exit. Oct 30 00:05:18.380180 systemd-logind[1880]: Removed session 7. Oct 30 00:05:28.486896 systemd[1]: Started sshd@5-10.200.8.13:22-10.200.16.10:41766.service - OpenSSH per-connection server daemon (10.200.16.10:41766). Oct 30 00:05:29.140674 sshd[3329]: Accepted publickey for core from 10.200.16.10 port 41766 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:29.142011 sshd-session[3329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:29.146146 systemd-logind[1880]: New session 8 of user core. Oct 30 00:05:29.160922 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 30 00:05:29.609529 sshd[3332]: Connection closed by 10.200.16.10 port 41766 Oct 30 00:05:29.609822 sshd-session[3329]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:29.613417 systemd[1]: sshd@5-10.200.8.13:22-10.200.16.10:41766.service: Deactivated successfully. Oct 30 00:05:29.615193 systemd[1]: session-8.scope: Deactivated successfully. Oct 30 00:05:29.615987 systemd-logind[1880]: Session 8 logged out. Waiting for processes to exit. Oct 30 00:05:29.616969 systemd-logind[1880]: Removed session 8. Oct 30 00:05:29.731153 systemd[1]: Started sshd@6-10.200.8.13:22-10.200.16.10:41782.service - OpenSSH per-connection server daemon (10.200.16.10:41782). Oct 30 00:05:30.375453 sshd[3338]: Accepted publickey for core from 10.200.16.10 port 41782 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:30.376799 sshd-session[3338]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:30.381179 systemd-logind[1880]: New session 9 of user core. Oct 30 00:05:30.396907 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 30 00:05:30.824090 sshd[3341]: Connection closed by 10.200.16.10 port 41782 Oct 30 00:05:30.824437 sshd-session[3338]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:30.828088 systemd[1]: sshd@6-10.200.8.13:22-10.200.16.10:41782.service: Deactivated successfully. Oct 30 00:05:30.829694 systemd[1]: session-9.scope: Deactivated successfully. Oct 30 00:05:30.830418 systemd-logind[1880]: Session 9 logged out. Waiting for processes to exit. Oct 30 00:05:30.831380 systemd-logind[1880]: Removed session 9. Oct 30 00:05:40.939176 systemd[1]: Started sshd@7-10.200.8.13:22-10.200.16.10:49794.service - OpenSSH per-connection server daemon (10.200.16.10:49794). Oct 30 00:05:41.567794 sshd[3403]: Accepted publickey for core from 10.200.16.10 port 49794 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:41.569537 sshd-session[3403]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:41.573816 systemd-logind[1880]: New session 10 of user core. Oct 30 00:05:41.586910 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 30 00:05:42.014306 sshd[3406]: Connection closed by 10.200.16.10 port 49794 Oct 30 00:05:42.014666 sshd-session[3403]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:42.017660 systemd[1]: sshd@7-10.200.8.13:22-10.200.16.10:49794.service: Deactivated successfully. Oct 30 00:05:42.019378 systemd[1]: session-10.scope: Deactivated successfully. Oct 30 00:05:42.020791 systemd-logind[1880]: Session 10 logged out. Waiting for processes to exit. Oct 30 00:05:42.021881 systemd-logind[1880]: Removed session 10. Oct 30 00:05:42.125488 systemd[1]: Started sshd@8-10.200.8.13:22-10.200.16.10:49796.service - OpenSSH per-connection server daemon (10.200.16.10:49796). Oct 30 00:05:42.751862 sshd[3471]: Accepted publickey for core from 10.200.16.10 port 49796 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:42.753080 sshd-session[3471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:42.757255 systemd-logind[1880]: New session 11 of user core. Oct 30 00:05:42.768914 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 30 00:05:43.201572 sshd[3508]: Connection closed by 10.200.16.10 port 49796 Oct 30 00:05:43.201868 sshd-session[3471]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:43.205359 systemd[1]: sshd@8-10.200.8.13:22-10.200.16.10:49796.service: Deactivated successfully. Oct 30 00:05:43.207020 systemd[1]: session-11.scope: Deactivated successfully. Oct 30 00:05:43.207684 systemd-logind[1880]: Session 11 logged out. Waiting for processes to exit. Oct 30 00:05:43.208650 systemd-logind[1880]: Removed session 11. Oct 30 00:05:53.316183 systemd[1]: Started sshd@9-10.200.8.13:22-10.200.16.10:51594.service - OpenSSH per-connection server daemon (10.200.16.10:51594). Oct 30 00:05:53.959016 sshd[7267]: Accepted publickey for core from 10.200.16.10 port 51594 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:53.960321 sshd-session[7267]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:53.965464 systemd-logind[1880]: New session 12 of user core. Oct 30 00:05:53.972922 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 30 00:05:54.406057 sshd[7480]: Connection closed by 10.200.16.10 port 51594 Oct 30 00:05:54.406567 sshd-session[7267]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:54.411101 systemd-logind[1880]: Session 12 logged out. Waiting for processes to exit. Oct 30 00:05:54.411638 systemd[1]: sshd@9-10.200.8.13:22-10.200.16.10:51594.service: Deactivated successfully. Oct 30 00:05:54.413528 systemd[1]: session-12.scope: Deactivated successfully. Oct 30 00:05:54.418229 systemd-logind[1880]: Removed session 12. Oct 30 00:05:54.524037 systemd[1]: Started sshd@10-10.200.8.13:22-10.200.16.10:51602.service - OpenSSH per-connection server daemon (10.200.16.10:51602). Oct 30 00:05:55.192862 sshd[7704]: Accepted publickey for core from 10.200.16.10 port 51602 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:05:55.194174 sshd-session[7704]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:05:55.200310 systemd-logind[1880]: New session 13 of user core. Oct 30 00:05:55.208941 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 30 00:05:55.642801 sshd[7908]: Connection closed by 10.200.16.10 port 51602 Oct 30 00:05:55.644423 sshd-session[7704]: pam_unix(sshd:session): session closed for user core Oct 30 00:05:55.648240 systemd[1]: sshd@10-10.200.8.13:22-10.200.16.10:51602.service: Deactivated successfully. Oct 30 00:05:55.650067 systemd[1]: session-13.scope: Deactivated successfully. Oct 30 00:05:55.650977 systemd-logind[1880]: Session 13 logged out. Waiting for processes to exit. Oct 30 00:05:55.653940 systemd-logind[1880]: Removed session 13. Oct 30 00:06:05.758859 systemd[1]: Started sshd@11-10.200.8.13:22-10.200.16.10:45690.service - OpenSSH per-connection server daemon (10.200.16.10:45690). Oct 30 00:06:06.392244 sshd[12170]: Accepted publickey for core from 10.200.16.10 port 45690 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:06.393576 sshd-session[12170]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:06.400967 systemd-logind[1880]: New session 14 of user core. Oct 30 00:06:06.408950 systemd[1]: Started session-14.scope - Session 14 of User core. Oct 30 00:06:06.843398 sshd[12385]: Connection closed by 10.200.16.10 port 45690 Oct 30 00:06:06.843998 sshd-session[12170]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:06.848961 systemd[1]: sshd@11-10.200.8.13:22-10.200.16.10:45690.service: Deactivated successfully. Oct 30 00:06:06.851203 systemd-logind[1880]: Session 14 logged out. Waiting for processes to exit. Oct 30 00:06:06.851685 systemd[1]: session-14.scope: Deactivated successfully. Oct 30 00:06:06.856148 systemd-logind[1880]: Removed session 14. Oct 30 00:06:06.960395 systemd[1]: Started sshd@12-10.200.8.13:22-10.200.16.10:45696.service - OpenSSH per-connection server daemon (10.200.16.10:45696). Oct 30 00:06:07.608161 sshd[12503]: Accepted publickey for core from 10.200.16.10 port 45696 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:07.610822 sshd-session[12503]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:07.616107 systemd-logind[1880]: New session 15 of user core. Oct 30 00:06:07.622943 systemd[1]: Started session-15.scope - Session 15 of User core. Oct 30 00:06:08.064246 sshd[12724]: Connection closed by 10.200.16.10 port 45696 Oct 30 00:06:08.065049 sshd-session[12503]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:08.071796 systemd-logind[1880]: Session 15 logged out. Waiting for processes to exit. Oct 30 00:06:08.072156 systemd[1]: sshd@12-10.200.8.13:22-10.200.16.10:45696.service: Deactivated successfully. Oct 30 00:06:08.074006 systemd[1]: session-15.scope: Deactivated successfully. Oct 30 00:06:08.079722 systemd-logind[1880]: Removed session 15. Oct 30 00:06:18.187026 systemd[1]: Started sshd@13-10.200.8.13:22-10.200.16.10:36670.service - OpenSSH per-connection server daemon (10.200.16.10:36670). Oct 30 00:06:18.836848 sshd[14318]: Accepted publickey for core from 10.200.16.10 port 36670 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:18.839396 sshd-session[14318]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:18.845493 systemd-logind[1880]: New session 16 of user core. Oct 30 00:06:18.852239 systemd[1]: Started session-16.scope - Session 16 of User core. Oct 30 00:06:19.288950 sshd[14368]: Connection closed by 10.200.16.10 port 36670 Oct 30 00:06:19.289306 sshd-session[14318]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:19.293761 systemd[1]: sshd@13-10.200.8.13:22-10.200.16.10:36670.service: Deactivated successfully. Oct 30 00:06:19.294623 systemd-logind[1880]: Session 16 logged out. Waiting for processes to exit. Oct 30 00:06:19.296558 systemd[1]: session-16.scope: Deactivated successfully. Oct 30 00:06:19.299470 systemd-logind[1880]: Removed session 16. Oct 30 00:06:19.404666 systemd[1]: Started sshd@14-10.200.8.13:22-10.200.16.10:36674.service - OpenSSH per-connection server daemon (10.200.16.10:36674). Oct 30 00:06:20.051961 sshd[14386]: Accepted publickey for core from 10.200.16.10 port 36674 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:20.053362 sshd-session[14386]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:20.065596 systemd-logind[1880]: New session 17 of user core. Oct 30 00:06:20.071385 systemd[1]: Started session-17.scope - Session 17 of User core. Oct 30 00:06:20.509009 sshd[14396]: Connection closed by 10.200.16.10 port 36674 Oct 30 00:06:20.509408 sshd-session[14386]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:20.512961 systemd-logind[1880]: Session 17 logged out. Waiting for processes to exit. Oct 30 00:06:20.514381 systemd[1]: sshd@14-10.200.8.13:22-10.200.16.10:36674.service: Deactivated successfully. Oct 30 00:06:20.517260 systemd[1]: session-17.scope: Deactivated successfully. Oct 30 00:06:20.519079 systemd-logind[1880]: Removed session 17. Oct 30 00:06:30.627015 systemd[1]: Started sshd@15-10.200.8.13:22-10.200.16.10:42566.service - OpenSSH per-connection server daemon (10.200.16.10:42566). Oct 30 00:06:31.278723 sshd[14731]: Accepted publickey for core from 10.200.16.10 port 42566 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:31.280017 sshd-session[14731]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:31.287844 systemd-logind[1880]: New session 18 of user core. Oct 30 00:06:31.295460 systemd[1]: Started session-18.scope - Session 18 of User core. Oct 30 00:06:31.732330 sshd[14758]: Connection closed by 10.200.16.10 port 42566 Oct 30 00:06:31.734595 sshd-session[14731]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:31.739235 systemd[1]: sshd@15-10.200.8.13:22-10.200.16.10:42566.service: Deactivated successfully. Oct 30 00:06:31.741244 systemd[1]: session-18.scope: Deactivated successfully. Oct 30 00:06:31.742759 systemd-logind[1880]: Session 18 logged out. Waiting for processes to exit. Oct 30 00:06:31.746280 systemd-logind[1880]: Removed session 18. Oct 30 00:06:31.845207 systemd[1]: Started sshd@16-10.200.8.13:22-10.200.16.10:42580.service - OpenSSH per-connection server daemon (10.200.16.10:42580). Oct 30 00:06:32.502881 sshd[14776]: Accepted publickey for core from 10.200.16.10 port 42580 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:32.504397 sshd-session[14776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:32.512852 systemd-logind[1880]: New session 19 of user core. Oct 30 00:06:32.520926 systemd[1]: Started session-19.scope - Session 19 of User core. Oct 30 00:06:32.957313 sshd[14804]: Connection closed by 10.200.16.10 port 42580 Oct 30 00:06:32.957707 sshd-session[14776]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:32.964680 systemd-logind[1880]: Session 19 logged out. Waiting for processes to exit. Oct 30 00:06:32.965570 systemd[1]: sshd@16-10.200.8.13:22-10.200.16.10:42580.service: Deactivated successfully. Oct 30 00:06:32.969448 systemd[1]: session-19.scope: Deactivated successfully. Oct 30 00:06:32.971758 systemd-logind[1880]: Removed session 19. Oct 30 00:06:43.072334 systemd[1]: Started sshd@17-10.200.8.13:22-10.200.16.10:48792.service - OpenSSH per-connection server daemon (10.200.16.10:48792). Oct 30 00:06:43.721201 sshd[15129]: Accepted publickey for core from 10.200.16.10 port 48792 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:43.724268 sshd-session[15129]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:43.731416 systemd-logind[1880]: New session 20 of user core. Oct 30 00:06:43.736315 systemd[1]: Started session-20.scope - Session 20 of User core. Oct 30 00:06:44.171539 sshd[15175]: Connection closed by 10.200.16.10 port 48792 Oct 30 00:06:44.171846 sshd-session[15129]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:44.176382 systemd[1]: sshd@17-10.200.8.13:22-10.200.16.10:48792.service: Deactivated successfully. Oct 30 00:06:44.178020 systemd[1]: session-20.scope: Deactivated successfully. Oct 30 00:06:44.178752 systemd-logind[1880]: Session 20 logged out. Waiting for processes to exit. Oct 30 00:06:44.179738 systemd-logind[1880]: Removed session 20. Oct 30 00:06:44.286427 systemd[1]: Started sshd@18-10.200.8.13:22-10.200.16.10:48800.service - OpenSSH per-connection server daemon (10.200.16.10:48800). Oct 30 00:06:44.914635 sshd[15199]: Accepted publickey for core from 10.200.16.10 port 48800 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:44.915978 sshd-session[15199]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:44.920066 systemd-logind[1880]: New session 21 of user core. Oct 30 00:06:44.929908 systemd[1]: Started session-21.scope - Session 21 of User core. Oct 30 00:06:45.361239 sshd[15202]: Connection closed by 10.200.16.10 port 48800 Oct 30 00:06:45.361608 sshd-session[15199]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:45.365474 systemd[1]: sshd@18-10.200.8.13:22-10.200.16.10:48800.service: Deactivated successfully. Oct 30 00:06:45.367108 systemd[1]: session-21.scope: Deactivated successfully. Oct 30 00:06:45.367782 systemd-logind[1880]: Session 21 logged out. Waiting for processes to exit. Oct 30 00:06:45.368664 systemd-logind[1880]: Removed session 21. Oct 30 00:06:52.728183 setup-nvidia[2504]: \u000d Oct 30 00:06:52.728183 setup-nvidia[2504]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Oct 30 00:06:52.728183 setup-nvidia[2504]: frequently when this kernel module was built against the wrong or Oct 30 00:06:52.728183 setup-nvidia[2504]: improperly configured kernel sources, with a version of gcc that differs Oct 30 00:06:52.728183 setup-nvidia[2504]: from the one used to build the target kernel, or if another driver, such Oct 30 00:06:52.728183 setup-nvidia[2504]: as nouveau, is present and prevents the NVIDIA kernel module from Oct 30 00:06:52.728183 setup-nvidia[2504]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Oct 30 00:06:52.728183 setup-nvidia[2504]: installed in this system is supported by this NVIDIA Linux graphics Oct 30 00:06:52.728183 setup-nvidia[2504]: driver release. Oct 30 00:06:52.728183 setup-nvidia[2504]: \u000d Oct 30 00:06:52.728183 setup-nvidia[2504]: Please see the log entries 'Kernel module load error' and 'Kernel Oct 30 00:06:52.728183 setup-nvidia[2504]: messages' at the end of the file Oct 30 00:06:52.728183 setup-nvidia[2504]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Oct 30 00:06:52.728183 setup-nvidia[2504]: information. Oct 30 00:06:52.728183 setup-nvidia[2504]: \u000d Oct 30 00:06:52.743442 setup-nvidia[2504]: dmesg: read kernel buffer failed: Operation not permitted Oct 30 00:06:52.763345 setup-nvidia[2504]: \u000d Oct 30 00:06:52.763345 setup-nvidia[2504]: ERROR: Installation has failed. Please see the file Oct 30 00:06:52.763345 setup-nvidia[2504]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Oct 30 00:06:52.763345 setup-nvidia[2504]: details. You may find suggestions on fixing installation problems in Oct 30 00:06:52.763345 setup-nvidia[2504]: the README available on the Linux driver download page at Oct 30 00:06:52.763345 setup-nvidia[2504]: www.nvidia.com. Oct 30 00:06:52.763345 setup-nvidia[2504]: \u000d Oct 30 00:06:52.763836 setup-nvidia[2504]: Last 50 lines of nvidia-installer.log: Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Oct 30 00:06:52.764895 setup-nvidia[2504]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Oct 30 00:06:52.764895 setup-nvidia[2504]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Oct 30 00:06:52.764895 setup-nvidia[2504]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Oct 30 00:06:52.764895 setup-nvidia[2504]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Oct 30 00:06:52.764895 setup-nvidia[2504]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Oct 30 00:06:52.764895 setup-nvidia[2504]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Oct 30 00:06:52.764895 setup-nvidia[2504]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Oct 30 00:06:52.764895 setup-nvidia[2504]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Oct 30 00:06:52.764895 setup-nvidia[2504]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Oct 30 00:06:52.764895 setup-nvidia[2504]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Oct 30 00:06:52.764895 setup-nvidia[2504]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Oct 30 00:06:52.764895 setup-nvidia[2504]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Oct 30 00:06:52.764895 setup-nvidia[2504]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Oct 30 00:06:52.764895 setup-nvidia[2504]: make[2]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/build' Oct 30 00:06:52.764895 setup-nvidia[2504]: make[1]: Leaving directory '/usr/lib/modules/6.12.54-flatcar/source' Oct 30 00:06:52.764895 setup-nvidia[2504]: -> done. Oct 30 00:06:52.764895 setup-nvidia[2504]: -> Kernel module compilation complete. Oct 30 00:06:52.764895 setup-nvidia[2504]: -> Unable to determine if Secure Boot is enabled: No such file or directory Oct 30 00:06:52.764895 setup-nvidia[2504]: 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. Oct 30 00:06:52.764895 setup-nvidia[2504]: \u000d Oct 30 00:06:52.766078 setup-nvidia[2504]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Oct 30 00:06:52.766078 setup-nvidia[2504]: -> Kernel module load error: Operation not permitted Oct 30 00:06:52.766078 setup-nvidia[2504]: -> Kernel messages: Oct 30 00:06:52.766078 setup-nvidia[2504]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Oct 30 00:06:55.477111 systemd[1]: Started sshd@19-10.200.8.13:22-10.200.16.10:58104.service - OpenSSH per-connection server daemon (10.200.16.10:58104). Oct 30 00:06:56.105985 sshd[15388]: Accepted publickey for core from 10.200.16.10 port 58104 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:56.107209 sshd-session[15388]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:56.111477 systemd-logind[1880]: New session 22 of user core. Oct 30 00:06:56.121907 systemd[1]: Started session-22.scope - Session 22 of User core. Oct 30 00:06:56.555429 sshd[15391]: Connection closed by 10.200.16.10 port 58104 Oct 30 00:06:56.555801 sshd-session[15388]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:56.559353 systemd[1]: sshd@19-10.200.8.13:22-10.200.16.10:58104.service: Deactivated successfully. Oct 30 00:06:56.560964 systemd[1]: session-22.scope: Deactivated successfully. Oct 30 00:06:56.561623 systemd-logind[1880]: Session 22 logged out. Waiting for processes to exit. Oct 30 00:06:56.562520 systemd-logind[1880]: Removed session 22. Oct 30 00:06:56.670737 systemd[1]: Started sshd@20-10.200.8.13:22-10.200.16.10:58116.service - OpenSSH per-connection server daemon (10.200.16.10:58116). Oct 30 00:06:57.316766 sshd[15397]: Accepted publickey for core from 10.200.16.10 port 58116 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:06:57.318095 sshd-session[15397]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:06:57.322332 systemd-logind[1880]: New session 23 of user core. Oct 30 00:06:57.336920 systemd[1]: Started session-23.scope - Session 23 of User core. Oct 30 00:06:57.773790 sshd[15400]: Connection closed by 10.200.16.10 port 58116 Oct 30 00:06:57.774223 sshd-session[15397]: pam_unix(sshd:session): session closed for user core Oct 30 00:06:57.777722 systemd[1]: sshd@20-10.200.8.13:22-10.200.16.10:58116.service: Deactivated successfully. Oct 30 00:06:57.779302 systemd[1]: session-23.scope: Deactivated successfully. Oct 30 00:06:57.779972 systemd-logind[1880]: Session 23 logged out. Waiting for processes to exit. Oct 30 00:06:57.780857 systemd-logind[1880]: Removed session 23. Oct 30 00:07:07.889757 systemd[1]: Started sshd@21-10.200.8.13:22-10.200.16.10:39206.service - OpenSSH per-connection server daemon (10.200.16.10:39206). Oct 30 00:07:08.520246 sshd[15406]: Accepted publickey for core from 10.200.16.10 port 39206 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:08.521537 sshd-session[15406]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:08.525638 systemd-logind[1880]: New session 24 of user core. Oct 30 00:07:08.535884 systemd[1]: Started session-24.scope - Session 24 of User core. Oct 30 00:07:08.978841 sshd[15409]: Connection closed by 10.200.16.10 port 39206 Oct 30 00:07:08.979211 sshd-session[15406]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:08.982955 systemd[1]: sshd@21-10.200.8.13:22-10.200.16.10:39206.service: Deactivated successfully. Oct 30 00:07:08.984757 systemd[1]: session-24.scope: Deactivated successfully. Oct 30 00:07:08.985535 systemd-logind[1880]: Session 24 logged out. Waiting for processes to exit. Oct 30 00:07:08.986903 systemd-logind[1880]: Removed session 24. Oct 30 00:07:09.094099 systemd[1]: Started sshd@22-10.200.8.13:22-10.200.16.10:39214.service - OpenSSH per-connection server daemon (10.200.16.10:39214). Oct 30 00:07:09.747559 sshd[15415]: Accepted publickey for core from 10.200.16.10 port 39214 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:09.748785 sshd-session[15415]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:09.753266 systemd-logind[1880]: New session 25 of user core. Oct 30 00:07:09.763896 systemd[1]: Started session-25.scope - Session 25 of User core. Oct 30 00:07:10.197820 sshd[15418]: Connection closed by 10.200.16.10 port 39214 Oct 30 00:07:10.198084 sshd-session[15415]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:10.201523 systemd[1]: sshd@22-10.200.8.13:22-10.200.16.10:39214.service: Deactivated successfully. Oct 30 00:07:10.203225 systemd[1]: session-25.scope: Deactivated successfully. Oct 30 00:07:10.203957 systemd-logind[1880]: Session 25 logged out. Waiting for processes to exit. Oct 30 00:07:10.204863 systemd-logind[1880]: Removed session 25. Oct 30 00:07:20.310105 systemd[1]: Started sshd@23-10.200.8.13:22-10.200.16.10:38868.service - OpenSSH per-connection server daemon (10.200.16.10:38868). Oct 30 00:07:20.960343 sshd[15425]: Accepted publickey for core from 10.200.16.10 port 38868 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:20.961612 sshd-session[15425]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:20.965946 systemd-logind[1880]: New session 26 of user core. Oct 30 00:07:20.975914 systemd[1]: Started session-26.scope - Session 26 of User core. Oct 30 00:07:21.329798 kernel: EXT4-fs (loop6p9): unmounting filesystem ff60a145-de44-4789-82a5-6d59a2c3f56a. Oct 30 00:07:21.330357 setup-nvidia[2504]: Container flatcardevelopercontainer-4459.1.0nightly-20251029-2100.bin exited successfully. Oct 30 00:07:21.333245 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.1.0nightly\x2d20251029\x2d2100.bin.mount: Deactivated successfully. Oct 30 00:07:21.384620 sudo[2376]: pam_unix(sudo:session): session closed for user root Oct 30 00:07:21.386573 systemd[1]: session-c2.scope: Deactivated successfully. Oct 30 00:07:21.386842 systemd[1]: session-c2.scope: Consumed 5min 24.058s CPU time, 889.7M memory peak. Oct 30 00:07:21.405444 sshd[15428]: Connection closed by 10.200.16.10 port 38868 Oct 30 00:07:21.405845 sshd-session[15425]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:21.409704 systemd[1]: sshd@23-10.200.8.13:22-10.200.16.10:38868.service: Deactivated successfully. Oct 30 00:07:21.411377 systemd[1]: session-26.scope: Deactivated successfully. Oct 30 00:07:21.412090 systemd-logind[1880]: Session 26 logged out. Waiting for processes to exit. Oct 30 00:07:21.413004 systemd-logind[1880]: Removed session 26. Oct 30 00:07:21.521412 systemd[1]: Started sshd@24-10.200.8.13:22-10.200.16.10:38870.service - OpenSSH per-connection server daemon (10.200.16.10:38870). Oct 30 00:07:21.836074 setup-nvidia[1872]: /opt/nvidia / Oct 30 00:07:21.837105 setup-nvidia[1872]: / Oct 30 00:07:21.847795 kernel: IPMI message handler: version 39.2 Oct 30 00:07:21.852798 kernel: ipmi device interface Oct 30 00:07:21.865178 setup-nvidia[1872]: /opt/nvidia/current/usr/lib/modules/6.12.54-flatcar/video / Oct 30 00:07:21.919809 kernel: nvidia: loading out-of-tree module taints kernel. Oct 30 00:07:21.919953 kernel: nvidia: module license 'NVIDIA' taints kernel. Oct 30 00:07:21.919980 kernel: Disabling lock debugging due to kernel taint Oct 30 00:07:21.928029 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Oct 30 00:07:21.928072 kernel: nvidia: module license taints kernel. Oct 30 00:07:22.176238 sshd[15439]: Accepted publickey for core from 10.200.16.10 port 38870 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:22.177432 sshd-session[15439]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:22.181650 systemd-logind[1880]: New session 27 of user core. Oct 30 00:07:22.190910 systemd[1]: Started session-27.scope - Session 27 of User core. Oct 30 00:07:22.327802 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Oct 30 00:07:22.717465 sshd[15462]: Connection closed by 10.200.16.10 port 38870 Oct 30 00:07:22.717880 sshd-session[15439]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:22.722333 systemd[1]: sshd@24-10.200.8.13:22-10.200.16.10:38870.service: Deactivated successfully. Oct 30 00:07:22.723954 systemd[1]: session-27.scope: Deactivated successfully. Oct 30 00:07:22.724628 systemd-logind[1880]: Session 27 logged out. Waiting for processes to exit. Oct 30 00:07:22.725548 systemd-logind[1880]: Removed session 27. Oct 30 00:07:22.735384 kernel: Oct 30 00:07:22.735444 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Oct 30 00:07:22.943790 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Oct 30 00:07:22.964799 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Oct 30 00:07:22.986811 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Oct 30 00:07:23.029804 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Oct 30 00:07:23.030045 setup-nvidia[1872]: / Oct 30 00:07:23.045051 setup-nvidia[15476]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 30 00:07:24.852351 setup-nvidia[15493]: Thu Oct 30 00:07:24 2025 Oct 30 00:07:24.852351 setup-nvidia[15493]: +---------------------------------------------------------------------------------------+ Oct 30 00:07:24.852351 setup-nvidia[15493]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Oct 30 00:07:24.852351 setup-nvidia[15493]: |-----------------------------------------+----------------------+----------------------+ Oct 30 00:07:24.852351 setup-nvidia[15493]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Oct 30 00:07:24.852351 setup-nvidia[15493]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Oct 30 00:07:24.852351 setup-nvidia[15493]: | | | MIG M. | Oct 30 00:07:24.852351 setup-nvidia[15493]: |=========================================+======================+======================| Oct 30 00:07:24.855868 setup-nvidia[15493]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Oct 30 00:07:24.855868 setup-nvidia[15493]: | N/A 25C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Oct 30 00:07:24.855868 setup-nvidia[15493]: | | | N/A | Oct 30 00:07:24.855868 setup-nvidia[15493]: +-----------------------------------------+----------------------+----------------------+ Oct 30 00:07:24.855868 setup-nvidia[15493]: Oct 30 00:07:24.855868 setup-nvidia[15493]: +---------------------------------------------------------------------------------------+ Oct 30 00:07:24.855868 setup-nvidia[15493]: | Processes: | Oct 30 00:07:24.855868 setup-nvidia[15493]: | GPU GI CI PID Type Process name GPU Memory | Oct 30 00:07:24.855868 setup-nvidia[15493]: | ID ID Usage | Oct 30 00:07:24.855868 setup-nvidia[15493]: |=======================================================================================| Oct 30 00:07:24.856123 setup-nvidia[15493]: | No running processes found | Oct 30 00:07:24.856123 setup-nvidia[15493]: +---------------------------------------------------------------------------------------+ Oct 30 00:07:25.230604 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 30 00:07:25.232629 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 30 00:07:25.233015 systemd[1]: Startup finished in 1.211s (firmware) + 29.298s (loader) + 3.719s (kernel) + 14.846s (initrd) + 3min 29.278s (userspace) = 4min 18.353s. Oct 30 00:07:31.457541 systemd[1]: Stopping user@0.service - User Manager for UID 0... Oct 30 00:07:31.458438 systemd[2419]: Activating special unit exit.target... Oct 30 00:07:31.458514 systemd[2419]: Stopped target default.target - Main User Target. Oct 30 00:07:31.458556 systemd[2419]: Stopped target basic.target - Basic System. Oct 30 00:07:31.458586 systemd[2419]: Stopped target paths.target - Paths. Oct 30 00:07:31.458613 systemd[2419]: Stopped target sockets.target - Sockets. Oct 30 00:07:31.458639 systemd[2419]: Stopped target timers.target - Timers. Oct 30 00:07:31.459005 systemd[2419]: Closed dbus.socket - D-Bus User Message Bus Socket. Oct 30 00:07:31.459223 systemd[2419]: Removed slice app.slice - User Application Slice. Oct 30 00:07:31.459255 systemd[2419]: Reached target shutdown.target - Shutdown. Oct 30 00:07:31.459392 systemd[2419]: Finished systemd-exit.service - Exit the Session. Oct 30 00:07:31.459427 systemd[2419]: Reached target exit.target - Exit the Session. Oct 30 00:07:31.483323 (sd-pam)[2426]: pam_unix(systemd-user:session): session closed for user root Oct 30 00:07:31.484557 systemd[1]: user@0.service: Deactivated successfully. Oct 30 00:07:31.485011 systemd[1]: Stopped user@0.service - User Manager for UID 0. Oct 30 00:07:31.487553 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Oct 30 00:07:31.511477 systemd[1]: run-user-0.mount: Deactivated successfully. Oct 30 00:07:31.512380 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Oct 30 00:07:31.512559 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Oct 30 00:07:31.514078 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Oct 30 00:07:31.514167 systemd[1]: user-0.slice: Consumed 5min 24.305s CPU time, 895.8M memory peak. Oct 30 00:07:31.514562 systemd-logind[1880]: Removed session c3. Oct 30 00:07:32.840246 systemd[1]: Started sshd@25-10.200.8.13:22-10.200.16.10:42430.service - OpenSSH per-connection server daemon (10.200.16.10:42430). Oct 30 00:07:33.481486 sshd[15504]: Accepted publickey for core from 10.200.16.10 port 42430 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:33.482746 sshd-session[15504]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:33.487085 systemd-logind[1880]: New session 28 of user core. Oct 30 00:07:33.497907 systemd[1]: Started session-28.scope - Session 28 of User core. Oct 30 00:07:33.930260 sshd[15507]: Connection closed by 10.200.16.10 port 42430 Oct 30 00:07:33.930526 sshd-session[15504]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:33.934517 systemd[1]: sshd@25-10.200.8.13:22-10.200.16.10:42430.service: Deactivated successfully. Oct 30 00:07:33.936227 systemd[1]: session-28.scope: Deactivated successfully. Oct 30 00:07:33.936966 systemd-logind[1880]: Session 28 logged out. Waiting for processes to exit. Oct 30 00:07:33.937922 systemd-logind[1880]: Removed session 28. Oct 30 00:07:34.054022 systemd[1]: Started sshd@26-10.200.8.13:22-10.200.16.10:42438.service - OpenSSH per-connection server daemon (10.200.16.10:42438). Oct 30 00:07:34.703334 sshd[15514]: Accepted publickey for core from 10.200.16.10 port 42438 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:34.704581 sshd-session[15514]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:34.709109 systemd-logind[1880]: New session 29 of user core. Oct 30 00:07:34.718898 systemd[1]: Started session-29.scope - Session 29 of User core. Oct 30 00:07:35.151401 sshd[15517]: Connection closed by 10.200.16.10 port 42438 Oct 30 00:07:35.151787 sshd-session[15514]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:35.155382 systemd[1]: sshd@26-10.200.8.13:22-10.200.16.10:42438.service: Deactivated successfully. Oct 30 00:07:35.157018 systemd[1]: session-29.scope: Deactivated successfully. Oct 30 00:07:35.157739 systemd-logind[1880]: Session 29 logged out. Waiting for processes to exit. Oct 30 00:07:35.158680 systemd-logind[1880]: Removed session 29. Oct 30 00:07:35.265945 systemd[1]: Started sshd@27-10.200.8.13:22-10.200.16.10:42444.service - OpenSSH per-connection server daemon (10.200.16.10:42444). Oct 30 00:07:35.916365 sshd[15524]: Accepted publickey for core from 10.200.16.10 port 42444 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:44.761047 sshd-session[15524]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:44.773749 systemd-logind[1880]: New session 30 of user core. Oct 30 00:07:44.780924 systemd[1]: Started session-30.scope - Session 30 of User core. Oct 30 00:07:45.151261 sshd[15528]: Connection closed by 10.200.16.10 port 42444 Oct 30 00:07:45.151594 sshd-session[15524]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:45.155173 systemd[1]: sshd@27-10.200.8.13:22-10.200.16.10:42444.service: Deactivated successfully. Oct 30 00:07:45.156820 systemd[1]: session-30.scope: Deactivated successfully. Oct 30 00:07:45.157496 systemd-logind[1880]: Session 30 logged out. Waiting for processes to exit. Oct 30 00:07:45.158389 systemd-logind[1880]: Removed session 30. Oct 30 00:07:45.265802 systemd[1]: Started sshd@28-10.200.8.13:22-10.200.16.10:35892.service - OpenSSH per-connection server daemon (10.200.16.10:35892). Oct 30 00:07:45.899042 sshd[15534]: Accepted publickey for core from 10.200.16.10 port 35892 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:45.900279 sshd-session[15534]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:45.904373 systemd-logind[1880]: New session 31 of user core. Oct 30 00:07:45.918902 systemd[1]: Started session-31.scope - Session 31 of User core. Oct 30 00:07:46.261104 sudo[15538]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 30 00:07:46.261405 sudo[15538]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 30 00:07:46.309463 sudo[15538]: pam_unix(sudo:session): session closed for user root Oct 30 00:07:46.411861 sshd[15537]: Connection closed by 10.200.16.10 port 35892 Oct 30 00:07:46.412224 sshd-session[15534]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:46.415654 systemd[1]: sshd@28-10.200.8.13:22-10.200.16.10:35892.service: Deactivated successfully. Oct 30 00:07:46.417271 systemd[1]: session-31.scope: Deactivated successfully. Oct 30 00:07:46.417964 systemd-logind[1880]: Session 31 logged out. Waiting for processes to exit. Oct 30 00:07:46.418901 systemd-logind[1880]: Removed session 31. Oct 30 00:07:46.534055 systemd[1]: Started sshd@29-10.200.8.13:22-10.200.16.10:35906.service - OpenSSH per-connection server daemon (10.200.16.10:35906). Oct 30 00:07:47.175551 sshd[15544]: Accepted publickey for core from 10.200.16.10 port 35906 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:47.176911 sshd-session[15544]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:47.181004 systemd-logind[1880]: New session 32 of user core. Oct 30 00:07:47.204914 systemd[1]: Started session-32.scope - Session 32 of User core. Oct 30 00:07:47.521050 sudo[15549]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 30 00:07:47.521341 sudo[15549]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 30 00:07:47.528883 sudo[15549]: pam_unix(sudo:session): session closed for user root Oct 30 00:07:47.534399 sudo[15548]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 30 00:07:47.534685 sudo[15548]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 30 00:07:47.543837 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 30 00:07:47.581086 augenrules[15571]: No rules Oct 30 00:07:47.582352 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 00:07:47.582621 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 30 00:07:47.583458 sudo[15548]: pam_unix(sudo:session): session closed for user root Oct 30 00:07:47.684682 sshd[15547]: Connection closed by 10.200.16.10 port 35906 Oct 30 00:07:47.685073 sshd-session[15544]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:47.688946 systemd[1]: sshd@29-10.200.8.13:22-10.200.16.10:35906.service: Deactivated successfully. Oct 30 00:07:47.690664 systemd[1]: session-32.scope: Deactivated successfully. Oct 30 00:07:47.691435 systemd-logind[1880]: Session 32 logged out. Waiting for processes to exit. Oct 30 00:07:47.692356 systemd-logind[1880]: Removed session 32. Oct 30 00:07:47.800994 systemd[1]: Started sshd@30-10.200.8.13:22-10.200.16.10:35910.service - OpenSSH per-connection server daemon (10.200.16.10:35910). Oct 30 00:07:48.455790 sshd[15580]: Accepted publickey for core from 10.200.16.10 port 35910 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:48.457031 sshd-session[15580]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:48.461144 systemd-logind[1880]: New session 33 of user core. Oct 30 00:07:48.483912 systemd[1]: Started session-33.scope - Session 33 of User core. Oct 30 00:07:48.802277 sudo[15584]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Oct 30 00:07:48.802567 sudo[15584]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 30 00:07:48.805834 sudo[15584]: pam_unix(sudo:session): session closed for user root Oct 30 00:07:48.945063 sshd[15583]: Connection closed by 10.200.16.10 port 35910 Oct 30 00:07:48.945389 sshd-session[15580]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:48.948748 systemd[1]: sshd@30-10.200.8.13:22-10.200.16.10:35910.service: Deactivated successfully. Oct 30 00:07:48.950366 systemd[1]: session-33.scope: Deactivated successfully. Oct 30 00:07:48.951085 systemd-logind[1880]: Session 33 logged out. Waiting for processes to exit. Oct 30 00:07:48.951972 systemd-logind[1880]: Removed session 33. Oct 30 00:07:49.061762 systemd[1]: Started sshd@31-10.200.8.13:22-10.200.16.10:35916.service - OpenSSH per-connection server daemon (10.200.16.10:35916). Oct 30 00:07:49.771932 sshd[15590]: Accepted publickey for core from 10.200.16.10 port 35916 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:49.773214 sshd-session[15590]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:49.777299 systemd-logind[1880]: New session 34 of user core. Oct 30 00:07:49.792896 systemd[1]: Started session-34.scope - Session 34 of User core. Oct 30 00:07:50.124598 sudo[15594]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Oct 30 00:07:50.124912 sudo[15594]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 30 00:07:57.428537 sudo[15594]: pam_unix(sudo:session): session closed for user root Oct 30 00:07:57.533969 sshd[15593]: Connection closed by 10.200.16.10 port 35916 Oct 30 00:07:57.534231 sshd-session[15590]: pam_unix(sshd:session): session closed for user core Oct 30 00:07:57.537845 systemd[1]: sshd@31-10.200.8.13:22-10.200.16.10:35916.service: Deactivated successfully. Oct 30 00:07:57.539572 systemd[1]: session-34.scope: Deactivated successfully. Oct 30 00:07:57.540296 systemd-logind[1880]: Session 34 logged out. Waiting for processes to exit. Oct 30 00:07:57.541290 systemd-logind[1880]: Removed session 34. Oct 30 00:07:57.648392 systemd[1]: Started sshd@32-10.200.8.13:22-10.200.16.10:58666.service - OpenSSH per-connection server daemon (10.200.16.10:58666). Oct 30 00:07:58.296438 sshd[15600]: Accepted publickey for core from 10.200.16.10 port 58666 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:07:58.297654 sshd-session[15600]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:07:58.301792 systemd-logind[1880]: New session 35 of user core. Oct 30 00:07:58.311901 systemd[1]: Started session-35.scope - Session 35 of User core. Oct 30 00:07:58.713104 ntpdate[15613]: ntpdate 4.2.8p18@1.4062-o Wed Oct 29 21:31:59 UTC 2025 (1) Oct 30 00:08:05.444866 ntpdate[15613]: adjust time server 89.234.64.77 offset -0.004646 sec Oct 30 00:08:05.550801 sshd[15603]: Connection closed by 10.200.16.10 port 58666 Oct 30 00:08:05.551224 sshd-session[15600]: pam_unix(sshd:session): session closed for user core Oct 30 00:08:05.555098 systemd[1]: sshd@32-10.200.8.13:22-10.200.16.10:58666.service: Deactivated successfully. Oct 30 00:08:05.557311 systemd[1]: session-35.scope: Deactivated successfully. Oct 30 00:08:05.559127 systemd-logind[1880]: Session 35 logged out. Waiting for processes to exit. Oct 30 00:08:05.560220 systemd-logind[1880]: Removed session 35. Oct 30 00:08:05.665981 systemd[1]: Started sshd@33-10.200.8.13:22-10.200.16.10:38950.service - OpenSSH per-connection server daemon (10.200.16.10:38950). Oct 30 00:08:06.319263 sshd[15618]: Accepted publickey for core from 10.200.16.10 port 38950 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:08:06.320517 sshd-session[15618]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:08:06.328613 systemd-logind[1880]: New session 36 of user core. Oct 30 00:08:06.341905 systemd[1]: Started session-36.scope - Session 36 of User core. Oct 30 00:08:06.824429 sshd[15621]: Connection closed by 10.200.16.10 port 38950 Oct 30 00:08:06.824826 sshd-session[15618]: pam_unix(sshd:session): session closed for user core Oct 30 00:08:06.828534 systemd[1]: sshd@33-10.200.8.13:22-10.200.16.10:38950.service: Deactivated successfully. Oct 30 00:08:06.830124 systemd[1]: session-36.scope: Deactivated successfully. Oct 30 00:08:06.830825 systemd-logind[1880]: Session 36 logged out. Waiting for processes to exit. Oct 30 00:08:06.832012 systemd-logind[1880]: Removed session 36. Oct 30 00:08:06.938961 systemd[1]: Started sshd@34-10.200.8.13:22-10.200.16.10:38954.service - OpenSSH per-connection server daemon (10.200.16.10:38954). Oct 30 00:08:07.569232 sshd[15636]: Accepted publickey for core from 10.200.16.10 port 38954 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:08:07.570627 sshd-session[15636]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:08:07.575342 systemd-logind[1880]: New session 37 of user core. Oct 30 00:08:07.596899 systemd[1]: Started session-37.scope - Session 37 of User core. Oct 30 00:08:09.050842 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 30 00:08:09.071144 (dockerd)[15659]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Oct 30 00:08:11.001379 dockerd[15659]: time="2025-10-30T00:08:11.001295422Z" level=info msg="Starting up" Oct 30 00:08:11.002079 dockerd[15659]: time="2025-10-30T00:08:11.002053589Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Oct 30 00:08:11.012765 dockerd[15659]: time="2025-10-30T00:08:11.012731422Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Oct 30 00:08:11.815866 dockerd[15659]: time="2025-10-30T00:08:11.815819597Z" level=info msg="Loading containers: start." Oct 30 00:08:11.830794 kernel: Initializing XFRM netlink socket Oct 30 00:08:12.167369 systemd-networkd[1576]: docker0: Link UP Oct 30 00:08:12.187868 dockerd[15659]: time="2025-10-30T00:08:12.187832327Z" level=info msg="Loading containers: done." Oct 30 00:08:12.201202 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1103152558-merged.mount: Deactivated successfully. Oct 30 00:08:12.212390 dockerd[15659]: time="2025-10-30T00:08:12.212354054Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 30 00:08:12.212464 dockerd[15659]: time="2025-10-30T00:08:12.212417851Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Oct 30 00:08:12.212514 dockerd[15659]: time="2025-10-30T00:08:12.212492548Z" level=info msg="Initializing buildkit" Oct 30 00:08:12.280089 dockerd[15659]: time="2025-10-30T00:08:12.280059293Z" level=info msg="Completed buildkit initialization" Oct 30 00:08:12.286918 dockerd[15659]: time="2025-10-30T00:08:12.286883594Z" level=info msg="Daemon has completed initialization" Oct 30 00:08:12.287008 dockerd[15659]: time="2025-10-30T00:08:12.286950092Z" level=info msg="API listen on /run/docker.sock" Oct 30 00:08:12.287121 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 30 00:08:13.459156 systemd[1]: var-lib-docker-overlay2-73563cf101789ab41314e583facee750ae2c615f41739eb00f089359f137499d\x2dinit-merged.mount: Deactivated successfully. Oct 30 00:08:13.576571 containerd[1913]: time="2025-10-30T00:08:13.576527720Z" level=info msg="connecting to shim 8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13" address="unix:///run/containerd/s/0de0459a8a57298dcbe61d30976f3d161eaa24a6cfdfed6430fb2866f0aed16d" namespace=moby protocol=ttrpc version=3 Oct 30 00:08:13.615916 systemd[1]: Started docker-8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13.scope - libcontainer container 8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13. Oct 30 00:08:13.663687 kernel: docker0: port 1(veth44a33ba) entered blocking state Oct 30 00:08:13.663794 kernel: docker0: port 1(veth44a33ba) entered disabled state Oct 30 00:08:13.663827 kernel: veth44a33ba: entered allmulticast mode Oct 30 00:08:13.668830 kernel: veth44a33ba: entered promiscuous mode Oct 30 00:08:13.669315 systemd-networkd[1576]: veth44a33ba: Link UP Oct 30 00:08:13.706795 kernel: eth0: renamed from vetheef858f Oct 30 00:08:13.713844 kernel: docker0: port 1(veth44a33ba) entered blocking state Oct 30 00:08:13.713906 kernel: docker0: port 1(veth44a33ba) entered forwarding state Oct 30 00:08:13.714240 systemd-networkd[1576]: veth44a33ba: Gained carrier Oct 30 00:08:13.714506 systemd-networkd[1576]: docker0: Gained carrier Oct 30 00:08:15.264918 systemd-networkd[1576]: docker0: Gained IPv6LL Oct 30 00:08:15.712915 systemd-networkd[1576]: veth44a33ba: Gained IPv6LL Oct 30 00:08:16.777470 systemd[1]: docker-8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13.scope: Deactivated successfully. Oct 30 00:08:16.790611 containerd[1913]: time="2025-10-30T00:08:16.790423929Z" level=info msg="shim disconnected" id=8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13 namespace=moby Oct 30 00:08:16.790611 containerd[1913]: time="2025-10-30T00:08:16.790459427Z" level=warning msg="cleaning up after shim disconnected" id=8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13 namespace=moby Oct 30 00:08:16.790611 containerd[1913]: time="2025-10-30T00:08:16.790468926Z" level=info msg="cleaning up dead shim" namespace=moby Oct 30 00:08:16.791010 dockerd[15659]: time="2025-10-30T00:08:16.790517624Z" level=info msg="ignoring event" container=8d5c3eda5aac19a2ed4fc727a288cc943d1cccaad371ba4e4152a70d12ee9a13 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 30 00:08:16.830568 systemd-networkd[1576]: veth44a33ba: Lost carrier Oct 30 00:08:16.830904 kernel: docker0: port 1(veth44a33ba) entered disabled state Oct 30 00:08:16.830936 kernel: vetheef858f: renamed from eth0 Oct 30 00:08:16.865039 systemd-networkd[1576]: veth44a33ba: Link DOWN Oct 30 00:08:16.868789 kernel: docker0: port 1(veth44a33ba) entered disabled state Oct 30 00:08:16.875590 kernel: veth44a33ba (unregistering): left allmulticast mode Oct 30 00:08:16.875639 kernel: veth44a33ba (unregistering): left promiscuous mode Oct 30 00:08:16.879030 kernel: docker0: port 1(veth44a33ba) entered disabled state Oct 30 00:08:16.905189 systemd[1]: run-docker-netns-9aee492a7a94.mount: Deactivated successfully. Oct 30 00:08:16.911251 systemd[1]: var-lib-docker-overlay2-73563cf101789ab41314e583facee750ae2c615f41739eb00f089359f137499d-merged.mount: Deactivated successfully. Oct 30 00:08:17.056589 sshd[15639]: Connection closed by 10.200.16.10 port 38954 Oct 30 00:08:17.056916 sshd-session[15636]: pam_unix(sshd:session): session closed for user core Oct 30 00:08:17.060717 systemd[1]: sshd@34-10.200.8.13:22-10.200.16.10:38954.service: Deactivated successfully. Oct 30 00:08:17.062479 systemd[1]: session-37.scope: Deactivated successfully. Oct 30 00:08:17.063246 systemd-logind[1880]: Session 37 logged out. Waiting for processes to exit. Oct 30 00:08:17.064480 systemd-logind[1880]: Removed session 37. Oct 30 00:08:17.177081 systemd[1]: Started sshd@35-10.200.8.13:22-10.200.16.10:44834.service - OpenSSH per-connection server daemon (10.200.16.10:44834). Oct 30 00:08:17.820375 sshd[15949]: Accepted publickey for core from 10.200.16.10 port 44834 ssh2: RSA SHA256:JyDYuHNPH0KFWpkefbMHGpeSgJxzAZfbbHlgzkeeU1M Oct 30 00:08:17.821707 sshd-session[15949]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 30 00:08:17.826057 systemd-logind[1880]: New session 38 of user core. Oct 30 00:08:17.835925 systemd[1]: Started session-38.scope - Session 38 of User core. Oct 30 00:08:17.888991 systemd-networkd[1576]: docker0: Lost carrier Oct 30 00:08:18.222643 systemd[1]: var-lib-docker-overlay2-f0fdc8ded27147985badd5f048143fade9bcd9e21eaadb5dcf0e36b268dfbd6d\x2dinit-merged.mount: Deactivated successfully. Oct 30 00:08:18.326839 containerd[1913]: time="2025-10-30T00:08:18.326788817Z" level=info msg="connecting to shim 30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd" address="unix:///run/containerd/s/5125240ffddc7c21f15876bd3756ba7fd317bdb041f57320f8aac73d7b8fc21d" namespace=moby protocol=ttrpc version=3 Oct 30 00:08:18.357920 systemd[1]: Started docker-30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd.scope - libcontainer container 30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd. Oct 30 00:08:18.401482 kernel: docker0: port 1(veth32ee670) entered blocking state Oct 30 00:08:18.401557 kernel: docker0: port 1(veth32ee670) entered disabled state Oct 30 00:08:18.404444 kernel: veth32ee670: entered allmulticast mode Oct 30 00:08:18.407788 kernel: veth32ee670: entered promiscuous mode Oct 30 00:08:18.407807 systemd-networkd[1576]: veth32ee670: Link UP Oct 30 00:08:18.433796 kernel: eth0: renamed from veth48144a4 Oct 30 00:08:18.441194 kernel: docker0: port 1(veth32ee670) entered blocking state Oct 30 00:08:18.441241 kernel: docker0: port 1(veth32ee670) entered forwarding state Oct 30 00:08:18.441589 systemd-networkd[1576]: veth32ee670: Gained carrier Oct 30 00:08:18.441872 systemd-networkd[1576]: docker0: Gained carrier Oct 30 00:08:18.478263 systemd[1]: docker-30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd.scope: Deactivated successfully. Oct 30 00:08:18.500040 dockerd[15659]: time="2025-10-30T00:08:18.499996019Z" level=info msg="ignoring event" container=30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 30 00:08:18.500450 containerd[1913]: time="2025-10-30T00:08:18.500118313Z" level=info msg="shim disconnected" id=30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd namespace=moby Oct 30 00:08:18.500450 containerd[1913]: time="2025-10-30T00:08:18.500145312Z" level=warning msg="cleaning up after shim disconnected" id=30133261d659023303b0257e1b23334c00f783e0886b6b296ff2a4e988eae3dd namespace=moby Oct 30 00:08:18.500450 containerd[1913]: time="2025-10-30T00:08:18.500153612Z" level=info msg="cleaning up dead shim" namespace=moby Oct 30 00:08:18.529989 systemd-networkd[1576]: veth32ee670: Lost carrier Oct 30 00:08:18.530793 kernel: docker0: port 1(veth32ee670) entered disabled state Oct 30 00:08:18.530836 kernel: veth48144a4: renamed from eth0 Oct 30 00:08:18.565118 systemd-networkd[1576]: veth32ee670: Link DOWN Oct 30 00:08:18.568797 kernel: docker0: port 1(veth32ee670) entered disabled state Oct 30 00:08:18.575899 kernel: veth32ee670 (unregistering): left allmulticast mode Oct 30 00:08:18.575961 kernel: veth32ee670 (unregistering): left promiscuous mode Oct 30 00:08:18.579323 kernel: docker0: port 1(veth32ee670) entered disabled state Oct 30 00:08:18.737497 sshd[15952]: Connection closed by 10.200.16.10 port 44834 Oct 30 00:08:18.737837 sshd-session[15949]: pam_unix(sshd:session): session closed for user core Oct 30 00:08:18.741376 systemd[1]: sshd@35-10.200.8.13:22-10.200.16.10:44834.service: Deactivated successfully. Oct 30 00:08:18.743015 systemd[1]: session-38.scope: Deactivated successfully. Oct 30 00:08:18.744359 systemd-logind[1880]: Session 38 logged out. Waiting for processes to exit. Oct 30 00:08:18.745289 systemd-logind[1880]: Removed session 38. Oct 30 00:08:18.896977 systemd-networkd[1576]: docker0: Lost carrier Oct 30 00:08:19.219839 systemd[1]: var-lib-docker-overlay2-f0fdc8ded27147985badd5f048143fade9bcd9e21eaadb5dcf0e36b268dfbd6d-merged.mount: Deactivated successfully.