Jul 7 06:31:52.246694 kernel: Linux version 6.12.35-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Sun Jul 6 21:56:00 -00 2025 Jul 7 06:31:52.246723 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=2e0b2c30526b1d273b6d599d4c30389a93a14ce36aaa5af83a05b11c5ea5ae50 Jul 7 06:31:52.246733 kernel: BIOS-provided physical RAM map: Jul 7 06:31:52.246740 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 7 06:31:52.246747 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 7 06:31:52.246754 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 7 06:31:52.246762 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 7 06:31:52.246772 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 7 06:31:52.246779 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 7 06:31:52.246786 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 7 06:31:52.246793 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 7 06:31:52.246800 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 7 06:31:52.246807 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 7 06:31:52.246814 kernel: NX (Execute Disable) protection: active Jul 7 06:31:52.246825 kernel: APIC: Static calls initialized Jul 7 06:31:52.246833 kernel: efi: EFI v2.7 by Microsoft Jul 7 06:31:52.246841 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ee5fa98 RNG=0x3ffd1018 Jul 7 06:31:52.246849 kernel: random: crng init done Jul 7 06:31:52.246857 kernel: secureboot: Secure boot disabled Jul 7 06:31:52.246865 kernel: SMBIOS 3.1.0 present. Jul 7 06:31:52.246873 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 7 06:31:52.246881 kernel: DMI: Memory slots populated: 3/3 Jul 7 06:31:52.246888 kernel: Hypervisor detected: Microsoft Hyper-V Jul 7 06:31:52.246896 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 7 06:31:52.246906 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 7 06:31:52.246914 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 7 06:31:52.246922 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 06:31:52.246930 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 7 06:31:52.246976 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 7 06:31:52.246987 kernel: tsc: Detected 2593.993 MHz processor Jul 7 06:31:52.246996 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 7 06:31:52.247004 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 7 06:31:52.247012 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 7 06:31:52.247025 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 7 06:31:52.247033 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 7 06:31:52.247041 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 7 06:31:52.247049 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 7 06:31:52.247056 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 7 06:31:52.247064 kernel: Using GB pages for direct mapping Jul 7 06:31:52.247073 kernel: ACPI: Early table checksum verification disabled Jul 7 06:31:52.247085 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 7 06:31:52.247096 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247104 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247112 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 7 06:31:52.247121 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 7 06:31:52.247129 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247138 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247149 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247157 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247165 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247174 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247182 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 7 06:31:52.247199 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 7 06:31:52.247208 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 7 06:31:52.247216 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 7 06:31:52.247228 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 7 06:31:52.247236 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 7 06:31:52.247245 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 7 06:31:52.247253 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 7 06:31:52.247261 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 7 06:31:52.247270 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 7 06:31:52.247278 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 7 06:31:52.247287 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 7 06:31:52.247295 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 7 06:31:52.247306 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 7 06:31:52.247314 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 7 06:31:52.247323 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 7 06:31:52.247331 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 7 06:31:52.247340 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 7 06:31:52.247348 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 7 06:31:52.247357 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 7 06:31:52.247366 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 7 06:31:52.247374 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 7 06:31:52.247385 kernel: Zone ranges: Jul 7 06:31:52.247394 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 7 06:31:52.247402 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 7 06:31:52.247411 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 7 06:31:52.247419 kernel: Device empty Jul 7 06:31:52.247428 kernel: Movable zone start for each node Jul 7 06:31:52.247436 kernel: Early memory node ranges Jul 7 06:31:52.247444 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 7 06:31:52.247453 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 7 06:31:52.247464 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 7 06:31:52.247472 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 7 06:31:52.247480 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 7 06:31:52.247489 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 7 06:31:52.247512 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 7 06:31:52.247520 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 7 06:31:52.247529 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 7 06:31:52.247537 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 7 06:31:52.247545 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 7 06:31:52.247557 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 7 06:31:52.247566 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 7 06:31:52.247574 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 7 06:31:52.247582 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 7 06:31:52.247591 kernel: CPU topo: Max. logical packages: 1 Jul 7 06:31:52.247599 kernel: CPU topo: Max. logical dies: 1 Jul 7 06:31:52.247607 kernel: CPU topo: Max. dies per package: 1 Jul 7 06:31:52.247616 kernel: CPU topo: Max. threads per core: 1 Jul 7 06:31:52.247624 kernel: CPU topo: Num. cores per package: 6 Jul 7 06:31:52.247635 kernel: CPU topo: Num. threads per package: 6 Jul 7 06:31:52.247644 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 7 06:31:52.247652 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 7 06:31:52.247660 kernel: Booting paravirtualized kernel on Hyper-V Jul 7 06:31:52.247669 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 7 06:31:52.247677 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 7 06:31:52.247686 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 7 06:31:52.247694 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 7 06:31:52.247703 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 7 06:31:52.247713 kernel: Hyper-V: PV spinlocks enabled Jul 7 06:31:52.247722 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 7 06:31:52.247731 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=2e0b2c30526b1d273b6d599d4c30389a93a14ce36aaa5af83a05b11c5ea5ae50 Jul 7 06:31:52.247740 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 7 06:31:52.247749 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 7 06:31:52.247757 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 7 06:31:52.247766 kernel: Fallback order for Node 0: 0 Jul 7 06:31:52.247774 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 7 06:31:52.247785 kernel: Policy zone: Normal Jul 7 06:31:52.247801 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 7 06:31:52.247810 kernel: software IO TLB: area num 8. Jul 7 06:31:52.247821 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 7 06:31:52.247830 kernel: Kernel/User page tables isolation: enabled Jul 7 06:31:52.247839 kernel: ftrace: allocating 40095 entries in 157 pages Jul 7 06:31:52.247848 kernel: ftrace: allocated 157 pages with 5 groups Jul 7 06:31:52.247857 kernel: Dynamic Preempt: voluntary Jul 7 06:31:52.247866 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 7 06:31:52.247875 kernel: rcu: RCU event tracing is enabled. Jul 7 06:31:52.247884 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 7 06:31:52.247894 kernel: Trampoline variant of Tasks RCU enabled. Jul 7 06:31:52.247905 kernel: Rude variant of Tasks RCU enabled. Jul 7 06:31:52.247914 kernel: Tracing variant of Tasks RCU enabled. Jul 7 06:31:52.247923 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 7 06:31:52.247932 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 7 06:31:52.247941 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 06:31:52.247953 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 06:31:52.247962 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 7 06:31:52.247971 kernel: Using NULL legacy PIC Jul 7 06:31:52.247979 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 7 06:31:52.247988 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 7 06:31:52.247997 kernel: Console: colour dummy device 80x25 Jul 7 06:31:52.248006 kernel: printk: legacy console [tty1] enabled Jul 7 06:31:52.248015 kernel: printk: legacy console [ttyS0] enabled Jul 7 06:31:52.248024 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 7 06:31:52.248035 kernel: ACPI: Core revision 20240827 Jul 7 06:31:52.248044 kernel: Failed to register legacy timer interrupt Jul 7 06:31:52.248053 kernel: APIC: Switch to symmetric I/O mode setup Jul 7 06:31:52.248062 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 7 06:31:52.248070 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 7 06:31:52.248079 kernel: Hyper-V: Using IPI hypercalls Jul 7 06:31:52.248088 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 7 06:31:52.248097 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 7 06:31:52.248106 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 7 06:31:52.248117 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 7 06:31:52.248126 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 7 06:31:52.248135 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 7 06:31:52.248144 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 7 06:31:52.248153 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 7 06:31:52.248161 kernel: APIC: read() replaced with hv_apic_read() Jul 7 06:31:52.248170 kernel: APIC: write() replaced with hv_apic_write() Jul 7 06:31:52.248179 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 7 06:31:52.248188 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 7 06:31:52.248199 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593993) Jul 7 06:31:52.248208 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 7 06:31:52.248217 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 7 06:31:52.248226 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 7 06:31:52.248235 kernel: Spectre V2 : Mitigation: Retpolines Jul 7 06:31:52.248243 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 7 06:31:52.248252 kernel: Speculative Store Bypass: Vulnerable Jul 7 06:31:52.248261 kernel: MDS: Mitigation: Clear CPU buffers Jul 7 06:31:52.248270 kernel: TAA: Mitigation: Clear CPU buffers Jul 7 06:31:52.248278 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 7 06:31:52.248290 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 7 06:31:52.248298 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 7 06:31:52.248307 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 7 06:31:52.248316 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 7 06:31:52.248324 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 7 06:31:52.248333 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 7 06:31:52.248342 kernel: Freeing SMP alternatives memory: 32K Jul 7 06:31:52.248351 kernel: pid_max: default: 32768 minimum: 301 Jul 7 06:31:52.248360 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 7 06:31:52.248368 kernel: landlock: Up and running. Jul 7 06:31:52.248377 kernel: SELinux: Initializing. Jul 7 06:31:52.248388 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 06:31:52.248397 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 7 06:31:52.248406 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 7 06:31:52.248415 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 7 06:31:52.248424 kernel: signal: max sigframe size: 1776 Jul 7 06:31:52.248433 kernel: rcu: Hierarchical SRCU implementation. Jul 7 06:31:52.248442 kernel: rcu: Max phase no-delay instances is 400. Jul 7 06:31:52.248451 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 7 06:31:52.248460 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 7 06:31:52.248469 kernel: smp: Bringing up secondary CPUs ... Jul 7 06:31:52.248480 kernel: smpboot: x86: Booting SMP configuration: Jul 7 06:31:52.248489 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 7 06:31:52.248507 kernel: smp: Brought up 1 node, 6 CPUs Jul 7 06:31:52.248516 kernel: smpboot: Total of 6 processors activated (31127.91 BogoMIPS) Jul 7 06:31:52.248525 kernel: Memory: 115339088K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54432K init, 2536K bss, 2090000K reserved, 0K cma-reserved) Jul 7 06:31:52.248535 kernel: devtmpfs: initialized Jul 7 06:31:52.248543 kernel: x86/mm: Memory block size: 1024MB Jul 7 06:31:52.248552 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 7 06:31:52.248561 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 7 06:31:52.248573 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 7 06:31:52.248582 kernel: pinctrl core: initialized pinctrl subsystem Jul 7 06:31:52.248591 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 7 06:31:52.248600 kernel: audit: initializing netlink subsys (disabled) Jul 7 06:31:52.248609 kernel: audit: type=2000 audit(1751869908.028:1): state=initialized audit_enabled=0 res=1 Jul 7 06:31:52.248618 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 7 06:31:52.248627 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 7 06:31:52.248636 kernel: cpuidle: using governor menu Jul 7 06:31:52.248644 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 7 06:31:52.248656 kernel: dca service started, version 1.12.1 Jul 7 06:31:52.248665 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 7 06:31:52.248673 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 7 06:31:52.248682 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 7 06:31:52.248691 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 7 06:31:52.248700 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 7 06:31:52.248709 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 7 06:31:52.248718 kernel: ACPI: Added _OSI(Module Device) Jul 7 06:31:52.248727 kernel: ACPI: Added _OSI(Processor Device) Jul 7 06:31:52.248738 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 7 06:31:52.248747 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 7 06:31:52.248756 kernel: ACPI: Interpreter enabled Jul 7 06:31:52.248765 kernel: ACPI: PM: (supports S0 S5) Jul 7 06:31:52.248773 kernel: ACPI: Using IOAPIC for interrupt routing Jul 7 06:31:52.248782 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 7 06:31:52.248791 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 7 06:31:52.248800 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 7 06:31:52.248809 kernel: iommu: Default domain type: Translated Jul 7 06:31:52.248820 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 7 06:31:52.248829 kernel: efivars: Registered efivars operations Jul 7 06:31:52.248838 kernel: PCI: Using ACPI for IRQ routing Jul 7 06:31:52.248847 kernel: PCI: System does not support PCI Jul 7 06:31:52.248856 kernel: vgaarb: loaded Jul 7 06:31:52.248865 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 7 06:31:52.248874 kernel: VFS: Disk quotas dquot_6.6.0 Jul 7 06:31:52.248882 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 7 06:31:52.248891 kernel: pnp: PnP ACPI init Jul 7 06:31:52.248903 kernel: pnp: PnP ACPI: found 3 devices Jul 7 06:31:52.248912 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 7 06:31:52.248921 kernel: NET: Registered PF_INET protocol family Jul 7 06:31:52.248930 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 7 06:31:52.248939 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 7 06:31:52.248948 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 7 06:31:52.248957 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 7 06:31:52.248966 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 06:31:52.248977 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 7 06:31:52.248986 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 06:31:52.248995 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 7 06:31:52.249004 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 7 06:31:52.249012 kernel: NET: Registered PF_XDP protocol family Jul 7 06:31:52.249021 kernel: PCI: CLS 0 bytes, default 64 Jul 7 06:31:52.249030 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 7 06:31:52.249039 kernel: software IO TLB: mapped [mem 0x000000003ae5f000-0x000000003ee5f000] (64MB) Jul 7 06:31:52.249048 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 7 06:31:52.249059 kernel: Initialise system trusted keyrings Jul 7 06:31:52.249068 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 7 06:31:52.249077 kernel: Key type asymmetric registered Jul 7 06:31:52.249086 kernel: Asymmetric key parser 'x509' registered Jul 7 06:31:52.249094 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 7 06:31:52.249103 kernel: io scheduler mq-deadline registered Jul 7 06:31:52.249112 kernel: io scheduler kyber registered Jul 7 06:31:52.249121 kernel: io scheduler bfq registered Jul 7 06:31:52.249130 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 7 06:31:52.249141 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 7 06:31:52.249150 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 7 06:31:52.249159 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 7 06:31:52.249168 kernel: i8042: PNP: No PS/2 controller found. Jul 7 06:31:52.249325 kernel: rtc_cmos 00:02: registered as rtc0 Jul 7 06:31:52.249432 kernel: rtc_cmos 00:02: setting system clock to 2025-07-07T06:31:51 UTC (1751869911) Jul 7 06:31:52.249547 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 7 06:31:52.249561 kernel: intel_pstate: CPU model not supported Jul 7 06:31:52.249575 kernel: efifb: probing for efifb Jul 7 06:31:52.249584 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 7 06:31:52.249593 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 7 06:31:52.249602 kernel: efifb: scrolling: redraw Jul 7 06:31:52.249610 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 7 06:31:52.249619 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 06:31:52.249628 kernel: fb0: EFI VGA frame buffer device Jul 7 06:31:52.249637 kernel: pstore: Using crash dump compression: deflate Jul 7 06:31:52.249646 kernel: pstore: Registered efi_pstore as persistent store backend Jul 7 06:31:52.249657 kernel: NET: Registered PF_INET6 protocol family Jul 7 06:31:52.249666 kernel: Segment Routing with IPv6 Jul 7 06:31:52.249675 kernel: In-situ OAM (IOAM) with IPv6 Jul 7 06:31:52.249684 kernel: NET: Registered PF_PACKET protocol family Jul 7 06:31:52.249693 kernel: Key type dns_resolver registered Jul 7 06:31:52.249702 kernel: IPI shorthand broadcast: enabled Jul 7 06:31:52.249711 kernel: sched_clock: Marking stable (3759004800, 106103200)->(4115957100, -250849100) Jul 7 06:31:52.249719 kernel: registered taskstats version 1 Jul 7 06:31:52.249728 kernel: Loading compiled-in X.509 certificates Jul 7 06:31:52.249740 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.35-flatcar: b8e96f4c6a9e663230fc9c12b186cf91fcc7a64e' Jul 7 06:31:52.249749 kernel: Demotion targets for Node 0: null Jul 7 06:31:52.249758 kernel: Key type .fscrypt registered Jul 7 06:31:52.249767 kernel: Key type fscrypt-provisioning registered Jul 7 06:31:52.249775 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 7 06:31:52.249784 kernel: ima: Allocated hash algorithm: sha1 Jul 7 06:31:52.249793 kernel: ima: No architecture policies found Jul 7 06:31:52.249802 kernel: clk: Disabling unused clocks Jul 7 06:31:52.249811 kernel: Warning: unable to open an initial console. Jul 7 06:31:52.249822 kernel: Freeing unused kernel image (initmem) memory: 54432K Jul 7 06:31:52.249831 kernel: Write protecting the kernel read-only data: 24576k Jul 7 06:31:52.249840 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 7 06:31:52.249849 kernel: Run /init as init process Jul 7 06:31:52.249858 kernel: with arguments: Jul 7 06:31:52.249867 kernel: /init Jul 7 06:31:52.249875 kernel: with environment: Jul 7 06:31:52.249884 kernel: HOME=/ Jul 7 06:31:52.249892 kernel: TERM=linux Jul 7 06:31:52.249904 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 7 06:31:52.249914 systemd[1]: Successfully made /usr/ read-only. Jul 7 06:31:52.249926 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) Jul 7 06:31:52.249936 systemd[1]: Detected virtualization microsoft. Jul 7 06:31:52.249945 systemd[1]: Detected architecture x86-64. Jul 7 06:31:52.249955 systemd[1]: Running in initrd. Jul 7 06:31:52.249964 systemd[1]: No hostname configured, using default hostname. Jul 7 06:31:52.249976 systemd[1]: Hostname set to . Jul 7 06:31:52.249986 systemd[1]: Initializing machine ID from random generator. Jul 7 06:31:52.249995 systemd[1]: Queued start job for default target initrd.target. Jul 7 06:31:52.250005 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 06:31:52.250014 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 06:31:52.250024 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 7 06:31:52.250034 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 06:31:52.250044 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 7 06:31:52.250057 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 7 06:31:52.250067 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 7 06:31:52.250077 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 7 06:31:52.250087 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 06:31:52.250096 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 06:31:52.250105 systemd[1]: Reached target paths.target - Path Units. Jul 7 06:31:52.250115 systemd[1]: Reached target slices.target - Slice Units. Jul 7 06:31:52.250127 systemd[1]: Reached target swap.target - Swaps. Jul 7 06:31:52.250136 systemd[1]: Reached target timers.target - Timer Units. Jul 7 06:31:52.250146 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 06:31:52.250179 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 06:31:52.250192 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 7 06:31:52.250202 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 7 06:31:52.250212 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 06:31:52.250221 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 06:31:52.250231 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 06:31:52.250245 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 06:31:52.250255 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 7 06:31:52.250264 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 06:31:52.250274 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 7 06:31:52.250283 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). Jul 7 06:31:52.250293 systemd[1]: Starting systemd-fsck-usr.service... Jul 7 06:31:52.250302 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 06:31:52.250312 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 06:31:52.250324 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:31:52.250334 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 7 06:31:52.250344 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 06:31:52.250354 systemd[1]: Finished systemd-fsck-usr.service. Jul 7 06:31:52.250364 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 06:31:52.250398 systemd-journald[233]: Collecting audit messages is disabled. Jul 7 06:31:52.250436 systemd-journald[233]: Journal started Jul 7 06:31:52.250459 systemd-journald[233]: Runtime Journal (/run/log/journal/8cc3223d757c4acc9f883dbfa769fe4c) is 8M, max 2.2G, 2.1G free. Jul 7 06:31:52.246077 systemd-modules-load[234]: Inserted module 'overlay' Jul 7 06:31:52.257547 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 06:31:52.262189 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:31:52.267179 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 06:31:52.273845 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 7 06:31:52.283262 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 06:31:52.310514 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 7 06:31:52.312136 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 06:31:52.315997 kernel: Bridge firewalling registered Jul 7 06:31:52.314524 systemd-modules-load[234]: Inserted module 'br_netfilter' Jul 7 06:31:52.315202 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 06:31:52.316925 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 06:31:52.319348 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 06:31:52.327397 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 06:31:52.328352 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 7 06:31:52.340747 systemd-tmpfiles[254]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 7 06:31:52.344679 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 06:31:52.369616 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 06:31:52.378055 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 06:31:52.389572 dracut-cmdline[269]: 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=2e0b2c30526b1d273b6d599d4c30389a93a14ce36aaa5af83a05b11c5ea5ae50 Jul 7 06:31:52.436927 systemd-resolved[277]: Positive Trust Anchors: Jul 7 06:31:52.436942 systemd-resolved[277]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 06:31:52.436973 systemd-resolved[277]: 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 Jul 7 06:31:52.439674 systemd-resolved[277]: Defaulting to hostname 'linux'. Jul 7 06:31:52.440711 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 06:31:52.445947 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 06:31:52.545526 kernel: SCSI subsystem initialized Jul 7 06:31:52.556519 kernel: Loading iSCSI transport class v2.0-870. Jul 7 06:31:52.568518 kernel: iscsi: registered transport (tcp) Jul 7 06:31:52.593527 kernel: iscsi: registered transport (qla4xxx) Jul 7 06:31:52.593558 kernel: QLogic iSCSI HBA Driver Jul 7 06:31:52.612032 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 06:31:52.645114 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 06:31:52.646276 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 06:31:52.697070 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 7 06:31:52.698383 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 7 06:31:52.767510 kernel: raid6: avx2x4 gen() 30941 MB/s Jul 7 06:31:52.786506 kernel: raid6: avx2x2 gen() 31054 MB/s Jul 7 06:31:52.807503 kernel: raid6: avx2x1 gen() 26828 MB/s Jul 7 06:31:52.807531 kernel: raid6: using algorithm avx2x2 gen() 31054 MB/s Jul 7 06:31:52.830460 kernel: raid6: .... xor() 18408 MB/s, rmw enabled Jul 7 06:31:52.830507 kernel: raid6: using avx2x2 recovery algorithm Jul 7 06:31:52.853546 kernel: xor: automatically using best checksumming function avx Jul 7 06:31:53.022525 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 7 06:31:53.029182 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 7 06:31:53.030802 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 06:31:53.072959 systemd-udevd[485]: Using default interface naming scheme 'v255'. Jul 7 06:31:53.081515 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 06:31:53.082471 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 7 06:31:53.120604 dracut-pre-trigger[488]: rd.md=0: removing MD RAID activation Jul 7 06:31:53.147949 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 06:31:53.149321 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 06:31:53.232696 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 06:31:53.249902 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 7 06:31:53.263534 kernel: cryptd: max_cpu_qlen set to 1000 Jul 7 06:31:53.272512 kernel: AES CTR mode by8 optimization enabled Jul 7 06:31:53.288575 kernel: hv_vmbus: Vmbus version:4.0 Jul 7 06:31:53.297525 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 7 06:31:53.297572 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 7 06:31:53.297587 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 Jul 7 06:31:53.306003 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 7 06:31:53.309276 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 06:31:53.318788 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 7 06:31:53.309861 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:31:53.331513 kernel: PTP clock support registered Jul 7 06:31:53.331679 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:31:53.353918 kernel: hv_vmbus: registering driver hv_pci Jul 7 06:31:53.353938 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 7 06:31:53.354121 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 7 06:31:53.354259 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 7 06:31:53.354397 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 7 06:31:53.354540 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 7 06:31:53.360508 kernel: hv_vmbus: registering driver hv_netvsc Jul 7 06:31:53.360538 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 7 06:31:53.369975 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 7 06:31:53.374081 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 7 06:31:53.380231 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 7 06:31:53.380821 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:31:53.490768 kernel: hv_vmbus: registering driver hv_storvsc Jul 7 06:31:53.490795 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 7 06:31:53.490996 kernel: hv_vmbus: registering driver hid_hyperv Jul 7 06:31:53.491012 kernel: hv_utils: Registering HyperV Utility Driver Jul 7 06:31:53.491024 kernel: hv_vmbus: registering driver hv_utils Jul 7 06:31:53.491036 kernel: hv_utils: Heartbeat IC version 3.0 Jul 7 06:31:53.491052 kernel: hv_utils: Shutdown IC version 3.2 Jul 7 06:31:53.491064 kernel: hv_utils: TimeSync IC version 4.0 Jul 7 06:31:53.491075 kernel: scsi host0: storvsc_host_t Jul 7 06:31:53.491242 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 7 06:31:53.491260 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 06:31:53.491287 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 7 06:31:53.491418 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 7 06:31:53.491440 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 7 06:31:53.491565 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 7 06:31:53.491700 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 7 06:31:53.491831 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 7 06:31:53.491959 kernel: scsi host1: storvsc_host_t Jul 7 06:31:53.491982 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 7 06:31:53.396901 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 06:31:53.464229 systemd-resolved[277]: Clock change detected. Flushing caches. Jul 7 06:31:53.530449 kernel: sd 0:0:0:1: [sda] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 7 06:31:53.530672 kernel: sd 0:0:0:0: [sdb] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 7 06:31:53.530824 kernel: sd 0:0:0:1: [sda] 4096-byte physical blocks Jul 7 06:31:53.530957 kernel: sd 0:0:0:0: [sdb] 4096-byte physical blocks Jul 7 06:31:53.535371 kernel: sd 0:0:0:1: [sda] Write Protect is off Jul 7 06:31:53.535561 kernel: sd 0:0:0:0: [sdb] Write Protect is off Jul 7 06:31:53.537621 kernel: sd 0:0:0:1: [sda] Mode Sense: 0f 00 10 00 Jul 7 06:31:53.537790 kernel: sd 0:0:0:1: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 06:31:53.539912 kernel: sd 0:0:0:0: [sdb] Mode Sense: 0f 00 10 00 Jul 7 06:31:53.540117 kernel: sd 0:0:0:0: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 7 06:31:53.542181 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#390 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:53.562620 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#449 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:53.564183 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:31:53.576105 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#259 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:53.576276 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#373 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:53.579181 kernel: sda: sda1 Jul 7 06:31:53.589180 kernel: sd 0:0:0:1: [sda] Attached SCSI disk Jul 7 06:31:53.596341 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 06:31:53.596379 kernel: sd 0:0:0:0: [sdb] Attached SCSI disk Jul 7 06:31:53.597191 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 7 06:31:53.601952 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 7 06:31:53.604233 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 7 06:31:54.154956 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 7 06:31:54.181059 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 06:31:54.215343 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 7 06:31:54.231485 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 7 06:31:54.231654 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 7 06:31:54.242644 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 7 06:31:54.287189 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#598 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:54.305183 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 06:31:54.313921 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#615 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:54.340187 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 06:31:55.348073 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#88 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:31:55.362871 disk-uuid[719]: The operation has completed successfully. Jul 7 06:31:55.370300 kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb6 sdb7 sdb9 Jul 7 06:31:57.338940 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 7 06:31:57.339054 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 7 06:31:57.355225 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 7 06:31:57.364008 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 7 06:31:57.364917 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 06:31:57.373354 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 06:31:57.388030 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 06:31:57.397706 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 7 06:31:57.404507 sh[883]: Success Jul 7 06:31:57.439150 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 7 06:31:57.461619 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 7 06:31:57.461676 kernel: device-mapper: uevent: version 1.0.3 Jul 7 06:31:57.466184 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 7 06:31:57.480188 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 7 06:31:57.702890 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 7 06:31:57.712142 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 7 06:31:57.735049 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 7 06:31:57.754816 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 7 06:31:57.754846 kernel: BTRFS: device fsid 9d124217-7448-4fc6-a329-8a233bb5a0ac devid 1 transid 38 /dev/mapper/usr (254:0) scanned by mount (909) Jul 7 06:31:57.763985 kernel: BTRFS info (device dm-0): first mount of filesystem 9d124217-7448-4fc6-a329-8a233bb5a0ac Jul 7 06:31:57.764038 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:31:57.767073 kernel: BTRFS info (device dm-0): using free-space-tree Jul 7 06:31:58.200090 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 7 06:31:58.201510 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 7 06:31:58.206872 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 7 06:31:58.207846 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 7 06:31:58.248472 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 7 06:31:58.275186 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (940) Jul 7 06:31:58.283701 kernel: BTRFS info (device sdb6): first mount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:31:58.283737 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:31:58.286721 kernel: BTRFS info (device sdb6): using free-space-tree Jul 7 06:31:58.309191 kernel: BTRFS info (device sdb6): last unmount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:31:58.310059 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 7 06:31:58.311146 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 7 06:31:58.357841 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 06:31:58.370780 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 06:31:58.423860 systemd-networkd[1078]: lo: Link UP Jul 7 06:31:58.423870 systemd-networkd[1078]: lo: Gained carrier Jul 7 06:31:58.426610 systemd-networkd[1078]: Enumeration completed Jul 7 06:31:58.427475 systemd-networkd[1078]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:31:58.427479 systemd-networkd[1078]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 06:31:58.429469 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 06:31:58.429565 systemd-networkd[1078]: eth0: Link UP Jul 7 06:31:58.430225 systemd-networkd[1078]: eth0: Gained carrier Jul 7 06:31:58.430236 systemd-networkd[1078]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:31:58.438264 systemd[1]: Reached target network.target - Network. Jul 7 06:31:58.490214 systemd-networkd[1078]: eth0: DHCPv4 address 10.200.4.4/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 06:31:59.715889 ignition[1021]: Ignition 2.21.0 Jul 7 06:31:59.715907 ignition[1021]: Stage: fetch-offline Jul 7 06:31:59.715995 ignition[1021]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:31:59.716005 ignition[1021]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:31:59.716119 ignition[1021]: parsed url from cmdline: "" Jul 7 06:31:59.716123 ignition[1021]: no config URL provided Jul 7 06:31:59.716129 ignition[1021]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 06:31:59.716138 ignition[1021]: no config at "/usr/lib/ignition/user.ign" Jul 7 06:31:59.716146 ignition[1021]: failed to fetch config: resource requires networking Jul 7 06:31:59.716345 ignition[1021]: Ignition finished successfully Jul 7 06:31:59.746504 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 06:31:59.752265 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 7 06:31:59.800087 ignition[1091]: Ignition 2.21.0 Jul 7 06:31:59.800101 ignition[1091]: Stage: fetch Jul 7 06:31:59.800587 ignition[1091]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:31:59.800605 ignition[1091]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:31:59.800835 ignition[1091]: parsed url from cmdline: "" Jul 7 06:31:59.800841 ignition[1091]: no config URL provided Jul 7 06:31:59.800849 ignition[1091]: reading system config file "/usr/lib/ignition/user.ign" Jul 7 06:31:59.800861 ignition[1091]: no config at "/usr/lib/ignition/user.ign" Jul 7 06:31:59.800912 ignition[1091]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 7 06:31:59.903935 ignition[1091]: GET result: OK Jul 7 06:31:59.904023 ignition[1091]: config has been read from IMDS userdata Jul 7 06:31:59.904037 ignition[1091]: parsing config with SHA512: 6de4ad9f11cdaede3745fbb2454fe530125017004c584f6c7e22347650cd56c1a4df2e050a5ecf42c9bf8a5b371906617b6d2ad888f309f6c64f816ee32d1a3c Jul 7 06:31:59.907827 unknown[1091]: fetched base config from "system" Jul 7 06:31:59.908002 ignition[1091]: fetch: fetch complete Jul 7 06:31:59.907834 unknown[1091]: fetched base config from "system" Jul 7 06:31:59.908007 ignition[1091]: fetch: fetch passed Jul 7 06:31:59.907839 unknown[1091]: fetched user config from "azure" Jul 7 06:31:59.908063 ignition[1091]: Ignition finished successfully Jul 7 06:31:59.917767 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 7 06:31:59.919555 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 7 06:31:59.962350 ignition[1100]: Ignition 2.21.0 Jul 7 06:31:59.962377 ignition[1100]: Stage: kargs Jul 7 06:31:59.962888 ignition[1100]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:31:59.962902 ignition[1100]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:31:59.964186 ignition[1100]: kargs: kargs passed Jul 7 06:31:59.964254 ignition[1100]: Ignition finished successfully Jul 7 06:31:59.980934 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 7 06:31:59.982115 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 7 06:32:00.022149 ignition[1109]: Ignition 2.21.0 Jul 7 06:32:00.022197 ignition[1109]: Stage: disks Jul 7 06:32:00.022465 ignition[1109]: no configs at "/usr/lib/ignition/base.d" Jul 7 06:32:00.022477 ignition[1109]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:32:00.028419 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 7 06:32:00.025199 ignition[1109]: disks: disks passed Jul 7 06:32:00.030445 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 7 06:32:00.025262 ignition[1109]: Ignition finished successfully Jul 7 06:32:00.032384 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 7 06:32:00.050475 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 06:32:00.056797 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 06:32:00.064885 systemd[1]: Reached target basic.target - Basic System. Jul 7 06:32:00.072232 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 7 06:32:00.191478 systemd-fsck[1120]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 7 06:32:00.198890 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 7 06:32:00.209616 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 7 06:32:00.449180 kernel: EXT4-fs (sdb9): mounted filesystem df0fa228-af1b-4496-9a54-2d4ccccd27d9 r/w with ordered data mode. Quota mode: none. Jul 7 06:32:00.449712 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 7 06:32:00.450341 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 7 06:32:00.469102 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 06:32:00.470007 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 7 06:32:00.477261 systemd-networkd[1078]: eth0: Gained IPv6LL Jul 7 06:32:00.498681 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 7 06:32:00.512114 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1134) Jul 7 06:32:00.498817 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 7 06:32:00.535528 kernel: BTRFS info (device sdb6): first mount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:32:00.535551 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:32:00.535564 kernel: BTRFS info (device sdb6): using free-space-tree Jul 7 06:32:00.498845 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 06:32:00.513372 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 7 06:32:00.540537 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 7 06:32:00.548393 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 06:32:01.496502 coreos-metadata[1136]: Jul 07 06:32:01.496 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 06:32:01.502705 coreos-metadata[1136]: Jul 07 06:32:01.502 INFO Fetch successful Jul 7 06:32:01.502705 coreos-metadata[1136]: Jul 07 06:32:01.502 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 7 06:32:01.514863 coreos-metadata[1136]: Jul 07 06:32:01.506 INFO Fetch successful Jul 7 06:32:01.530122 coreos-metadata[1136]: Jul 07 06:32:01.530 INFO wrote hostname ci-4372.0.1-a-f66a854089 to /sysroot/etc/hostname Jul 7 06:32:01.537802 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 06:32:01.829596 initrd-setup-root[1167]: cut: /sysroot/etc/passwd: No such file or directory Jul 7 06:32:01.914301 initrd-setup-root[1174]: cut: /sysroot/etc/group: No such file or directory Jul 7 06:32:01.921806 initrd-setup-root[1181]: cut: /sysroot/etc/shadow: No such file or directory Jul 7 06:32:01.928808 initrd-setup-root[1188]: cut: /sysroot/etc/gshadow: No such file or directory Jul 7 06:32:03.078356 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 7 06:32:03.085154 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 7 06:32:03.094402 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 7 06:32:03.111447 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 7 06:32:03.121374 kernel: BTRFS info (device sdb6): last unmount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:32:03.137288 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 7 06:32:03.154112 ignition[1257]: INFO : Ignition 2.21.0 Jul 7 06:32:03.154112 ignition[1257]: INFO : Stage: mount Jul 7 06:32:03.159875 ignition[1257]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 06:32:03.159875 ignition[1257]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:32:03.159875 ignition[1257]: INFO : mount: mount passed Jul 7 06:32:03.159875 ignition[1257]: INFO : Ignition finished successfully Jul 7 06:32:03.159126 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 7 06:32:03.167864 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 7 06:32:03.204786 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 7 06:32:03.225208 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sdb6 (8:22) scanned by mount (1272) Jul 7 06:32:03.225245 kernel: BTRFS info (device sdb6): first mount of filesystem 847f3129-822b-493d-8278-974df083638f Jul 7 06:32:03.233200 kernel: BTRFS info (device sdb6): using crc32c (crc32c-intel) checksum algorithm Jul 7 06:32:03.236296 kernel: BTRFS info (device sdb6): using free-space-tree Jul 7 06:32:03.240234 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 7 06:32:03.273108 ignition[1289]: INFO : Ignition 2.21.0 Jul 7 06:32:03.273108 ignition[1289]: INFO : Stage: files Jul 7 06:32:03.278908 ignition[1289]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 06:32:03.278908 ignition[1289]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:32:03.278908 ignition[1289]: DEBUG : files: compiled without relabeling support, skipping Jul 7 06:32:03.291674 ignition[1289]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 7 06:32:03.291674 ignition[1289]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 7 06:32:03.334731 ignition[1289]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 7 06:32:03.340007 ignition[1289]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 7 06:32:03.340007 ignition[1289]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 7 06:32:03.335592 unknown[1289]: wrote ssh authorized keys file for user: core Jul 7 06:32:03.375008 ignition[1289]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 06:32:03.381796 ignition[1289]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 7 06:32:03.381796 ignition[1289]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 7 06:32:03.381796 ignition[1289]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 7 06:32:03.381796 ignition[1289]: INFO : files: files passed Jul 7 06:32:03.381796 ignition[1289]: INFO : Ignition finished successfully Jul 7 06:32:03.398017 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 7 06:32:03.402923 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 7 06:32:03.425407 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 7 06:32:03.429306 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 7 06:32:03.429602 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 7 06:32:03.457050 initrd-setup-root-after-ignition[1322]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 06:32:03.457050 initrd-setup-root-after-ignition[1322]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 7 06:32:03.469190 initrd-setup-root-after-ignition[1326]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 7 06:32:03.464219 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 06:32:03.474291 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 7 06:32:03.480720 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 7 06:32:03.523150 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 7 06:32:03.523272 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 7 06:32:03.528462 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 7 06:32:03.536440 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 7 06:32:03.544203 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 7 06:32:03.546660 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 7 06:32:03.595445 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 06:32:03.599420 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 7 06:32:03.617138 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 7 06:32:03.617490 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 06:32:03.626061 systemd[1]: Stopped target timers.target - Timer Units. Jul 7 06:32:03.634681 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 7 06:32:03.634805 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 7 06:32:03.649336 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 7 06:32:03.653852 systemd[1]: Stopped target basic.target - Basic System. Jul 7 06:32:03.661509 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 7 06:32:03.668095 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 7 06:32:03.676292 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 7 06:32:03.689329 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 7 06:32:03.693509 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 7 06:32:03.701853 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 7 06:32:03.709445 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 7 06:32:03.726330 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 7 06:32:03.726588 systemd[1]: Stopped target swap.target - Swaps. Jul 7 06:32:03.734003 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 7 06:32:03.734140 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 7 06:32:03.747383 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 7 06:32:03.751365 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 06:32:03.759380 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 7 06:32:03.759611 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 06:32:03.768121 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 7 06:32:03.768495 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 7 06:32:03.785494 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 7 06:32:03.785648 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 7 06:32:03.789291 systemd[1]: ignition-files.service: Deactivated successfully. Jul 7 06:32:03.789419 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 7 06:32:03.797727 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 7 06:32:03.797845 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 7 06:32:03.806698 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 7 06:32:03.834459 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 7 06:32:03.841265 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 7 06:32:03.841416 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 06:32:03.857032 ignition[1346]: INFO : Ignition 2.21.0 Jul 7 06:32:03.857032 ignition[1346]: INFO : Stage: umount Jul 7 06:32:03.857032 ignition[1346]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 7 06:32:03.857032 ignition[1346]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 7 06:32:03.857032 ignition[1346]: INFO : umount: umount passed Jul 7 06:32:03.857032 ignition[1346]: INFO : Ignition finished successfully Jul 7 06:32:03.853016 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 7 06:32:03.853262 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 7 06:32:03.874684 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 7 06:32:03.877594 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 7 06:32:03.877866 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 7 06:32:03.890695 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 7 06:32:03.890870 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 7 06:32:03.895333 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 7 06:32:03.895404 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 7 06:32:03.901492 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 7 06:32:03.901540 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 7 06:32:03.908701 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 7 06:32:03.908745 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 7 06:32:03.916560 systemd[1]: Stopped target network.target - Network. Jul 7 06:32:03.927118 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 7 06:32:03.927182 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 7 06:32:03.931768 systemd[1]: Stopped target paths.target - Path Units. Jul 7 06:32:03.939636 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 7 06:32:03.943704 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 06:32:03.946547 systemd[1]: Stopped target slices.target - Slice Units. Jul 7 06:32:03.954586 systemd[1]: Stopped target sockets.target - Socket Units. Jul 7 06:32:03.961520 systemd[1]: iscsid.socket: Deactivated successfully. Jul 7 06:32:03.961564 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 7 06:32:03.968502 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 7 06:32:03.968573 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 7 06:32:03.975730 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 7 06:32:03.975788 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 7 06:32:03.982637 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 7 06:32:03.982687 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 7 06:32:03.990390 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 7 06:32:04.002052 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 7 06:32:04.010958 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 7 06:32:04.011069 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 7 06:32:04.029280 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 7 06:32:04.029382 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 7 06:32:04.040815 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 7 06:32:04.040963 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 7 06:32:04.045424 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 7 06:32:04.051325 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 7 06:32:04.051370 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 7 06:32:04.060624 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 7 06:32:04.060689 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 7 06:32:04.069582 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 7 06:32:04.076715 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 7 06:32:04.076771 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 7 06:32:04.084251 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 7 06:32:04.084296 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 7 06:32:04.101026 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 7 06:32:04.101076 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 7 06:32:04.108795 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 7 06:32:04.108845 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 06:32:04.121268 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 06:32:04.127029 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 7 06:32:04.127088 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 7 06:32:04.127127 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 7 06:32:04.127182 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 7 06:32:04.150774 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 7 06:32:04.150920 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 06:32:04.153652 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 7 06:32:04.153737 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 7 06:32:04.166810 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 7 06:32:04.166869 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 7 06:32:04.173903 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 7 06:32:04.173938 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 06:32:04.182900 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 7 06:32:04.182950 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 7 06:32:04.198186 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 7 06:32:04.198233 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 7 06:32:04.209891 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 7 06:32:04.209940 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 7 06:32:04.224178 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 7 06:32:04.232822 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 7 06:32:04.232877 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 06:32:04.247219 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 7 06:32:04.247269 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 06:32:04.261925 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 7 06:32:04.261970 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 06:32:04.275154 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 7 06:32:04.275211 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 06:32:04.284367 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 06:32:04.284413 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:32:04.296457 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 7 06:32:04.296512 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jul 7 06:32:04.296550 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 7 06:32:04.296588 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 7 06:32:04.296962 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 7 06:32:04.297054 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 7 06:32:04.300973 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 7 06:32:04.309373 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 7 06:32:04.444747 systemd-journald[233]: Received SIGTERM from PID 1 (systemd). Jul 7 06:32:04.341984 systemd[1]: Switching root. Jul 7 06:32:04.447484 systemd-journald[233]: Journal stopped Jul 7 06:32:10.380880 kernel: SELinux: policy capability network_peer_controls=1 Jul 7 06:32:10.380911 kernel: SELinux: policy capability open_perms=1 Jul 7 06:32:10.380923 kernel: SELinux: policy capability extended_socket_class=1 Jul 7 06:32:10.380932 kernel: SELinux: policy capability always_check_network=0 Jul 7 06:32:10.380941 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 7 06:32:10.380950 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 7 06:32:10.380960 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 7 06:32:10.380972 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 7 06:32:10.380981 kernel: SELinux: policy capability userspace_initial_context=0 Jul 7 06:32:10.380991 kernel: audit: type=1403 audit(1751869925.403:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 7 06:32:10.381002 systemd[1]: Successfully loaded SELinux policy in 200.406ms. Jul 7 06:32:10.381014 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 16.128ms. Jul 7 06:32:10.381025 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) Jul 7 06:32:10.381035 systemd[1]: Detected virtualization microsoft. Jul 7 06:32:10.381049 systemd[1]: Detected architecture x86-64. Jul 7 06:32:10.381059 systemd[1]: Detected first boot. Jul 7 06:32:10.381070 systemd[1]: Hostname set to . Jul 7 06:32:10.381081 systemd[1]: Initializing machine ID from random generator. Jul 7 06:32:10.381091 zram_generator::config[1391]: No configuration found. Jul 7 06:32:10.381105 kernel: Guest personality initialized and is inactive Jul 7 06:32:10.381115 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 7 06:32:10.381128 kernel: Initialized host personality Jul 7 06:32:10.381137 kernel: NET: Registered PF_VSOCK protocol family Jul 7 06:32:10.381148 systemd[1]: Populated /etc with preset unit settings. Jul 7 06:32:10.381159 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 7 06:32:10.381228 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 7 06:32:10.381247 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 7 06:32:10.381258 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 7 06:32:10.381269 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 7 06:32:10.381280 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 7 06:32:10.381291 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 7 06:32:10.381302 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 7 06:32:10.381313 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 7 06:32:10.381323 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 7 06:32:10.381337 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 7 06:32:10.381348 systemd[1]: Created slice user.slice - User and Session Slice. Jul 7 06:32:10.381359 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 7 06:32:10.381370 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 7 06:32:10.381381 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 7 06:32:10.381392 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 7 06:32:10.381407 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 7 06:32:10.381418 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 7 06:32:10.381436 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 7 06:32:10.381447 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 7 06:32:10.381458 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 7 06:32:10.381469 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 7 06:32:10.381480 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 7 06:32:10.381492 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 7 06:32:10.381503 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 7 06:32:10.381516 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 7 06:32:10.381528 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 7 06:32:10.381539 systemd[1]: Reached target slices.target - Slice Units. Jul 7 06:32:10.381550 systemd[1]: Reached target swap.target - Swaps. Jul 7 06:32:10.381560 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 7 06:32:10.381571 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 7 06:32:10.381586 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 7 06:32:10.381597 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 7 06:32:10.381608 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 7 06:32:10.381619 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 7 06:32:10.381630 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 7 06:32:10.381641 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 7 06:32:10.381653 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 7 06:32:10.381666 systemd[1]: Mounting media.mount - External Media Directory... Jul 7 06:32:10.381681 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:32:10.381693 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 7 06:32:10.381704 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 7 06:32:10.381715 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 7 06:32:10.381726 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). Jul 7 06:32:10.381738 systemd[1]: Reached target machines.target - Containers. Jul 7 06:32:10.381749 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 7 06:32:10.381760 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 06:32:10.381774 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 7 06:32:10.381786 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 7 06:32:10.381797 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 06:32:10.381808 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 06:32:10.381819 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 06:32:10.381830 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 7 06:32:10.381841 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 06:32:10.381852 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 7 06:32:10.381866 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 7 06:32:10.381877 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 7 06:32:10.381889 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 7 06:32:10.381900 systemd[1]: Stopped systemd-fsck-usr.service. Jul 7 06:32:10.381911 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). Jul 7 06:32:10.381926 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 7 06:32:10.381937 kernel: loop: module loaded Jul 7 06:32:10.381948 kernel: fuse: init (API version 7.41) Jul 7 06:32:10.381988 systemd-journald[1498]: Collecting audit messages is disabled. Jul 7 06:32:10.382017 systemd-journald[1498]: Journal started Jul 7 06:32:10.382043 systemd-journald[1498]: Runtime Journal (/run/log/journal/708a2fa760294637b2ef7d629d2dc854) is 8M, max 2.2G, 2.1G free. Jul 7 06:32:09.624261 systemd[1]: Queued start job for default target multi-user.target. Jul 7 06:32:09.650251 systemd[1]: Unnecessary job was removed for dev-sdb6.device - /dev/sdb6. Jul 7 06:32:09.650753 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 7 06:32:10.394191 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 7 06:32:10.419288 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 7 06:32:10.430197 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 7 06:32:10.445252 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 7 06:32:10.457047 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 7 06:32:10.457100 systemd[1]: verity-setup.service: Deactivated successfully. Jul 7 06:32:10.463370 systemd[1]: Stopped verity-setup.service. Jul 7 06:32:10.475187 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:32:10.479187 kernel: ACPI: bus type drm_connector registered Jul 7 06:32:10.479238 systemd[1]: Started systemd-journald.service - Journal Service. Jul 7 06:32:10.488194 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 7 06:32:10.492412 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 7 06:32:10.496937 systemd[1]: Mounted media.mount - External Media Directory. Jul 7 06:32:10.501090 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 7 06:32:10.505714 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 7 06:32:10.510310 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 7 06:32:10.514443 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 7 06:32:10.519238 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 7 06:32:10.524455 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 7 06:32:10.524641 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 7 06:32:10.529578 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 06:32:10.529754 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 06:32:10.535038 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 06:32:10.535234 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 06:32:10.539654 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 06:32:10.539834 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 06:32:10.544883 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 7 06:32:10.545060 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 7 06:32:10.549583 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 06:32:10.549763 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 06:32:10.554372 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 7 06:32:10.558976 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 7 06:32:10.564314 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 7 06:32:10.569547 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 7 06:32:10.584084 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 7 06:32:10.589760 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 7 06:32:10.620395 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 7 06:32:10.625634 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 7 06:32:10.625676 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 7 06:32:10.630783 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 7 06:32:10.636858 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 7 06:32:10.652604 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 06:32:10.665387 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 7 06:32:10.679865 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 7 06:32:10.684587 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 06:32:10.685543 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 7 06:32:10.689770 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 06:32:10.690741 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 7 06:32:10.695744 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 7 06:32:10.701392 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 7 06:32:10.707934 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 7 06:32:10.712882 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 7 06:32:10.717636 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 7 06:32:10.730480 systemd-journald[1498]: Time spent on flushing to /var/log/journal/708a2fa760294637b2ef7d629d2dc854 is 13.858ms for 951 entries. Jul 7 06:32:10.730480 systemd-journald[1498]: System Journal (/var/log/journal/708a2fa760294637b2ef7d629d2dc854) is 8M, max 2.6G, 2.6G free. Jul 7 06:32:10.782064 systemd-journald[1498]: Received client request to flush runtime journal. Jul 7 06:32:10.782121 kernel: loop0: detected capacity change from 0 to 28496 Jul 7 06:32:10.742972 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 7 06:32:10.748142 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 7 06:32:10.754015 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 7 06:32:10.783541 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 7 06:32:10.811431 systemd-tmpfiles[1535]: ACLs are not supported, ignoring. Jul 7 06:32:10.811451 systemd-tmpfiles[1535]: ACLs are not supported, ignoring. Jul 7 06:32:10.816594 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 7 06:32:10.822755 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 7 06:32:10.823409 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 7 06:32:10.830385 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 7 06:32:10.860503 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 7 06:32:11.443611 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 7 06:32:11.451199 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 7 06:32:11.452604 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 7 06:32:11.480323 systemd-tmpfiles[1553]: ACLs are not supported, ignoring. Jul 7 06:32:11.480343 systemd-tmpfiles[1553]: ACLs are not supported, ignoring. Jul 7 06:32:11.484764 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 7 06:32:11.605201 kernel: loop1: detected capacity change from 0 to 113872 Jul 7 06:32:12.310201 kernel: loop2: detected capacity change from 0 to 146240 Jul 7 06:32:12.407663 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 7 06:32:12.414114 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 7 06:32:12.458533 systemd-udevd[1558]: Using default interface naming scheme 'v255'. Jul 7 06:32:12.805910 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 7 06:32:12.816458 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 7 06:32:12.838725 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 7 06:32:12.916914 kernel: hv_vmbus: registering driver hyperv_fb Jul 7 06:32:12.925890 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 7 06:32:12.925969 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 7 06:32:12.929680 kernel: Console: switching to colour dummy device 80x25 Jul 7 06:32:12.933835 kernel: Console: switching to colour frame buffer device 128x48 Jul 7 06:32:12.933912 kernel: mousedev: PS/2 mouse device common for all mice Jul 7 06:32:12.934194 kernel: hv_vmbus: registering driver hv_balloon Jul 7 06:32:12.944180 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 7 06:32:12.974248 kernel: EDAC MC: Ver: 3.0.0 Jul 7 06:32:13.017444 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:32:13.032543 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 7 06:32:13.036562 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 7 06:32:13.036753 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:32:13.043866 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 7 06:32:13.113813 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 7 06:32:13.114083 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 7 06:32:13.116704 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 7 06:32:13.173191 kernel: loop3: detected capacity change from 0 to 28496 Jul 7 06:32:13.182184 kernel: loop4: detected capacity change from 0 to 113872 Jul 7 06:32:13.193193 kernel: loop5: detected capacity change from 0 to 146240 Jul 7 06:32:13.205964 (sd-merge)[1706]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 7 06:32:13.206478 (sd-merge)[1706]: Merged extensions into '/usr'. Jul 7 06:32:13.210281 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 7 06:32:13.215062 systemd[1]: Starting ensure-sysext.service... Jul 7 06:32:13.217890 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 7 06:32:13.233690 systemd[1]: Reload requested from client PID 1709 ('systemctl') (unit ensure-sysext.service)... Jul 7 06:32:13.233707 systemd[1]: Reloading... Jul 7 06:32:13.258571 systemd-tmpfiles[1710]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 7 06:32:13.258612 systemd-tmpfiles[1710]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 7 06:32:13.258887 systemd-tmpfiles[1710]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 7 06:32:13.259549 systemd-tmpfiles[1710]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 7 06:32:13.260586 systemd-tmpfiles[1710]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 7 06:32:13.260989 systemd-tmpfiles[1710]: ACLs are not supported, ignoring. Jul 7 06:32:13.261133 systemd-tmpfiles[1710]: ACLs are not supported, ignoring. Jul 7 06:32:13.274281 zram_generator::config[1741]: No configuration found. Jul 7 06:32:13.274927 systemd-tmpfiles[1710]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 06:32:13.274943 systemd-tmpfiles[1710]: Skipping /boot Jul 7 06:32:13.287117 systemd-tmpfiles[1710]: Detected autofs mount point /boot during canonicalization of boot. Jul 7 06:32:13.287133 systemd-tmpfiles[1710]: Skipping /boot Jul 7 06:32:13.289821 systemd-networkd[1586]: lo: Link UP Jul 7 06:32:13.289832 systemd-networkd[1586]: lo: Gained carrier Jul 7 06:32:13.291220 systemd-networkd[1586]: Enumeration completed Jul 7 06:32:13.291783 systemd-networkd[1586]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:32:13.291793 systemd-networkd[1586]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 06:32:13.292448 systemd-networkd[1586]: eth0: Link UP Jul 7 06:32:13.292458 systemd-networkd[1586]: eth0: Gained carrier Jul 7 06:32:13.292472 systemd-networkd[1586]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:32:13.337231 systemd-networkd[1586]: eth0: DHCPv4 address 10.200.4.4/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 06:32:13.397247 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 7 06:32:13.549821 systemd[1]: Reloading finished in 315 ms. Jul 7 06:32:13.583804 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 7 06:32:13.588523 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 7 06:32:13.593438 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 7 06:32:13.627200 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 7 06:32:13.645666 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:32:13.647044 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 7 06:32:13.665995 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 7 06:32:13.670919 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 06:32:13.672208 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 06:32:13.677488 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 06:32:13.683066 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 06:32:13.687302 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 06:32:13.687441 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). Jul 7 06:32:13.688648 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 7 06:32:13.694540 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 7 06:32:13.700407 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 7 06:32:13.706955 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 7 06:32:13.712273 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 7 06:32:13.716961 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:32:13.719481 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 06:32:13.719675 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 06:32:13.724694 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 06:32:13.724880 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 06:32:13.729972 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 06:32:13.730150 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 06:32:13.742290 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 7 06:32:13.753536 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:32:13.753863 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 7 06:32:13.755218 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 7 06:32:13.769533 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 7 06:32:13.774410 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 7 06:32:13.779908 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 7 06:32:13.784062 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 7 06:32:13.784244 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). Jul 7 06:32:13.784427 systemd[1]: Reached target time-set.target - System Time Set. Jul 7 06:32:13.788434 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 7 06:32:13.790350 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 7 06:32:13.795726 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 7 06:32:13.795917 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 7 06:32:13.800933 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 7 06:32:13.801111 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 7 06:32:13.805648 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 7 06:32:13.805828 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 7 06:32:13.811025 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 7 06:32:13.811245 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 7 06:32:13.816089 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 7 06:32:13.824409 systemd[1]: Finished ensure-sysext.service. Jul 7 06:32:13.831274 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 7 06:32:13.831343 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 7 06:32:13.856714 systemd-resolved[1815]: Positive Trust Anchors: Jul 7 06:32:13.856733 systemd-resolved[1815]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 7 06:32:13.856765 systemd-resolved[1815]: 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 Jul 7 06:32:13.857195 augenrules[1852]: No rules Jul 7 06:32:13.859229 systemd[1]: audit-rules.service: Deactivated successfully. Jul 7 06:32:13.859483 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 7 06:32:13.871366 systemd-resolved[1815]: Using system hostname 'ci-4372.0.1-a-f66a854089'. Jul 7 06:32:13.873450 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 7 06:32:13.877926 systemd[1]: Reached target network.target - Network. Jul 7 06:32:13.881494 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 7 06:32:14.245368 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 7 06:32:14.250822 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). Jul 7 06:32:14.364321 systemd-networkd[1586]: eth0: Gained IPv6LL Jul 7 06:32:14.367181 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 7 06:32:14.372670 systemd[1]: Reached target network-online.target - Network is Online. Jul 7 06:32:17.646651 ldconfig[1528]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 06:32:17.657525 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 7 06:32:17.663900 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 7 06:32:17.695056 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 7 06:32:17.699860 systemd[1]: Reached target sysinit.target - System Initialization. Jul 7 06:32:17.704368 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 7 06:32:17.709304 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 7 06:32:17.714283 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 7 06:32:17.718996 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 7 06:32:17.723313 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 7 06:32:17.728668 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 7 06:32:17.733638 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 7 06:32:17.733681 systemd[1]: Reached target paths.target - Path Units. Jul 7 06:32:17.737352 systemd[1]: Reached target timers.target - Timer Units. Jul 7 06:32:17.742262 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 7 06:32:17.748778 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 7 06:32:17.755267 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 7 06:32:17.760356 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 7 06:32:17.765348 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 7 06:32:17.771725 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 7 06:32:17.776033 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 7 06:32:17.781326 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 7 06:32:17.786372 systemd[1]: Reached target sockets.target - Socket Units. Jul 7 06:32:17.790265 systemd[1]: Reached target basic.target - Basic System. Jul 7 06:32:17.793976 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 7 06:32:17.794017 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 7 06:32:17.796400 systemd[1]: Starting chronyd.service - NTP client/server... Jul 7 06:32:17.800782 systemd[1]: Starting containerd.service - containerd container runtime... Jul 7 06:32:17.822060 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 7 06:32:17.827421 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 7 06:32:17.832307 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 7 06:32:17.837761 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 7 06:32:17.842621 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 7 06:32:17.846739 jq[1873]: false Jul 7 06:32:17.846913 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). Jul 7 06:32:17.847914 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 7 06:32:17.852000 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). Jul 7 06:32:17.852978 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 7 06:32:17.857091 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 7 06:32:17.857226 oslogin_cache_refresh[1875]: Refreshing passwd entry cache Jul 7 06:32:17.858779 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Refreshing passwd entry cache Jul 7 06:32:17.858524 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 7 06:32:17.859977 KVP[1876]: KVP starting; pid is:1876 Jul 7 06:32:17.862323 KVP[1876]: KVP LIC Version: 3.1 Jul 7 06:32:17.865215 kernel: hv_utils: KVP IC version 4.0 Jul 7 06:32:17.865623 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 7 06:32:17.868323 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Failure getting users, quitting Jul 7 06:32:17.868323 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 06:32:17.868143 oslogin_cache_refresh[1875]: Failure getting users, quitting Jul 7 06:32:17.868213 oslogin_cache_refresh[1875]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 7 06:32:17.868947 oslogin_cache_refresh[1875]: Refreshing group entry cache Jul 7 06:32:17.869100 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Refreshing group entry cache Jul 7 06:32:17.870454 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 7 06:32:17.871509 (chronyd)[1865]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 7 06:32:17.874837 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Failure getting groups, quitting Jul 7 06:32:17.874837 google_oslogin_nss_cache[1875]: oslogin_cache_refresh[1875]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 06:32:17.874829 oslogin_cache_refresh[1875]: Failure getting groups, quitting Jul 7 06:32:17.874841 oslogin_cache_refresh[1875]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 7 06:32:17.876069 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 7 06:32:17.880719 chronyd[1887]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 7 06:32:17.882608 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 7 06:32:17.887355 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 7 06:32:17.887777 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 7 06:32:17.888387 systemd[1]: Starting update-engine.service - Update Engine... Jul 7 06:32:17.893003 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 7 06:32:17.899668 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 7 06:32:17.900899 extend-filesystems[1874]: Found /dev/sdb6 Jul 7 06:32:17.907301 jq[1892]: true Jul 7 06:32:17.904720 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 7 06:32:17.904923 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 7 06:32:17.905213 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 7 06:32:17.905406 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 7 06:32:17.912921 systemd[1]: motdgen.service: Deactivated successfully. Jul 7 06:32:17.913141 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 7 06:32:17.917809 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 7 06:32:17.918021 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 7 06:32:17.928351 (ntainerd)[1902]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 7 06:32:17.930570 jq[1901]: true Jul 7 06:32:17.941729 extend-filesystems[1874]: Found /dev/sdb9 Jul 7 06:32:17.945962 extend-filesystems[1874]: Checking size of /dev/sdb9 Jul 7 06:32:17.956793 chronyd[1887]: Timezone right/UTC failed leap second check, ignoring Jul 7 06:32:17.956987 chronyd[1887]: Loaded seccomp filter (level 2) Jul 7 06:32:17.957876 systemd[1]: Started chronyd.service - NTP client/server. Jul 7 06:32:17.975695 extend-filesystems[1874]: Old size kept for /dev/sdb9 Jul 7 06:32:17.976774 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 7 06:32:17.977042 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 7 06:32:18.000295 update_engine[1891]: I20250707 06:32:18.000202 1891 main.cc:92] Flatcar Update Engine starting Jul 7 06:32:18.001651 systemd-logind[1888]: New seat seat0. Jul 7 06:32:18.002943 systemd-logind[1888]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 7 06:32:18.003205 systemd[1]: Started systemd-logind.service - User Login Management. Jul 7 06:32:18.037117 bash[1931]: Updated "/home/core/.ssh/authorized_keys" Jul 7 06:32:18.039970 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 7 06:32:18.067371 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 7 06:32:18.085699 dbus-daemon[1868]: [system] SELinux support is enabled Jul 7 06:32:18.085900 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 7 06:32:18.090982 update_engine[1891]: I20250707 06:32:18.090817 1891 update_check_scheduler.cc:74] Next update check in 10m57s Jul 7 06:32:18.093139 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). Jul 7 06:32:18.093191 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 7 06:32:18.094001 dbus-daemon[1868]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 7 06:32:18.098394 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). Jul 7 06:32:18.098421 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 7 06:32:18.103709 systemd[1]: Started update-engine.service - Update Engine. Jul 7 06:32:18.104016 setup-nvidia[1881]: Downloading Flatcar Container Linux Developer Container for version: 4372.0.1 Jul 7 06:32:18.109431 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 7 06:32:18.146598 coreos-metadata[1867]: Jul 07 06:32:18.146 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 7 06:32:18.148913 coreos-metadata[1867]: Jul 07 06:32:18.148 INFO Fetch successful Jul 7 06:32:18.149055 coreos-metadata[1867]: Jul 07 06:32:18.149 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 7 06:32:18.152932 coreos-metadata[1867]: Jul 07 06:32:18.152 INFO Fetch successful Jul 7 06:32:18.153189 coreos-metadata[1867]: Jul 07 06:32:18.153 INFO Fetching http://168.63.129.16/machine/07540c77-1d06-4535-85cf-758737b91eb1/5fdfe5de%2D9d2e%2D49d2%2D9909%2Dd300947c3feb.%5Fci%2D4372.0.1%2Da%2Df66a854089?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 7 06:32:18.178247 coreos-metadata[1867]: Jul 07 06:32:18.178 INFO Fetch successful Jul 7 06:32:18.178359 coreos-metadata[1867]: Jul 07 06:32:18.178 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 7 06:32:18.185591 coreos-metadata[1867]: Jul 07 06:32:18.185 INFO Fetch successful Jul 7 06:32:18.226403 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 7 06:32:18.231222 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 7 06:32:18.443867 locksmithd[2008]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 7 06:32:18.452289 sshd_keygen[1900]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 7 06:32:18.477095 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 7 06:32:18.483231 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 7 06:32:18.487823 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 7 06:32:18.516989 systemd[1]: issuegen.service: Deactivated successfully. Jul 7 06:32:18.517212 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 7 06:32:18.523644 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 7 06:32:18.529832 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 7 06:32:18.582111 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 7 06:32:18.588771 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 7 06:32:18.593803 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 7 06:32:18.600794 systemd[1]: Reached target getty.target - Login Prompts. Jul 7 06:32:18.679604 setup-nvidia[2046]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 06:32:18.679918 setup-nvidia[2046]: Dload Upload Total Spent Left Speed Jul 7 06:32:19.145883 containerd[1902]: time="2025-07-07T06:32:19Z" 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 Jul 7 06:32:19.146515 containerd[1902]: time="2025-07-07T06:32:19.146480300Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 Jul 7 06:32:19.154110 containerd[1902]: time="2025-07-07T06:32:19.154074800Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.3µs" Jul 7 06:32:19.154110 containerd[1902]: time="2025-07-07T06:32:19.154103100Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 7 06:32:19.154205 containerd[1902]: time="2025-07-07T06:32:19.154120900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 7 06:32:19.154304 containerd[1902]: time="2025-07-07T06:32:19.154280300Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 7 06:32:19.154346 containerd[1902]: time="2025-07-07T06:32:19.154304100Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 7 06:32:19.154346 containerd[1902]: time="2025-07-07T06:32:19.154329600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154410 containerd[1902]: time="2025-07-07T06:32:19.154388600Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154410 containerd[1902]: time="2025-07-07T06:32:19.154406300Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154617 containerd[1902]: time="2025-07-07T06:32:19.154594300Z" 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 Jul 7 06:32:19.154645 containerd[1902]: time="2025-07-07T06:32:19.154614700Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154673 containerd[1902]: time="2025-07-07T06:32:19.154646400Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154673 containerd[1902]: time="2025-07-07T06:32:19.154658100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154763 containerd[1902]: time="2025-07-07T06:32:19.154743200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 7 06:32:19.154981 containerd[1902]: time="2025-07-07T06:32:19.154959600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 7 06:32:19.155014 containerd[1902]: time="2025-07-07T06:32:19.154994100Z" 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 Jul 7 06:32:19.155014 containerd[1902]: time="2025-07-07T06:32:19.155004400Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 7 06:32:19.155060 containerd[1902]: time="2025-07-07T06:32:19.155034100Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 7 06:32:19.155250 containerd[1902]: time="2025-07-07T06:32:19.155226800Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 7 06:32:19.155309 containerd[1902]: time="2025-07-07T06:32:19.155290800Z" level=info msg="metadata content store policy set" policy=shared Jul 7 06:32:19.170270 containerd[1902]: time="2025-07-07T06:32:19.170240900Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 7 06:32:19.170329 containerd[1902]: time="2025-07-07T06:32:19.170297200Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 7 06:32:19.170329 containerd[1902]: time="2025-07-07T06:32:19.170314400Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 7 06:32:19.170329 containerd[1902]: time="2025-07-07T06:32:19.170326300Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 7 06:32:19.170396 containerd[1902]: time="2025-07-07T06:32:19.170337800Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 7 06:32:19.170396 containerd[1902]: time="2025-07-07T06:32:19.170358300Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 7 06:32:19.170396 containerd[1902]: time="2025-07-07T06:32:19.170373300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 7 06:32:19.170396 containerd[1902]: time="2025-07-07T06:32:19.170384000Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 7 06:32:19.170396 containerd[1902]: time="2025-07-07T06:32:19.170393700Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 7 06:32:19.170505 containerd[1902]: time="2025-07-07T06:32:19.170402900Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 7 06:32:19.170505 containerd[1902]: time="2025-07-07T06:32:19.170412500Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 7 06:32:19.170505 containerd[1902]: time="2025-07-07T06:32:19.170423900Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 7 06:32:19.170566 containerd[1902]: time="2025-07-07T06:32:19.170523600Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 7 06:32:19.170566 containerd[1902]: time="2025-07-07T06:32:19.170542800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 7 06:32:19.170566 containerd[1902]: time="2025-07-07T06:32:19.170556800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 7 06:32:19.170629 containerd[1902]: time="2025-07-07T06:32:19.170586200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 7 06:32:19.170629 containerd[1902]: time="2025-07-07T06:32:19.170598700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 7 06:32:19.170629 containerd[1902]: time="2025-07-07T06:32:19.170608000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 7 06:32:19.170629 containerd[1902]: time="2025-07-07T06:32:19.170618400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 7 06:32:19.170629 containerd[1902]: time="2025-07-07T06:32:19.170626800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 7 06:32:19.170734 containerd[1902]: time="2025-07-07T06:32:19.170637400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 7 06:32:19.170734 containerd[1902]: time="2025-07-07T06:32:19.170647000Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 7 06:32:19.170734 containerd[1902]: time="2025-07-07T06:32:19.170672100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 7 06:32:19.170734 containerd[1902]: time="2025-07-07T06:32:19.170727300Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 7 06:32:19.170818 containerd[1902]: time="2025-07-07T06:32:19.170741100Z" level=info msg="Start snapshots syncer" Jul 7 06:32:19.170818 containerd[1902]: time="2025-07-07T06:32:19.170769200Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 7 06:32:19.171014 containerd[1902]: time="2025-07-07T06:32:19.170973300Z" 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\"}" Jul 7 06:32:19.171137 containerd[1902]: time="2025-07-07T06:32:19.171028800Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 7 06:32:19.171137 containerd[1902]: time="2025-07-07T06:32:19.171103500Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 7 06:32:19.171249 containerd[1902]: time="2025-07-07T06:32:19.171224800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 7 06:32:19.171287 containerd[1902]: time="2025-07-07T06:32:19.171250800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 7 06:32:19.171287 containerd[1902]: time="2025-07-07T06:32:19.171261700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 7 06:32:19.171287 containerd[1902]: time="2025-07-07T06:32:19.171273100Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 7 06:32:19.171287 containerd[1902]: time="2025-07-07T06:32:19.171283400Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 7 06:32:19.171370 containerd[1902]: time="2025-07-07T06:32:19.171293200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 7 06:32:19.171370 containerd[1902]: time="2025-07-07T06:32:19.171305000Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 7 06:32:19.171370 containerd[1902]: time="2025-07-07T06:32:19.171338000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 7 06:32:19.171370 containerd[1902]: time="2025-07-07T06:32:19.171361100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171373800Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171400300Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171413800Z" 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 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171422600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171431100Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171438300Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171446200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 7 06:32:19.171455 containerd[1902]: time="2025-07-07T06:32:19.171455200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 7 06:32:19.171614 containerd[1902]: time="2025-07-07T06:32:19.171471400Z" level=info msg="runtime interface created" Jul 7 06:32:19.171614 containerd[1902]: time="2025-07-07T06:32:19.171477200Z" level=info msg="created NRI interface" Jul 7 06:32:19.171614 containerd[1902]: time="2025-07-07T06:32:19.171484600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 7 06:32:19.171614 containerd[1902]: time="2025-07-07T06:32:19.171494300Z" level=info msg="Connect containerd service" Jul 7 06:32:19.171614 containerd[1902]: time="2025-07-07T06:32:19.171517300Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 7 06:32:19.172174 containerd[1902]: time="2025-07-07T06:32:19.172144400Z" 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" Jul 7 06:32:20.540538 containerd[1902]: time="2025-07-07T06:32:20.540484000Z" level=info msg="Start subscribing containerd event" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540545500Z" level=info msg="Start recovering state" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540646500Z" level=info msg="Start event monitor" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540669100Z" level=info msg="Start cni network conf syncer for default" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540680400Z" level=info msg="Start streaming server" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540701000Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540709400Z" level=info msg="runtime interface starting up..." Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540715000Z" level=info msg="starting plugins..." Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540730000Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540733200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 7 06:32:20.540894 containerd[1902]: time="2025-07-07T06:32:20.540821400Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 7 06:32:20.541747 containerd[1902]: time="2025-07-07T06:32:20.541124400Z" level=info msg="containerd successfully booted in 1.395377s" Jul 7 06:32:20.540995 systemd[1]: Started containerd.service - containerd container runtime. Jul 7 06:32:21.364286 waagent[2041]: 2025-07-07T06:32:21.364189Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 7 06:32:21.368874 waagent[2041]: 2025-07-07T06:32:21.368826Z INFO Daemon Daemon OS: flatcar 4372.0.1 Jul 7 06:32:21.372633 waagent[2041]: 2025-07-07T06:32:21.372583Z INFO Daemon Daemon Python: 3.11.12 Jul 7 06:32:21.376339 waagent[2041]: 2025-07-07T06:32:21.376278Z INFO Daemon Daemon Run daemon Jul 7 06:32:21.379718 waagent[2041]: 2025-07-07T06:32:21.379664Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4372.0.1' Jul 7 06:32:21.386525 waagent[2041]: 2025-07-07T06:32:21.386480Z INFO Daemon Daemon Using waagent for provisioning Jul 7 06:32:21.390787 waagent[2041]: 2025-07-07T06:32:21.390735Z INFO Daemon Daemon Activate resource disk Jul 7 06:32:21.394638 waagent[2041]: 2025-07-07T06:32:21.394586Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 7 06:32:21.402256 waagent[2041]: 2025-07-07T06:32:21.402209Z INFO Daemon Daemon Found device: sda Jul 7 06:32:21.411754 waagent[2041]: 2025-07-07T06:32:21.411699Z INFO Daemon Daemon Examining partition table Jul 7 06:32:21.453703 waagent[2041]: 2025-07-07T06:32:21.453630Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 7 06:32:21.495899 waagent[2041]: 2025-07-07T06:32:21.495821Z INFO Daemon Daemon sfdisk --part-type -f /dev/sda 1 -n succeeded Jul 7 06:32:21.500427 waagent[2041]: 2025-07-07T06:32:21.500372Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 7 06:32:22.083854 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#421 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:32:22.094128 kernel: sda: sda1 Jul 7 06:32:22.101697 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#428 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 7 06:32:22.112185 kernel: sda: sda1 Jul 7 06:32:22.128082 waagent[2041]: 2025-07-07T06:32:22.128004Z INFO Daemon Daemon sfdisk --part-type /dev/sda 1 83 succeeded Jul 7 06:32:22.140770 waagent[2041]: 2025-07-07T06:32:22.140722Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sda1] Jul 7 06:32:26.602897 login[2045]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 7 06:32:26.603230 login[2044]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 06:32:26.609708 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 7 06:32:26.610803 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 7 06:32:26.616953 systemd-logind[1888]: New session 2 of user core. Jul 7 06:32:26.714787 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 7 06:32:26.717635 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 7 06:32:26.809551 (systemd)[2135]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 7 06:32:26.811982 systemd-logind[1888]: New session c1 of user core. Jul 7 06:32:27.604774 login[2045]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 7 06:32:27.609524 systemd-logind[1888]: New session 1 of user core. Jul 7 06:32:27.859950 waagent[2041]: 2025-07-07T06:32:27.859808Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sda1 /mnt/resource] Jul 7 06:32:27.864281 systemd[2135]: Queued start job for default target default.target. Jul 7 06:32:27.876186 systemd[2135]: Created slice app.slice - User Application Slice. Jul 7 06:32:27.876218 systemd[2135]: Reached target paths.target - Paths. Jul 7 06:32:27.876269 systemd[2135]: Reached target timers.target - Timers. Jul 7 06:32:27.877642 systemd[2135]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 06:32:27.889230 systemd[2135]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 06:32:27.889351 systemd[2135]: Reached target sockets.target - Sockets. Jul 7 06:32:27.889406 systemd[2135]: Reached target basic.target - Basic System. Jul 7 06:32:27.889451 systemd[2135]: Reached target default.target - Main User Target. Jul 7 06:32:27.889485 systemd[2135]: Startup finished in 1.071s. Jul 7 06:32:27.889538 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 7 06:32:27.890889 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 7 06:32:27.891619 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 7 06:32:27.989195 kernel: EXT4-fs (sda1): mounted filesystem 93b28894-3d33-42df-8ea6-1bc58abe1c7e r/w with ordered data mode. Quota mode: none. Jul 7 06:32:27.989531 waagent[2041]: 2025-07-07T06:32:27.989452Z INFO Daemon Daemon Resource disk /dev/sda is mounted at /mnt/resource with ext4 Jul 7 06:32:27.990333 waagent[2041]: 2025-07-07T06:32:27.990277Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 06:32:27.991190 waagent[2041]: 2025-07-07T06:32:27.991139Z INFO Daemon Daemon Running default provisioning handler Jul 7 06:32:27.997369 waagent[2041]: 2025-07-07T06:32:27.997319Z 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. Jul 7 06:32:27.997966 waagent[2041]: 2025-07-07T06:32:27.997928Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 7 06:32:27.999132 waagent[2041]: 2025-07-07T06:32:27.999097Z INFO Daemon Daemon cloud-init is enabled: False Jul 7 06:32:27.999882 waagent[2041]: 2025-07-07T06:32:27.999850Z INFO Daemon Daemon Copying ovf-env.xml Jul 7 06:32:28.128938 waagent[2041]: 2025-07-07T06:32:28.128804Z INFO Daemon Daemon Successfully mounted dvd Jul 7 06:32:28.172673 waagent[2041]: 2025-07-07T06:32:28.172609Z INFO Daemon Daemon Detect protocol endpoint Jul 7 06:32:28.172657 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.172878Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.174502Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.175807Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.177379Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.180096Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.191549Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.193041Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 7 06:32:28.207178 waagent[2041]: 2025-07-07T06:32:28.194284Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 7 06:32:28.300704 waagent[2041]: 2025-07-07T06:32:28.300608Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 7 06:32:28.314098 waagent[2041]: 2025-07-07T06:32:28.300939Z INFO Daemon Daemon Forcing an update of the goal state. Jul 7 06:32:28.314098 waagent[2041]: 2025-07-07T06:32:28.305526Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 06:32:28.319445 waagent[2041]: 2025-07-07T06:32:28.319408Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 7 06:32:28.326848 waagent[2041]: 2025-07-07T06:32:28.319977Z INFO Daemon Jul 7 06:32:28.326848 waagent[2041]: 2025-07-07T06:32:28.321046Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: cd51f835-5a0e-4aaf-a582-0823df68c145 eTag: 759652752346363452 source: Fabric] Jul 7 06:32:28.326848 waagent[2041]: 2025-07-07T06:32:28.322783Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 7 06:32:28.326848 waagent[2041]: 2025-07-07T06:32:28.324194Z INFO Daemon Jul 7 06:32:28.326848 waagent[2041]: 2025-07-07T06:32:28.325386Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 7 06:32:28.347435 waagent[2041]: 2025-07-07T06:32:28.329130Z INFO Daemon Daemon Downloading artifacts profile blob Jul 7 06:32:28.447826 waagent[2041]: 2025-07-07T06:32:28.447696Z INFO Daemon Downloaded certificate {'thumbprint': '27ED6F20D8C4F44860D51B79F099617D3AD02655', 'hasPrivateKey': True} Jul 7 06:32:28.448386 waagent[2041]: 2025-07-07T06:32:28.448342Z INFO Daemon Fetch goal state completed Jul 7 06:32:28.489116 waagent[2041]: 2025-07-07T06:32:28.489061Z INFO Daemon Daemon Starting provisioning Jul 7 06:32:28.489393 waagent[2041]: 2025-07-07T06:32:28.489350Z INFO Daemon Daemon Handle ovf-env.xml. Jul 7 06:32:28.491569 waagent[2041]: 2025-07-07T06:32:28.491030Z INFO Daemon Daemon Set hostname [ci-4372.0.1-a-f66a854089] Jul 7 06:32:28.514038 waagent[2041]: 2025-07-07T06:32:28.513975Z INFO Daemon Daemon Publish hostname [ci-4372.0.1-a-f66a854089] Jul 7 06:32:28.514470 waagent[2041]: 2025-07-07T06:32:28.514425Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 7 06:32:28.515174 waagent[2041]: 2025-07-07T06:32:28.515128Z INFO Daemon Daemon Primary interface is [eth0] Jul 7 06:32:28.524210 systemd-networkd[1586]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 7 06:32:28.524221 systemd-networkd[1586]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 7 06:32:28.524247 systemd-networkd[1586]: eth0: DHCP lease lost Jul 7 06:32:28.524821 waagent[2041]: 2025-07-07T06:32:28.524745Z INFO Daemon Daemon Create user account if not exists Jul 7 06:32:28.525987 waagent[2041]: 2025-07-07T06:32:28.525948Z INFO Daemon Daemon User core already exists, skip useradd Jul 7 06:32:28.527375 waagent[2041]: 2025-07-07T06:32:28.527334Z INFO Daemon Daemon Configure sudoer Jul 7 06:32:28.532814 waagent[2041]: 2025-07-07T06:32:28.532767Z INFO Daemon Daemon Configure sshd Jul 7 06:32:28.537634 waagent[2041]: 2025-07-07T06:32:28.537589Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 7 06:32:28.539056 waagent[2041]: 2025-07-07T06:32:28.539018Z INFO Daemon Daemon Deploy ssh public key. Jul 7 06:32:28.611212 systemd-networkd[1586]: eth0: DHCPv4 address 10.200.4.4/24, gateway 10.200.4.1 acquired from 168.63.129.16 Jul 7 06:32:41.737325 chronyd[1887]: Selected source PHC0 Jul 7 06:32:41.816274 setup-nvidia[2046]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 485M 0 1744k 0 0 1155k 0 0:07:10 0:00:01 0:07:09 1155k\u000d 4 485M 4 21.1M 0 0 8968k 0 0:00:55 0:00:02 0:00:53 8967k\u000d 9 485M 9 45.4M 0 0 13.0M 0 0:00:37 0:00:03 0:00:34 13.0M\u000d 13 485M 13 66.0M 0 0 15.1M 0 0:00:32 0:00:04 0:00:28 15.0M\u000d 18 485M 18 89.4M 0 0 16.4M 0 0:00:29 0:00:05 0:00:24 18.1M\u000d 23 485M 23 114M 0 0 17.6M 0 0:00:27 0:00:06 0:00:21 22.6M\u000d 27 485M 27 134M 0 0 18.2M 0 0:00:26 0:00:07 0:00:19 22.8M\u000d 32 485M 32 159M 0 0 18.8M 0 0:00:25 0:00:08 0:00:17 22.9M\u000d 36 485M 36 179M 0 0 19.1M 0 0:00:25 0:00:09 0:00:16 22.6M\u000d 41 485M 41 203M 0 0 19.4M 0 0:00:24 0:00:10 0:00:14 22.7M\u000d 46 485M 46 228M 0 0 19.8M 0 0:00:24 0:00:11 0:00:13 22.6M\u000d 50 485M 50 247M 0 0 19.9M 0 0:00:24 0:00:12 0:00:12 22.5M\u000d 55 485M 55 270M 0 0 20.1M 0 0:00:24 0:00:13 0:00:11 22.2M\u000d 60 485M 60 293M 0 0 20.2M 0 0:00:24 0:00:14 0:00:10 22.1M\u000d 64 485M 64 311M 0 0 20.2M 0 0:00:24 0:00:15 0:00:09 21.7M\u000d 69 485M 69 335M 0 0 20.3M 0 0:00:23 0:00:16 0:00:07 21.4M\u000d 72 485M 72 353M 0 0 20.3M 0 0:00:23 0:00:17 0:00:06 21.2M\u000d 77 485M 77 377M 0 0 20.4M 0 0:00:23 0:00:18 0:00:05 21.3M\u000d 82 485M 82 401M 0 0 20.6M 0 0:00:23 0:00:19 0:00:04 21.8M\u000d 87 485M 87 422M 0 0 20.7M 0 0:00:23 0:00:20 0:00:03 22.2M\u000d 92 485M 92 447M 0 0 20.8M 0 0:00:23 0:00:21 0:00:02 22.4M\u000d 96 485M 96 468M 0 0 20.9M 0 0:00:23 0:00:22 0:00:01 22.9M\u000d100 485M 100 485M 0 0 20.9M 0 0:00:23 0:00:23 --:--:-- 23.1M Jul 7 06:32:51.945540 setup-nvidia[1881]: Downloading NVIDIA 535.230.02 Driver Jul 7 06:32:51.949849 setup-nvidia[2205]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 7 06:32:51.950311 setup-nvidia[2205]: Dload Upload Total Spent Left Speed Jul 7 06:32:53.893566 setup-nvidia[2205]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 326M 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 41 326M 41 135M 0 0 123M 0 0:00:02 0:00:01 0:00:01 123M\u000d100 326M 100 326M 0 0 167M 0 0:00:01 0:00:01 --:--:-- 167M Jul 7 06:32:53.994200 setup-nvidia[1881]: Extract the NVIDIA Driver Installer 535.230.02 Jul 7 06:32:53.994592 setup-nvidia[1881]: /opt/nvidia/workdir/nvidia-workdir / Jul 7 06:32:54.022250 setup-nvidia[2208]: Creating directory NVIDIA-Linux-x86_64-535.230.02 Jul 7 06:32:54.304354 setup-nvidia[2208]: Verifying archive integrity... OK Jul 7 06:32:55.052562 setup-nvidia[2208]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.230.02 Jul 7 06:32:56.845750 setup-nvidia[2234]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 7 06:32:56.905072 setup-nvidia[1881]: / Jul 7 06:32:56.905072 setup-nvidia[1881]: Spawn system-nspawn container to install the NVIDIA drivers Jul 7 06:32:57.119572 sudo[2241]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-4372.0.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.230.02 Jul 7 06:32:57.121475 sudo[2241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 7 06:32:57.127178 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 7 06:32:57.128212 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 06:32:57.155593 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 06:32:57.158031 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 7 06:32:57.173087 (systemd)[2244]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 7 06:32:57.175461 systemd-logind[1888]: New session c3 of user root. Jul 7 06:32:57.361749 systemd[2244]: Queued start job for default target default.target. Jul 7 06:32:57.385345 systemd[2244]: Created slice app.slice - User Application Slice. Jul 7 06:32:57.385378 systemd[2244]: Reached target paths.target - Paths. Jul 7 06:32:57.385427 systemd[2244]: Reached target timers.target - Timers. Jul 7 06:32:57.386625 systemd[2244]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 7 06:32:57.398470 systemd[2244]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 7 06:32:57.398588 systemd[2244]: Reached target sockets.target - Sockets. Jul 7 06:32:57.398641 systemd[2244]: Reached target basic.target - Basic System. Jul 7 06:32:57.398684 systemd[2244]: Reached target default.target - Main User Target. Jul 7 06:32:57.398723 systemd[2244]: Startup finished in 217ms. Jul 7 06:32:57.398777 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 7 06:32:57.400221 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 7 06:32:57.473232 kernel: loop6: detected capacity change from 0 to 12644352 Jul 7 06:32:57.481191 kernel: loop6: p9 Jul 7 06:32:57.653425 setup-nvidia[2253]: ░ Spawning container flatcardevelopercontainer-4372.0.1.bin on /opt/nvidia/workdir/flatcar_developer_container-4372.0.1.bin. Jul 7 06:32:58.621009 waagent[2041]: 2025-07-07T06:32:58.620945Z INFO Daemon Daemon Provisioning complete Jul 7 06:32:58.629402 waagent[2041]: 2025-07-07T06:32:58.629359Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 7 06:32:58.629671 waagent[2041]: 2025-07-07T06:32:58.629631Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 7 06:32:58.631102 waagent[2041]: 2025-07-07T06:32:58.631058Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 7 06:32:58.794458 waagent[2257]: 2025-07-07T06:32:58.794374Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 7 06:32:58.794805 waagent[2257]: 2025-07-07T06:32:58.794534Z INFO ExtHandler ExtHandler OS: flatcar 4372.0.1 Jul 7 06:32:58.794805 waagent[2257]: 2025-07-07T06:32:58.794608Z INFO ExtHandler ExtHandler Python: 3.11.12 Jul 7 06:32:58.794805 waagent[2257]: 2025-07-07T06:32:58.794681Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 7 06:32:58.884157 waagent[2257]: 2025-07-07T06:32:58.884033Z INFO ExtHandler ExtHandler Distro: flatcar-4372.0.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 7 06:32:58.884330 waagent[2257]: 2025-07-07T06:32:58.884290Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 06:32:58.884415 waagent[2257]: 2025-07-07T06:32:58.884382Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 06:32:58.891391 waagent[2257]: 2025-07-07T06:32:58.891344Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 7 06:32:58.901413 waagent[2257]: 2025-07-07T06:32:58.901369Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 7 06:32:58.901877 waagent[2257]: 2025-07-07T06:32:58.901838Z INFO ExtHandler Jul 7 06:32:58.901961 waagent[2257]: 2025-07-07T06:32:58.901927Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 4d7b2c3b-0288-4aba-b8a3-5bb84ce8d864 eTag: 759652752346363452 source: Fabric] Jul 7 06:32:58.902301 waagent[2257]: 2025-07-07T06:32:58.902261Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 7 06:32:58.902908 waagent[2257]: 2025-07-07T06:32:58.902870Z INFO ExtHandler Jul 7 06:32:58.902981 waagent[2257]: 2025-07-07T06:32:58.902951Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 7 06:32:58.906159 waagent[2257]: 2025-07-07T06:32:58.906121Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 7 06:32:58.965259 waagent[2257]: 2025-07-07T06:32:58.965206Z INFO ExtHandler Downloaded certificate {'thumbprint': '27ED6F20D8C4F44860D51B79F099617D3AD02655', 'hasPrivateKey': True} Jul 7 06:32:58.965745 waagent[2257]: 2025-07-07T06:32:58.965705Z INFO ExtHandler Fetch goal state completed Jul 7 06:32:58.976454 waagent[2257]: 2025-07-07T06:32:58.976407Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jul 7 06:32:58.981402 waagent[2257]: 2025-07-07T06:32:58.981360Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2257 Jul 7 06:32:58.981544 waagent[2257]: 2025-07-07T06:32:58.981509Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 7 06:32:58.981876 waagent[2257]: 2025-07-07T06:32:58.981839Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 7 06:32:58.983468 waagent[2257]: 2025-07-07T06:32:58.983427Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4372.0.1', '', 'Flatcar Container Linux by Kinvolk'] Jul 7 06:32:58.983946 waagent[2257]: 2025-07-07T06:32:58.983901Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4372.0.1', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 7 06:32:58.984097 waagent[2257]: 2025-07-07T06:32:58.984060Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 7 06:32:58.984732 waagent[2257]: 2025-07-07T06:32:58.984693Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 7 06:32:59.099005 waagent[2257]: 2025-07-07T06:32:59.098957Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 7 06:32:59.099220 waagent[2257]: 2025-07-07T06:32:59.099181Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 7 06:32:59.105844 waagent[2257]: 2025-07-07T06:32:59.105810Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 7 06:32:59.113080 systemd[1]: Reload requested from client PID 2272 ('systemctl') (unit waagent.service)... Jul 7 06:32:59.113097 systemd[1]: Reloading... Jul 7 06:32:59.171192 zram_generator::config[2314]: No configuration found. Jul 7 06:32:59.289915 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 7 06:32:59.342296 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 7 06:32:59.342392 kernel: EXT4-fs (loop6p9): mounted filesystem bbc9574b-3fa0-492d-bcc0-ab0a1bc7f9ec ro without journal. Quota mode: none. Jul 7 06:32:59.488894 systemd[1]: Reloading finished in 375 ms. Jul 7 06:32:59.519584 waagent[2257]: 2025-07-07T06:32:59.519517Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 7 06:32:59.519688 waagent[2257]: 2025-07-07T06:32:59.519661Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 7 06:33:00.533808 waagent[2257]: 2025-07-07T06:33:00.533714Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 7 06:33:00.534185 waagent[2257]: 2025-07-07T06:33:00.534053Z 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] Jul 7 06:33:00.534877 waagent[2257]: 2025-07-07T06:33:00.534827Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 7 06:33:00.535348 waagent[2257]: 2025-07-07T06:33:00.535300Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 06:33:00.535389 waagent[2257]: 2025-07-07T06:33:00.535348Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 7 06:33:00.535741 waagent[2257]: 2025-07-07T06:33:00.535704Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 06:33:00.535844 waagent[2257]: 2025-07-07T06:33:00.535802Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 7 06:33:00.536104 waagent[2257]: 2025-07-07T06:33:00.536049Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 7 06:33:00.536403 waagent[2257]: 2025-07-07T06:33:00.536355Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 7 06:33:00.536450 waagent[2257]: 2025-07-07T06:33:00.536401Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 7 06:33:00.536892 waagent[2257]: 2025-07-07T06:33:00.536854Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 7 06:33:00.537148 waagent[2257]: 2025-07-07T06:33:00.537098Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 7 06:33:00.537219 waagent[2257]: 2025-07-07T06:33:00.537179Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 7 06:33:00.537483 waagent[2257]: 2025-07-07T06:33:00.537447Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 7 06:33:00.537614 waagent[2257]: 2025-07-07T06:33:00.537569Z INFO EnvHandler ExtHandler Configure routes Jul 7 06:33:00.538024 waagent[2257]: 2025-07-07T06:33:00.537983Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 7 06:33:00.538024 waagent[2257]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 7 06:33:00.538024 waagent[2257]: eth0 00000000 0104C80A 0003 0 0 1024 00000000 0 0 0 Jul 7 06:33:00.538024 waagent[2257]: eth0 0004C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 7 06:33:00.538024 waagent[2257]: eth0 0104C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 7 06:33:00.538024 waagent[2257]: eth0 10813FA8 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 06:33:00.538024 waagent[2257]: eth0 FEA9FEA9 0104C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 7 06:33:00.538194 waagent[2257]: 2025-07-07T06:33:00.538043Z INFO EnvHandler ExtHandler Gateway:None Jul 7 06:33:00.538688 waagent[2257]: 2025-07-07T06:33:00.538654Z INFO EnvHandler ExtHandler Routes:None Jul 7 06:33:00.544015 waagent[2257]: 2025-07-07T06:33:00.543970Z INFO ExtHandler ExtHandler Jul 7 06:33:00.544153 waagent[2257]: 2025-07-07T06:33:00.544114Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 71ba3b37-60c7-4e4f-b184-97accf9f1948 correlation 3667a363-7139-45a4-bc58-f76b0202a9ef created: 2025-07-07T06:30:56.372520Z] Jul 7 06:33:00.545112 waagent[2257]: 2025-07-07T06:33:00.545068Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 7 06:33:00.547298 waagent[2257]: 2025-07-07T06:33:00.547251Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 7 06:33:00.580040 waagent[2257]: 2025-07-07T06:33:00.579986Z 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 Jul 7 06:33:00.580040 waagent[2257]: Try `iptables -h' or 'iptables --help' for more information.) Jul 7 06:33:00.580417 waagent[2257]: 2025-07-07T06:33:00.580378Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 3673C3AD-D5E1-46DF-922A-1FEEB0A2AD07;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 7 06:33:00.655949 waagent[2257]: 2025-07-07T06:33:00.655884Z INFO MonitorHandler ExtHandler Network interfaces: Jul 7 06:33:00.655949 waagent[2257]: Executing ['ip', '-a', '-o', 'link']: Jul 7 06:33:00.655949 waagent[2257]: 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 Jul 7 06:33:00.655949 waagent[2257]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:40:04:b4 brd ff:ff:ff:ff:ff:ff Jul 7 06:33:00.655949 waagent[2257]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 7 06:33:00.655949 waagent[2257]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 7 06:33:00.655949 waagent[2257]: 2: eth0 inet 10.200.4.4/24 metric 1024 brd 10.200.4.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 7 06:33:00.655949 waagent[2257]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 7 06:33:00.655949 waagent[2257]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 7 06:33:00.655949 waagent[2257]: 2: eth0 inet6 fe80::222:48ff:fe40:4b4/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 7 06:33:00.841986 waagent[2257]: 2025-07-07T06:33:00.841879Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 7 06:33:00.841986 waagent[2257]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:33:00.841986 waagent[2257]: pkts bytes target prot opt in out source destination Jul 7 06:33:00.841986 waagent[2257]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:33:00.841986 waagent[2257]: pkts bytes target prot opt in out source destination Jul 7 06:33:00.841986 waagent[2257]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:33:00.841986 waagent[2257]: pkts bytes target prot opt in out source destination Jul 7 06:33:00.841986 waagent[2257]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 06:33:00.841986 waagent[2257]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 06:33:00.841986 waagent[2257]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 06:33:00.845399 waagent[2257]: 2025-07-07T06:33:00.845359Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 7 06:33:00.845399 waagent[2257]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:33:00.845399 waagent[2257]: pkts bytes target prot opt in out source destination Jul 7 06:33:00.845399 waagent[2257]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:33:00.845399 waagent[2257]: pkts bytes target prot opt in out source destination Jul 7 06:33:00.845399 waagent[2257]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 7 06:33:00.845399 waagent[2257]: pkts bytes target prot opt in out source destination Jul 7 06:33:00.845399 waagent[2257]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 7 06:33:00.845399 waagent[2257]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 7 06:33:00.845399 waagent[2257]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 7 06:33:00.845651 waagent[2257]: 2025-07-07T06:33:00.845617Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 7 06:33:00.845802 waagent[2257]: 2025-07-07T06:33:00.845772Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 7 06:33:01.059539 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 7 06:33:02.883728 update_engine[1891]: I20250707 06:33:02.883635 1891 update_attempter.cc:509] Updating boot flags... Jul 7 06:33:13.911940 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 7 06:33:13.913398 systemd[1]: Started sshd@0-10.200.4.4:22-10.200.16.10:41808.service - OpenSSH per-connection server daemon (10.200.16.10:41808). Jul 7 06:33:14.782183 sshd[3291]: Accepted publickey for core from 10.200.16.10 port 41808 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:14.783552 sshd-session[3291]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:14.787905 systemd-logind[1888]: New session 3 of user core. Jul 7 06:33:14.800292 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 7 06:33:15.324099 systemd[1]: Started sshd@1-10.200.4.4:22-10.200.16.10:41810.service - OpenSSH per-connection server daemon (10.200.16.10:41810). Jul 7 06:33:15.931580 sshd[3296]: Accepted publickey for core from 10.200.16.10 port 41810 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:15.932941 sshd-session[3296]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:15.937318 systemd-logind[1888]: New session 4 of user core. Jul 7 06:33:15.953320 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 7 06:33:16.368903 sshd[3298]: Connection closed by 10.200.16.10 port 41810 Jul 7 06:33:16.369296 sshd-session[3296]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:16.372827 systemd[1]: sshd@1-10.200.4.4:22-10.200.16.10:41810.service: Deactivated successfully. Jul 7 06:33:16.374415 systemd[1]: session-4.scope: Deactivated successfully. Jul 7 06:33:16.375077 systemd-logind[1888]: Session 4 logged out. Waiting for processes to exit. Jul 7 06:33:16.376013 systemd-logind[1888]: Removed session 4. Jul 7 06:33:16.474842 systemd[1]: Started sshd@2-10.200.4.4:22-10.200.16.10:41816.service - OpenSSH per-connection server daemon (10.200.16.10:41816). Jul 7 06:33:17.096887 sshd[3305]: Accepted publickey for core from 10.200.16.10 port 41816 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:17.098262 sshd-session[3305]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:17.102637 systemd-logind[1888]: New session 5 of user core. Jul 7 06:33:17.121339 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 7 06:33:17.517182 sshd[3307]: Connection closed by 10.200.16.10 port 41816 Jul 7 06:33:17.517575 sshd-session[3305]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:17.521127 systemd[1]: sshd@2-10.200.4.4:22-10.200.16.10:41816.service: Deactivated successfully. Jul 7 06:33:17.522719 systemd[1]: session-5.scope: Deactivated successfully. Jul 7 06:33:17.523407 systemd-logind[1888]: Session 5 logged out. Waiting for processes to exit. Jul 7 06:33:17.524400 systemd-logind[1888]: Removed session 5. Jul 7 06:33:27.627533 systemd[1]: Started sshd@3-10.200.4.4:22-10.200.16.10:60596.service - OpenSSH per-connection server daemon (10.200.16.10:60596). Jul 7 06:33:28.238408 sshd[3429]: Accepted publickey for core from 10.200.16.10 port 60596 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:28.239779 sshd-session[3429]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:28.244264 systemd-logind[1888]: New session 6 of user core. Jul 7 06:33:28.260316 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 7 06:33:28.661466 sshd[3456]: Connection closed by 10.200.16.10 port 60596 Jul 7 06:33:28.661867 sshd-session[3429]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:28.665801 systemd[1]: sshd@3-10.200.4.4:22-10.200.16.10:60596.service: Deactivated successfully. Jul 7 06:33:28.667421 systemd[1]: session-6.scope: Deactivated successfully. Jul 7 06:33:28.668077 systemd-logind[1888]: Session 6 logged out. Waiting for processes to exit. Jul 7 06:33:28.669005 systemd-logind[1888]: Removed session 6. Jul 7 06:33:28.775668 systemd[1]: Started sshd@4-10.200.4.4:22-10.200.16.10:60598.service - OpenSSH per-connection server daemon (10.200.16.10:60598). Jul 7 06:33:29.374608 sshd[3462]: Accepted publickey for core from 10.200.16.10 port 60598 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:29.376056 sshd-session[3462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:29.380443 systemd-logind[1888]: New session 7 of user core. Jul 7 06:33:29.392291 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 7 06:33:29.802924 sshd[3464]: Connection closed by 10.200.16.10 port 60598 Jul 7 06:33:29.803295 sshd-session[3462]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:29.806572 systemd[1]: sshd@4-10.200.4.4:22-10.200.16.10:60598.service: Deactivated successfully. Jul 7 06:33:29.808091 systemd[1]: session-7.scope: Deactivated successfully. Jul 7 06:33:29.808911 systemd-logind[1888]: Session 7 logged out. Waiting for processes to exit. Jul 7 06:33:29.809897 systemd-logind[1888]: Removed session 7. Jul 7 06:33:39.918213 systemd[1]: Started sshd@5-10.200.4.4:22-10.200.16.10:39666.service - OpenSSH per-connection server daemon (10.200.16.10:39666). Jul 7 06:33:40.538277 sshd[6112]: Accepted publickey for core from 10.200.16.10 port 39666 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:40.538029 sshd-session[6112]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:40.543868 systemd-logind[1888]: New session 8 of user core. Jul 7 06:33:40.552322 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 7 06:33:40.959882 sshd[6274]: Connection closed by 10.200.16.10 port 39666 Jul 7 06:33:40.961790 sshd-session[6112]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:40.966079 systemd-logind[1888]: Session 8 logged out. Waiting for processes to exit. Jul 7 06:33:40.966369 systemd[1]: sshd@5-10.200.4.4:22-10.200.16.10:39666.service: Deactivated successfully. Jul 7 06:33:40.968160 systemd[1]: session-8.scope: Deactivated successfully. Jul 7 06:33:40.969718 systemd-logind[1888]: Removed session 8. Jul 7 06:33:41.069887 systemd[1]: Started sshd@6-10.200.4.4:22-10.200.16.10:39676.service - OpenSSH per-connection server daemon (10.200.16.10:39676). Jul 7 06:33:41.670573 sshd[6459]: Accepted publickey for core from 10.200.16.10 port 39676 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:41.672453 sshd-session[6459]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:41.679626 systemd-logind[1888]: New session 9 of user core. Jul 7 06:33:41.686465 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 7 06:33:42.098231 sshd[6624]: Connection closed by 10.200.16.10 port 39676 Jul 7 06:33:42.100358 sshd-session[6459]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:42.103726 systemd-logind[1888]: Session 9 logged out. Waiting for processes to exit. Jul 7 06:33:42.106541 systemd[1]: sshd@6-10.200.4.4:22-10.200.16.10:39676.service: Deactivated successfully. Jul 7 06:33:42.109076 systemd[1]: session-9.scope: Deactivated successfully. Jul 7 06:33:42.112963 systemd-logind[1888]: Removed session 9. Jul 7 06:33:52.214398 systemd[1]: Started sshd@7-10.200.4.4:22-10.200.16.10:33220.service - OpenSSH per-connection server daemon (10.200.16.10:33220). Jul 7 06:33:52.834514 sshd[10239]: Accepted publickey for core from 10.200.16.10 port 33220 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:52.835927 sshd-session[10239]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:52.841051 systemd-logind[1888]: New session 10 of user core. Jul 7 06:33:52.849624 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 7 06:33:53.262473 sshd[10411]: Connection closed by 10.200.16.10 port 33220 Jul 7 06:33:53.263505 sshd-session[10239]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:53.269918 systemd[1]: sshd@7-10.200.4.4:22-10.200.16.10:33220.service: Deactivated successfully. Jul 7 06:33:53.270416 systemd-logind[1888]: Session 10 logged out. Waiting for processes to exit. Jul 7 06:33:53.272790 systemd[1]: session-10.scope: Deactivated successfully. Jul 7 06:33:53.275881 systemd-logind[1888]: Removed session 10. Jul 7 06:33:53.371989 systemd[1]: Started sshd@8-10.200.4.4:22-10.200.16.10:33226.service - OpenSSH per-connection server daemon (10.200.16.10:33226). Jul 7 06:33:53.993346 sshd[10553]: Accepted publickey for core from 10.200.16.10 port 33226 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:33:53.995186 sshd-session[10553]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:33:54.003574 systemd-logind[1888]: New session 11 of user core. Jul 7 06:33:54.013363 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 7 06:33:54.431719 sshd[10763]: Connection closed by 10.200.16.10 port 33226 Jul 7 06:33:54.432458 sshd-session[10553]: pam_unix(sshd:session): session closed for user core Jul 7 06:33:54.437761 systemd-logind[1888]: Session 11 logged out. Waiting for processes to exit. Jul 7 06:33:54.439602 systemd[1]: sshd@8-10.200.4.4:22-10.200.16.10:33226.service: Deactivated successfully. Jul 7 06:33:54.442015 systemd[1]: session-11.scope: Deactivated successfully. Jul 7 06:33:54.444941 systemd-logind[1888]: Removed session 11. Jul 7 06:34:04.544790 systemd[1]: Started sshd@9-10.200.4.4:22-10.200.16.10:44062.service - OpenSSH per-connection server daemon (10.200.16.10:44062). Jul 7 06:34:05.166259 sshd[11758]: Accepted publickey for core from 10.200.16.10 port 44062 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:05.167787 sshd-session[11758]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:05.175441 systemd-logind[1888]: New session 12 of user core. Jul 7 06:34:05.184263 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 7 06:34:05.595981 sshd[11779]: Connection closed by 10.200.16.10 port 44062 Jul 7 06:34:05.596370 sshd-session[11758]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:05.600632 systemd-logind[1888]: Session 12 logged out. Waiting for processes to exit. Jul 7 06:34:05.601455 systemd[1]: sshd@9-10.200.4.4:22-10.200.16.10:44062.service: Deactivated successfully. Jul 7 06:34:05.607446 systemd[1]: session-12.scope: Deactivated successfully. Jul 7 06:34:05.609674 systemd-logind[1888]: Removed session 12. Jul 7 06:34:05.701888 systemd[1]: Started sshd@10-10.200.4.4:22-10.200.16.10:44074.service - OpenSSH per-connection server daemon (10.200.16.10:44074). Jul 7 06:34:06.330801 sshd[11790]: Accepted publickey for core from 10.200.16.10 port 44074 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:06.331670 sshd-session[11790]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:06.339495 systemd-logind[1888]: New session 13 of user core. Jul 7 06:34:06.348257 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 7 06:34:06.772765 sshd[11818]: Connection closed by 10.200.16.10 port 44074 Jul 7 06:34:06.774549 sshd-session[11790]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:06.777819 systemd-logind[1888]: Session 13 logged out. Waiting for processes to exit. Jul 7 06:34:06.779400 systemd[1]: sshd@10-10.200.4.4:22-10.200.16.10:44074.service: Deactivated successfully. Jul 7 06:34:06.782761 systemd[1]: session-13.scope: Deactivated successfully. Jul 7 06:34:06.784968 systemd-logind[1888]: Removed session 13. Jul 7 06:34:16.882411 systemd[1]: Started sshd@11-10.200.4.4:22-10.200.16.10:44304.service - OpenSSH per-connection server daemon (10.200.16.10:44304). Jul 7 06:34:17.513274 sshd[12165]: Accepted publickey for core from 10.200.16.10 port 44304 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:17.515595 sshd-session[12165]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:17.524966 systemd-logind[1888]: New session 14 of user core. Jul 7 06:34:17.534330 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 7 06:34:17.952446 sshd[12178]: Connection closed by 10.200.16.10 port 44304 Jul 7 06:34:17.952722 sshd-session[12165]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:17.956613 systemd-logind[1888]: Session 14 logged out. Waiting for processes to exit. Jul 7 06:34:17.956900 systemd[1]: sshd@11-10.200.4.4:22-10.200.16.10:44304.service: Deactivated successfully. Jul 7 06:34:17.958550 systemd[1]: session-14.scope: Deactivated successfully. Jul 7 06:34:17.960824 systemd-logind[1888]: Removed session 14. Jul 7 06:34:18.063248 systemd[1]: Started sshd@12-10.200.4.4:22-10.200.16.10:44306.service - OpenSSH per-connection server daemon (10.200.16.10:44306). Jul 7 06:34:18.683917 sshd[12202]: Accepted publickey for core from 10.200.16.10 port 44306 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:18.684803 sshd-session[12202]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:18.691040 systemd-logind[1888]: New session 15 of user core. Jul 7 06:34:18.699261 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 7 06:34:19.124743 sshd[12218]: Connection closed by 10.200.16.10 port 44306 Jul 7 06:34:19.125114 sshd-session[12202]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:19.129487 systemd-logind[1888]: Session 15 logged out. Waiting for processes to exit. Jul 7 06:34:19.130933 systemd[1]: sshd@12-10.200.4.4:22-10.200.16.10:44306.service: Deactivated successfully. Jul 7 06:34:19.132606 systemd[1]: session-15.scope: Deactivated successfully. Jul 7 06:34:19.134920 systemd-logind[1888]: Removed session 15. Jul 7 06:34:29.231887 systemd[1]: Started sshd@13-10.200.4.4:22-10.200.16.10:41332.service - OpenSSH per-connection server daemon (10.200.16.10:41332). Jul 7 06:34:29.837377 sshd[12562]: Accepted publickey for core from 10.200.16.10 port 41332 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:29.838757 sshd-session[12562]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:29.843072 systemd-logind[1888]: New session 16 of user core. Jul 7 06:34:29.854298 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 7 06:34:30.258092 sshd[12564]: Connection closed by 10.200.16.10 port 41332 Jul 7 06:34:30.258465 sshd-session[12562]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:30.261942 systemd[1]: sshd@13-10.200.4.4:22-10.200.16.10:41332.service: Deactivated successfully. Jul 7 06:34:30.263510 systemd[1]: session-16.scope: Deactivated successfully. Jul 7 06:34:30.264186 systemd-logind[1888]: Session 16 logged out. Waiting for processes to exit. Jul 7 06:34:30.265209 systemd-logind[1888]: Removed session 16. Jul 7 06:34:30.391565 systemd[1]: Started sshd@14-10.200.4.4:22-10.200.16.10:54040.service - OpenSSH per-connection server daemon (10.200.16.10:54040). Jul 7 06:34:30.987733 sshd[12570]: Accepted publickey for core from 10.200.16.10 port 54040 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:30.989197 sshd-session[12570]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:30.993549 systemd-logind[1888]: New session 17 of user core. Jul 7 06:34:31.006318 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 7 06:34:31.408247 sshd[12572]: Connection closed by 10.200.16.10 port 54040 Jul 7 06:34:31.408606 sshd-session[12570]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:31.412035 systemd[1]: sshd@14-10.200.4.4:22-10.200.16.10:54040.service: Deactivated successfully. Jul 7 06:34:31.413633 systemd[1]: session-17.scope: Deactivated successfully. Jul 7 06:34:31.414450 systemd-logind[1888]: Session 17 logged out. Waiting for processes to exit. Jul 7 06:34:31.415678 systemd-logind[1888]: Removed session 17. Jul 7 06:34:37.516845 setup-nvidia[2253]: \u000d Jul 7 06:34:37.516845 setup-nvidia[2253]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 7 06:34:37.516845 setup-nvidia[2253]: frequently when this kernel module was built against the wrong or Jul 7 06:34:37.516845 setup-nvidia[2253]: improperly configured kernel sources, with a version of gcc that differs Jul 7 06:34:37.516845 setup-nvidia[2253]: from the one used to build the target kernel, or if another driver, such Jul 7 06:34:37.516845 setup-nvidia[2253]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 7 06:34:37.516845 setup-nvidia[2253]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 7 06:34:37.516845 setup-nvidia[2253]: installed in this system is supported by this NVIDIA Linux graphics Jul 7 06:34:37.516845 setup-nvidia[2253]: driver release. Jul 7 06:34:37.516845 setup-nvidia[2253]: \u000d Jul 7 06:34:37.516845 setup-nvidia[2253]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 7 06:34:37.516845 setup-nvidia[2253]: messages' at the end of the file Jul 7 06:34:37.516845 setup-nvidia[2253]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more Jul 7 06:34:37.516845 setup-nvidia[2253]: information. Jul 7 06:34:37.516845 setup-nvidia[2253]: \u000d Jul 7 06:34:37.531810 setup-nvidia[2253]: dmesg: read kernel buffer failed: Operation not permitted Jul 7 06:34:37.551012 setup-nvidia[2253]: \u000d Jul 7 06:34:37.551012 setup-nvidia[2253]: ERROR: Installation has failed. Please see the file Jul 7 06:34:37.551012 setup-nvidia[2253]: '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for Jul 7 06:34:37.551012 setup-nvidia[2253]: details. You may find suggestions on fixing installation problems in Jul 7 06:34:37.551012 setup-nvidia[2253]: the README available on the Linux driver download page at Jul 7 06:34:37.551012 setup-nvidia[2253]: www.nvidia.com. Jul 7 06:34:37.551012 setup-nvidia[2253]: \u000d Jul 7 06:34:37.551386 setup-nvidia[2253]: Last 50 lines of nvidia-installer.log: Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039000rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038997rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038990rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039046rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039042rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039041rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039043rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv038987rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030558rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030557rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030561rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030560rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030559rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030562rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv030564rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv011860rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.o: warning: objtool: _nv039354rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 7 06:34:37.552178 setup-nvidia[2253]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/Module.symvers Jul 7 06:34:37.552178 setup-nvidia[2253]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.mod.o Jul 7 06:34:37.552178 setup-nvidia[2253]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/.module-common.o Jul 7 06:34:37.552178 setup-nvidia[2253]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.mod.o Jul 7 06:34:37.552178 setup-nvidia[2253]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.mod.o Jul 7 06:34:37.552178 setup-nvidia[2253]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.mod.o Jul 7 06:34:37.552178 setup-nvidia[2253]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.mod.o Jul 7 06:34:37.552178 setup-nvidia[2253]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 7 06:34:37.552178 setup-nvidia[2253]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 7 06:34:37.552178 setup-nvidia[2253]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 7 06:34:37.552178 setup-nvidia[2253]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 7 06:34:37.552178 setup-nvidia[2253]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko Jul 7 06:34:37.552178 setup-nvidia[2253]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.230.02/kernel/nvidia.ko due to unavailability of vmlinux Jul 7 06:34:37.552178 setup-nvidia[2253]: make[2]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/build' Jul 7 06:34:37.552178 setup-nvidia[2253]: make[1]: Leaving directory '/usr/lib/modules/6.12.35-flatcar/source' Jul 7 06:34:37.552178 setup-nvidia[2253]: -> done. Jul 7 06:34:37.552178 setup-nvidia[2253]: -> Kernel module compilation complete. Jul 7 06:34:37.552178 setup-nvidia[2253]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 7 06:34:37.552178 setup-nvidia[2253]: 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. Jul 7 06:34:37.553347 setup-nvidia[2253]: \u000d Jul 7 06:34:37.553347 setup-nvidia[2253]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for more information. Jul 7 06:34:37.553347 setup-nvidia[2253]: -> Kernel module load error: Operation not permitted Jul 7 06:34:37.553347 setup-nvidia[2253]: -> Kernel messages: Jul 7 06:34:37.553347 setup-nvidia[2253]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.230.02/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jul 7 06:34:41.519706 systemd[1]: Started sshd@15-10.200.4.4:22-10.200.16.10:49936.service - OpenSSH per-connection server daemon (10.200.16.10:49936). Jul 7 06:34:42.117002 sshd[12758]: Accepted publickey for core from 10.200.16.10 port 49936 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:42.118376 sshd-session[12758]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:42.122680 systemd-logind[1888]: New session 18 of user core. Jul 7 06:34:42.134295 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 7 06:34:42.545458 sshd[12760]: Connection closed by 10.200.16.10 port 49936 Jul 7 06:34:42.545799 sshd-session[12758]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:42.549247 systemd[1]: sshd@15-10.200.4.4:22-10.200.16.10:49936.service: Deactivated successfully. Jul 7 06:34:42.550809 systemd[1]: session-18.scope: Deactivated successfully. Jul 7 06:34:42.551510 systemd-logind[1888]: Session 18 logged out. Waiting for processes to exit. Jul 7 06:34:42.552531 systemd-logind[1888]: Removed session 18. Jul 7 06:34:42.656439 systemd[1]: Started sshd@16-10.200.4.4:22-10.200.16.10:49948.service - OpenSSH per-connection server daemon (10.200.16.10:49948). Jul 7 06:34:43.268611 sshd[12766]: Accepted publickey for core from 10.200.16.10 port 49948 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:43.269927 sshd-session[12766]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:43.274114 systemd-logind[1888]: New session 19 of user core. Jul 7 06:34:43.287305 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 7 06:34:43.694994 sshd[12768]: Connection closed by 10.200.16.10 port 49948 Jul 7 06:34:43.695292 sshd-session[12766]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:43.698713 systemd[1]: sshd@16-10.200.4.4:22-10.200.16.10:49948.service: Deactivated successfully. Jul 7 06:34:43.700338 systemd[1]: session-19.scope: Deactivated successfully. Jul 7 06:34:43.700980 systemd-logind[1888]: Session 19 logged out. Waiting for processes to exit. Jul 7 06:34:43.702240 systemd-logind[1888]: Removed session 19. Jul 7 06:34:53.805475 systemd[1]: Started sshd@17-10.200.4.4:22-10.200.16.10:59834.service - OpenSSH per-connection server daemon (10.200.16.10:59834). Jul 7 06:34:54.406648 sshd[12775]: Accepted publickey for core from 10.200.16.10 port 59834 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:54.407987 sshd-session[12775]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:54.412414 systemd-logind[1888]: New session 20 of user core. Jul 7 06:34:54.422285 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 7 06:34:54.584189 kernel: EXT4-fs (loop6p9): unmounting filesystem bbc9574b-3fa0-492d-bcc0-ab0a1bc7f9ec. Jul 7 06:34:54.584378 setup-nvidia[2253]: Container flatcardevelopercontainer-4372.0.1.bin exited successfully. Jul 7 06:34:54.586573 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d4372.0.1.bin.mount: Deactivated successfully. Jul 7 06:34:54.644057 sudo[2241]: pam_unix(sudo:session): session closed for user root Jul 7 06:34:54.646011 systemd[1]: session-c2.scope: Deactivated successfully. Jul 7 06:34:54.646276 systemd[1]: session-c2.scope: Consumed 5min 13.383s CPU time, 893.3M memory peak. Jul 7 06:34:54.829706 sshd[12777]: Connection closed by 10.200.16.10 port 59834 Jul 7 06:34:54.830087 sshd-session[12775]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:54.833550 systemd[1]: sshd@17-10.200.4.4:22-10.200.16.10:59834.service: Deactivated successfully. Jul 7 06:34:54.835221 systemd[1]: session-20.scope: Deactivated successfully. Jul 7 06:34:54.835900 systemd-logind[1888]: Session 20 logged out. Waiting for processes to exit. Jul 7 06:34:54.836889 systemd-logind[1888]: Removed session 20. Jul 7 06:34:54.939954 systemd[1]: Started sshd@18-10.200.4.4:22-10.200.16.10:59838.service - OpenSSH per-connection server daemon (10.200.16.10:59838). Jul 7 06:34:55.061265 setup-nvidia[1881]: /opt/nvidia / Jul 7 06:34:55.062990 setup-nvidia[1881]: / Jul 7 06:34:55.072184 kernel: IPMI message handler: version 39.2 Jul 7 06:34:55.076191 kernel: ipmi device interface Jul 7 06:34:55.088989 setup-nvidia[1881]: /opt/nvidia/current/usr/lib/modules/6.12.35-flatcar/video / Jul 7 06:34:55.145544 kernel: nvidia: loading out-of-tree module taints kernel. Jul 7 06:34:55.145680 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 7 06:34:55.145719 kernel: Disabling lock debugging due to kernel taint Jul 7 06:34:55.152921 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 7 06:34:55.153078 kernel: nvidia: module license taints kernel. Jul 7 06:34:55.554189 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 7 06:34:55.564933 sshd[12788]: Accepted publickey for core from 10.200.16.10 port 59838 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:34:55.566333 sshd-session[12788]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:34:55.570685 systemd-logind[1888]: New session 21 of user core. Jul 7 06:34:55.961431 kernel: Jul 7 06:34:55.961566 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 7 06:34:55.971379 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 7 06:34:56.015192 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.230.02 Fri Dec 20 21:42:05 UTC 2024 Jul 7 06:34:56.037198 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.230.02 Fri Dec 20 21:52:34 UTC 2024 Jul 7 06:34:56.058207 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 7 06:34:56.102856 setup-nvidia[1881]: / Jul 7 06:34:56.103184 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 7 06:34:56.118564 setup-nvidia[12820]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 7 06:34:56.319127 sshd[12812]: Connection closed by 10.200.16.10 port 59838 Jul 7 06:34:56.319506 sshd-session[12788]: pam_unix(sshd:session): session closed for user core Jul 7 06:34:56.322977 systemd[1]: sshd@18-10.200.4.4:22-10.200.16.10:59838.service: Deactivated successfully. Jul 7 06:34:56.324546 systemd[1]: session-21.scope: Deactivated successfully. Jul 7 06:34:56.325263 systemd-logind[1888]: Session 21 logged out. Waiting for processes to exit. Jul 7 06:34:56.326251 systemd-logind[1888]: Removed session 21. Jul 7 06:34:57.749719 setup-nvidia[12838]: Mon Jul 7 06:34:57 2025 Jul 7 06:34:57.749719 setup-nvidia[12838]: +---------------------------------------------------------------------------------------+ Jul 7 06:34:57.749719 setup-nvidia[12838]: | NVIDIA-SMI 535.230.02 Driver Version: 535.230.02 CUDA Version: 12.2 | Jul 7 06:34:57.749719 setup-nvidia[12838]: |-----------------------------------------+----------------------+----------------------+ Jul 7 06:34:57.749719 setup-nvidia[12838]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 7 06:34:57.749719 setup-nvidia[12838]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 7 06:34:57.749719 setup-nvidia[12838]: | | | MIG M. | Jul 7 06:34:57.749719 setup-nvidia[12838]: |=========================================+======================+======================| Jul 7 06:34:57.753396 setup-nvidia[12838]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jul 7 06:34:57.753396 setup-nvidia[12838]: | N/A 29C P0 33W / 250W | 0MiB / 16384MiB | 1% Default | Jul 7 06:34:57.753396 setup-nvidia[12838]: | | | N/A | Jul 7 06:34:57.753396 setup-nvidia[12838]: +-----------------------------------------+----------------------+----------------------+ Jul 7 06:34:57.753396 setup-nvidia[12838]: Jul 7 06:34:57.753396 setup-nvidia[12838]: +---------------------------------------------------------------------------------------+ Jul 7 06:34:57.753396 setup-nvidia[12838]: | Processes: | Jul 7 06:34:57.753396 setup-nvidia[12838]: | GPU GI CI PID Type Process name GPU Memory | Jul 7 06:34:57.753396 setup-nvidia[12838]: | ID ID Usage | Jul 7 06:34:57.753396 setup-nvidia[12838]: |=======================================================================================| Jul 7 06:34:57.753729 setup-nvidia[12838]: | No running processes found | Jul 7 06:34:57.753729 setup-nvidia[12838]: +---------------------------------------------------------------------------------------+ Jul 7 06:34:58.154908 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 7 06:34:58.157107 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 7 06:34:58.163038 systemd[1]: Startup finished in 1.310s (firmware) + 29.968s (loader) + 3.888s (kernel) + 13.436s (initrd) + 2min 52.958s (userspace) = 3min 41.561s. Jul 7 06:35:04.755887 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 7 06:35:04.756704 systemd[2244]: Activating special unit exit.target... Jul 7 06:35:04.756786 systemd[2244]: Stopped target default.target - Main User Target. Jul 7 06:35:04.756824 systemd[2244]: Stopped target basic.target - Basic System. Jul 7 06:35:04.756850 systemd[2244]: Stopped target paths.target - Paths. Jul 7 06:35:04.756874 systemd[2244]: Stopped target sockets.target - Sockets. Jul 7 06:35:04.756899 systemd[2244]: Stopped target timers.target - Timers. Jul 7 06:35:04.757272 systemd[2244]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 7 06:35:04.757480 systemd[2244]: Removed slice app.slice - User Application Slice. Jul 7 06:35:04.757512 systemd[2244]: Reached target shutdown.target - Shutdown. Jul 7 06:35:04.757661 systemd[2244]: Finished systemd-exit.service - Exit the Session. Jul 7 06:35:04.757697 systemd[2244]: Reached target exit.target - Exit the Session. Jul 7 06:35:04.778610 (sd-pam)[2245]: pam_unix(systemd-user:session): session closed for user root Jul 7 06:35:04.779934 systemd[1]: user@0.service: Deactivated successfully. Jul 7 06:35:04.780285 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 7 06:35:04.782498 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 7 06:35:04.804946 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 7 06:35:04.805856 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 7 06:35:04.806043 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 7 06:35:04.807631 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 7 06:35:04.807717 systemd[1]: user-0.slice: Consumed 5min 13.700s CPU time, 902.8M memory peak. Jul 7 06:35:04.808107 systemd-logind[1888]: Removed session c3. Jul 7 06:35:06.425758 systemd[1]: Started sshd@19-10.200.4.4:22-10.200.16.10:41046.service - OpenSSH per-connection server daemon (10.200.16.10:41046). Jul 7 06:35:07.036477 sshd-session[12852]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:07.040828 systemd-logind[1888]: New session 22 of user core. Jul 7 06:35:07.350488 sshd[12852]: Accepted publickey for core from 10.200.16.10 port 41046 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:07.050302 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 7 06:35:07.464694 sshd[12854]: Connection closed by 10.200.16.10 port 41046 Jul 7 06:35:07.465083 sshd-session[12852]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:07.468698 systemd[1]: sshd@19-10.200.4.4:22-10.200.16.10:41046.service: Deactivated successfully. Jul 7 06:35:07.470412 systemd[1]: session-22.scope: Deactivated successfully. Jul 7 06:35:07.471064 systemd-logind[1888]: Session 22 logged out. Waiting for processes to exit. Jul 7 06:35:07.472341 systemd-logind[1888]: Removed session 22. Jul 7 06:35:07.574448 systemd[1]: Started sshd@20-10.200.4.4:22-10.200.16.10:41062.service - OpenSSH per-connection server daemon (10.200.16.10:41062). Jul 7 06:35:08.197720 sshd[12860]: Accepted publickey for core from 10.200.16.10 port 41062 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:08.199080 sshd-session[12860]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:08.203462 systemd-logind[1888]: New session 23 of user core. Jul 7 06:35:08.220294 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 7 06:35:08.632994 sshd[12862]: Connection closed by 10.200.16.10 port 41062 Jul 7 06:35:08.633390 sshd-session[12860]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:08.637003 systemd[1]: sshd@20-10.200.4.4:22-10.200.16.10:41062.service: Deactivated successfully. Jul 7 06:35:08.638824 systemd[1]: session-23.scope: Deactivated successfully. Jul 7 06:35:08.639587 systemd-logind[1888]: Session 23 logged out. Waiting for processes to exit. Jul 7 06:35:08.640555 systemd-logind[1888]: Removed session 23. Jul 7 06:35:08.743794 systemd[1]: Started sshd@21-10.200.4.4:22-10.200.16.10:41066.service - OpenSSH per-connection server daemon (10.200.16.10:41066). Jul 7 06:35:09.358617 sshd[12868]: Accepted publickey for core from 10.200.16.10 port 41066 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:09.359950 sshd-session[12868]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:09.364466 systemd-logind[1888]: New session 24 of user core. Jul 7 06:35:09.373311 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 7 06:35:09.785698 sshd[12870]: Connection closed by 10.200.16.10 port 41066 Jul 7 06:35:09.786073 sshd-session[12868]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:09.789471 systemd[1]: sshd@21-10.200.4.4:22-10.200.16.10:41066.service: Deactivated successfully. Jul 7 06:35:09.791068 systemd[1]: session-24.scope: Deactivated successfully. Jul 7 06:35:09.791728 systemd-logind[1888]: Session 24 logged out. Waiting for processes to exit. Jul 7 06:35:09.792650 systemd-logind[1888]: Removed session 24. Jul 7 06:35:09.895358 systemd[1]: Started sshd@22-10.200.4.4:22-10.200.16.10:49306.service - OpenSSH per-connection server daemon (10.200.16.10:49306). Jul 7 06:35:10.492154 sshd[12876]: Accepted publickey for core from 10.200.16.10 port 49306 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:10.493479 sshd-session[12876]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:10.497648 systemd-logind[1888]: New session 25 of user core. Jul 7 06:35:10.511297 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 7 06:35:10.820487 sudo[12879]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jul 7 06:35:10.820791 sudo[12879]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 06:35:10.868389 sudo[12879]: pam_unix(sudo:session): session closed for user root Jul 7 06:35:10.970671 sshd[12878]: Connection closed by 10.200.16.10 port 49306 Jul 7 06:35:10.971057 sshd-session[12876]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:10.974095 systemd[1]: sshd@22-10.200.4.4:22-10.200.16.10:49306.service: Deactivated successfully. Jul 7 06:35:10.975808 systemd[1]: session-25.scope: Deactivated successfully. Jul 7 06:35:10.976503 systemd-logind[1888]: Session 25 logged out. Waiting for processes to exit. Jul 7 06:35:10.977928 systemd-logind[1888]: Removed session 25. Jul 7 06:35:11.080642 systemd[1]: Started sshd@23-10.200.4.4:22-10.200.16.10:49308.service - OpenSSH per-connection server daemon (10.200.16.10:49308). Jul 7 06:35:11.703575 sshd[12885]: Accepted publickey for core from 10.200.16.10 port 49308 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:11.705041 sshd-session[12885]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:11.709385 systemd-logind[1888]: New session 26 of user core. Jul 7 06:35:11.723338 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 7 06:35:12.033489 sudo[12889]: 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 Jul 7 06:35:12.033782 sudo[12889]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 06:35:12.040651 sudo[12889]: pam_unix(sudo:session): session closed for user root Jul 7 06:35:12.046011 sudo[12888]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Jul 7 06:35:12.046325 sudo[12888]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 06:35:12.056956 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 7 06:35:12.105628 augenrules[12911]: No rules Jul 7 06:35:12.106907 systemd[1]: audit-rules.service: Deactivated successfully. Jul 7 06:35:12.107153 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 7 06:35:12.108039 sudo[12888]: pam_unix(sudo:session): session closed for user root Jul 7 06:35:12.217047 sshd[12887]: Connection closed by 10.200.16.10 port 49308 Jul 7 06:35:12.217455 sshd-session[12885]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:12.221144 systemd[1]: sshd@23-10.200.4.4:22-10.200.16.10:49308.service: Deactivated successfully. Jul 7 06:35:12.222870 systemd[1]: session-26.scope: Deactivated successfully. Jul 7 06:35:12.223606 systemd-logind[1888]: Session 26 logged out. Waiting for processes to exit. Jul 7 06:35:12.224567 systemd-logind[1888]: Removed session 26. Jul 7 06:35:12.334700 systemd[1]: Started sshd@24-10.200.4.4:22-10.200.16.10:49318.service - OpenSSH per-connection server daemon (10.200.16.10:49318). Jul 7 06:35:12.953469 sshd[12920]: Accepted publickey for core from 10.200.16.10 port 49318 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:12.954857 sshd-session[12920]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:12.959268 systemd-logind[1888]: New session 27 of user core. Jul 7 06:35:12.975301 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 7 06:35:13.281466 sudo[12923]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Jul 7 06:35:13.281763 sudo[12923]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 06:35:13.284885 sudo[12923]: pam_unix(sudo:session): session closed for user root Jul 7 06:35:13.386876 sshd[12922]: Connection closed by 10.200.16.10 port 49318 Jul 7 06:35:13.387277 sshd-session[12920]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:13.391612 systemd[1]: sshd@24-10.200.4.4:22-10.200.16.10:49318.service: Deactivated successfully. Jul 7 06:35:13.393210 systemd[1]: session-27.scope: Deactivated successfully. Jul 7 06:35:13.393851 systemd-logind[1888]: Session 27 logged out. Waiting for processes to exit. Jul 7 06:35:13.394791 systemd-logind[1888]: Removed session 27. Jul 7 06:35:13.496519 systemd[1]: Started sshd@25-10.200.4.4:22-10.200.16.10:49322.service - OpenSSH per-connection server daemon (10.200.16.10:49322). Jul 7 06:35:14.116976 sshd[12929]: Accepted publickey for core from 10.200.16.10 port 49322 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:14.118374 sshd-session[12929]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:14.122735 systemd-logind[1888]: New session 28 of user core. Jul 7 06:35:14.132290 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 7 06:35:14.445037 sudo[12934]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Jul 7 06:35:14.445349 sudo[12934]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Jul 7 06:35:17.550618 sudo[12934]: pam_unix(sudo:session): session closed for user root Jul 7 06:35:17.645113 sshd[12931]: Connection closed by 10.200.16.10 port 49322 Jul 7 06:35:17.645393 sshd-session[12929]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:17.648980 systemd[1]: sshd@25-10.200.4.4:22-10.200.16.10:49322.service: Deactivated successfully. Jul 7 06:35:17.650655 systemd[1]: session-28.scope: Deactivated successfully. Jul 7 06:35:17.651365 systemd-logind[1888]: Session 28 logged out. Waiting for processes to exit. Jul 7 06:35:17.652343 systemd-logind[1888]: Removed session 28. Jul 7 06:35:17.755478 systemd[1]: Started sshd@26-10.200.4.4:22-10.200.16.10:49326.service - OpenSSH per-connection server daemon (10.200.16.10:49326). Jul 7 06:35:18.382243 sshd[12941]: Accepted publickey for core from 10.200.16.10 port 49326 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:18.383636 sshd-session[12941]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:18.388271 systemd-logind[1888]: New session 29 of user core. Jul 7 06:35:18.399300 systemd[1]: Started session-29.scope - Session 29 of User core. Jul 7 06:35:19.517923 systemd[1]: Starting docker.service - Docker Application Container Engine... Jul 7 06:35:19.535517 (dockerd)[12964]: 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 Jul 7 06:35:20.862173 dockerd[12964]: time="2025-07-07T06:35:20.862102976Z" level=info msg="Starting up" Jul 7 06:35:20.862783 dockerd[12964]: time="2025-07-07T06:35:20.862758849Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jul 7 06:35:21.304738 dockerd[12964]: time="2025-07-07T06:35:21.304685891Z" level=info msg="Loading containers: start." Jul 7 06:35:21.318187 kernel: Initializing XFRM netlink socket Jul 7 06:35:21.629304 systemd-networkd[1586]: docker0: Link UP Jul 7 06:35:21.644775 dockerd[12964]: time="2025-07-07T06:35:21.644734089Z" level=info msg="Loading containers: done." Jul 7 06:35:21.658303 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3588635731-merged.mount: Deactivated successfully. Jul 7 06:35:21.666195 dockerd[12964]: time="2025-07-07T06:35:21.666140294Z" 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 Jul 7 06:35:21.666272 dockerd[12964]: time="2025-07-07T06:35:21.666234590Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 Jul 7 06:35:21.666399 dockerd[12964]: time="2025-07-07T06:35:21.666373885Z" level=info msg="Initializing buildkit" Jul 7 06:35:21.716575 dockerd[12964]: time="2025-07-07T06:35:21.716537990Z" level=info msg="Completed buildkit initialization" Jul 7 06:35:21.723049 dockerd[12964]: time="2025-07-07T06:35:21.723014919Z" level=info msg="Daemon has completed initialization" Jul 7 06:35:21.723139 dockerd[12964]: time="2025-07-07T06:35:21.723079416Z" level=info msg="API listen on /run/docker.sock" Jul 7 06:35:21.723270 systemd[1]: Started docker.service - Docker Application Container Engine. Jul 7 06:35:22.904921 systemd[1]: var-lib-docker-overlay2-0f31f948e04237643bf2605b1545c69272212c86e447ef8849ef53c0c2d87459\x2dinit-merged.mount: Deactivated successfully. Jul 7 06:35:23.028068 containerd[1902]: time="2025-07-07T06:35:23.028014393Z" level=info msg="connecting to shim 60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76" address="unix:///run/containerd/s/ae966116070ba59c70dc8d5744ee455b2e314189f856b0e37a3e10031ae82fc7" namespace=moby protocol=ttrpc version=3 Jul 7 06:35:23.059291 systemd[1]: Started docker-60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76.scope - libcontainer container 60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76. Jul 7 06:35:23.110610 kernel: docker0: port 1(veth470ef8e) entered blocking state Jul 7 06:35:23.110702 kernel: docker0: port 1(veth470ef8e) entered disabled state Jul 7 06:35:23.110732 kernel: veth470ef8e: entered allmulticast mode Jul 7 06:35:23.115646 kernel: veth470ef8e: entered promiscuous mode Jul 7 06:35:23.115990 systemd-networkd[1586]: veth470ef8e: Link UP Jul 7 06:35:23.145181 kernel: eth0: renamed from veth0d30535 Jul 7 06:35:23.145230 kernel: docker0: port 1(veth470ef8e) entered blocking state Jul 7 06:35:23.152123 kernel: docker0: port 1(veth470ef8e) entered forwarding state Jul 7 06:35:23.152258 systemd-networkd[1586]: veth470ef8e: Gained carrier Jul 7 06:35:23.152609 systemd-networkd[1586]: docker0: Gained carrier Jul 7 06:35:24.700322 systemd-networkd[1586]: veth470ef8e: Gained IPv6LL Jul 7 06:35:25.084323 systemd-networkd[1586]: docker0: Gained IPv6LL Jul 7 06:35:26.435193 systemd[1]: docker-60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76.scope: Deactivated successfully. Jul 7 06:35:26.448521 containerd[1902]: time="2025-07-07T06:35:26.448469311Z" level=info msg="shim disconnected" id=60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76 namespace=moby Jul 7 06:35:26.448521 containerd[1902]: time="2025-07-07T06:35:26.448505910Z" level=warning msg="cleaning up after shim disconnected" id=60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76 namespace=moby Jul 7 06:35:26.448870 dockerd[12964]: time="2025-07-07T06:35:26.448463512Z" level=info msg="ignoring event" container=60eba4b453952d3f9c4bb0e8725ac9a0dbf12814d3ccb0c98e1bd9c2c187cc76 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 7 06:35:26.449096 containerd[1902]: time="2025-07-07T06:35:26.448514909Z" level=info msg="cleaning up dead shim" namespace=moby Jul 7 06:35:26.479323 systemd-networkd[1586]: veth470ef8e: Lost carrier Jul 7 06:35:26.479908 kernel: docker0: port 1(veth470ef8e) entered disabled state Jul 7 06:35:26.479948 kernel: veth0d30535: renamed from eth0 Jul 7 06:35:26.506856 systemd-networkd[1586]: veth470ef8e: Link DOWN Jul 7 06:35:26.510181 kernel: docker0: port 1(veth470ef8e) entered disabled state Jul 7 06:35:26.516509 kernel: veth470ef8e (unregistering): left allmulticast mode Jul 7 06:35:26.516565 kernel: veth470ef8e (unregistering): left promiscuous mode Jul 7 06:35:26.519940 kernel: docker0: port 1(veth470ef8e) entered disabled state Jul 7 06:35:26.540527 systemd[1]: run-docker-netns-2a6fba42268b.mount: Deactivated successfully. Jul 7 06:35:26.544968 systemd[1]: var-lib-docker-overlay2-0f31f948e04237643bf2605b1545c69272212c86e447ef8849ef53c0c2d87459-merged.mount: Deactivated successfully. Jul 7 06:35:26.666182 sshd[12943]: Connection closed by 10.200.16.10 port 49326 Jul 7 06:35:26.666581 sshd-session[12941]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:26.670139 systemd[1]: sshd@26-10.200.4.4:22-10.200.16.10:49326.service: Deactivated successfully. Jul 7 06:35:26.671805 systemd[1]: session-29.scope: Deactivated successfully. Jul 7 06:35:26.672492 systemd-logind[1888]: Session 29 logged out. Waiting for processes to exit. Jul 7 06:35:26.673458 systemd-logind[1888]: Removed session 29. Jul 7 06:35:26.784457 systemd[1]: Started sshd@27-10.200.4.4:22-10.200.16.10:40746.service - OpenSSH per-connection server daemon (10.200.16.10:40746). Jul 7 06:35:27.385455 sshd[13246]: Accepted publickey for core from 10.200.16.10 port 40746 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:27.386807 sshd-session[13246]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:27.391074 systemd-logind[1888]: New session 30 of user core. Jul 7 06:35:27.399294 systemd[1]: Started session-30.scope - Session 30 of User core. Jul 7 06:35:27.516444 systemd-networkd[1586]: docker0: Lost carrier Jul 7 06:35:27.922705 containerd[1902]: time="2025-07-07T06:35:27.922659270Z" level=info msg="connecting to shim 1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047" address="unix:///run/containerd/s/8baa4cc2ba213b4e284915686b575c85c89cf6dfb8233c0c93cdde0f11f106cb" namespace=moby protocol=ttrpc version=3 Jul 7 06:35:27.957296 systemd[1]: Started docker-1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047.scope - libcontainer container 1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047. Jul 7 06:35:28.002010 kernel: docker0: port 1(veth28b1fcc) entered blocking state Jul 7 06:35:28.002099 kernel: docker0: port 1(veth28b1fcc) entered disabled state Jul 7 06:35:28.004812 kernel: veth28b1fcc: entered allmulticast mode Jul 7 06:35:28.005211 kernel: veth28b1fcc: entered promiscuous mode Jul 7 06:35:28.008147 systemd-networkd[1586]: veth28b1fcc: Link UP Jul 7 06:35:28.028184 kernel: eth0: renamed from veth8d01b18 Jul 7 06:35:28.028307 kernel: docker0: port 1(veth28b1fcc) entered blocking state Jul 7 06:35:28.034311 kernel: docker0: port 1(veth28b1fcc) entered forwarding state Jul 7 06:35:28.034757 systemd-networkd[1586]: veth28b1fcc: Gained carrier Jul 7 06:35:28.035052 systemd-networkd[1586]: docker0: Gained carrier Jul 7 06:35:28.072137 systemd[1]: docker-1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047.scope: Deactivated successfully. Jul 7 06:35:28.099487 containerd[1902]: time="2025-07-07T06:35:28.099377421Z" level=info msg="shim disconnected" id=1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047 namespace=moby Jul 7 06:35:28.099487 containerd[1902]: time="2025-07-07T06:35:28.099411919Z" level=warning msg="cleaning up after shim disconnected" id=1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047 namespace=moby Jul 7 06:35:28.099487 containerd[1902]: time="2025-07-07T06:35:28.099420619Z" level=info msg="cleaning up dead shim" namespace=moby Jul 7 06:35:28.099671 dockerd[12964]: time="2025-07-07T06:35:28.099565313Z" level=info msg="ignoring event" container=1b9314890b823673388abcbfc67e5c8ee9ffe4179b23a36b1261fbd41c5a2047 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Jul 7 06:35:28.130955 systemd-networkd[1586]: veth28b1fcc: Lost carrier Jul 7 06:35:28.131264 kernel: docker0: port 1(veth28b1fcc) entered disabled state Jul 7 06:35:28.131365 kernel: veth8d01b18: renamed from eth0 Jul 7 06:35:28.149180 kernel: docker0: port 1(veth28b1fcc) entered disabled state Jul 7 06:35:28.155324 kernel: veth28b1fcc (unregistering): left allmulticast mode Jul 7 06:35:28.155372 kernel: veth28b1fcc (unregistering): left promiscuous mode Jul 7 06:35:28.158540 kernel: docker0: port 1(veth28b1fcc) entered disabled state Jul 7 06:35:28.159200 systemd-networkd[1586]: veth28b1fcc: Link DOWN Jul 7 06:35:28.301947 sshd[13248]: Connection closed by 10.200.16.10 port 40746 Jul 7 06:35:28.302340 sshd-session[13246]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:28.305159 systemd[1]: sshd@27-10.200.4.4:22-10.200.16.10:40746.service: Deactivated successfully. Jul 7 06:35:28.306867 systemd[1]: session-30.scope: Deactivated successfully. Jul 7 06:35:28.308279 systemd-logind[1888]: Session 30 logged out. Waiting for processes to exit. Jul 7 06:35:28.309279 systemd-logind[1888]: Removed session 30. Jul 7 06:35:28.410561 systemd[1]: Started sshd@28-10.200.4.4:22-10.200.16.10:40760.service - OpenSSH per-connection server daemon (10.200.16.10:40760). Jul 7 06:35:28.524409 systemd-networkd[1586]: docker0: Lost carrier Jul 7 06:35:28.769332 systemd[1]: var-lib-docker-overlay2-4152debfd4b1a15b6476ef2a465bbbf8bf5c4ec71aa7cffb3e0148a6d131a146-merged.mount: Deactivated successfully. Jul 7 06:35:29.006717 sshd[13351]: Accepted publickey for core from 10.200.16.10 port 40760 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:29.008142 sshd-session[13351]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:29.012475 systemd-logind[1888]: New session 31 of user core. Jul 7 06:35:29.026289 systemd[1]: Started session-31.scope - Session 31 of User core. Jul 7 06:35:29.384288 ntpdate[13364]: ntpdate 4.2.8p17@1.4004-o Sun Jul 6 21:17:12 UTC 2025 (1) Jul 7 06:35:36.261720 ntpdate[13364]: adjust time server 85.199.214.100 offset -0.000297 sec Jul 7 06:35:36.357351 sshd[13353]: Connection closed by 10.200.16.10 port 40760 Jul 7 06:35:36.357737 sshd-session[13351]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:36.361230 systemd[1]: sshd@28-10.200.4.4:22-10.200.16.10:40760.service: Deactivated successfully. Jul 7 06:35:36.362835 systemd[1]: session-31.scope: Deactivated successfully. Jul 7 06:35:36.363507 systemd-logind[1888]: Session 31 logged out. Waiting for processes to exit. Jul 7 06:35:36.364468 systemd-logind[1888]: Removed session 31. Jul 7 06:35:36.466557 systemd[1]: Started sshd@29-10.200.4.4:22-10.200.16.10:60444.service - OpenSSH per-connection server daemon (10.200.16.10:60444). Jul 7 06:35:37.092708 sshd[13369]: Accepted publickey for core from 10.200.16.10 port 60444 ssh2: RSA SHA256:BjY1cpa4vOHWVVE6VJtcFmhJLPpAxOHcSMP2lyQ/XKA Jul 7 06:35:37.094082 sshd-session[13369]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 7 06:35:37.098377 systemd-logind[1888]: New session 32 of user core. Jul 7 06:35:37.115305 systemd[1]: Started session-32.scope - Session 32 of User core. Jul 7 06:35:37.562351 sshd[13371]: Connection closed by 10.200.16.10 port 60444 Jul 7 06:35:37.562697 sshd-session[13369]: pam_unix(sshd:session): session closed for user core Jul 7 06:35:37.565375 systemd[1]: sshd@29-10.200.4.4:22-10.200.16.10:60444.service: Deactivated successfully. Jul 7 06:35:37.568737 systemd[1]: session-32.scope: Deactivated successfully. Jul 7 06:35:37.569975 systemd-logind[1888]: Session 32 logged out. Waiting for processes to exit. Jul 7 06:35:37.571042 systemd-logind[1888]: Removed session 32.