Nov 24 00:26:51.192411 kernel: Linux version 6.12.58-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Nov 23 20:49:05 -00 2025 Nov 24 00:26:51.192439 kernel: Command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a5a093dfb613b73c778207057706f88d5254927e05ae90617f314b938bd34a14 Nov 24 00:26:51.192449 kernel: BIOS-provided physical RAM map: Nov 24 00:26:51.192455 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 24 00:26:51.192462 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 24 00:26:51.192468 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Nov 24 00:26:51.192476 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Nov 24 00:26:51.192486 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 24 00:26:51.192492 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 24 00:26:51.192499 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 24 00:26:51.192506 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Nov 24 00:26:51.192512 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Nov 24 00:26:51.192519 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 24 00:26:51.192526 kernel: NX (Execute Disable) protection: active Nov 24 00:26:51.192537 kernel: APIC: Static calls initialized Nov 24 00:26:51.192544 kernel: efi: EFI v2.7 by Microsoft Nov 24 00:26:51.192552 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Nov 24 00:26:51.192559 kernel: random: crng init done Nov 24 00:26:51.192567 kernel: secureboot: Secure boot disabled Nov 24 00:26:51.192574 kernel: SMBIOS 3.1.0 present. Nov 24 00:26:51.192582 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Nov 24 00:26:51.192589 kernel: DMI: Memory slots populated: 3/3 Nov 24 00:26:51.192596 kernel: Hypervisor detected: Microsoft Hyper-V Nov 24 00:26:51.192604 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Nov 24 00:26:51.192613 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 24 00:26:51.192621 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 24 00:26:51.192628 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 24 00:26:51.192635 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 24 00:26:51.192643 kernel: tsc: Marking TSC unstable due to running on Hyper-V Nov 24 00:26:51.192651 kernel: tsc: Detected 2593.992 MHz processor Nov 24 00:26:51.192659 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 24 00:26:51.192667 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 24 00:26:51.192674 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Nov 24 00:26:51.192684 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Nov 24 00:26:51.192692 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 24 00:26:51.192699 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Nov 24 00:26:51.192707 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Nov 24 00:26:51.192714 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Nov 24 00:26:51.192721 kernel: Using GB pages for direct mapping Nov 24 00:26:51.192741 kernel: ACPI: Early table checksum verification disabled Nov 24 00:26:51.192753 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 24 00:26:51.192764 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192772 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192780 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Nov 24 00:26:51.192788 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 24 00:26:51.192796 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192804 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192814 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192822 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192830 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192838 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192846 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 24 00:26:51.192854 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 24 00:26:51.192862 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Nov 24 00:26:51.192870 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 24 00:26:51.192878 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 24 00:26:51.192888 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 24 00:26:51.192896 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 24 00:26:51.192904 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Nov 24 00:26:51.192912 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Nov 24 00:26:51.192920 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 24 00:26:51.192928 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Nov 24 00:26:51.192936 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 24 00:26:51.192944 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Nov 24 00:26:51.192954 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Nov 24 00:26:51.192962 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Nov 24 00:26:51.192970 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Nov 24 00:26:51.192978 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Nov 24 00:26:51.192986 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Nov 24 00:26:51.192994 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Nov 24 00:26:51.193002 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Nov 24 00:26:51.193010 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Nov 24 00:26:51.193018 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Nov 24 00:26:51.193028 kernel: Zone ranges: Nov 24 00:26:51.193037 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 24 00:26:51.193044 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 24 00:26:51.193052 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Nov 24 00:26:51.193060 kernel: Device empty Nov 24 00:26:51.193068 kernel: Movable zone start for each node Nov 24 00:26:51.193076 kernel: Early memory node ranges Nov 24 00:26:51.193084 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 24 00:26:51.193092 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Nov 24 00:26:51.193102 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 24 00:26:51.193110 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Nov 24 00:26:51.193118 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Nov 24 00:26:51.193126 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Nov 24 00:26:51.193134 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 24 00:26:51.193142 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 24 00:26:51.193150 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Nov 24 00:26:51.193157 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 24 00:26:51.193165 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Nov 24 00:26:51.193176 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Nov 24 00:26:51.193184 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 24 00:26:51.193192 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 24 00:26:51.193200 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 24 00:26:51.193208 kernel: CPU topo: Max. logical packages: 1 Nov 24 00:26:51.193216 kernel: CPU topo: Max. logical dies: 1 Nov 24 00:26:51.193224 kernel: CPU topo: Max. dies per package: 1 Nov 24 00:26:51.193232 kernel: CPU topo: Max. threads per core: 1 Nov 24 00:26:51.193239 kernel: CPU topo: Num. cores per package: 6 Nov 24 00:26:51.193250 kernel: CPU topo: Num. threads per package: 6 Nov 24 00:26:51.193258 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Nov 24 00:26:51.193266 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 24 00:26:51.193274 kernel: Booting paravirtualized kernel on Hyper-V Nov 24 00:26:51.193282 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 24 00:26:51.193290 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Nov 24 00:26:51.193298 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Nov 24 00:26:51.193306 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Nov 24 00:26:51.193314 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Nov 24 00:26:51.193324 kernel: Hyper-V: PV spinlocks enabled Nov 24 00:26:51.193332 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 24 00:26:51.193341 kernel: Kernel command line: rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a5a093dfb613b73c778207057706f88d5254927e05ae90617f314b938bd34a14 Nov 24 00:26:51.193349 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Nov 24 00:26:51.193358 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Nov 24 00:26:51.193366 kernel: Fallback order for Node 0: 0 Nov 24 00:26:51.193373 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Nov 24 00:26:51.193381 kernel: Policy zone: Normal Nov 24 00:26:51.193392 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 24 00:26:51.193408 kernel: software IO TLB: area num 8. Nov 24 00:26:51.193417 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Nov 24 00:26:51.193428 kernel: Kernel/User page tables isolation: enabled Nov 24 00:26:51.193436 kernel: ftrace: allocating 40103 entries in 157 pages Nov 24 00:26:51.193444 kernel: ftrace: allocated 157 pages with 5 groups Nov 24 00:26:51.193453 kernel: Dynamic Preempt: voluntary Nov 24 00:26:51.193461 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 24 00:26:51.193473 kernel: rcu: RCU event tracing is enabled. Nov 24 00:26:51.193482 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Nov 24 00:26:51.193491 kernel: Trampoline variant of Tasks RCU enabled. Nov 24 00:26:51.193502 kernel: Rude variant of Tasks RCU enabled. Nov 24 00:26:51.193511 kernel: Tracing variant of Tasks RCU enabled. Nov 24 00:26:51.193519 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 24 00:26:51.193528 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Nov 24 00:26:51.193536 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:26:51.193545 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:26:51.193556 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Nov 24 00:26:51.193564 kernel: Using NULL legacy PIC Nov 24 00:26:51.193573 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Nov 24 00:26:51.193581 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 24 00:26:51.193589 kernel: Console: colour dummy device 80x25 Nov 24 00:26:51.193598 kernel: printk: legacy console [tty1] enabled Nov 24 00:26:51.193606 kernel: printk: legacy console [ttyS0] enabled Nov 24 00:26:51.193615 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 24 00:26:51.193623 kernel: ACPI: Core revision 20240827 Nov 24 00:26:51.193634 kernel: Failed to register legacy timer interrupt Nov 24 00:26:51.193642 kernel: APIC: Switch to symmetric I/O mode setup Nov 24 00:26:51.193651 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Nov 24 00:26:51.193659 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 24 00:26:51.193667 kernel: Hyper-V: Using IPI hypercalls Nov 24 00:26:51.193676 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 24 00:26:51.193684 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 24 00:26:51.193692 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 24 00:26:51.193701 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 24 00:26:51.193712 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 24 00:26:51.193720 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 24 00:26:51.193737 kernel: Hyper-V: Using enlightened APIC (xapic mode) Nov 24 00:26:51.193745 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Nov 24 00:26:51.193753 kernel: APIC: read() replaced with hv_apic_read() Nov 24 00:26:51.193762 kernel: APIC: write() replaced with hv_apic_write() Nov 24 00:26:51.193770 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Nov 24 00:26:51.193778 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Nov 24 00:26:51.193787 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593992) Nov 24 00:26:51.193799 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Nov 24 00:26:51.193807 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Nov 24 00:26:51.193816 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 24 00:26:51.193824 kernel: Spectre V2 : Mitigation: Retpolines Nov 24 00:26:51.193832 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 24 00:26:51.193840 kernel: Speculative Store Bypass: Vulnerable Nov 24 00:26:51.193848 kernel: MDS: Mitigation: Clear CPU buffers Nov 24 00:26:51.193857 kernel: TAA: Mitigation: Clear CPU buffers Nov 24 00:26:51.193865 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Nov 24 00:26:51.193873 kernel: active return thunk: its_return_thunk Nov 24 00:26:51.193881 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 24 00:26:51.193892 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 24 00:26:51.193907 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 24 00:26:51.193916 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 24 00:26:51.193925 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 24 00:26:51.193933 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Nov 24 00:26:51.193942 kernel: Freeing SMP alternatives memory: 32K Nov 24 00:26:51.193950 kernel: pid_max: default: 32768 minimum: 301 Nov 24 00:26:51.193958 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 24 00:26:51.193966 kernel: landlock: Up and running. Nov 24 00:26:51.193975 kernel: SELinux: Initializing. Nov 24 00:26:51.193983 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 24 00:26:51.193995 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 24 00:26:51.194003 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Nov 24 00:26:51.194012 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Nov 24 00:26:51.194021 kernel: signal: max sigframe size: 1776 Nov 24 00:26:51.194029 kernel: rcu: Hierarchical SRCU implementation. Nov 24 00:26:51.194038 kernel: rcu: Max phase no-delay instances is 400. Nov 24 00:26:51.194046 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 24 00:26:51.194055 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 24 00:26:51.194063 kernel: smp: Bringing up secondary CPUs ... Nov 24 00:26:51.194074 kernel: smpboot: x86: Booting SMP configuration: Nov 24 00:26:51.194083 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Nov 24 00:26:51.194091 kernel: smp: Brought up 1 node, 6 CPUs Nov 24 00:26:51.194100 kernel: smpboot: Total of 6 processors activated (31127.90 BogoMIPS) Nov 24 00:26:51.194109 kernel: Memory: 115330900K/117439364K available (14336K kernel code, 2444K rwdata, 26064K rodata, 46200K init, 2560K bss, 2098188K reserved, 0K cma-reserved) Nov 24 00:26:51.194117 kernel: devtmpfs: initialized Nov 24 00:26:51.194126 kernel: x86/mm: Memory block size: 1024MB Nov 24 00:26:51.194134 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 24 00:26:51.194143 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 24 00:26:51.194154 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Nov 24 00:26:51.194163 kernel: pinctrl core: initialized pinctrl subsystem Nov 24 00:26:51.194171 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 24 00:26:51.194179 kernel: audit: initializing netlink subsys (disabled) Nov 24 00:26:51.194188 kernel: audit: type=2000 audit(1763944007.028:1): state=initialized audit_enabled=0 res=1 Nov 24 00:26:51.194196 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 24 00:26:51.194205 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 24 00:26:51.194213 kernel: cpuidle: using governor menu Nov 24 00:26:51.194222 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 24 00:26:51.194233 kernel: dca service started, version 1.12.1 Nov 24 00:26:51.194241 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Nov 24 00:26:51.194250 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 24 00:26:51.194258 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 24 00:26:51.194267 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 24 00:26:51.194275 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 24 00:26:51.194284 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 24 00:26:51.194292 kernel: ACPI: Added _OSI(Module Device) Nov 24 00:26:51.194301 kernel: ACPI: Added _OSI(Processor Device) Nov 24 00:26:51.194312 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 24 00:26:51.194320 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 24 00:26:51.194329 kernel: ACPI: Interpreter enabled Nov 24 00:26:51.194337 kernel: ACPI: PM: (supports S0 S5) Nov 24 00:26:51.194346 kernel: ACPI: Using IOAPIC for interrupt routing Nov 24 00:26:51.194354 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 24 00:26:51.194363 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 24 00:26:51.194371 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 24 00:26:51.194380 kernel: iommu: Default domain type: Translated Nov 24 00:26:51.194390 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 24 00:26:51.194399 kernel: efivars: Registered efivars operations Nov 24 00:26:51.194407 kernel: PCI: Using ACPI for IRQ routing Nov 24 00:26:51.194416 kernel: PCI: System does not support PCI Nov 24 00:26:51.194424 kernel: vgaarb: loaded Nov 24 00:26:51.194433 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Nov 24 00:26:51.194441 kernel: VFS: Disk quotas dquot_6.6.0 Nov 24 00:26:51.194449 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 24 00:26:51.194458 kernel: pnp: PnP ACPI init Nov 24 00:26:51.194469 kernel: pnp: PnP ACPI: found 3 devices Nov 24 00:26:51.194477 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 24 00:26:51.194486 kernel: NET: Registered PF_INET protocol family Nov 24 00:26:51.194494 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 24 00:26:51.194503 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Nov 24 00:26:51.194511 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 24 00:26:51.194520 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 24 00:26:51.194528 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:26:51.194537 kernel: TCP: Hash tables configured (established 524288 bind 65536) Nov 24 00:26:51.194548 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:26:51.194556 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 24 00:26:51.194565 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 24 00:26:51.194573 kernel: NET: Registered PF_XDP protocol family Nov 24 00:26:51.194581 kernel: PCI: CLS 0 bytes, default 64 Nov 24 00:26:51.194590 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 24 00:26:51.194598 kernel: software IO TLB: mapped [mem 0x000000003ae61000-0x000000003ee61000] (64MB) Nov 24 00:26:51.194607 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Nov 24 00:26:51.194615 kernel: Initialise system trusted keyrings Nov 24 00:26:51.194626 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Nov 24 00:26:51.194634 kernel: Key type asymmetric registered Nov 24 00:26:51.194643 kernel: Asymmetric key parser 'x509' registered Nov 24 00:26:51.194651 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 24 00:26:51.194659 kernel: io scheduler mq-deadline registered Nov 24 00:26:51.194668 kernel: io scheduler kyber registered Nov 24 00:26:51.194676 kernel: io scheduler bfq registered Nov 24 00:26:51.194685 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 24 00:26:51.194693 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 24 00:26:51.194704 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 24 00:26:51.194713 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 24 00:26:51.194721 kernel: i8042: PNP: No PS/2 controller found. Nov 24 00:26:51.194880 kernel: rtc_cmos 00:02: registered as rtc0 Nov 24 00:26:51.194984 kernel: rtc_cmos 00:02: setting system clock to 2025-11-24T00:26:50 UTC (1763944010) Nov 24 00:26:51.195080 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 24 00:26:51.195093 kernel: intel_pstate: CPU model not supported Nov 24 00:26:51.195107 kernel: efifb: probing for efifb Nov 24 00:26:51.195115 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 24 00:26:51.195124 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 24 00:26:51.195132 kernel: efifb: scrolling: redraw Nov 24 00:26:51.195141 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 24 00:26:51.195149 kernel: Console: switching to colour frame buffer device 128x48 Nov 24 00:26:51.195158 kernel: fb0: EFI VGA frame buffer device Nov 24 00:26:51.195166 kernel: pstore: Using crash dump compression: deflate Nov 24 00:26:51.195175 kernel: pstore: Registered efi_pstore as persistent store backend Nov 24 00:26:51.195186 kernel: NET: Registered PF_INET6 protocol family Nov 24 00:26:51.195195 kernel: Segment Routing with IPv6 Nov 24 00:26:51.195203 kernel: In-situ OAM (IOAM) with IPv6 Nov 24 00:26:51.195212 kernel: NET: Registered PF_PACKET protocol family Nov 24 00:26:51.195220 kernel: Key type dns_resolver registered Nov 24 00:26:51.195228 kernel: IPI shorthand broadcast: enabled Nov 24 00:26:51.195237 kernel: sched_clock: Marking stable (3614006100, 113421100)->(4077701100, -350273900) Nov 24 00:26:51.195245 kernel: registered taskstats version 1 Nov 24 00:26:51.195254 kernel: Loading compiled-in X.509 certificates Nov 24 00:26:51.195262 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.58-flatcar: 960cbe7f2b1ea74b5c881d6d42eea4d1ac19a607' Nov 24 00:26:51.195273 kernel: Demotion targets for Node 0: null Nov 24 00:26:51.195281 kernel: Key type .fscrypt registered Nov 24 00:26:51.195290 kernel: Key type fscrypt-provisioning registered Nov 24 00:26:51.195298 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 24 00:26:51.195306 kernel: ima: Allocated hash algorithm: sha1 Nov 24 00:26:51.195315 kernel: ima: No architecture policies found Nov 24 00:26:51.195323 kernel: clk: Disabling unused clocks Nov 24 00:26:51.195331 kernel: Warning: unable to open an initial console. Nov 24 00:26:51.195340 kernel: Freeing unused kernel image (initmem) memory: 46200K Nov 24 00:26:51.195351 kernel: Write protecting the kernel read-only data: 40960k Nov 24 00:26:51.195360 kernel: Freeing unused kernel image (rodata/data gap) memory: 560K Nov 24 00:26:51.195368 kernel: Run /init as init process Nov 24 00:26:51.195377 kernel: with arguments: Nov 24 00:26:51.195385 kernel: /init Nov 24 00:26:51.195393 kernel: with environment: Nov 24 00:26:51.195401 kernel: HOME=/ Nov 24 00:26:51.195409 kernel: TERM=linux Nov 24 00:26:51.195419 systemd[1]: Successfully made /usr/ read-only. Nov 24 00:26:51.195433 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 24 00:26:51.195443 systemd[1]: Detected virtualization microsoft. Nov 24 00:26:51.195452 systemd[1]: Detected architecture x86-64. Nov 24 00:26:51.195460 systemd[1]: Running in initrd. Nov 24 00:26:51.195469 systemd[1]: No hostname configured, using default hostname. Nov 24 00:26:51.195478 systemd[1]: Hostname set to . Nov 24 00:26:51.195487 systemd[1]: Initializing machine ID from random generator. Nov 24 00:26:51.195498 systemd[1]: Queued start job for default target initrd.target. Nov 24 00:26:51.195507 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:26:51.195516 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:26:51.195526 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 24 00:26:51.195535 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 24 00:26:51.195544 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 24 00:26:51.195554 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 24 00:26:51.195566 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Nov 24 00:26:51.195576 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Nov 24 00:26:51.195585 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:26:51.195594 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:26:51.195603 systemd[1]: Reached target paths.target - Path Units. Nov 24 00:26:51.195612 systemd[1]: Reached target slices.target - Slice Units. Nov 24 00:26:51.195620 systemd[1]: Reached target swap.target - Swaps. Nov 24 00:26:51.195629 systemd[1]: Reached target timers.target - Timer Units. Nov 24 00:26:51.195641 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 24 00:26:51.195650 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 24 00:26:51.195659 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 24 00:26:51.195668 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 24 00:26:51.195677 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:26:51.195686 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 24 00:26:51.195695 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:26:51.195703 systemd[1]: Reached target sockets.target - Socket Units. Nov 24 00:26:51.195712 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 24 00:26:51.195735 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 24 00:26:51.195746 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 24 00:26:51.195755 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 24 00:26:51.195764 systemd[1]: Starting systemd-fsck-usr.service... Nov 24 00:26:51.195773 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 24 00:26:51.195782 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 24 00:26:51.195810 systemd-journald[214]: Collecting audit messages is disabled. Nov 24 00:26:51.195836 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:26:51.195846 systemd-journald[214]: Journal started Nov 24 00:26:51.195869 systemd-journald[214]: Runtime Journal (/run/log/journal/ba52891babd94342b370e01d1caa2c4f) is 8M, max 2.2G, 2.1G free. Nov 24 00:26:51.198854 systemd-modules-load[216]: Inserted module 'overlay' Nov 24 00:26:51.206843 systemd[1]: Started systemd-journald.service - Journal Service. Nov 24 00:26:51.207281 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 24 00:26:51.207797 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:26:51.208493 systemd[1]: Finished systemd-fsck-usr.service. Nov 24 00:26:51.210718 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 24 00:26:51.211772 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 24 00:26:51.257229 systemd-tmpfiles[224]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 24 00:26:51.257971 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 24 00:26:51.268455 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:26:51.278740 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:26:51.284628 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 24 00:26:51.293385 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 24 00:26:51.320746 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 24 00:26:51.324826 systemd-modules-load[216]: Inserted module 'br_netfilter' Nov 24 00:26:51.325742 kernel: Bridge firewalling registered Nov 24 00:26:51.325777 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 24 00:26:51.327192 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 24 00:26:51.329855 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:26:51.359500 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:26:51.361015 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 24 00:26:51.365589 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 24 00:26:51.375797 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 24 00:26:51.396262 dracut-cmdline[257]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 rootflags=rw mount.usrflags=ro BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=a5a093dfb613b73c778207057706f88d5254927e05ae90617f314b938bd34a14 Nov 24 00:26:51.410756 systemd-resolved[253]: Positive Trust Anchors: Nov 24 00:26:51.410764 systemd-resolved[253]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 24 00:26:51.410796 systemd-resolved[253]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 24 00:26:51.413811 systemd-resolved[253]: Defaulting to hostname 'linux'. Nov 24 00:26:51.414892 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 24 00:26:51.424772 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:26:51.559748 kernel: SCSI subsystem initialized Nov 24 00:26:51.570748 kernel: Loading iSCSI transport class v2.0-870. Nov 24 00:26:51.582747 kernel: iscsi: registered transport (tcp) Nov 24 00:26:51.607667 kernel: iscsi: registered transport (qla4xxx) Nov 24 00:26:51.607697 kernel: QLogic iSCSI HBA Driver Nov 24 00:26:51.626343 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 24 00:26:51.657197 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:26:51.658291 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 24 00:26:51.719316 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 24 00:26:51.725314 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 24 00:26:51.790758 kernel: raid6: avx2x4 gen() 29689 MB/s Nov 24 00:26:51.809741 kernel: raid6: avx2x2 gen() 29375 MB/s Nov 24 00:26:51.831196 kernel: raid6: avx2x1 gen() 27908 MB/s Nov 24 00:26:51.831224 kernel: raid6: using algorithm avx2x4 gen() 29689 MB/s Nov 24 00:26:51.853764 kernel: raid6: .... xor() 10343 MB/s, rmw enabled Nov 24 00:26:51.853793 kernel: raid6: using avx2x2 recovery algorithm Nov 24 00:26:51.876747 kernel: xor: automatically using best checksumming function avx Nov 24 00:26:52.071758 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 24 00:26:52.078340 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 24 00:26:52.088277 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:26:52.123121 systemd-udevd[467]: Using default interface naming scheme 'v255'. Nov 24 00:26:52.129026 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:26:52.130039 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 24 00:26:52.173422 dracut-pre-trigger[472]: rd.md=0: removing MD RAID activation Nov 24 00:26:52.205384 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 24 00:26:52.206621 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 24 00:26:52.286559 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:26:52.294127 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 24 00:26:52.323042 kernel: cryptd: max_cpu_qlen set to 1000 Nov 24 00:26:52.329746 kernel: hv_vmbus: Vmbus version:4.0 Nov 24 00:26:52.337754 kernel: AES CTR mode by8 optimization enabled Nov 24 00:26:52.365494 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:26:52.383902 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 24 00:26:52.383929 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 24 00:26:52.383942 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 24 00:26:52.383953 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Nov 24 00:26:52.383973 kernel: PTP clock support registered Nov 24 00:26:52.365668 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:26:52.397829 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:26:52.407584 kernel: hv_vmbus: registering driver hv_pci Nov 24 00:26:52.400065 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:26:52.435899 kernel: hv_vmbus: registering driver hv_storvsc Nov 24 00:26:52.435919 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Nov 24 00:26:52.436092 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 24 00:26:52.436106 kernel: scsi host0: storvsc_host_t Nov 24 00:26:52.436430 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 24 00:26:52.436483 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 24 00:26:52.421837 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:26:52.445745 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Nov 24 00:26:52.448741 kernel: scsi host1: storvsc_host_t Nov 24 00:26:52.448786 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Nov 24 00:26:52.456048 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Nov 24 00:26:52.460090 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Nov 24 00:26:52.464204 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Nov 24 00:26:52.469821 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Nov 24 00:26:52.469865 kernel: hv_vmbus: registering driver hv_netvsc Nov 24 00:26:52.471738 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Nov 24 00:26:52.471765 kernel: hv_utils: Registering HyperV Utility Driver Nov 24 00:26:52.472902 kernel: hv_vmbus: registering driver hid_hyperv Nov 24 00:26:52.472929 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 24 00:26:52.473227 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 24 00:26:52.473374 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 24 00:26:52.473514 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 24 00:26:52.473657 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 24 00:26:52.473822 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#134 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:26:52.473959 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Nov 24 00:26:52.476042 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Nov 24 00:26:52.476193 kernel: sd 0:0:0:1: [sdb] Write Protect is off Nov 24 00:26:52.476328 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Nov 24 00:26:52.476459 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 24 00:26:52.476588 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#65 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:26:52.476710 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#578 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:26:52.476846 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Nov 24 00:26:52.476877 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Nov 24 00:26:52.476890 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 24 00:26:52.477020 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#73 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:26:52.474208 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:26:53.664422 kernel: hv_vmbus: registering driver hv_utils Nov 24 00:26:53.664452 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Nov 24 00:26:53.664741 kernel: hv_utils: Heartbeat IC version 3.0 Nov 24 00:26:53.664760 kernel: pci 0001:00:00.0: Enabling HDA controller Nov 24 00:26:53.664912 kernel: hv_utils: TimeSync IC version 4.0 Nov 24 00:26:53.664926 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Nov 24 00:26:53.665059 kernel: hv_utils: Shutdown IC version 3.2 Nov 24 00:26:53.665073 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Nov 24 00:26:53.665202 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Nov 24 00:26:53.665336 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Nov 24 00:26:53.665471 kernel: sdb: sdb1 Nov 24 00:26:53.665485 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Nov 24 00:26:53.665646 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:26:53.665660 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 24 00:26:52.474314 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:26:53.578755 systemd-resolved[253]: Clock change detected. Flushing caches. Nov 24 00:26:53.686058 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 24 00:26:53.686296 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 24 00:26:53.686312 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 24 00:26:53.662364 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:26:53.701993 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:26:54.120126 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 24 00:26:54.140135 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 24 00:26:54.206180 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Nov 24 00:26:54.206342 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 24 00:26:54.228116 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 24 00:26:54.251392 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 24 00:26:54.272562 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#424 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:26:54.300563 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:26:55.315682 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#95 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:26:55.329360 disk-uuid[705]: The operation has completed successfully. Nov 24 00:26:55.333609 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 24 00:26:56.991476 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 24 00:26:56.991605 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 24 00:26:57.002042 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Nov 24 00:26:57.012345 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 24 00:26:57.017602 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 24 00:26:57.032348 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:26:57.047693 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 24 00:26:57.059343 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 24 00:26:57.091702 sh[870]: Success Nov 24 00:26:57.099177 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 24 00:26:57.137496 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 24 00:26:57.137531 kernel: device-mapper: uevent: version 1.0.3 Nov 24 00:26:57.142602 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 24 00:26:57.154562 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Nov 24 00:26:57.384656 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Nov 24 00:26:57.394592 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Nov 24 00:26:57.416072 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Nov 24 00:26:57.433573 kernel: BTRFS: device fsid 3af95a3e-5df6-49e0-91e3-ddf2109f68c7 devid 1 transid 35 /dev/mapper/usr (254:0) scanned by mount (902) Nov 24 00:26:57.433609 kernel: BTRFS info (device dm-0): first mount of filesystem 3af95a3e-5df6-49e0-91e3-ddf2109f68c7 Nov 24 00:26:57.442469 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:26:57.733833 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 24 00:26:57.733923 kernel: BTRFS info (device dm-0): enabling free space tree Nov 24 00:26:57.779167 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Nov 24 00:26:57.787061 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 24 00:26:57.796667 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 24 00:26:57.805975 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 24 00:26:57.827040 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 24 00:26:57.862567 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (932) Nov 24 00:26:57.872565 kernel: BTRFS info (device sda6): first mount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:26:57.872596 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:26:57.894258 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:26:57.894303 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:26:57.902564 kernel: BTRFS info (device sda6): last unmount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:26:57.903505 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 24 00:26:57.904608 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 24 00:26:57.952685 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 24 00:26:57.954390 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 24 00:26:58.006036 systemd-networkd[1071]: lo: Link UP Nov 24 00:26:58.006047 systemd-networkd[1071]: lo: Gained carrier Nov 24 00:26:58.007115 systemd-networkd[1071]: Enumeration completed Nov 24 00:26:58.007471 systemd-networkd[1071]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:26:58.007476 systemd-networkd[1071]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:26:58.008220 systemd-networkd[1071]: eth0: Link UP Nov 24 00:26:58.008379 systemd-networkd[1071]: eth0: Gained carrier Nov 24 00:26:58.008389 systemd-networkd[1071]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:26:58.009141 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 24 00:26:58.012186 systemd[1]: Reached target network.target - Network. Nov 24 00:26:58.070587 systemd-networkd[1071]: eth0: DHCPv4 address 10.200.4.4/24, gateway 10.200.4.1 acquired from 168.63.129.16 Nov 24 00:26:59.136895 ignition[1032]: Ignition 2.22.0 Nov 24 00:26:59.136931 ignition[1032]: Stage: fetch-offline Nov 24 00:26:59.137083 ignition[1032]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:26:59.137099 ignition[1032]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:26:59.137200 ignition[1032]: parsed url from cmdline: "" Nov 24 00:26:59.137204 ignition[1032]: no config URL provided Nov 24 00:26:59.150161 ignition[1032]: reading system config file "/usr/lib/ignition/user.ign" Nov 24 00:26:59.153088 ignition[1032]: no config at "/usr/lib/ignition/user.ign" Nov 24 00:26:59.153096 ignition[1032]: failed to fetch config: resource requires networking Nov 24 00:26:59.164967 ignition[1032]: Ignition finished successfully Nov 24 00:26:59.166654 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 24 00:26:59.173758 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 24 00:26:59.211242 ignition[1084]: Ignition 2.22.0 Nov 24 00:26:59.211256 ignition[1084]: Stage: fetch Nov 24 00:26:59.211460 ignition[1084]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:26:59.211471 ignition[1084]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:26:59.211576 ignition[1084]: parsed url from cmdline: "" Nov 24 00:26:59.211580 ignition[1084]: no config URL provided Nov 24 00:26:59.211585 ignition[1084]: reading system config file "/usr/lib/ignition/user.ign" Nov 24 00:26:59.211594 ignition[1084]: no config at "/usr/lib/ignition/user.ign" Nov 24 00:26:59.211611 ignition[1084]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 24 00:26:59.333735 ignition[1084]: GET result: OK Nov 24 00:26:59.333787 ignition[1084]: config has been read from IMDS userdata Nov 24 00:26:59.333799 ignition[1084]: parsing config with SHA512: c29c7553e4cada4606f00c7cd090f6c4cd7bc06a012d242dbce184ef3ebad8d1b84058af04e852db23dd261f5cb27d4a4139ed6e72bb9d4a26609e6a06a4e3d4 Nov 24 00:26:59.339161 unknown[1084]: fetched base config from "system" Nov 24 00:26:59.339382 ignition[1084]: fetch: fetch complete Nov 24 00:26:59.339172 unknown[1084]: fetched base config from "system" Nov 24 00:26:59.339388 ignition[1084]: fetch: fetch passed Nov 24 00:26:59.339180 unknown[1084]: fetched user config from "azure" Nov 24 00:26:59.339432 ignition[1084]: Ignition finished successfully Nov 24 00:26:59.348068 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 24 00:26:59.351016 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 24 00:26:59.398249 ignition[1094]: Ignition 2.22.0 Nov 24 00:26:59.398263 ignition[1094]: Stage: kargs Nov 24 00:26:59.398505 ignition[1094]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:26:59.398517 ignition[1094]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:26:59.399137 ignition[1094]: kargs: kargs passed Nov 24 00:26:59.407093 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 24 00:26:59.399180 ignition[1094]: Ignition finished successfully Nov 24 00:26:59.411818 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 24 00:26:59.458099 ignition[1104]: Ignition 2.22.0 Nov 24 00:26:59.458113 ignition[1104]: Stage: disks Nov 24 00:26:59.458354 ignition[1104]: no configs at "/usr/lib/ignition/base.d" Nov 24 00:26:59.458365 ignition[1104]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:26:59.464317 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 24 00:26:59.458859 ignition[1104]: disks: disks passed Nov 24 00:26:59.466714 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 24 00:26:59.458906 ignition[1104]: Ignition finished successfully Nov 24 00:26:59.474792 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 24 00:26:59.488017 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 24 00:26:59.494516 systemd[1]: Reached target sysinit.target - System Initialization. Nov 24 00:26:59.495300 systemd[1]: Reached target basic.target - Basic System. Nov 24 00:26:59.506653 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 24 00:26:59.595534 systemd-fsck[1116]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Nov 24 00:26:59.600696 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 24 00:26:59.606723 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 24 00:26:59.736736 systemd-networkd[1071]: eth0: Gained IPv6LL Nov 24 00:26:59.859558 kernel: EXT4-fs (sda9): mounted filesystem f89e2a65-2a4a-426b-9659-02844cc29a2a r/w with ordered data mode. Quota mode: none. Nov 24 00:26:59.860170 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 24 00:26:59.860789 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 24 00:26:59.879483 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 24 00:26:59.880495 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 24 00:26:59.887100 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 24 00:26:59.892741 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 24 00:26:59.892777 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 24 00:26:59.941333 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 24 00:26:59.948339 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 24 00:26:59.960570 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1130) Nov 24 00:26:59.969928 kernel: BTRFS info (device sda6): first mount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:26:59.969957 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:26:59.980816 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:26:59.980841 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:26:59.982637 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 24 00:27:00.573937 coreos-metadata[1132]: Nov 24 00:27:00.573 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 24 00:27:00.581830 coreos-metadata[1132]: Nov 24 00:27:00.581 INFO Fetch successful Nov 24 00:27:00.585715 coreos-metadata[1132]: Nov 24 00:27:00.581 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 24 00:27:00.600585 coreos-metadata[1132]: Nov 24 00:27:00.600 INFO Fetch successful Nov 24 00:27:00.604485 coreos-metadata[1132]: Nov 24 00:27:00.600 INFO wrote hostname ci-4459.2.1-a-b2d2dc4cec to /sysroot/etc/hostname Nov 24 00:27:00.602789 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 24 00:27:00.842003 initrd-setup-root[1160]: cut: /sysroot/etc/passwd: No such file or directory Nov 24 00:27:00.900736 initrd-setup-root[1167]: cut: /sysroot/etc/group: No such file or directory Nov 24 00:27:00.926101 initrd-setup-root[1174]: cut: /sysroot/etc/shadow: No such file or directory Nov 24 00:27:00.931238 initrd-setup-root[1181]: cut: /sysroot/etc/gshadow: No such file or directory Nov 24 00:27:01.684115 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 24 00:27:01.691400 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 24 00:27:01.698335 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 24 00:27:01.716699 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 24 00:27:01.725528 kernel: BTRFS info (device sda6): last unmount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:27:01.745304 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 24 00:27:01.754700 ignition[1251]: INFO : Ignition 2.22.0 Nov 24 00:27:01.754700 ignition[1251]: INFO : Stage: mount Nov 24 00:27:01.760286 ignition[1251]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:27:01.760286 ignition[1251]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:27:01.760286 ignition[1251]: INFO : mount: mount passed Nov 24 00:27:01.760286 ignition[1251]: INFO : Ignition finished successfully Nov 24 00:27:01.770972 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 24 00:27:01.772952 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 24 00:27:01.800519 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 24 00:27:01.826566 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1264) Nov 24 00:27:01.835438 kernel: BTRFS info (device sda6): first mount of filesystem 1e21b02a-5e52-4507-8281-b06fd4c187c7 Nov 24 00:27:01.835473 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Nov 24 00:27:01.846062 kernel: BTRFS info (device sda6): turning on async discard Nov 24 00:27:01.846090 kernel: BTRFS info (device sda6): enabling free space tree Nov 24 00:27:01.848284 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 24 00:27:01.892794 ignition[1281]: INFO : Ignition 2.22.0 Nov 24 00:27:01.892794 ignition[1281]: INFO : Stage: files Nov 24 00:27:01.898841 ignition[1281]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:27:01.898841 ignition[1281]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:27:01.898841 ignition[1281]: DEBUG : files: compiled without relabeling support, skipping Nov 24 00:27:01.912162 ignition[1281]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 24 00:27:01.912162 ignition[1281]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 24 00:27:01.936796 ignition[1281]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 24 00:27:01.942252 ignition[1281]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 24 00:27:01.947408 ignition[1281]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 24 00:27:01.942741 unknown[1281]: wrote ssh authorized keys file for user: core Nov 24 00:27:01.978469 ignition[1281]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 24 00:27:01.985575 ignition[1281]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 24 00:27:01.985575 ignition[1281]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 24 00:27:01.985575 ignition[1281]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 24 00:27:01.985575 ignition[1281]: INFO : files: files passed Nov 24 00:27:01.985575 ignition[1281]: INFO : Ignition finished successfully Nov 24 00:27:02.002241 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 24 00:27:02.008907 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 24 00:27:02.033815 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 24 00:27:02.037671 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 24 00:27:02.037771 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 24 00:27:02.069554 initrd-setup-root-after-ignition[1313]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:27:02.075937 initrd-setup-root-after-ignition[1313]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:27:02.082712 initrd-setup-root-after-ignition[1317]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 24 00:27:02.083575 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 24 00:27:02.089896 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 24 00:27:02.100430 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 24 00:27:02.146883 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 24 00:27:02.147031 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 24 00:27:02.151276 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 24 00:27:02.160791 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 24 00:27:02.168561 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 24 00:27:02.169407 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 24 00:27:02.219766 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 24 00:27:02.221119 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 24 00:27:02.257562 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:27:02.257790 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:27:02.266803 systemd[1]: Stopped target timers.target - Timer Units. Nov 24 00:27:02.275832 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 24 00:27:02.275967 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 24 00:27:02.291175 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 24 00:27:02.295826 systemd[1]: Stopped target basic.target - Basic System. Nov 24 00:27:02.303628 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 24 00:27:02.310966 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 24 00:27:02.319164 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 24 00:27:02.328222 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 24 00:27:02.336808 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 24 00:27:02.346459 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 24 00:27:02.354171 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 24 00:27:02.363847 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 24 00:27:02.371699 systemd[1]: Stopped target swap.target - Swaps. Nov 24 00:27:02.383011 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 24 00:27:02.383154 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 24 00:27:02.394038 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:27:02.398166 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:27:02.406471 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 24 00:27:02.406606 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:27:02.429868 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 24 00:27:02.430014 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 24 00:27:02.441995 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 24 00:27:02.442127 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 24 00:27:02.446529 systemd[1]: ignition-files.service: Deactivated successfully. Nov 24 00:27:02.446665 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 24 00:27:02.456319 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 24 00:27:02.456429 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 24 00:27:02.464677 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 24 00:27:02.493815 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 24 00:27:02.493945 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 24 00:27:02.494069 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:27:02.518787 ignition[1337]: INFO : Ignition 2.22.0 Nov 24 00:27:02.518787 ignition[1337]: INFO : Stage: umount Nov 24 00:27:02.518787 ignition[1337]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 24 00:27:02.518787 ignition[1337]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 24 00:27:02.518787 ignition[1337]: INFO : umount: umount passed Nov 24 00:27:02.518787 ignition[1337]: INFO : Ignition finished successfully Nov 24 00:27:02.501108 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 24 00:27:02.501224 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 24 00:27:02.522365 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 24 00:27:02.522462 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 24 00:27:02.524925 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 24 00:27:02.525012 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 24 00:27:02.534490 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 24 00:27:02.535846 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 24 00:27:02.535902 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 24 00:27:02.548863 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 24 00:27:02.548909 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 24 00:27:02.553342 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 24 00:27:02.553392 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 24 00:27:02.564315 systemd[1]: Stopped target network.target - Network. Nov 24 00:27:02.571956 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 24 00:27:02.572007 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 24 00:27:02.575676 systemd[1]: Stopped target paths.target - Path Units. Nov 24 00:27:02.584708 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 24 00:27:02.588599 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:27:02.592158 systemd[1]: Stopped target slices.target - Slice Units. Nov 24 00:27:02.600739 systemd[1]: Stopped target sockets.target - Socket Units. Nov 24 00:27:02.611810 systemd[1]: iscsid.socket: Deactivated successfully. Nov 24 00:27:02.611857 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 24 00:27:02.615507 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 24 00:27:02.615563 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 24 00:27:02.626946 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 24 00:27:02.626997 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 24 00:27:02.630659 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 24 00:27:02.630711 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 24 00:27:02.638351 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 24 00:27:02.645946 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 24 00:27:02.653765 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 24 00:27:02.653885 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 24 00:27:02.669277 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 24 00:27:02.669429 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 24 00:27:02.681189 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 24 00:27:02.681306 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 24 00:27:02.684031 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Nov 24 00:27:02.684105 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Nov 24 00:27:02.684382 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 24 00:27:02.693362 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 24 00:27:02.693398 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:27:02.699141 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 24 00:27:02.699192 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 24 00:27:02.715025 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 24 00:27:02.718242 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 24 00:27:02.718293 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 24 00:27:02.725240 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 24 00:27:02.725284 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:27:02.740797 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 24 00:27:02.740844 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 24 00:27:02.744669 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 24 00:27:02.744717 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:27:02.760983 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:27:02.770508 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Nov 24 00:27:02.770601 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:27:02.798170 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 24 00:27:02.798344 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:27:02.805936 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 24 00:27:02.805991 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 24 00:27:02.818316 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 24 00:27:02.823286 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:27:02.831758 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 24 00:27:02.831815 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 24 00:27:02.844036 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 24 00:27:02.844089 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 24 00:27:02.856001 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 24 00:27:02.856058 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 24 00:27:02.869609 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 24 00:27:02.874761 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 24 00:27:02.874822 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:27:02.891755 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 24 00:27:02.891821 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:27:02.904572 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 24 00:27:02.904629 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 24 00:27:02.918236 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 24 00:27:02.918291 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:27:02.928015 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:27:02.928070 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:27:02.938654 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Nov 24 00:27:02.938714 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Nov 24 00:27:02.938758 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Nov 24 00:27:02.938804 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Nov 24 00:27:03.090381 systemd-journald[214]: Received SIGTERM from PID 1 (systemd). Nov 24 00:27:02.939266 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 24 00:27:02.939372 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 24 00:27:02.942844 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 24 00:27:02.942953 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 24 00:27:02.950907 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 24 00:27:02.960231 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 24 00:27:02.993697 systemd[1]: Switching root. Nov 24 00:27:03.094355 systemd-journald[214]: Journal stopped Nov 24 00:27:07.209804 kernel: SELinux: policy capability network_peer_controls=1 Nov 24 00:27:07.209836 kernel: SELinux: policy capability open_perms=1 Nov 24 00:27:07.209849 kernel: SELinux: policy capability extended_socket_class=1 Nov 24 00:27:07.209858 kernel: SELinux: policy capability always_check_network=0 Nov 24 00:27:07.209867 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 24 00:27:07.209877 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 24 00:27:07.209888 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 24 00:27:07.209900 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 24 00:27:07.209910 kernel: SELinux: policy capability userspace_initial_context=0 Nov 24 00:27:07.209920 kernel: audit: type=1403 audit(1763944023.895:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 24 00:27:07.209931 systemd[1]: Successfully loaded SELinux policy in 195.839ms. Nov 24 00:27:07.209944 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.668ms. Nov 24 00:27:07.209955 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 24 00:27:07.209966 systemd[1]: Detected virtualization microsoft. Nov 24 00:27:07.209980 systemd[1]: Detected architecture x86-64. Nov 24 00:27:07.209991 systemd[1]: Detected first boot. Nov 24 00:27:07.210002 systemd[1]: Hostname set to . Nov 24 00:27:07.210013 systemd[1]: Initializing machine ID from random generator. Nov 24 00:27:07.210024 zram_generator::config[1382]: No configuration found. Nov 24 00:27:07.210038 kernel: Guest personality initialized and is inactive Nov 24 00:27:07.210048 kernel: VMCI host device registered (name=vmci, major=10, minor=259) Nov 24 00:27:07.210062 kernel: Initialized host personality Nov 24 00:27:07.210072 kernel: NET: Registered PF_VSOCK protocol family Nov 24 00:27:07.210082 systemd[1]: Populated /etc with preset unit settings. Nov 24 00:27:07.210094 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Nov 24 00:27:07.210105 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 24 00:27:07.210118 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 24 00:27:07.210129 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 24 00:27:07.210140 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 24 00:27:07.210151 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 24 00:27:07.210162 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 24 00:27:07.210173 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 24 00:27:07.210184 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 24 00:27:07.210195 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 24 00:27:07.210209 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 24 00:27:07.210220 systemd[1]: Created slice user.slice - User and Session Slice. Nov 24 00:27:07.210231 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 24 00:27:07.210242 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 24 00:27:07.210253 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 24 00:27:07.210265 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 24 00:27:07.210279 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 24 00:27:07.210291 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 24 00:27:07.210308 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 24 00:27:07.210320 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 24 00:27:07.210331 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 24 00:27:07.210342 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 24 00:27:07.210353 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 24 00:27:07.210365 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 24 00:27:07.210376 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 24 00:27:07.210390 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 24 00:27:07.210401 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 24 00:27:07.210412 systemd[1]: Reached target slices.target - Slice Units. Nov 24 00:27:07.210423 systemd[1]: Reached target swap.target - Swaps. Nov 24 00:27:07.210434 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 24 00:27:07.210445 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 24 00:27:07.210460 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 24 00:27:07.210472 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 24 00:27:07.210483 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 24 00:27:07.210495 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 24 00:27:07.210506 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 24 00:27:07.210517 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 24 00:27:07.210529 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 24 00:27:07.210552 systemd[1]: Mounting media.mount - External Media Directory... Nov 24 00:27:07.210567 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:27:07.210579 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 24 00:27:07.210590 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 24 00:27:07.210602 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 24 00:27:07.210614 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 24 00:27:07.210626 systemd[1]: Reached target machines.target - Containers. Nov 24 00:27:07.210637 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 24 00:27:07.210649 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:27:07.210664 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 24 00:27:07.210675 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 24 00:27:07.210687 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:27:07.210698 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 24 00:27:07.210709 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:27:07.210721 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 24 00:27:07.210732 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:27:07.210744 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 24 00:27:07.210758 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 24 00:27:07.210770 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 24 00:27:07.210781 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 24 00:27:07.210793 systemd[1]: Stopped systemd-fsck-usr.service. Nov 24 00:27:07.210805 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 24 00:27:07.210819 kernel: fuse: init (API version 7.41) Nov 24 00:27:07.210830 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 24 00:27:07.210841 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 24 00:27:07.210856 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 24 00:27:07.210867 kernel: loop: module loaded Nov 24 00:27:07.210878 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 24 00:27:07.210890 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 24 00:27:07.210901 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 24 00:27:07.210934 systemd-journald[1486]: Collecting audit messages is disabled. Nov 24 00:27:07.210962 systemd[1]: verity-setup.service: Deactivated successfully. Nov 24 00:27:07.210975 systemd[1]: Stopped verity-setup.service. Nov 24 00:27:07.210987 systemd-journald[1486]: Journal started Nov 24 00:27:07.211009 systemd-journald[1486]: Runtime Journal (/run/log/journal/7a79056a9ef84b39bc7409fe5db999b3) is 8M, max 2.2G, 2.1G free. Nov 24 00:27:06.471423 systemd[1]: Queued start job for default target multi-user.target. Nov 24 00:27:06.495919 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 24 00:27:06.496453 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 24 00:27:07.223561 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:27:07.230567 systemd[1]: Started systemd-journald.service - Journal Service. Nov 24 00:27:07.235230 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 24 00:27:07.239623 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 24 00:27:07.244170 systemd[1]: Mounted media.mount - External Media Directory. Nov 24 00:27:07.248735 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 24 00:27:07.253393 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 24 00:27:07.258673 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 24 00:27:07.262961 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 24 00:27:07.268100 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 24 00:27:07.273588 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 24 00:27:07.273780 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 24 00:27:07.278997 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:27:07.279177 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:27:07.284291 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:27:07.284477 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:27:07.290096 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 24 00:27:07.290370 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 24 00:27:07.296747 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:27:07.296933 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:27:07.301536 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 24 00:27:07.306159 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 24 00:27:07.311496 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 24 00:27:07.316559 kernel: ACPI: bus type drm_connector registered Nov 24 00:27:07.319590 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 24 00:27:07.324855 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 24 00:27:07.325099 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 24 00:27:07.329709 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 24 00:27:07.344332 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 24 00:27:07.349986 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 24 00:27:07.363769 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 24 00:27:07.368205 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 24 00:27:07.368240 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 24 00:27:07.373654 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 24 00:27:07.379656 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 24 00:27:07.383687 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:27:07.385399 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 24 00:27:07.390587 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 24 00:27:07.395273 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 24 00:27:07.396176 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 24 00:27:07.400490 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 24 00:27:07.401419 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 24 00:27:07.406803 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 24 00:27:07.412443 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 24 00:27:07.419831 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 24 00:27:07.421202 systemd-journald[1486]: Time spent on flushing to /var/log/journal/7a79056a9ef84b39bc7409fe5db999b3 is 15.095ms for 953 entries. Nov 24 00:27:07.421202 systemd-journald[1486]: System Journal (/var/log/journal/7a79056a9ef84b39bc7409fe5db999b3) is 8M, max 2.6G, 2.6G free. Nov 24 00:27:07.473558 systemd-journald[1486]: Received client request to flush runtime journal. Nov 24 00:27:07.428912 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 24 00:27:07.435109 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 24 00:27:07.441970 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 24 00:27:07.448225 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 24 00:27:07.471244 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 24 00:27:07.476031 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 24 00:27:07.489566 kernel: loop0: detected capacity change from 0 to 128560 Nov 24 00:27:07.505916 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 24 00:27:07.506602 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 24 00:27:07.513859 systemd-tmpfiles[1526]: ACLs are not supported, ignoring. Nov 24 00:27:07.513880 systemd-tmpfiles[1526]: ACLs are not supported, ignoring. Nov 24 00:27:07.519263 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 24 00:27:07.526122 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 24 00:27:07.695893 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 24 00:27:07.701862 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 24 00:27:07.728122 systemd-tmpfiles[1542]: ACLs are not supported, ignoring. Nov 24 00:27:07.728144 systemd-tmpfiles[1542]: ACLs are not supported, ignoring. Nov 24 00:27:07.731433 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 24 00:27:07.869572 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 24 00:27:07.953577 kernel: loop1: detected capacity change from 0 to 27936 Nov 24 00:27:08.153418 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 24 00:27:08.159948 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 24 00:27:08.209967 systemd-udevd[1548]: Using default interface naming scheme 'v255'. Nov 24 00:27:08.376625 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 24 00:27:08.387762 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 24 00:27:08.435420 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 24 00:27:08.455817 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 24 00:27:08.459567 kernel: mousedev: PS/2 mouse device common for all mice Nov 24 00:27:08.502571 kernel: hv_vmbus: registering driver hv_balloon Nov 24 00:27:08.517679 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 24 00:27:08.526992 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 24 00:27:08.539214 kernel: hv_vmbus: registering driver hyperv_fb Nov 24 00:27:08.539276 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 24 00:27:08.543860 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 24 00:27:08.547104 kernel: Console: switching to colour dummy device 80x25 Nov 24 00:27:08.548565 kernel: Console: switching to colour frame buffer device 128x48 Nov 24 00:27:08.576328 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:27:08.577598 kernel: EDAC MC: Ver: 3.0.0 Nov 24 00:27:08.585611 kernel: loop2: detected capacity change from 0 to 110984 Nov 24 00:27:08.592839 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 24 00:27:08.593034 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:27:08.599532 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 24 00:27:08.678293 systemd-networkd[1570]: lo: Link UP Nov 24 00:27:08.678304 systemd-networkd[1570]: lo: Gained carrier Nov 24 00:27:08.679628 systemd-networkd[1570]: Enumeration completed Nov 24 00:27:08.679737 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 24 00:27:08.680045 systemd-networkd[1570]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:27:08.680055 systemd-networkd[1570]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:27:08.680581 systemd-networkd[1570]: eth0: Link UP Nov 24 00:27:08.680591 systemd-networkd[1570]: eth0: Gained carrier Nov 24 00:27:08.680605 systemd-networkd[1570]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:27:08.681121 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 24 00:27:08.682231 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 24 00:27:08.735583 systemd-networkd[1570]: eth0: DHCPv4 address 10.200.4.4/24, gateway 10.200.4.1 acquired from 168.63.129.16 Nov 24 00:27:08.769980 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 24 00:27:08.813885 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 24 00:27:08.818896 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 24 00:27:08.886573 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 24 00:27:09.008312 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 24 00:27:09.025562 kernel: loop3: detected capacity change from 0 to 128560 Nov 24 00:27:09.044567 kernel: loop4: detected capacity change from 0 to 27936 Nov 24 00:27:09.061565 kernel: loop5: detected capacity change from 0 to 110984 Nov 24 00:27:09.083675 (sd-merge)[1708]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Nov 24 00:27:09.084152 (sd-merge)[1708]: Merged extensions into '/usr'. Nov 24 00:27:09.087580 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 24 00:27:09.094019 systemd[1]: Starting ensure-sysext.service... Nov 24 00:27:09.097930 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 24 00:27:09.139322 systemd[1]: Reload requested from client PID 1710 ('systemctl') (unit ensure-sysext.service)... Nov 24 00:27:09.139342 systemd[1]: Reloading... Nov 24 00:27:09.159719 systemd-tmpfiles[1711]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 24 00:27:09.159765 systemd-tmpfiles[1711]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 24 00:27:09.160067 systemd-tmpfiles[1711]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 24 00:27:09.160371 systemd-tmpfiles[1711]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 24 00:27:09.161287 systemd-tmpfiles[1711]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 24 00:27:09.161584 systemd-tmpfiles[1711]: ACLs are not supported, ignoring. Nov 24 00:27:09.161679 systemd-tmpfiles[1711]: ACLs are not supported, ignoring. Nov 24 00:27:09.190359 systemd-tmpfiles[1711]: Detected autofs mount point /boot during canonicalization of boot. Nov 24 00:27:09.190562 zram_generator::config[1744]: No configuration found. Nov 24 00:27:09.190374 systemd-tmpfiles[1711]: Skipping /boot Nov 24 00:27:09.198715 systemd-tmpfiles[1711]: Detected autofs mount point /boot during canonicalization of boot. Nov 24 00:27:09.198729 systemd-tmpfiles[1711]: Skipping /boot Nov 24 00:27:09.461188 systemd[1]: Reloading finished in 321 ms. Nov 24 00:27:09.505586 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 24 00:27:09.522965 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:27:09.524347 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 24 00:27:09.530079 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 24 00:27:09.534750 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:27:09.549355 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:27:09.554600 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:27:09.560710 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:27:09.564882 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:27:09.565019 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 24 00:27:09.566320 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 24 00:27:09.573113 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 24 00:27:09.579655 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 24 00:27:09.584361 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:27:09.586803 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:27:09.587043 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:27:09.592706 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:27:09.592896 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:27:09.598363 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:27:09.598554 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:27:09.616686 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 24 00:27:09.627745 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:27:09.628023 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 24 00:27:09.629254 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 24 00:27:09.647958 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 24 00:27:09.653471 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 24 00:27:09.659083 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 24 00:27:09.663447 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 24 00:27:09.663594 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 24 00:27:09.663957 systemd[1]: Reached target time-set.target - System Time Set. Nov 24 00:27:09.668016 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 24 00:27:09.669680 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 24 00:27:09.669973 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 24 00:27:09.675177 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 24 00:27:09.675384 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 24 00:27:09.680179 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 24 00:27:09.680384 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 24 00:27:09.686056 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 24 00:27:09.686316 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 24 00:27:09.694055 systemd[1]: Finished ensure-sysext.service. Nov 24 00:27:09.701550 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 24 00:27:09.701624 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 24 00:27:09.706635 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 24 00:27:09.714337 systemd-resolved[1809]: Positive Trust Anchors: Nov 24 00:27:09.714350 systemd-resolved[1809]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 24 00:27:09.714381 systemd-resolved[1809]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 24 00:27:09.719023 systemd-resolved[1809]: Using system hostname 'ci-4459.2.1-a-b2d2dc4cec'. Nov 24 00:27:09.720581 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 24 00:27:09.725042 systemd[1]: Reached target network.target - Network. Nov 24 00:27:09.728609 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 24 00:27:09.800730 augenrules[1844]: No rules Nov 24 00:27:09.801929 systemd[1]: audit-rules.service: Deactivated successfully. Nov 24 00:27:09.802177 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 24 00:27:10.365565 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 24 00:27:10.372025 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 24 00:27:10.616705 systemd-networkd[1570]: eth0: Gained IPv6LL Nov 24 00:27:10.619001 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 24 00:27:10.624400 systemd[1]: Reached target network-online.target - Network is Online. Nov 24 00:27:13.201493 ldconfig[1519]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 24 00:27:13.211391 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 24 00:27:13.217788 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 24 00:27:13.261253 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 24 00:27:13.266101 systemd[1]: Reached target sysinit.target - System Initialization. Nov 24 00:27:13.270426 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 24 00:27:13.275453 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 24 00:27:13.280554 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 24 00:27:13.285391 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 24 00:27:13.289888 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 24 00:27:13.295141 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 24 00:27:13.300523 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 24 00:27:13.300576 systemd[1]: Reached target paths.target - Path Units. Nov 24 00:27:13.304266 systemd[1]: Reached target timers.target - Timer Units. Nov 24 00:27:13.320034 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 24 00:27:13.325980 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 24 00:27:13.332072 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 24 00:27:13.337214 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 24 00:27:13.342120 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 24 00:27:13.354231 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 24 00:27:13.358587 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 24 00:27:13.363884 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 24 00:27:13.368902 systemd[1]: Reached target sockets.target - Socket Units. Nov 24 00:27:13.372750 systemd[1]: Reached target basic.target - Basic System. Nov 24 00:27:13.376421 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 24 00:27:13.376453 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 24 00:27:13.379237 systemd[1]: Starting chronyd.service - NTP client/server... Nov 24 00:27:13.383476 systemd[1]: Starting containerd.service - containerd container runtime... Nov 24 00:27:13.408514 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 24 00:27:13.413495 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 24 00:27:13.418094 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 24 00:27:13.423661 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 24 00:27:13.428680 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 24 00:27:13.432695 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 24 00:27:13.432817 jq[1865]: false Nov 24 00:27:13.433622 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 24 00:27:13.437649 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Nov 24 00:27:13.438608 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 24 00:27:13.442784 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 24 00:27:13.443825 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 24 00:27:13.448777 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 24 00:27:13.455377 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 24 00:27:13.460056 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Refreshing passwd entry cache Nov 24 00:27:13.460065 oslogin_cache_refresh[1867]: Refreshing passwd entry cache Nov 24 00:27:13.461557 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 24 00:27:13.462639 KVP[1868]: KVP starting; pid is:1868 Nov 24 00:27:13.463031 chronyd[1857]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 24 00:27:13.464696 KVP[1868]: KVP LIC Version: 3.1 Nov 24 00:27:13.468827 kernel: hv_utils: KVP IC version 4.0 Nov 24 00:27:13.471083 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 24 00:27:13.475217 extend-filesystems[1866]: Found /dev/sda6 Nov 24 00:27:13.478361 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Failure getting users, quitting Nov 24 00:27:13.478361 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 24 00:27:13.478361 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Refreshing group entry cache Nov 24 00:27:13.476081 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 24 00:27:13.475661 oslogin_cache_refresh[1867]: Failure getting users, quitting Nov 24 00:27:13.476506 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 24 00:27:13.475678 oslogin_cache_refresh[1867]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 24 00:27:13.477065 systemd[1]: Starting update-engine.service - Update Engine... Nov 24 00:27:13.475722 oslogin_cache_refresh[1867]: Refreshing group entry cache Nov 24 00:27:13.481279 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Failure getting groups, quitting Nov 24 00:27:13.481279 google_oslogin_nss_cache[1867]: oslogin_cache_refresh[1867]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 24 00:27:13.481245 oslogin_cache_refresh[1867]: Failure getting groups, quitting Nov 24 00:27:13.481258 oslogin_cache_refresh[1867]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 24 00:27:13.483130 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 24 00:27:13.490687 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 24 00:27:13.492283 chronyd[1857]: Timezone right/UTC failed leap second check, ignoring Nov 24 00:27:13.492453 chronyd[1857]: Loaded seccomp filter (level 2) Nov 24 00:27:13.496289 systemd[1]: Started chronyd.service - NTP client/server. Nov 24 00:27:13.498646 extend-filesystems[1866]: Found /dev/sda9 Nov 24 00:27:13.508986 extend-filesystems[1866]: Checking size of /dev/sda9 Nov 24 00:27:13.500229 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 24 00:27:13.500431 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 24 00:27:13.500733 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 24 00:27:13.500928 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 24 00:27:13.505728 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 24 00:27:13.505957 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 24 00:27:13.510645 systemd[1]: motdgen.service: Deactivated successfully. Nov 24 00:27:13.520748 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 24 00:27:13.525353 jq[1885]: true Nov 24 00:27:13.531114 (ntainerd)[1897]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 24 00:27:13.533594 jq[1896]: true Nov 24 00:27:13.534428 extend-filesystems[1866]: Old size kept for /dev/sda9 Nov 24 00:27:13.534893 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 24 00:27:13.535134 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 24 00:27:13.573815 systemd-logind[1878]: New seat seat0. Nov 24 00:27:13.575088 systemd-logind[1878]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Nov 24 00:27:13.576416 systemd[1]: Started systemd-logind.service - User Login Management. Nov 24 00:27:13.594344 setup-nvidia[1872]: Downloading Flatcar Container Linux Developer Container for version: 4459.2.1 Nov 24 00:27:13.607904 dbus-daemon[1860]: [system] SELinux support is enabled Nov 24 00:27:13.611728 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 24 00:27:13.621369 update_engine[1883]: I20251124 00:27:13.621291 1883 main.cc:92] Flatcar Update Engine starting Nov 24 00:27:13.622662 update_engine[1883]: I20251124 00:27:13.622624 1883 update_check_scheduler.cc:74] Next update check in 4m51s Nov 24 00:27:13.627339 dbus-daemon[1860]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 24 00:27:13.637120 systemd[1]: Started update-engine.service - Update Engine. Nov 24 00:27:13.640934 bash[1928]: Updated "/home/core/.ssh/authorized_keys" Nov 24 00:27:13.642200 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 24 00:27:13.648904 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 24 00:27:13.649075 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 24 00:27:13.649209 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 24 00:27:13.654274 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 24 00:27:13.654400 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 24 00:27:13.660648 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 24 00:27:13.667558 coreos-metadata[1859]: Nov 24 00:27:13.666 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 24 00:27:13.668433 coreos-metadata[1859]: Nov 24 00:27:13.668 INFO Fetch successful Nov 24 00:27:13.668607 coreos-metadata[1859]: Nov 24 00:27:13.668 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 24 00:27:13.672524 coreos-metadata[1859]: Nov 24 00:27:13.672 INFO Fetch successful Nov 24 00:27:13.672856 coreos-metadata[1859]: Nov 24 00:27:13.672 INFO Fetching http://168.63.129.16/machine/c6ada9b5-0aed-44cd-99e8-9c78a8c42b94/a65a534a%2Dc839%2D4b21%2Db6b8%2D4229d0b4a840.%5Fci%2D4459.2.1%2Da%2Db2d2dc4cec?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 24 00:27:13.674299 coreos-metadata[1859]: Nov 24 00:27:13.674 INFO Fetch successful Nov 24 00:27:13.674528 coreos-metadata[1859]: Nov 24 00:27:13.674 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 24 00:27:13.682286 coreos-metadata[1859]: Nov 24 00:27:13.682 INFO Fetch successful Nov 24 00:27:13.732505 sshd_keygen[1884]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 24 00:27:13.735386 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 24 00:27:13.740278 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 24 00:27:13.756247 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 24 00:27:13.762451 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 24 00:27:13.777903 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 24 00:27:13.783769 systemd[1]: issuegen.service: Deactivated successfully. Nov 24 00:27:13.783981 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 24 00:27:13.790865 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 24 00:27:13.814006 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 24 00:27:13.819070 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 24 00:27:13.826145 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 24 00:27:13.831057 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 24 00:27:13.836002 systemd[1]: Reached target getty.target - Login Prompts. Nov 24 00:27:13.887985 locksmithd[2004]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 24 00:27:14.134529 setup-nvidia[2042]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 00:27:14.134921 setup-nvidia[2042]: Dload Upload Total Spent Left Speed Nov 24 00:27:14.372660 containerd[1897]: time="2025-11-24T00:27:14Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 24 00:27:14.373298 containerd[1897]: time="2025-11-24T00:27:14.373261900Z" level=info msg="starting containerd" revision=4ac6c20c7bbf8177f29e46bbdc658fec02ffb8ad version=v2.0.7 Nov 24 00:27:14.381088 containerd[1897]: time="2025-11-24T00:27:14.381056000Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.2µs" Nov 24 00:27:14.381088 containerd[1897]: time="2025-11-24T00:27:14.381083600Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 24 00:27:14.381171 containerd[1897]: time="2025-11-24T00:27:14.381102100Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 24 00:27:14.381265 containerd[1897]: time="2025-11-24T00:27:14.381243700Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 24 00:27:14.381304 containerd[1897]: time="2025-11-24T00:27:14.381278700Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 24 00:27:14.381330 containerd[1897]: time="2025-11-24T00:27:14.381307800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381417 containerd[1897]: time="2025-11-24T00:27:14.381394300Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381417 containerd[1897]: time="2025-11-24T00:27:14.381414200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381683 containerd[1897]: time="2025-11-24T00:27:14.381654200Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381683 containerd[1897]: time="2025-11-24T00:27:14.381675000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381756 containerd[1897]: time="2025-11-24T00:27:14.381686600Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381756 containerd[1897]: time="2025-11-24T00:27:14.381695100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 24 00:27:14.381808 containerd[1897]: time="2025-11-24T00:27:14.381787800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 24 00:27:14.382123 containerd[1897]: time="2025-11-24T00:27:14.382094700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 24 00:27:14.382163 containerd[1897]: time="2025-11-24T00:27:14.382144300Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 24 00:27:14.382188 containerd[1897]: time="2025-11-24T00:27:14.382165800Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 24 00:27:14.382227 containerd[1897]: time="2025-11-24T00:27:14.382209100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 24 00:27:14.382482 containerd[1897]: time="2025-11-24T00:27:14.382452000Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 24 00:27:14.382571 containerd[1897]: time="2025-11-24T00:27:14.382530300Z" level=info msg="metadata content store policy set" policy=shared Nov 24 00:27:14.393857 containerd[1897]: time="2025-11-24T00:27:14.393797400Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 24 00:27:14.393857 containerd[1897]: time="2025-11-24T00:27:14.393840800Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 24 00:27:14.393857 containerd[1897]: time="2025-11-24T00:27:14.393856000Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 24 00:27:14.393943 containerd[1897]: time="2025-11-24T00:27:14.393866700Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 24 00:27:14.393943 containerd[1897]: time="2025-11-24T00:27:14.393878400Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 24 00:27:14.393943 containerd[1897]: time="2025-11-24T00:27:14.393888400Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 24 00:27:14.393943 containerd[1897]: time="2025-11-24T00:27:14.393900000Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 24 00:27:14.393943 containerd[1897]: time="2025-11-24T00:27:14.393910900Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 24 00:27:14.393943 containerd[1897]: time="2025-11-24T00:27:14.393921300Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 24 00:27:14.394066 containerd[1897]: time="2025-11-24T00:27:14.393946900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 24 00:27:14.394066 containerd[1897]: time="2025-11-24T00:27:14.393959600Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 24 00:27:14.394066 containerd[1897]: time="2025-11-24T00:27:14.393971300Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 24 00:27:14.394125 containerd[1897]: time="2025-11-24T00:27:14.394077000Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 24 00:27:14.394125 containerd[1897]: time="2025-11-24T00:27:14.394097300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 24 00:27:14.394125 containerd[1897]: time="2025-11-24T00:27:14.394110100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 24 00:27:14.394125 containerd[1897]: time="2025-11-24T00:27:14.394120800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394130300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394139900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394150200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394160000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394184100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394196700Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 24 00:27:14.394216 containerd[1897]: time="2025-11-24T00:27:14.394207300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 24 00:27:14.394363 containerd[1897]: time="2025-11-24T00:27:14.394253400Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 24 00:27:14.394363 containerd[1897]: time="2025-11-24T00:27:14.394265800Z" level=info msg="Start snapshots syncer" Nov 24 00:27:14.394363 containerd[1897]: time="2025-11-24T00:27:14.394291900Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 24 00:27:14.394600 containerd[1897]: time="2025-11-24T00:27:14.394562200Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 24 00:27:14.394729 containerd[1897]: time="2025-11-24T00:27:14.394609900Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 24 00:27:14.394729 containerd[1897]: time="2025-11-24T00:27:14.394646700Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 24 00:27:14.394781 containerd[1897]: time="2025-11-24T00:27:14.394750200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 24 00:27:14.394781 containerd[1897]: time="2025-11-24T00:27:14.394772700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 24 00:27:14.394827 containerd[1897]: time="2025-11-24T00:27:14.394788600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 24 00:27:14.394827 containerd[1897]: time="2025-11-24T00:27:14.394799300Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 24 00:27:14.394827 containerd[1897]: time="2025-11-24T00:27:14.394811600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 24 00:27:14.394827 containerd[1897]: time="2025-11-24T00:27:14.394822600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 24 00:27:14.394904 containerd[1897]: time="2025-11-24T00:27:14.394832900Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 24 00:27:14.394904 containerd[1897]: time="2025-11-24T00:27:14.394853400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 24 00:27:14.394904 containerd[1897]: time="2025-11-24T00:27:14.394864400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 24 00:27:14.394904 containerd[1897]: time="2025-11-24T00:27:14.394874500Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394902100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394915900Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394923900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394932800Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394940100Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394948400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394965200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 24 00:27:14.394984 containerd[1897]: time="2025-11-24T00:27:14.394982000Z" level=info msg="runtime interface created" Nov 24 00:27:14.395145 containerd[1897]: time="2025-11-24T00:27:14.394987600Z" level=info msg="created NRI interface" Nov 24 00:27:14.395145 containerd[1897]: time="2025-11-24T00:27:14.394995900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 24 00:27:14.395145 containerd[1897]: time="2025-11-24T00:27:14.395005700Z" level=info msg="Connect containerd service" Nov 24 00:27:14.395145 containerd[1897]: time="2025-11-24T00:27:14.395023800Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 24 00:27:14.395659 containerd[1897]: time="2025-11-24T00:27:14.395629200Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 24 00:27:14.756835 containerd[1897]: time="2025-11-24T00:27:14.756728500Z" level=info msg="Start subscribing containerd event" Nov 24 00:27:14.756835 containerd[1897]: time="2025-11-24T00:27:14.756782200Z" level=info msg="Start recovering state" Nov 24 00:27:14.756972 containerd[1897]: time="2025-11-24T00:27:14.756897100Z" level=info msg="Start event monitor" Nov 24 00:27:14.756972 containerd[1897]: time="2025-11-24T00:27:14.756921500Z" level=info msg="Start cni network conf syncer for default" Nov 24 00:27:14.756972 containerd[1897]: time="2025-11-24T00:27:14.756930500Z" level=info msg="Start streaming server" Nov 24 00:27:14.756972 containerd[1897]: time="2025-11-24T00:27:14.756939700Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 24 00:27:14.756972 containerd[1897]: time="2025-11-24T00:27:14.756947100Z" level=info msg="runtime interface starting up..." Nov 24 00:27:14.756972 containerd[1897]: time="2025-11-24T00:27:14.756957000Z" level=info msg="starting plugins..." Nov 24 00:27:14.757112 containerd[1897]: time="2025-11-24T00:27:14.756978000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 24 00:27:14.757112 containerd[1897]: time="2025-11-24T00:27:14.756941300Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 24 00:27:14.757154 containerd[1897]: time="2025-11-24T00:27:14.757130800Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 24 00:27:14.757351 containerd[1897]: time="2025-11-24T00:27:14.757200400Z" level=info msg="containerd successfully booted in 0.385009s" Nov 24 00:27:14.757307 systemd[1]: Started containerd.service - containerd container runtime. Nov 24 00:27:15.387896 waagent[2029]: 2025-11-24T00:27:15.387808Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 24 00:27:15.392268 waagent[2029]: 2025-11-24T00:27:15.392172Z INFO Daemon Daemon OS: flatcar 4459.2.1 Nov 24 00:27:15.395838 waagent[2029]: 2025-11-24T00:27:15.395770Z INFO Daemon Daemon Python: 3.11.13 Nov 24 00:27:15.399537 waagent[2029]: 2025-11-24T00:27:15.399490Z INFO Daemon Daemon Run daemon Nov 24 00:27:15.403425 waagent[2029]: 2025-11-24T00:27:15.402984Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4459.2.1' Nov 24 00:27:15.410220 waagent[2029]: 2025-11-24T00:27:15.410173Z INFO Daemon Daemon Using waagent for provisioning Nov 24 00:27:15.416214 waagent[2029]: 2025-11-24T00:27:15.416166Z INFO Daemon Daemon Activate resource disk Nov 24 00:27:15.420232 waagent[2029]: 2025-11-24T00:27:15.420175Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 24 00:27:15.427907 waagent[2029]: 2025-11-24T00:27:15.427858Z INFO Daemon Daemon Found device: sdb Nov 24 00:27:15.437339 waagent[2029]: 2025-11-24T00:27:15.437286Z INFO Daemon Daemon Examining partition table Nov 24 00:27:15.464801 waagent[2029]: 2025-11-24T00:27:15.464750Z INFO Daemon Daemon GPT not detected, determining filesystem Nov 24 00:27:15.519231 waagent[2029]: 2025-11-24T00:27:15.519151Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Nov 24 00:27:15.524060 waagent[2029]: 2025-11-24T00:27:15.524011Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Nov 24 00:27:16.317979 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#64 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:27:16.329570 kernel: sdb: sdb1 Nov 24 00:27:16.537210 waagent[2029]: 2025-11-24T00:27:16.537115Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Nov 24 00:27:16.556696 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#101 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 24 00:27:16.569571 kernel: sdb: sdb1 Nov 24 00:27:16.569645 waagent[2029]: 2025-11-24T00:27:16.569559Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Nov 24 00:27:21.741726 login[2031]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 24 00:27:21.743450 login[2032]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 24 00:27:21.750347 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 24 00:27:21.751539 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 24 00:27:21.757931 systemd-logind[1878]: New session 1 of user core. Nov 24 00:27:21.843343 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 24 00:27:21.845763 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 24 00:27:21.929208 (systemd)[2123]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 24 00:27:21.931536 systemd-logind[1878]: New session c1 of user core. Nov 24 00:27:22.335109 waagent[2029]: 2025-11-24T00:27:22.335021Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Nov 24 00:27:22.442247 waagent[2029]: 2025-11-24T00:27:22.442159Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Nov 24 00:27:22.442566 kernel: EXT4-fs (sdb1): mounted filesystem c20dc091-2d1a-4a44-be12-75a5d0453f8e r/w with ordered data mode. Quota mode: none. Nov 24 00:27:22.443103 waagent[2029]: 2025-11-24T00:27:22.443058Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 24 00:27:22.444565 waagent[2029]: 2025-11-24T00:27:22.444515Z INFO Daemon Daemon Running default provisioning handler Nov 24 00:27:22.451469 waagent[2029]: 2025-11-24T00:27:22.451418Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 24 00:27:22.452894 waagent[2029]: 2025-11-24T00:27:22.452854Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 24 00:27:22.453227 waagent[2029]: 2025-11-24T00:27:22.453193Z INFO Daemon Daemon cloud-init is enabled: False Nov 24 00:27:22.454898 waagent[2029]: 2025-11-24T00:27:22.454552Z INFO Daemon Daemon Copying ovf-env.xml Nov 24 00:27:22.508342 systemd[2123]: Queued start job for default target default.target. Nov 24 00:27:22.518790 waagent[2029]: 2025-11-24T00:27:22.518707Z INFO Daemon Daemon Successfully mounted dvd Nov 24 00:27:22.530075 systemd[2123]: Created slice app.slice - User Application Slice. Nov 24 00:27:22.530122 systemd[2123]: Reached target paths.target - Paths. Nov 24 00:27:22.530172 systemd[2123]: Reached target timers.target - Timers. Nov 24 00:27:22.531433 systemd[2123]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 00:27:22.543127 systemd[2123]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:27:22.543255 systemd[2123]: Reached target sockets.target - Sockets. Nov 24 00:27:22.543306 systemd[2123]: Reached target basic.target - Basic System. Nov 24 00:27:22.543349 systemd[2123]: Reached target default.target - Main User Target. Nov 24 00:27:22.543382 systemd[2123]: Startup finished in 605ms. Nov 24 00:27:22.543519 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 24 00:27:22.545099 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 24 00:27:22.551444 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 24 00:27:22.553437 waagent[2029]: 2025-11-24T00:27:22.553378Z INFO Daemon Daemon Detect protocol endpoint Nov 24 00:27:22.553680 waagent[2029]: 2025-11-24T00:27:22.553640Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 24 00:27:22.554361 waagent[2029]: 2025-11-24T00:27:22.554327Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 24 00:27:22.555636 waagent[2029]: 2025-11-24T00:27:22.555599Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 24 00:27:22.557120 waagent[2029]: 2025-11-24T00:27:22.557079Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 24 00:27:22.558372 waagent[2029]: 2025-11-24T00:27:22.558329Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 24 00:27:22.570457 waagent[2029]: 2025-11-24T00:27:22.570413Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 24 00:27:22.571392 waagent[2029]: 2025-11-24T00:27:22.571369Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 24 00:27:22.573343 waagent[2029]: 2025-11-24T00:27:22.573300Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 24 00:27:22.704801 waagent[2029]: 2025-11-24T00:27:22.704699Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 24 00:27:22.705069 waagent[2029]: 2025-11-24T00:27:22.705026Z INFO Daemon Daemon Forcing an update of the goal state. Nov 24 00:27:22.710595 waagent[2029]: 2025-11-24T00:27:22.710519Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 24 00:27:22.742110 login[2031]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 24 00:27:22.746500 systemd-logind[1878]: New session 2 of user core. Nov 24 00:27:22.748188 waagent[2029]: 2025-11-24T00:27:22.748142Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.179 Nov 24 00:27:22.748916 waagent[2029]: 2025-11-24T00:27:22.748871Z INFO Daemon Nov 24 00:27:22.749217 waagent[2029]: 2025-11-24T00:27:22.749181Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 5ef7e8ec-1851-493b-b26c-3b15852e1e26 eTag: 11155062537380457923 source: Fabric] Nov 24 00:27:22.753093 waagent[2029]: 2025-11-24T00:27:22.753042Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 24 00:27:22.756235 waagent[2029]: 2025-11-24T00:27:22.756182Z INFO Daemon Nov 24 00:27:22.757517 waagent[2029]: 2025-11-24T00:27:22.757464Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 24 00:27:22.776118 waagent[2029]: 2025-11-24T00:27:22.776074Z INFO Daemon Daemon Downloading artifacts profile blob Nov 24 00:27:22.790715 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 24 00:27:22.898559 waagent[2029]: 2025-11-24T00:27:22.898466Z INFO Daemon Downloaded certificate {'thumbprint': 'FD09CD737B31480D636A3537940E4274D2F54123', 'hasPrivateKey': True} Nov 24 00:27:22.899151 waagent[2029]: 2025-11-24T00:27:22.899109Z INFO Daemon Fetch goal state completed Nov 24 00:27:22.932270 waagent[2029]: 2025-11-24T00:27:22.932210Z INFO Daemon Daemon Starting provisioning Nov 24 00:27:22.932497 waagent[2029]: 2025-11-24T00:27:22.932446Z INFO Daemon Daemon Handle ovf-env.xml. Nov 24 00:27:22.936230 waagent[2029]: 2025-11-24T00:27:22.936168Z INFO Daemon Daemon Set hostname [ci-4459.2.1-a-b2d2dc4cec] Nov 24 00:27:22.970962 waagent[2029]: 2025-11-24T00:27:22.970915Z INFO Daemon Daemon Publish hostname [ci-4459.2.1-a-b2d2dc4cec] Nov 24 00:27:22.971334 waagent[2029]: 2025-11-24T00:27:22.971290Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 24 00:27:22.972787 waagent[2029]: 2025-11-24T00:27:22.972739Z INFO Daemon Daemon Primary interface is [eth0] Nov 24 00:27:22.982508 systemd-networkd[1570]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Nov 24 00:27:23.009021 waagent[2029]: 2025-11-24T00:27:22.982937Z INFO Daemon Daemon Create user account if not exists Nov 24 00:27:23.009021 waagent[2029]: 2025-11-24T00:27:22.983184Z INFO Daemon Daemon User core already exists, skip useradd Nov 24 00:27:23.009021 waagent[2029]: 2025-11-24T00:27:22.984612Z INFO Daemon Daemon Configure sudoer Nov 24 00:27:23.009021 waagent[2029]: 2025-11-24T00:27:22.988704Z INFO Daemon Daemon Configure sshd Nov 24 00:27:23.009021 waagent[2029]: 2025-11-24T00:27:22.991772Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 24 00:27:23.009021 waagent[2029]: 2025-11-24T00:27:22.992508Z INFO Daemon Daemon Deploy ssh public key. Nov 24 00:27:22.982519 systemd-networkd[1570]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 24 00:27:22.982560 systemd-networkd[1570]: eth0: DHCP lease lost Nov 24 00:27:23.056588 systemd-networkd[1570]: eth0: DHCPv4 address 10.200.4.4/24, gateway 10.200.4.1 acquired from 168.63.129.16 Nov 24 00:27:23.379253 setup-nvidia[2042]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 6 472M 6 29.9M 0 0 27.5M 0 0:00:17 0:00:01 0:00:16 27.5M\u000d 17 472M 17 81.9M 0 0 39.4M 0 0:00:11 0:00:02 0:00:09 39.4M\u000d 27 472M 27 131M 0 0 42.8M 0 0:00:11 0:00:03 0:00:08 42.8M\u000d 39 472M 39 185M 0 0 45.6M 0 0:00:10 0:00:04 0:00:06 45.5M\u000d 51 472M 51 241M 0 0 47.5M 0 0:00:09 0:00:05 0:00:04 49.3M\u000d 62 472M 62 295M 0 0 48.6M 0 0:00:09 0:00:06 0:00:03 53.1M\u000d 74 472M 74 350M 0 0 49.4M 0 0:00:09 0:00:07 0:00:02 53.6M\u000d 85 472M 85 404M 0 0 50.0M 0 0:00:09 0:00:08 0:00:01 54.4M\u000d 97 472M 97 461M 0 0 50.8M 0 0:00:09 0:00:09 --:--:-- 55.2M\u000d100 472M 100 472M 0 0 51.0M 0 0:00:09 0:00:09 --:--:-- 55.4M Nov 24 00:27:33.437439 setup-nvidia[1872]: Downloading NVIDIA 535.261.03 Driver Nov 24 00:27:33.441778 setup-nvidia[2192]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 00:27:33.442012 setup-nvidia[2192]: Dload Upload Total Spent Left Speed Nov 24 00:27:37.271978 chronyd[1857]: Selected source PHC0 Nov 24 00:27:39.319141 setup-nvidia[2192]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 4 325M 4 15.8M 0 0 20.6M 0 0:00:15 --:--:-- 0:00:15 20.6M\u000d 25 325M 25 84.0M 0 0 47.2M 0 0:00:06 0:00:01 0:00:05 47.2M\u000d 43 325M 43 142M 0 0 51.5M 0 0:00:06 0:00:02 0:00:04 51.5M\u000d 62 325M 62 204M 0 0 54.2M 0 0:00:06 0:00:03 0:00:03 54.2M\u000d 81 325M 81 266M 0 0 55.6M 0 0:00:05 0:00:04 0:00:01 55.6M\u000d 97 325M 97 317M 0 0 55.0M 0 0:00:05 0:00:05 --:--:-- 60.3M\u000d100 325M 100 325M 0 0 55.4M 0 0:00:05 0:00:05 --:--:-- 58.9M Nov 24 00:27:39.365980 setup-nvidia[1872]: Extract the NVIDIA Driver Installer 535.261.03 Nov 24 00:27:39.366170 setup-nvidia[1872]: /opt/nvidia/workdir/nvidia-workdir / Nov 24 00:27:39.384748 setup-nvidia[2195]: Creating directory NVIDIA-Linux-x86_64-535.261.03 Nov 24 00:27:39.635994 setup-nvidia[2195]: Verifying archive integrity... OK Nov 24 00:27:40.363588 setup-nvidia[2195]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.261.03 Nov 24 00:27:42.244712 setup-nvidia[2221]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Nov 24 00:27:42.281598 setup-nvidia[1872]: / Nov 24 00:27:42.281815 setup-nvidia[1872]: Spawn system-nspawn container to install the NVIDIA drivers Nov 24 00:27:42.403094 sudo[2228]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4459.2.1.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.261.03 Nov 24 00:27:42.404872 sudo[2228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 24 00:27:42.410529 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Nov 24 00:27:42.411491 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 24 00:27:42.439392 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 24 00:27:42.442988 systemd[1]: Starting user@0.service - User Manager for UID 0... Nov 24 00:27:42.454733 (systemd)[2231]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Nov 24 00:27:42.457044 systemd-logind[1878]: New session c3 of user root. Nov 24 00:27:42.647511 systemd[2231]: Queued start job for default target default.target. Nov 24 00:27:42.666718 systemd[2231]: Created slice app.slice - User Application Slice. Nov 24 00:27:42.666749 systemd[2231]: Reached target paths.target - Paths. Nov 24 00:27:42.666796 systemd[2231]: Reached target timers.target - Timers. Nov 24 00:27:42.667976 systemd[2231]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 24 00:27:42.678702 systemd[2231]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:27:42.678825 systemd[2231]: Reached target sockets.target - Sockets. Nov 24 00:27:42.678875 systemd[2231]: Reached target basic.target - Basic System. Nov 24 00:27:42.678917 systemd[2231]: Reached target default.target - Main User Target. Nov 24 00:27:42.678947 systemd[1]: Started user@0.service - User Manager for UID 0. Nov 24 00:27:42.678949 systemd[2231]: Startup finished in 216ms. Nov 24 00:27:42.680282 systemd[1]: Started session-c2.scope - Session c2 of User root. Nov 24 00:27:42.747564 kernel: loop6: detected capacity change from 0 to 12644352 Nov 24 00:27:42.814573 kernel: loop6: p9 Nov 24 00:27:43.032004 setup-nvidia[2240]: ░ Spawning container flatcardevelopercontainer-4459.2.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4459.2.1.bin. Nov 24 00:27:43.997402 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Nov 24 00:27:43.997531 kernel: EXT4-fs (loop6p9): mounted filesystem 4d642f52-8507-4059-99b7-cbb575c77e1f ro without journal. Quota mode: none. Nov 24 00:27:53.077059 waagent[2029]: 2025-11-24T00:27:53.076993Z INFO Daemon Daemon Provisioning complete Nov 24 00:27:53.085050 waagent[2029]: 2025-11-24T00:27:53.085008Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 24 00:27:53.098787 waagent[2029]: 2025-11-24T00:27:53.088031Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 24 00:27:53.098787 waagent[2029]: 2025-11-24T00:27:53.089463Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 24 00:27:53.244483 waagent[2670]: 2025-11-24T00:27:53.244405Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 24 00:27:53.244857 waagent[2670]: 2025-11-24T00:27:53.244567Z INFO ExtHandler ExtHandler OS: flatcar 4459.2.1 Nov 24 00:27:53.244857 waagent[2670]: 2025-11-24T00:27:53.244648Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 24 00:27:53.244857 waagent[2670]: 2025-11-24T00:27:53.244724Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 24 00:27:53.303180 waagent[2670]: 2025-11-24T00:27:53.303109Z INFO ExtHandler ExtHandler Distro: flatcar-4459.2.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 24 00:27:53.303426 waagent[2670]: 2025-11-24T00:27:53.303383Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:27:53.303521 waagent[2670]: 2025-11-24T00:27:53.303485Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:27:53.310393 waagent[2670]: 2025-11-24T00:27:53.310343Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 24 00:27:53.319677 waagent[2670]: 2025-11-24T00:27:53.319638Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.179 Nov 24 00:27:53.320125 waagent[2670]: 2025-11-24T00:27:53.320085Z INFO ExtHandler Nov 24 00:27:53.320210 waagent[2670]: 2025-11-24T00:27:53.320177Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: ba742231-da2e-4cee-9617-38bf1866d7e3 eTag: 11155062537380457923 source: Fabric] Nov 24 00:27:53.320536 waagent[2670]: 2025-11-24T00:27:53.320498Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 24 00:27:53.321177 waagent[2670]: 2025-11-24T00:27:53.321137Z INFO ExtHandler Nov 24 00:27:53.321251 waagent[2670]: 2025-11-24T00:27:53.321220Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 24 00:27:53.324490 waagent[2670]: 2025-11-24T00:27:53.324447Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 24 00:27:53.385901 waagent[2670]: 2025-11-24T00:27:53.385841Z INFO ExtHandler Downloaded certificate {'thumbprint': 'FD09CD737B31480D636A3537940E4274D2F54123', 'hasPrivateKey': True} Nov 24 00:27:53.386433 waagent[2670]: 2025-11-24T00:27:53.386390Z INFO ExtHandler Fetch goal state completed Nov 24 00:27:53.402474 waagent[2670]: 2025-11-24T00:27:53.402424Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 24 00:27:53.407483 waagent[2670]: 2025-11-24T00:27:53.407435Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2670 Nov 24 00:27:53.407637 waagent[2670]: 2025-11-24T00:27:53.407601Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 24 00:27:53.407982 waagent[2670]: 2025-11-24T00:27:53.407946Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 24 00:27:53.409646 waagent[2670]: 2025-11-24T00:27:53.409596Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4459.2.1', '', 'Flatcar Container Linux by Kinvolk'] Nov 24 00:27:53.410068 waagent[2670]: 2025-11-24T00:27:53.410028Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4459.2.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 24 00:27:53.410222 waagent[2670]: 2025-11-24T00:27:53.410187Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 24 00:27:53.410841 waagent[2670]: 2025-11-24T00:27:53.410799Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 24 00:27:53.451345 waagent[2670]: 2025-11-24T00:27:53.451298Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 24 00:27:53.451556 waagent[2670]: 2025-11-24T00:27:53.451506Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 24 00:27:53.458227 waagent[2670]: 2025-11-24T00:27:53.458184Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 24 00:27:53.465094 systemd[1]: Reload requested from client PID 2849 ('systemctl') (unit waagent.service)... Nov 24 00:27:53.465114 systemd[1]: Reloading... Nov 24 00:27:53.538578 zram_generator::config[2932]: No configuration found. Nov 24 00:27:53.843398 systemd[1]: Reloading finished in 377 ms. Nov 24 00:27:53.866801 waagent[2670]: 2025-11-24T00:27:53.866728Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 24 00:27:53.866907 waagent[2670]: 2025-11-24T00:27:53.866872Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 24 00:27:54.085475 waagent[2670]: 2025-11-24T00:27:54.085400Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 24 00:27:54.085811 waagent[2670]: 2025-11-24T00:27:54.085770Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 24 00:27:54.086623 waagent[2670]: 2025-11-24T00:27:54.086561Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 24 00:27:54.087028 waagent[2670]: 2025-11-24T00:27:54.086982Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 24 00:27:54.087165 waagent[2670]: 2025-11-24T00:27:54.087080Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:27:54.087464 waagent[2670]: 2025-11-24T00:27:54.087419Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:27:54.087594 waagent[2670]: 2025-11-24T00:27:54.087523Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 24 00:27:54.087825 waagent[2670]: 2025-11-24T00:27:54.087767Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 24 00:27:54.088204 waagent[2670]: 2025-11-24T00:27:54.088153Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 24 00:27:54.088613 waagent[2670]: 2025-11-24T00:27:54.088566Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 24 00:27:54.088727 waagent[2670]: 2025-11-24T00:27:54.088667Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 24 00:27:54.088908 waagent[2670]: 2025-11-24T00:27:54.088869Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 24 00:27:54.089215 waagent[2670]: 2025-11-24T00:27:54.089162Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 24 00:27:54.089316 waagent[2670]: 2025-11-24T00:27:54.089284Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 24 00:27:54.089922 waagent[2670]: 2025-11-24T00:27:54.089861Z INFO EnvHandler ExtHandler Configure routes Nov 24 00:27:54.090014 waagent[2670]: 2025-11-24T00:27:54.089965Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 24 00:27:54.090014 waagent[2670]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 24 00:27:54.090014 waagent[2670]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Nov 24 00:27:54.090014 waagent[2670]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 24 00:27:54.090014 waagent[2670]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:27:54.090014 waagent[2670]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:27:54.090014 waagent[2670]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 24 00:27:54.090452 waagent[2670]: 2025-11-24T00:27:54.090400Z INFO EnvHandler ExtHandler Gateway:None Nov 24 00:27:54.091113 waagent[2670]: 2025-11-24T00:27:54.091068Z INFO EnvHandler ExtHandler Routes:None Nov 24 00:27:54.094973 waagent[2670]: 2025-11-24T00:27:54.094896Z INFO ExtHandler ExtHandler Nov 24 00:27:54.095373 waagent[2670]: 2025-11-24T00:27:54.095331Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 14af7519-06d5-456d-a498-aabb0114f854 correlation 05546657-53eb-40e5-89e3-b55a88a26440 created: 2025-11-24T00:26:09.421898Z] Nov 24 00:27:54.096382 waagent[2670]: 2025-11-24T00:27:54.096338Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 24 00:27:54.098196 waagent[2670]: 2025-11-24T00:27:54.098157Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Nov 24 00:27:54.128712 waagent[2670]: 2025-11-24T00:27:54.128658Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 24 00:27:54.128712 waagent[2670]: Try `iptables -h' or 'iptables --help' for more information.) Nov 24 00:27:54.129090 waagent[2670]: 2025-11-24T00:27:54.129047Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: D6B16985-69E7-43DC-831A-0B46D25AE5A9;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 24 00:27:54.156530 waagent[2670]: 2025-11-24T00:27:54.156467Z INFO MonitorHandler ExtHandler Network interfaces: Nov 24 00:27:54.156530 waagent[2670]: Executing ['ip', '-a', '-o', 'link']: Nov 24 00:27:54.156530 waagent[2670]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 24 00:27:54.156530 waagent[2670]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:38:e3:1f brd ff:ff:ff:ff:ff:ff Nov 24 00:27:54.156530 waagent[2670]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 24 00:27:54.156530 waagent[2670]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 24 00:27:54.156530 waagent[2670]: 2: eth0 inet 10.200.4.4/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 24 00:27:54.156530 waagent[2670]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 24 00:27:54.156530 waagent[2670]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 24 00:27:54.156530 waagent[2670]: 2: eth0 inet6 fe80::7eed:8dff:fe38:e31f/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 24 00:27:54.202216 waagent[2670]: 2025-11-24T00:27:54.202158Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 24 00:27:54.202216 waagent[2670]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:27:54.202216 waagent[2670]: pkts bytes target prot opt in out source destination Nov 24 00:27:54.202216 waagent[2670]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:27:54.202216 waagent[2670]: pkts bytes target prot opt in out source destination Nov 24 00:27:54.202216 waagent[2670]: Chain OUTPUT (policy ACCEPT 4 packets, 416 bytes) Nov 24 00:27:54.202216 waagent[2670]: pkts bytes target prot opt in out source destination Nov 24 00:27:54.202216 waagent[2670]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 24 00:27:54.202216 waagent[2670]: 10 1047 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 24 00:27:54.202216 waagent[2670]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 24 00:27:54.209986 waagent[2670]: 2025-11-24T00:27:54.209932Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 24 00:27:54.209986 waagent[2670]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:27:54.209986 waagent[2670]: pkts bytes target prot opt in out source destination Nov 24 00:27:54.209986 waagent[2670]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 24 00:27:54.209986 waagent[2670]: pkts bytes target prot opt in out source destination Nov 24 00:27:54.209986 waagent[2670]: Chain OUTPUT (policy ACCEPT 7 packets, 765 bytes) Nov 24 00:27:54.209986 waagent[2670]: pkts bytes target prot opt in out source destination Nov 24 00:27:54.209986 waagent[2670]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 24 00:27:54.209986 waagent[2670]: 11 1099 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 24 00:27:54.209986 waagent[2670]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 24 00:27:54.210687 waagent[2670]: 2025-11-24T00:27:54.210648Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Nov 24 00:27:54.211003 waagent[2670]: 2025-11-24T00:27:54.210945Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 24 00:27:55.900808 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 24 00:27:55.902849 systemd[1]: Started sshd@0-10.200.4.4:22-10.200.16.10:53124.service - OpenSSH per-connection server daemon (10.200.16.10:53124). Nov 24 00:27:56.611588 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Nov 24 00:27:56.624032 sshd[3307]: Accepted publickey for core from 10.200.16.10 port 53124 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:27:56.625757 sshd-session[3307]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:27:56.631525 systemd-logind[1878]: New session 3 of user core. Nov 24 00:27:56.639690 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 24 00:27:57.162586 systemd[1]: Started sshd@1-10.200.4.4:22-10.200.16.10:53138.service - OpenSSH per-connection server daemon (10.200.16.10:53138). Nov 24 00:27:57.789861 sshd[3846]: Accepted publickey for core from 10.200.16.10 port 53138 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:27:57.791165 sshd-session[3846]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:27:57.796314 systemd-logind[1878]: New session 4 of user core. Nov 24 00:27:57.806710 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 24 00:27:58.218234 sshd[4198]: Connection closed by 10.200.16.10 port 53138 Nov 24 00:27:58.218841 sshd-session[3846]: pam_unix(sshd:session): session closed for user core Nov 24 00:27:58.222390 systemd[1]: sshd@1-10.200.4.4:22-10.200.16.10:53138.service: Deactivated successfully. Nov 24 00:27:58.223979 systemd[1]: session-4.scope: Deactivated successfully. Nov 24 00:27:58.224675 systemd-logind[1878]: Session 4 logged out. Waiting for processes to exit. Nov 24 00:27:58.225636 systemd-logind[1878]: Removed session 4. Nov 24 00:27:58.324036 systemd[1]: Started sshd@2-10.200.4.4:22-10.200.16.10:53148.service - OpenSSH per-connection server daemon (10.200.16.10:53148). Nov 24 00:27:58.916735 sshd[4490]: Accepted publickey for core from 10.200.16.10 port 53148 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:27:58.918008 sshd-session[4490]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:27:58.922379 systemd-logind[1878]: New session 5 of user core. Nov 24 00:27:58.934753 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 24 00:27:59.042756 update_engine[1883]: I20251124 00:27:59.042686 1883 update_attempter.cc:509] Updating boot flags... Nov 24 00:27:59.352152 sshd[4848]: Connection closed by 10.200.16.10 port 53148 Nov 24 00:27:59.352577 sshd-session[4490]: pam_unix(sshd:session): session closed for user core Nov 24 00:27:59.358381 systemd[1]: sshd@2-10.200.4.4:22-10.200.16.10:53148.service: Deactivated successfully. Nov 24 00:27:59.359596 systemd-logind[1878]: Session 5 logged out. Waiting for processes to exit. Nov 24 00:27:59.361473 systemd[1]: session-5.scope: Deactivated successfully. Nov 24 00:27:59.370799 systemd-logind[1878]: Removed session 5. Nov 24 00:28:09.457753 systemd[1]: Started sshd@3-10.200.4.4:22-10.200.16.10:51762.service - OpenSSH per-connection server daemon (10.200.16.10:51762). Nov 24 00:28:10.060866 sshd[7243]: Accepted publickey for core from 10.200.16.10 port 51762 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:10.062848 sshd-session[7243]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:10.070482 systemd-logind[1878]: New session 6 of user core. Nov 24 00:28:10.074980 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 24 00:28:10.483133 sshd[7467]: Connection closed by 10.200.16.10 port 51762 Nov 24 00:28:10.486736 sshd-session[7243]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:10.491465 systemd-logind[1878]: Session 6 logged out. Waiting for processes to exit. Nov 24 00:28:10.493268 systemd[1]: sshd@3-10.200.4.4:22-10.200.16.10:51762.service: Deactivated successfully. Nov 24 00:28:10.496274 systemd[1]: session-6.scope: Deactivated successfully. Nov 24 00:28:10.499263 systemd-logind[1878]: Removed session 6. Nov 24 00:28:10.595780 systemd[1]: Started sshd@4-10.200.4.4:22-10.200.16.10:35426.service - OpenSSH per-connection server daemon (10.200.16.10:35426). Nov 24 00:28:11.207664 sshd[7674]: Accepted publickey for core from 10.200.16.10 port 35426 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:11.209337 sshd-session[7674]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:11.217905 systemd-logind[1878]: New session 7 of user core. Nov 24 00:28:11.227326 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 24 00:28:11.642577 sshd[7843]: Connection closed by 10.200.16.10 port 35426 Nov 24 00:28:11.643115 sshd-session[7674]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:11.647000 systemd[1]: sshd@4-10.200.4.4:22-10.200.16.10:35426.service: Deactivated successfully. Nov 24 00:28:11.648688 systemd[1]: session-7.scope: Deactivated successfully. Nov 24 00:28:11.650849 systemd-logind[1878]: Session 7 logged out. Waiting for processes to exit. Nov 24 00:28:11.652339 systemd-logind[1878]: Removed session 7. Nov 24 00:28:21.752768 systemd[1]: Started sshd@5-10.200.4.4:22-10.200.16.10:48770.service - OpenSSH per-connection server daemon (10.200.16.10:48770). Nov 24 00:28:22.367490 sshd[11316]: Accepted publickey for core from 10.200.16.10 port 48770 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:22.369147 sshd-session[11316]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:22.377663 systemd-logind[1878]: New session 8 of user core. Nov 24 00:28:22.393638 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 24 00:28:22.806346 sshd[11526]: Connection closed by 10.200.16.10 port 48770 Nov 24 00:28:22.806713 sshd-session[11316]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:22.810120 systemd-logind[1878]: Session 8 logged out. Waiting for processes to exit. Nov 24 00:28:22.811943 systemd[1]: sshd@5-10.200.4.4:22-10.200.16.10:48770.service: Deactivated successfully. Nov 24 00:28:22.814072 systemd[1]: session-8.scope: Deactivated successfully. Nov 24 00:28:22.817970 systemd-logind[1878]: Removed session 8. Nov 24 00:28:22.917899 systemd[1]: Started sshd@6-10.200.4.4:22-10.200.16.10:48780.service - OpenSSH per-connection server daemon (10.200.16.10:48780). Nov 24 00:28:23.545356 sshd[11727]: Accepted publickey for core from 10.200.16.10 port 48780 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:23.546799 sshd-session[11727]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:23.552655 systemd-logind[1878]: New session 9 of user core. Nov 24 00:28:23.561682 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 24 00:28:24.004767 sshd[11960]: Connection closed by 10.200.16.10 port 48780 Nov 24 00:28:24.005191 sshd-session[11727]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:24.011636 systemd[1]: sshd@6-10.200.4.4:22-10.200.16.10:48780.service: Deactivated successfully. Nov 24 00:28:24.014249 systemd[1]: session-9.scope: Deactivated successfully. Nov 24 00:28:24.015457 systemd-logind[1878]: Session 9 logged out. Waiting for processes to exit. Nov 24 00:28:24.016712 systemd-logind[1878]: Removed session 9. Nov 24 00:28:34.121808 systemd[1]: Started sshd@7-10.200.4.4:22-10.200.16.10:48822.service - OpenSSH per-connection server daemon (10.200.16.10:48822). Nov 24 00:28:34.724331 sshd[14179]: Accepted publickey for core from 10.200.16.10 port 48822 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:34.726275 sshd-session[14179]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:34.734486 systemd-logind[1878]: New session 10 of user core. Nov 24 00:28:34.739725 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 24 00:28:35.180905 sshd[14230]: Connection closed by 10.200.16.10 port 48822 Nov 24 00:28:35.183912 sshd-session[14179]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:35.188798 systemd[1]: sshd@7-10.200.4.4:22-10.200.16.10:48822.service: Deactivated successfully. Nov 24 00:28:35.192388 systemd[1]: session-10.scope: Deactivated successfully. Nov 24 00:28:35.194102 systemd-logind[1878]: Session 10 logged out. Waiting for processes to exit. Nov 24 00:28:35.195496 systemd-logind[1878]: Removed session 10. Nov 24 00:28:35.289874 systemd[1]: Started sshd@8-10.200.4.4:22-10.200.16.10:48830.service - OpenSSH per-connection server daemon (10.200.16.10:48830). Nov 24 00:28:35.899569 sshd[14249]: Accepted publickey for core from 10.200.16.10 port 48830 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:35.900351 sshd-session[14249]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:35.906048 systemd-logind[1878]: New session 11 of user core. Nov 24 00:28:35.915717 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 24 00:28:36.347573 sshd[14281]: Connection closed by 10.200.16.10 port 48830 Nov 24 00:28:36.348166 sshd-session[14249]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:36.352732 systemd-logind[1878]: Session 11 logged out. Waiting for processes to exit. Nov 24 00:28:36.353534 systemd[1]: sshd@8-10.200.4.4:22-10.200.16.10:48830.service: Deactivated successfully. Nov 24 00:28:36.357078 systemd[1]: session-11.scope: Deactivated successfully. Nov 24 00:28:36.360822 systemd-logind[1878]: Removed session 11. Nov 24 00:28:46.456812 systemd[1]: Started sshd@9-10.200.4.4:22-10.200.16.10:56532.service - OpenSSH per-connection server daemon (10.200.16.10:56532). Nov 24 00:28:47.070192 sshd[14606]: Accepted publickey for core from 10.200.16.10 port 56532 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:47.073020 sshd-session[14606]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:47.082679 systemd-logind[1878]: New session 12 of user core. Nov 24 00:28:47.091774 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 24 00:28:47.505734 sshd[14615]: Connection closed by 10.200.16.10 port 56532 Nov 24 00:28:47.506439 sshd-session[14606]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:47.510020 systemd-logind[1878]: Session 12 logged out. Waiting for processes to exit. Nov 24 00:28:47.511367 systemd[1]: sshd@9-10.200.4.4:22-10.200.16.10:56532.service: Deactivated successfully. Nov 24 00:28:47.514411 systemd[1]: session-12.scope: Deactivated successfully. Nov 24 00:28:47.518291 systemd-logind[1878]: Removed session 12. Nov 24 00:28:47.614177 systemd[1]: Started sshd@10-10.200.4.4:22-10.200.16.10:56534.service - OpenSSH per-connection server daemon (10.200.16.10:56534). Nov 24 00:28:48.223018 sshd[14656]: Accepted publickey for core from 10.200.16.10 port 56534 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:48.225503 sshd-session[14656]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:48.231954 systemd-logind[1878]: New session 13 of user core. Nov 24 00:28:48.238632 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 24 00:28:48.681579 sshd[14684]: Connection closed by 10.200.16.10 port 56534 Nov 24 00:28:48.685724 sshd-session[14656]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:48.689369 systemd[1]: sshd@10-10.200.4.4:22-10.200.16.10:56534.service: Deactivated successfully. Nov 24 00:28:48.692805 systemd[1]: session-13.scope: Deactivated successfully. Nov 24 00:28:48.696110 systemd-logind[1878]: Session 13 logged out. Waiting for processes to exit. Nov 24 00:28:48.697264 systemd-logind[1878]: Removed session 13. Nov 24 00:28:58.792201 systemd[1]: Started sshd@11-10.200.4.4:22-10.200.16.10:50816.service - OpenSSH per-connection server daemon (10.200.16.10:50816). Nov 24 00:28:59.405599 sshd[14995]: Accepted publickey for core from 10.200.16.10 port 50816 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:28:59.409258 sshd-session[14995]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:28:59.417632 systemd-logind[1878]: New session 14 of user core. Nov 24 00:28:59.424218 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 24 00:28:59.861242 sshd[15016]: Connection closed by 10.200.16.10 port 50816 Nov 24 00:28:59.861539 sshd-session[14995]: pam_unix(sshd:session): session closed for user core Nov 24 00:28:59.866119 systemd[1]: sshd@11-10.200.4.4:22-10.200.16.10:50816.service: Deactivated successfully. Nov 24 00:28:59.867817 systemd[1]: session-14.scope: Deactivated successfully. Nov 24 00:28:59.871731 systemd-logind[1878]: Session 14 logged out. Waiting for processes to exit. Nov 24 00:28:59.873140 systemd-logind[1878]: Removed session 14. Nov 24 00:28:59.969643 systemd[1]: Started sshd@12-10.200.4.4:22-10.200.16.10:50822.service - OpenSSH per-connection server daemon (10.200.16.10:50822). Nov 24 00:29:00.582619 sshd[15046]: Accepted publickey for core from 10.200.16.10 port 50822 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:00.583989 sshd-session[15046]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:00.590651 systemd-logind[1878]: New session 15 of user core. Nov 24 00:29:00.597680 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 24 00:29:01.004285 sshd[15080]: Connection closed by 10.200.16.10 port 50822 Nov 24 00:29:01.006264 sshd-session[15046]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:01.009625 systemd-logind[1878]: Session 15 logged out. Waiting for processes to exit. Nov 24 00:29:01.011894 systemd[1]: sshd@12-10.200.4.4:22-10.200.16.10:50822.service: Deactivated successfully. Nov 24 00:29:01.013948 systemd[1]: session-15.scope: Deactivated successfully. Nov 24 00:29:01.016850 systemd-logind[1878]: Removed session 15. Nov 24 00:29:10.300230 setup-nvidia[2240]: \u000d Nov 24 00:29:10.300230 setup-nvidia[2240]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Nov 24 00:29:10.300230 setup-nvidia[2240]: frequently when this kernel module was built against the wrong or Nov 24 00:29:10.300230 setup-nvidia[2240]: improperly configured kernel sources, with a version of gcc that differs Nov 24 00:29:10.300230 setup-nvidia[2240]: from the one used to build the target kernel, or if another driver, such Nov 24 00:29:10.300230 setup-nvidia[2240]: as nouveau, is present and prevents the NVIDIA kernel module from Nov 24 00:29:10.300230 setup-nvidia[2240]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Nov 24 00:29:10.300230 setup-nvidia[2240]: installed in this system is supported by this NVIDIA Linux graphics Nov 24 00:29:10.300230 setup-nvidia[2240]: driver release. Nov 24 00:29:10.300230 setup-nvidia[2240]: \u000d Nov 24 00:29:10.300230 setup-nvidia[2240]: Please see the log entries 'Kernel module load error' and 'Kernel Nov 24 00:29:10.300230 setup-nvidia[2240]: messages' at the end of the file Nov 24 00:29:10.300230 setup-nvidia[2240]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more Nov 24 00:29:10.300230 setup-nvidia[2240]: information. Nov 24 00:29:10.300230 setup-nvidia[2240]: \u000d Nov 24 00:29:10.317910 setup-nvidia[2240]: dmesg: read kernel buffer failed: Operation not permitted Nov 24 00:29:10.337381 setup-nvidia[2240]: \u000d Nov 24 00:29:10.337381 setup-nvidia[2240]: ERROR: Installation has failed. Please see the file Nov 24 00:29:10.337381 setup-nvidia[2240]: '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for Nov 24 00:29:10.337381 setup-nvidia[2240]: details. You may find suggestions on fixing installation problems in Nov 24 00:29:10.337381 setup-nvidia[2240]: the README available on the Linux driver download page at Nov 24 00:29:10.337381 setup-nvidia[2240]: www.nvidia.com. Nov 24 00:29:10.337381 setup-nvidia[2240]: \u000d Nov 24 00:29:10.337734 setup-nvidia[2240]: Last 50 lines of nvidia-installer.log: Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039059rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039056rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039049rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039105rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039101rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039100rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039102rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039046rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030615rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030614rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030618rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030617rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030616rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338586 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030619rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338971 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv030621rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338971 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338971 setup-nvidia[2240]: /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.o: warning: objtool: _nv039413rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Nov 24 00:29:10.338971 setup-nvidia[2240]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/Module.symvers Nov 24 00:29:10.338971 setup-nvidia[2240]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.mod.o Nov 24 00:29:10.338971 setup-nvidia[2240]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/.module-common.o Nov 24 00:29:10.338971 setup-nvidia[2240]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.mod.o Nov 24 00:29:10.338971 setup-nvidia[2240]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.mod.o Nov 24 00:29:10.338971 setup-nvidia[2240]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.mod.o Nov 24 00:29:10.338971 setup-nvidia[2240]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.mod.o Nov 24 00:29:10.338971 setup-nvidia[2240]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-peermem.ko due to unavailability of vmlinux Nov 24 00:29:10.338971 setup-nvidia[2240]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-modeset.ko due to unavailability of vmlinux Nov 24 00:29:10.338971 setup-nvidia[2240]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko Nov 24 00:29:10.338971 setup-nvidia[2240]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-drm.ko due to unavailability of vmlinux Nov 24 00:29:10.338971 setup-nvidia[2240]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko Nov 24 00:29:10.339488 setup-nvidia[2240]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia-uvm.ko due to unavailability of vmlinux Nov 24 00:29:10.339488 setup-nvidia[2240]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko Nov 24 00:29:10.339488 setup-nvidia[2240]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.261.03/kernel/nvidia.ko due to unavailability of vmlinux Nov 24 00:29:10.339488 setup-nvidia[2240]: make[2]: Leaving directory '/usr/lib/modules/6.12.58-flatcar/build' Nov 24 00:29:10.339488 setup-nvidia[2240]: make[1]: Leaving directory '/usr/lib/modules/6.12.58-flatcar/source' Nov 24 00:29:10.339488 setup-nvidia[2240]: -> done. Nov 24 00:29:10.339488 setup-nvidia[2240]: -> Kernel module compilation complete. Nov 24 00:29:10.339488 setup-nvidia[2240]: -> Unable to determine if Secure Boot is enabled: No such file or directory Nov 24 00:29:10.339488 setup-nvidia[2240]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Nov 24 00:29:10.339488 setup-nvidia[2240]: \u000d Nov 24 00:29:10.339488 setup-nvidia[2240]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for more information. Nov 24 00:29:10.339488 setup-nvidia[2240]: -> Kernel module load error: Operation not permitted Nov 24 00:29:10.339488 setup-nvidia[2240]: -> Kernel messages: Nov 24 00:29:10.339488 setup-nvidia[2240]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.261.03/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Nov 24 00:29:11.114996 systemd[1]: Started sshd@13-10.200.4.4:22-10.200.16.10:50194.service - OpenSSH per-connection server daemon (10.200.16.10:50194). Nov 24 00:29:11.712109 sshd[15340]: Accepted publickey for core from 10.200.16.10 port 50194 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:11.713334 sshd-session[15340]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:11.717605 systemd-logind[1878]: New session 16 of user core. Nov 24 00:29:11.727689 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 24 00:29:12.140042 sshd[15343]: Connection closed by 10.200.16.10 port 50194 Nov 24 00:29:12.140401 sshd-session[15340]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:12.143885 systemd[1]: sshd@13-10.200.4.4:22-10.200.16.10:50194.service: Deactivated successfully. Nov 24 00:29:12.145441 systemd[1]: session-16.scope: Deactivated successfully. Nov 24 00:29:12.146388 systemd-logind[1878]: Session 16 logged out. Waiting for processes to exit. Nov 24 00:29:12.147537 systemd-logind[1878]: Removed session 16. Nov 24 00:29:12.257634 systemd[1]: Started sshd@14-10.200.4.4:22-10.200.16.10:50206.service - OpenSSH per-connection server daemon (10.200.16.10:50206). Nov 24 00:29:12.873088 sshd[15349]: Accepted publickey for core from 10.200.16.10 port 50206 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:12.874332 sshd-session[15349]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:12.878567 systemd-logind[1878]: New session 17 of user core. Nov 24 00:29:12.892682 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 24 00:29:13.328654 sshd[15352]: Connection closed by 10.200.16.10 port 50206 Nov 24 00:29:13.329019 sshd-session[15349]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:13.332515 systemd[1]: sshd@14-10.200.4.4:22-10.200.16.10:50206.service: Deactivated successfully. Nov 24 00:29:13.334150 systemd[1]: session-17.scope: Deactivated successfully. Nov 24 00:29:13.334845 systemd-logind[1878]: Session 17 logged out. Waiting for processes to exit. Nov 24 00:29:13.335828 systemd-logind[1878]: Removed session 17. Nov 24 00:29:22.957573 kernel: EXT4-fs (loop6p9): unmounting filesystem 4d642f52-8507-4059-99b7-cbb575c77e1f. Nov 24 00:29:22.958180 setup-nvidia[2240]: Container flatcardevelopercontainer-4459.2.1.bin exited successfully. Nov 24 00:29:22.960482 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4459.2.1.bin.mount: Deactivated successfully. Nov 24 00:29:23.014449 sudo[2228]: pam_unix(sudo:session): session closed for user root Nov 24 00:29:23.016502 systemd[1]: session-c2.scope: Deactivated successfully. Nov 24 00:29:23.016798 systemd[1]: session-c2.scope: Consumed 5min 25.770s CPU time, 890.6M memory peak. Nov 24 00:29:23.438042 systemd[1]: Started sshd@15-10.200.4.4:22-10.200.16.10:56634.service - OpenSSH per-connection server daemon (10.200.16.10:56634). Nov 24 00:29:23.440969 setup-nvidia[1872]: /opt/nvidia / Nov 24 00:29:23.442158 setup-nvidia[1872]: / Nov 24 00:29:23.452564 kernel: IPMI message handler: version 39.2 Nov 24 00:29:23.455564 kernel: ipmi device interface Nov 24 00:29:23.467684 setup-nvidia[1872]: /opt/nvidia/current/usr/lib/modules/6.12.58-flatcar/video / Nov 24 00:29:23.521516 kernel: nvidia: loading out-of-tree module taints kernel. Nov 24 00:29:23.521846 kernel: nvidia: module license 'NVIDIA' taints kernel. Nov 24 00:29:23.521972 kernel: Disabling lock debugging due to kernel taint Nov 24 00:29:23.528912 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Nov 24 00:29:23.528958 kernel: nvidia: module license taints kernel. Nov 24 00:29:23.926575 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Nov 24 00:29:24.043928 sshd[15373]: Accepted publickey for core from 10.200.16.10 port 56634 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:24.045150 sshd-session[15373]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:24.050192 systemd-logind[1878]: New session 18 of user core. Nov 24 00:29:24.325119 kernel: Nov 24 00:29:24.325267 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Nov 24 00:29:24.335754 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 24 00:29:24.372568 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.261.03 Sat Jun 14 16:05:50 UTC 2025 Nov 24 00:29:24.394568 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.261.03 Sat Jun 14 15:39:16 UTC 2025 Nov 24 00:29:24.415584 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Nov 24 00:29:24.461515 setup-nvidia[1872]: / Nov 24 00:29:24.461980 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Nov 24 00:29:24.476695 setup-nvidia[15398]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 24 00:29:24.672572 sshd[15390]: Connection closed by 10.200.16.10 port 56634 Nov 24 00:29:24.672983 sshd-session[15373]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:24.676180 systemd[1]: sshd@15-10.200.4.4:22-10.200.16.10:56634.service: Deactivated successfully. Nov 24 00:29:24.677943 systemd[1]: session-18.scope: Deactivated successfully. Nov 24 00:29:24.679141 systemd-logind[1878]: Session 18 logged out. Waiting for processes to exit. Nov 24 00:29:24.680107 systemd-logind[1878]: Removed session 18. Nov 24 00:29:24.782960 systemd[1]: Started sshd@16-10.200.4.4:22-10.200.16.10:56648.service - OpenSSH per-connection server daemon (10.200.16.10:56648). Nov 24 00:29:25.378792 sshd[15424]: Accepted publickey for core from 10.200.16.10 port 56648 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:25.380087 sshd-session[15424]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:25.384273 systemd-logind[1878]: New session 19 of user core. Nov 24 00:29:25.401683 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 24 00:29:25.814279 sshd[15427]: Connection closed by 10.200.16.10 port 56648 Nov 24 00:29:25.814739 sshd-session[15424]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:25.818272 systemd[1]: sshd@16-10.200.4.4:22-10.200.16.10:56648.service: Deactivated successfully. Nov 24 00:29:25.819869 systemd[1]: session-19.scope: Deactivated successfully. Nov 24 00:29:25.820575 systemd-logind[1878]: Session 19 logged out. Waiting for processes to exit. Nov 24 00:29:25.821625 systemd-logind[1878]: Removed session 19. Nov 24 00:29:26.061620 setup-nvidia[15415]: Mon Nov 24 00:29:26 2025 Nov 24 00:29:26.061620 setup-nvidia[15415]: +---------------------------------------------------------------------------------------+ Nov 24 00:29:26.061620 setup-nvidia[15415]: | NVIDIA-SMI 535.261.03 Driver Version: 535.261.03 CUDA Version: 12.2 | Nov 24 00:29:26.061620 setup-nvidia[15415]: |-----------------------------------------+----------------------+----------------------+ Nov 24 00:29:26.061620 setup-nvidia[15415]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Nov 24 00:29:26.061620 setup-nvidia[15415]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Nov 24 00:29:26.061620 setup-nvidia[15415]: | | | MIG M. | Nov 24 00:29:26.061620 setup-nvidia[15415]: |=========================================+======================+======================| Nov 24 00:29:26.065117 setup-nvidia[15415]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Nov 24 00:29:26.065117 setup-nvidia[15415]: | N/A 29C P0 35W / 250W | 0MiB / 16384MiB | 1% Default | Nov 24 00:29:26.065117 setup-nvidia[15415]: | | | N/A | Nov 24 00:29:26.065117 setup-nvidia[15415]: +-----------------------------------------+----------------------+----------------------+ Nov 24 00:29:26.065117 setup-nvidia[15415]: Nov 24 00:29:26.065117 setup-nvidia[15415]: +---------------------------------------------------------------------------------------+ Nov 24 00:29:26.065117 setup-nvidia[15415]: | Processes: | Nov 24 00:29:26.065117 setup-nvidia[15415]: | GPU GI CI PID Type Process name GPU Memory | Nov 24 00:29:26.065117 setup-nvidia[15415]: | ID ID Usage | Nov 24 00:29:26.065117 setup-nvidia[15415]: |=======================================================================================| Nov 24 00:29:26.065365 setup-nvidia[15415]: | No running processes found | Nov 24 00:29:26.065365 setup-nvidia[15415]: +---------------------------------------------------------------------------------------+ Nov 24 00:29:26.436912 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 24 00:29:26.437533 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 24 00:29:26.437921 systemd[1]: Startup finished in 1.084s (firmware) + 14.434s (loader) + 3.730s (kernel) + 11.927s (initrd) + 2min 22.736s (userspace) = 2min 53.914s. Nov 24 00:29:33.135157 systemd[1]: Stopping user@0.service - User Manager for UID 0... Nov 24 00:29:33.136009 systemd[2231]: Activating special unit exit.target... Nov 24 00:29:33.136085 systemd[2231]: Stopped target default.target - Main User Target. Nov 24 00:29:33.136124 systemd[2231]: Stopped target basic.target - Basic System. Nov 24 00:29:33.136152 systemd[2231]: Stopped target paths.target - Paths. Nov 24 00:29:33.136177 systemd[2231]: Stopped target sockets.target - Sockets. Nov 24 00:29:33.136202 systemd[2231]: Stopped target timers.target - Timers. Nov 24 00:29:33.136555 systemd[2231]: Closed dbus.socket - D-Bus User Message Bus Socket. Nov 24 00:29:33.136769 systemd[2231]: Removed slice app.slice - User Application Slice. Nov 24 00:29:33.136802 systemd[2231]: Reached target shutdown.target - Shutdown. Nov 24 00:29:33.136941 systemd[2231]: Finished systemd-exit.service - Exit the Session. Nov 24 00:29:33.136977 systemd[2231]: Reached target exit.target - Exit the Session. Nov 24 00:29:33.158112 (sd-pam)[2232]: pam_unix(systemd-user:session): session closed for user root Nov 24 00:29:33.159311 systemd[1]: user@0.service: Deactivated successfully. Nov 24 00:29:33.159746 systemd[1]: Stopped user@0.service - User Manager for UID 0. Nov 24 00:29:33.162245 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Nov 24 00:29:33.182477 systemd[1]: run-user-0.mount: Deactivated successfully. Nov 24 00:29:33.183664 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Nov 24 00:29:33.183880 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Nov 24 00:29:33.185535 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Nov 24 00:29:33.185675 systemd[1]: user-0.slice: Consumed 5min 26.047s CPU time, 896.8M memory peak. Nov 24 00:29:33.186161 systemd-logind[1878]: Removed session c3. Nov 24 00:29:35.924928 systemd[1]: Started sshd@17-10.200.4.4:22-10.200.16.10:59412.service - OpenSSH per-connection server daemon (10.200.16.10:59412). Nov 24 00:29:37.126349 sshd[15439]: Accepted publickey for core from 10.200.16.10 port 59412 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:37.125810 sshd-session[15439]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:37.130200 systemd-logind[1878]: New session 20 of user core. Nov 24 00:29:37.148678 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 24 00:29:37.480976 sshd[15442]: Connection closed by 10.200.16.10 port 59412 Nov 24 00:29:37.481393 sshd-session[15439]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:37.485028 systemd[1]: sshd@17-10.200.4.4:22-10.200.16.10:59412.service: Deactivated successfully. Nov 24 00:29:37.486738 systemd[1]: session-20.scope: Deactivated successfully. Nov 24 00:29:37.487466 systemd-logind[1878]: Session 20 logged out. Waiting for processes to exit. Nov 24 00:29:37.489254 systemd-logind[1878]: Removed session 20. Nov 24 00:29:37.608860 systemd[1]: Started sshd@18-10.200.4.4:22-10.200.16.10:59416.service - OpenSSH per-connection server daemon (10.200.16.10:59416). Nov 24 00:29:38.195525 sshd[15448]: Accepted publickey for core from 10.200.16.10 port 59416 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:38.196814 sshd-session[15448]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:38.201044 systemd-logind[1878]: New session 21 of user core. Nov 24 00:29:38.210682 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 24 00:29:38.612975 sshd[15451]: Connection closed by 10.200.16.10 port 59416 Nov 24 00:29:38.613248 sshd-session[15448]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:38.616808 systemd[1]: sshd@18-10.200.4.4:22-10.200.16.10:59416.service: Deactivated successfully. Nov 24 00:29:38.618628 systemd[1]: session-21.scope: Deactivated successfully. Nov 24 00:29:38.619359 systemd-logind[1878]: Session 21 logged out. Waiting for processes to exit. Nov 24 00:29:38.620257 systemd-logind[1878]: Removed session 21. Nov 24 00:29:38.729941 systemd[1]: Started sshd@19-10.200.4.4:22-10.200.16.10:59422.service - OpenSSH per-connection server daemon (10.200.16.10:59422). Nov 24 00:29:39.318482 sshd[15457]: Accepted publickey for core from 10.200.16.10 port 59422 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:39.320267 sshd-session[15457]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:39.324607 systemd-logind[1878]: New session 22 of user core. Nov 24 00:29:39.338686 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 24 00:29:39.747502 sshd[15460]: Connection closed by 10.200.16.10 port 59422 Nov 24 00:29:39.747882 sshd-session[15457]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:39.751382 systemd[1]: sshd@19-10.200.4.4:22-10.200.16.10:59422.service: Deactivated successfully. Nov 24 00:29:39.753176 systemd[1]: session-22.scope: Deactivated successfully. Nov 24 00:29:39.753939 systemd-logind[1878]: Session 22 logged out. Waiting for processes to exit. Nov 24 00:29:39.754846 systemd-logind[1878]: Removed session 22. Nov 24 00:29:39.857874 systemd[1]: Started sshd@20-10.200.4.4:22-10.200.16.10:59428.service - OpenSSH per-connection server daemon (10.200.16.10:59428). Nov 24 00:29:40.469883 sshd[15466]: Accepted publickey for core from 10.200.16.10 port 59428 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:40.471106 sshd-session[15466]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:40.475285 systemd-logind[1878]: New session 23 of user core. Nov 24 00:29:40.483679 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 24 00:29:40.801485 sudo[15470]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 24 00:29:40.801806 sudo[15470]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 24 00:29:40.819289 sudo[15470]: pam_unix(sudo:session): session closed for user root Nov 24 00:29:40.923852 sshd[15469]: Connection closed by 10.200.16.10 port 59428 Nov 24 00:29:40.924261 sshd-session[15466]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:40.928263 systemd[1]: sshd@20-10.200.4.4:22-10.200.16.10:59428.service: Deactivated successfully. Nov 24 00:29:40.930155 systemd[1]: session-23.scope: Deactivated successfully. Nov 24 00:29:40.930923 systemd-logind[1878]: Session 23 logged out. Waiting for processes to exit. Nov 24 00:29:40.932162 systemd-logind[1878]: Removed session 23. Nov 24 00:29:41.040950 systemd[1]: Started sshd@21-10.200.4.4:22-10.200.16.10:49312.service - OpenSSH per-connection server daemon (10.200.16.10:49312). Nov 24 00:29:41.649084 sshd[15476]: Accepted publickey for core from 10.200.16.10 port 49312 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:41.650374 sshd-session[15476]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:41.654791 systemd-logind[1878]: New session 24 of user core. Nov 24 00:29:41.665687 systemd[1]: Started session-24.scope - Session 24 of User core. Nov 24 00:29:41.978367 sudo[15481]: 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 Nov 24 00:29:41.978686 sudo[15481]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 24 00:29:41.984588 sudo[15481]: pam_unix(sudo:session): session closed for user root Nov 24 00:29:41.990033 sudo[15480]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 24 00:29:41.990309 sudo[15480]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 24 00:29:41.999228 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 24 00:29:42.043616 augenrules[15503]: No rules Nov 24 00:29:42.044730 systemd[1]: audit-rules.service: Deactivated successfully. Nov 24 00:29:42.044983 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 24 00:29:42.045772 sudo[15480]: pam_unix(sudo:session): session closed for user root Nov 24 00:29:42.163263 sshd[15479]: Connection closed by 10.200.16.10 port 49312 Nov 24 00:29:42.163625 sshd-session[15476]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:42.167242 systemd[1]: sshd@21-10.200.4.4:22-10.200.16.10:49312.service: Deactivated successfully. Nov 24 00:29:42.168870 systemd[1]: session-24.scope: Deactivated successfully. Nov 24 00:29:42.169567 systemd-logind[1878]: Session 24 logged out. Waiting for processes to exit. Nov 24 00:29:42.170487 systemd-logind[1878]: Removed session 24. Nov 24 00:29:42.273057 systemd[1]: Started sshd@22-10.200.4.4:22-10.200.16.10:49316.service - OpenSSH per-connection server daemon (10.200.16.10:49316). Nov 24 00:29:42.894240 sshd[15512]: Accepted publickey for core from 10.200.16.10 port 49316 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:42.895465 sshd-session[15512]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:42.900094 systemd-logind[1878]: New session 25 of user core. Nov 24 00:29:42.910693 systemd[1]: Started session-25.scope - Session 25 of User core. Nov 24 00:29:43.224063 sudo[15516]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Nov 24 00:29:43.224359 sudo[15516]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 24 00:29:43.227640 sudo[15516]: pam_unix(sudo:session): session closed for user root Nov 24 00:29:43.322168 sshd[15515]: Connection closed by 10.200.16.10 port 49316 Nov 24 00:29:43.322517 sshd-session[15512]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:43.325963 systemd[1]: sshd@22-10.200.4.4:22-10.200.16.10:49316.service: Deactivated successfully. Nov 24 00:29:43.327676 systemd[1]: session-25.scope: Deactivated successfully. Nov 24 00:29:43.328339 systemd-logind[1878]: Session 25 logged out. Waiting for processes to exit. Nov 24 00:29:43.329242 systemd-logind[1878]: Removed session 25. Nov 24 00:29:43.431706 systemd[1]: Started sshd@23-10.200.4.4:22-10.200.16.10:49320.service - OpenSSH per-connection server daemon (10.200.16.10:49320). Nov 24 00:29:44.051222 sshd[15522]: Accepted publickey for core from 10.200.16.10 port 49320 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:44.052435 sshd-session[15522]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:44.056578 systemd-logind[1878]: New session 26 of user core. Nov 24 00:29:44.077676 systemd[1]: Started session-26.scope - Session 26 of User core. Nov 24 00:29:44.380946 sudo[15526]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Nov 24 00:29:44.381254 sudo[15526]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 24 00:29:52.118666 sudo[15526]: pam_unix(sudo:session): session closed for user root Nov 24 00:29:52.240149 sshd[15525]: Connection closed by 10.200.16.10 port 49320 Nov 24 00:29:52.240502 sshd-session[15522]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:52.244162 systemd[1]: sshd@23-10.200.4.4:22-10.200.16.10:49320.service: Deactivated successfully. Nov 24 00:29:52.246105 systemd[1]: session-26.scope: Deactivated successfully. Nov 24 00:29:52.246837 systemd-logind[1878]: Session 26 logged out. Waiting for processes to exit. Nov 24 00:29:52.247776 systemd-logind[1878]: Removed session 26. Nov 24 00:29:52.348990 systemd[1]: Started sshd@24-10.200.4.4:22-10.200.16.10:57368.service - OpenSSH per-connection server daemon (10.200.16.10:57368). Nov 24 00:29:52.942988 sshd[15532]: Accepted publickey for core from 10.200.16.10 port 57368 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:52.944219 sshd-session[15532]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:52.948562 systemd-logind[1878]: New session 27 of user core. Nov 24 00:29:52.958686 systemd[1]: Started session-27.scope - Session 27 of User core. Nov 24 00:29:53.450206 sshd[15535]: Connection closed by 10.200.16.10 port 57368 Nov 24 00:29:53.450605 sshd-session[15532]: pam_unix(sshd:session): session closed for user core Nov 24 00:29:53.454105 systemd[1]: sshd@24-10.200.4.4:22-10.200.16.10:57368.service: Deactivated successfully. Nov 24 00:29:53.455861 systemd[1]: session-27.scope: Deactivated successfully. Nov 24 00:29:53.456606 systemd-logind[1878]: Session 27 logged out. Waiting for processes to exit. Nov 24 00:29:53.457734 systemd-logind[1878]: Removed session 27. Nov 24 00:29:53.561771 systemd[1]: Started sshd@25-10.200.4.4:22-10.200.16.10:57384.service - OpenSSH per-connection server daemon (10.200.16.10:57384). Nov 24 00:29:54.159278 sshd[15550]: Accepted publickey for core from 10.200.16.10 port 57384 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:29:54.160485 sshd-session[15550]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:29:54.164865 systemd-logind[1878]: New session 28 of user core. Nov 24 00:29:54.176675 systemd[1]: Started session-28.scope - Session 28 of User core. Nov 24 00:29:55.290337 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 24 00:29:55.306937 (dockerd)[15575]: 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 Nov 24 00:29:56.436701 dockerd[15575]: time="2025-11-24T00:29:56.436583391Z" level=info msg="Starting up" Nov 24 00:29:56.437318 dockerd[15575]: time="2025-11-24T00:29:56.437293576Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 24 00:29:56.447885 dockerd[15575]: time="2025-11-24T00:29:56.447848350Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 24 00:29:57.016632 dockerd[15575]: time="2025-11-24T00:29:57.016586497Z" level=info msg="Loading containers: start." Nov 24 00:29:57.029565 kernel: Initializing XFRM netlink socket Nov 24 00:29:57.284561 systemd-networkd[1570]: docker0: Link UP Nov 24 00:29:57.295791 dockerd[15575]: time="2025-11-24T00:29:57.295752659Z" level=info msg="Loading containers: done." Nov 24 00:29:57.312447 dockerd[15575]: time="2025-11-24T00:29:57.312403972Z" 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 Nov 24 00:29:57.312590 dockerd[15575]: time="2025-11-24T00:29:57.312474470Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 24 00:29:57.312590 dockerd[15575]: time="2025-11-24T00:29:57.312579866Z" level=info msg="Initializing buildkit" Nov 24 00:29:57.368217 dockerd[15575]: time="2025-11-24T00:29:57.368174807Z" level=info msg="Completed buildkit initialization" Nov 24 00:29:57.373118 dockerd[15575]: time="2025-11-24T00:29:57.373091034Z" level=info msg="Daemon has completed initialization" Nov 24 00:29:57.373199 dockerd[15575]: time="2025-11-24T00:29:57.373152231Z" level=info msg="API listen on /run/docker.sock" Nov 24 00:29:57.373272 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 24 00:29:57.471174 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1083084314-merged.mount: Deactivated successfully. Nov 24 00:29:58.401173 systemd[1]: var-lib-docker-overlay2-2965b71d005d93f8cf3c131d6016aa1295293b544ef7dbaece503ca13ec5c6cc\x2dinit-merged.mount: Deactivated successfully. Nov 24 00:29:58.480331 containerd[1897]: time="2025-11-24T00:29:58.480282715Z" level=info msg="connecting to shim 6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192" address="unix:///run/containerd/s/b6e377ad184f5ba683334f6e6e7fbf083b193ccf5cfc7a671711c176c4113b46" namespace=moby protocol=ttrpc version=3 Nov 24 00:29:58.515676 systemd[1]: Started docker-6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192.scope - libcontainer container 6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192. Nov 24 00:29:58.564310 kernel: docker0: port 1(vetha2732d4) entered blocking state Nov 24 00:29:58.564409 kernel: docker0: port 1(vetha2732d4) entered disabled state Nov 24 00:29:58.566808 kernel: vetha2732d4: entered allmulticast mode Nov 24 00:29:58.569234 kernel: vetha2732d4: entered promiscuous mode Nov 24 00:29:58.569750 systemd-networkd[1570]: vetha2732d4: Link UP Nov 24 00:29:58.587567 kernel: eth0: renamed from vethd7658f8 Nov 24 00:29:58.594897 kernel: docker0: port 1(vetha2732d4) entered blocking state Nov 24 00:29:58.594947 kernel: docker0: port 1(vetha2732d4) entered forwarding state Nov 24 00:29:58.595309 systemd-networkd[1570]: vetha2732d4: Gained carrier Nov 24 00:29:58.595603 systemd-networkd[1570]: docker0: Gained carrier Nov 24 00:30:00.472692 systemd-networkd[1570]: docker0: Gained IPv6LL Nov 24 00:30:00.536631 systemd-networkd[1570]: vetha2732d4: Gained IPv6LL Nov 24 00:30:01.647704 systemd[1]: docker-6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192.scope: Deactivated successfully. Nov 24 00:30:01.660316 containerd[1897]: time="2025-11-24T00:30:01.660275295Z" level=info msg="shim disconnected" id=6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192 namespace=moby Nov 24 00:30:01.660641 dockerd[15575]: time="2025-11-24T00:30:01.660291894Z" level=info msg="ignoring event" container=6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 24 00:30:01.660911 containerd[1897]: time="2025-11-24T00:30:01.660878974Z" level=warning msg="cleaning up after shim disconnected" id=6e65a1987234a9836b3f17a229e7c47690aa138c3ae727dab9e82783af606192 namespace=moby Nov 24 00:30:01.660977 containerd[1897]: time="2025-11-24T00:30:01.660902873Z" level=info msg="cleaning up dead shim" namespace=moby Nov 24 00:30:01.689566 kernel: docker0: port 1(vetha2732d4) entered disabled state Nov 24 00:30:01.689645 kernel: vethd7658f8: renamed from eth0 Nov 24 00:30:01.689698 systemd-networkd[1570]: vetha2732d4: Lost carrier Nov 24 00:30:01.714813 systemd-networkd[1570]: vetha2732d4: Link DOWN Nov 24 00:30:01.718619 kernel: docker0: port 1(vetha2732d4) entered disabled state Nov 24 00:30:01.718671 kernel: vetha2732d4 (unregistering): left allmulticast mode Nov 24 00:30:01.724873 kernel: vetha2732d4 (unregistering): left promiscuous mode Nov 24 00:30:01.728201 kernel: docker0: port 1(vetha2732d4) entered disabled state Nov 24 00:30:01.751797 systemd[1]: run-docker-netns-89106e649eab.mount: Deactivated successfully. Nov 24 00:30:01.754016 systemd[1]: var-lib-docker-overlay2-2965b71d005d93f8cf3c131d6016aa1295293b544ef7dbaece503ca13ec5c6cc-merged.mount: Deactivated successfully. Nov 24 00:30:01.902165 sshd[15553]: Connection closed by 10.200.16.10 port 57384 Nov 24 00:30:01.902486 sshd-session[15550]: pam_unix(sshd:session): session closed for user core Nov 24 00:30:01.905335 systemd[1]: sshd@25-10.200.4.4:22-10.200.16.10:57384.service: Deactivated successfully. Nov 24 00:30:01.907194 systemd[1]: session-28.scope: Deactivated successfully. Nov 24 00:30:01.908432 systemd-logind[1878]: Session 28 logged out. Waiting for processes to exit. Nov 24 00:30:01.909395 systemd-logind[1878]: Removed session 28. Nov 24 00:30:02.018990 systemd[1]: Started sshd@26-10.200.4.4:22-10.200.16.10:55622.service - OpenSSH per-connection server daemon (10.200.16.10:55622). Nov 24 00:30:02.641877 sshd[15864]: Accepted publickey for core from 10.200.16.10 port 55622 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:30:02.643165 sshd-session[15864]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:30:02.647753 systemd-logind[1878]: New session 29 of user core. Nov 24 00:30:02.657667 systemd[1]: Started session-29.scope - Session 29 of User core. Nov 24 00:30:02.712816 systemd-networkd[1570]: docker0: Lost carrier Nov 24 00:30:03.018914 systemd[1]: var-lib-docker-overlay2-dc883454cabb15bf544eb6ab35793a8f5176f3e94bc8574301a7731e79545d34\x2dinit-merged.mount: Deactivated successfully. Nov 24 00:30:03.082179 containerd[1897]: time="2025-11-24T00:30:03.082137240Z" level=info msg="connecting to shim 07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45" address="unix:///run/containerd/s/2580aa7a5d53584030678e7354c218e1d999670523a42a77a3cf14e053951349" namespace=moby protocol=ttrpc version=3 Nov 24 00:30:03.106685 systemd[1]: Started docker-07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45.scope - libcontainer container 07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45. Nov 24 00:30:03.149686 kernel: docker0: port 1(vethabcba08) entered blocking state Nov 24 00:30:03.149781 kernel: docker0: port 1(vethabcba08) entered disabled state Nov 24 00:30:03.152523 kernel: vethabcba08: entered allmulticast mode Nov 24 00:30:03.155425 kernel: vethabcba08: entered promiscuous mode Nov 24 00:30:03.155953 systemd-networkd[1570]: vethabcba08: Link UP Nov 24 00:30:03.173577 kernel: eth0: renamed from veth3171ef7 Nov 24 00:30:03.173647 kernel: docker0: port 1(vethabcba08) entered blocking state Nov 24 00:30:03.180126 kernel: docker0: port 1(vethabcba08) entered forwarding state Nov 24 00:30:03.180540 systemd-networkd[1570]: vethabcba08: Gained carrier Nov 24 00:30:03.180805 systemd-networkd[1570]: docker0: Gained carrier Nov 24 00:30:03.207446 systemd[1]: docker-07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45.scope: Deactivated successfully. Nov 24 00:30:03.227737 containerd[1897]: time="2025-11-24T00:30:03.227558823Z" level=info msg="shim disconnected" id=07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45 namespace=moby Nov 24 00:30:03.227737 containerd[1897]: time="2025-11-24T00:30:03.227590722Z" level=warning msg="cleaning up after shim disconnected" id=07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45 namespace=moby Nov 24 00:30:03.227737 containerd[1897]: time="2025-11-24T00:30:03.227599421Z" level=info msg="cleaning up dead shim" namespace=moby Nov 24 00:30:03.227874 dockerd[15575]: time="2025-11-24T00:30:03.227625320Z" level=info msg="ignoring event" container=07b24ec62e7a685d4c25262aec2e6e1d0d4199442a8c6036a91df00f15eafb45 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 24 00:30:03.256576 kernel: docker0: port 1(vethabcba08) entered disabled state Nov 24 00:30:03.256654 kernel: veth3171ef7: renamed from eth0 Nov 24 00:30:03.256644 systemd-networkd[1570]: vethabcba08: Lost carrier Nov 24 00:30:03.278948 systemd-networkd[1570]: vethabcba08: Link DOWN Nov 24 00:30:03.286510 kernel: docker0: port 1(vethabcba08) entered disabled state Nov 24 00:30:03.286596 kernel: vethabcba08 (unregistering): left allmulticast mode Nov 24 00:30:03.291032 kernel: vethabcba08 (unregistering): left promiscuous mode Nov 24 00:30:03.291084 kernel: docker0: port 1(vethabcba08) entered disabled state Nov 24 00:30:03.436499 sshd[15869]: Connection closed by 10.200.16.10 port 55622 Nov 24 00:30:03.436897 sshd-session[15864]: pam_unix(sshd:session): session closed for user core Nov 24 00:30:03.440499 systemd[1]: sshd@26-10.200.4.4:22-10.200.16.10:55622.service: Deactivated successfully. Nov 24 00:30:03.442105 systemd[1]: session-29.scope: Deactivated successfully. Nov 24 00:30:03.442851 systemd-logind[1878]: Session 29 logged out. Waiting for processes to exit. Nov 24 00:30:03.443780 systemd-logind[1878]: Removed session 29. Nov 24 00:30:03.545774 systemd[1]: Started sshd@27-10.200.4.4:22-10.200.16.10:55628.service - OpenSSH per-connection server daemon (10.200.16.10:55628). Nov 24 00:30:03.720833 systemd-networkd[1570]: docker0: Lost carrier Nov 24 00:30:04.016058 systemd[1]: var-lib-docker-overlay2-dc883454cabb15bf544eb6ab35793a8f5176f3e94bc8574301a7731e79545d34-merged.mount: Deactivated successfully. Nov 24 00:30:04.155624 sshd[15972]: Accepted publickey for core from 10.200.16.10 port 55628 ssh2: RSA SHA256:41tw/Gfq5AHPbl8Hna3dSlgWJZkqnlMaT4jMX8l/Rrg Nov 24 00:30:04.156857 sshd-session[15972]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 24 00:30:04.161309 systemd-logind[1878]: New session 30 of user core. Nov 24 00:30:04.171696 systemd[1]: Started session-30.scope - Session 30 of User core. Nov 24 00:30:04.524869 ntpdate[15986]: ntpdate 4.2.8p18@1.4062-o Sun Nov 23 20:13:59 UTC 2025 (1) Nov 24 00:30:11.231883 ntpdate[15986]: adjust time server 185.83.169.27 offset -0.010455 sec Nov 24 00:30:11.336571 sshd[15975]: Connection closed by 10.200.16.10 port 55628 Nov 24 00:30:11.336962 sshd-session[15972]: pam_unix(sshd:session): session closed for user core Nov 24 00:30:11.340510 systemd[1]: sshd@27-10.200.4.4:22-10.200.16.10:55628.service: Deactivated successfully. Nov 24 00:30:11.342307 systemd[1]: session-30.scope: Deactivated successfully. Nov 24 00:30:11.343064 systemd-logind[1878]: Session 30 logged out. Waiting for processes to exit. Nov 24 00:30:11.344043 systemd-logind[1878]: Removed session 30.