May 27 03:18:38.960235 kernel: Linux version 6.12.30-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 Tue May 27 01:09:43 -00 2025 May 27 03:18:38.960254 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=f6c186658a19d5a08471ef76df75f82494b37b46908f9237b2c3cf497da860c6 May 27 03:18:38.960261 kernel: BIOS-provided physical RAM map: May 27 03:18:38.960266 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable May 27 03:18:38.960271 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved May 27 03:18:38.960275 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable May 27 03:18:38.960281 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc4fff] reserved May 27 03:18:38.960286 kernel: BIOS-e820: [mem 0x000000003ffc5000-0x000000003ffd0fff] usable May 27 03:18:38.960290 kernel: BIOS-e820: [mem 0x000000003ffd1000-0x000000003fffafff] ACPI data May 27 03:18:38.960295 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS May 27 03:18:38.960299 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable May 27 03:18:38.960304 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable May 27 03:18:38.960308 kernel: printk: legacy bootconsole [earlyser0] enabled May 27 03:18:38.960312 kernel: NX (Execute Disable) protection: active May 27 03:18:38.960319 kernel: APIC: Static calls initialized May 27 03:18:38.960324 kernel: efi: EFI v2.7 by Microsoft May 27 03:18:38.960329 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3ebb9a98 RNG=0x3ffd2018 May 27 03:18:38.960334 kernel: random: crng init done May 27 03:18:38.960338 kernel: secureboot: Secure boot disabled May 27 03:18:38.960343 kernel: SMBIOS 3.1.0 present. May 27 03:18:38.960348 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 11/21/2024 May 27 03:18:38.960352 kernel: DMI: Memory slots populated: 2/2 May 27 03:18:38.960358 kernel: Hypervisor detected: Microsoft Hyper-V May 27 03:18:38.960363 kernel: Hyper-V: privilege flags low 0xae7f, high 0x3b8030, hints 0x9e4e24, misc 0xe0bed7b2 May 27 03:18:38.960368 kernel: Hyper-V: Nested features: 0x3e0101 May 27 03:18:38.960372 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 May 27 03:18:38.960377 kernel: Hyper-V: Using hypercall for remote TLB flush May 27 03:18:38.960382 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 27 03:18:38.960386 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns May 27 03:18:38.960391 kernel: tsc: Detected 2300.001 MHz processor May 27 03:18:38.960396 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved May 27 03:18:38.960401 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable May 27 03:18:38.960406 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x10000000000 May 27 03:18:38.960413 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs May 27 03:18:38.960418 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT May 27 03:18:38.960423 kernel: e820: update [mem 0x48000000-0xffffffff] usable ==> reserved May 27 03:18:38.960427 kernel: last_pfn = 0x40000 max_arch_pfn = 0x10000000000 May 27 03:18:38.960432 kernel: Using GB pages for direct mapping May 27 03:18:38.960437 kernel: ACPI: Early table checksum verification disabled May 27 03:18:38.960442 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) May 27 03:18:38.960450 kernel: ACPI: XSDT 0x000000003FFF90E8 00005C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 03:18:38.960455 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 03:18:38.960460 kernel: ACPI: DSDT 0x000000003FFD6000 01E11C (v02 MSFTVM DSDT01 00000001 INTL 20230628) May 27 03:18:38.960465 kernel: ACPI: FACS 0x000000003FFFE000 000040 May 27 03:18:38.960470 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 03:18:38.960475 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 03:18:38.960481 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 03:18:38.960486 kernel: ACPI: APIC 0x000000003FFD5000 000052 (v05 HVLITE HVLITETB 00000000 MSHV 00000000) May 27 03:18:38.960491 kernel: ACPI: SRAT 0x000000003FFD4000 0000A0 (v03 HVLITE HVLITETB 00000000 MSHV 00000000) May 27 03:18:38.960497 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 03:18:38.960502 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] May 27 03:18:38.960507 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff411b] May 27 03:18:38.960512 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] May 27 03:18:38.960517 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] May 27 03:18:38.960522 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] May 27 03:18:38.960528 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] May 27 03:18:38.960533 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5051] May 27 03:18:38.960538 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd409f] May 27 03:18:38.960543 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] May 27 03:18:38.960548 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] May 27 03:18:38.960553 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff] May 27 03:18:38.960558 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00001000-0x2bfffffff] May 27 03:18:38.960563 kernel: NODE_DATA(0) allocated [mem 0x2bfff8dc0-0x2bfffffff] May 27 03:18:38.960569 kernel: Zone ranges: May 27 03:18:38.960575 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] May 27 03:18:38.960580 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] May 27 03:18:38.960585 kernel: Normal [mem 0x0000000100000000-0x00000002bfffffff] May 27 03:18:38.960590 kernel: Device empty May 27 03:18:38.960595 kernel: Movable zone start for each node May 27 03:18:38.960600 kernel: Early memory node ranges May 27 03:18:38.960605 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] May 27 03:18:38.960610 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] May 27 03:18:38.960615 kernel: node 0: [mem 0x000000003ffc5000-0x000000003ffd0fff] May 27 03:18:38.960621 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] May 27 03:18:38.960626 kernel: node 0: [mem 0x0000000100000000-0x00000002bfffffff] May 27 03:18:38.960630 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff] May 27 03:18:38.960635 kernel: On node 0, zone DMA: 1 pages in unavailable ranges May 27 03:18:38.960640 kernel: On node 0, zone DMA: 96 pages in unavailable ranges May 27 03:18:38.960645 kernel: On node 0, zone DMA32: 132 pages in unavailable ranges May 27 03:18:38.960650 kernel: On node 0, zone DMA32: 46 pages in unavailable ranges May 27 03:18:38.960655 kernel: ACPI: PM-Timer IO Port: 0x408 May 27 03:18:38.960661 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 May 27 03:18:38.960667 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) May 27 03:18:38.960672 kernel: ACPI: Using ACPI (MADT) for SMP configuration information May 27 03:18:38.960677 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 May 27 03:18:38.960681 kernel: TSC deadline timer available May 27 03:18:38.960687 kernel: CPU topo: Max. logical packages: 1 May 27 03:18:38.960692 kernel: CPU topo: Max. logical dies: 1 May 27 03:18:38.960696 kernel: CPU topo: Max. dies per package: 1 May 27 03:18:38.960701 kernel: CPU topo: Max. threads per core: 2 May 27 03:18:38.960706 kernel: CPU topo: Num. cores per package: 1 May 27 03:18:38.960712 kernel: CPU topo: Num. threads per package: 2 May 27 03:18:38.960717 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs May 27 03:18:38.960722 kernel: [mem 0x40000000-0xffffffff] available for PCI devices May 27 03:18:38.960727 kernel: Booting paravirtualized kernel on Hyper-V May 27 03:18:38.960733 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns May 27 03:18:38.960738 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 May 27 03:18:38.960743 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576 May 27 03:18:38.960748 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152 May 27 03:18:38.960753 kernel: pcpu-alloc: [0] 0 1 May 27 03:18:38.960760 kernel: Hyper-V: PV spinlocks enabled May 27 03:18:38.960764 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) May 27 03:18:38.960770 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=f6c186658a19d5a08471ef76df75f82494b37b46908f9237b2c3cf497da860c6 May 27 03:18:38.960774 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 27 03:18:38.960779 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) May 27 03:18:38.960783 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 27 03:18:38.960788 kernel: Fallback order for Node 0: 0 May 27 03:18:38.960792 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2096877 May 27 03:18:38.960798 kernel: Policy zone: Normal May 27 03:18:38.960802 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 27 03:18:38.960806 kernel: software IO TLB: area num 2. May 27 03:18:38.960811 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 27 03:18:38.960815 kernel: ftrace: allocating 40081 entries in 157 pages May 27 03:18:38.960820 kernel: ftrace: allocated 157 pages with 5 groups May 27 03:18:38.960824 kernel: Dynamic Preempt: voluntary May 27 03:18:38.960828 kernel: rcu: Preemptible hierarchical RCU implementation. May 27 03:18:38.960835 kernel: rcu: RCU event tracing is enabled. May 27 03:18:38.960841 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 27 03:18:38.960849 kernel: Trampoline variant of Tasks RCU enabled. May 27 03:18:38.960854 kernel: Rude variant of Tasks RCU enabled. May 27 03:18:38.960859 kernel: Tracing variant of Tasks RCU enabled. May 27 03:18:38.960864 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 27 03:18:38.960869 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 27 03:18:38.960874 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 27 03:18:38.960879 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 27 03:18:38.960884 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 27 03:18:38.960888 kernel: Using NULL legacy PIC May 27 03:18:38.960893 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0 May 27 03:18:38.960899 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 27 03:18:38.960903 kernel: Console: colour dummy device 80x25 May 27 03:18:38.960908 kernel: printk: legacy console [tty1] enabled May 27 03:18:38.960913 kernel: printk: legacy console [ttyS0] enabled May 27 03:18:38.960918 kernel: printk: legacy bootconsole [earlyser0] disabled May 27 03:18:38.960923 kernel: ACPI: Core revision 20240827 May 27 03:18:38.960928 kernel: Failed to register legacy timer interrupt May 27 03:18:38.960933 kernel: APIC: Switch to symmetric I/O mode setup May 27 03:18:38.960938 kernel: x2apic enabled May 27 03:18:38.960943 kernel: APIC: Switched APIC routing to: physical x2apic May 27 03:18:38.960947 kernel: Hyper-V: Host Build 10.0.26100.1221-1-0 May 27 03:18:38.960952 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 27 03:18:38.960957 kernel: Hyper-V: Disabling IBT because of Hyper-V bug May 27 03:18:38.960962 kernel: Hyper-V: Using IPI hypercalls May 27 03:18:38.960967 kernel: APIC: send_IPI() replaced with hv_send_ipi() May 27 03:18:38.960972 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() May 27 03:18:38.960977 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() May 27 03:18:38.960982 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() May 27 03:18:38.960987 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() May 27 03:18:38.960992 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() May 27 03:18:38.960997 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212735f0517, max_idle_ns: 440795237604 ns May 27 03:18:38.961002 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4600.00 BogoMIPS (lpj=2300001) May 27 03:18:38.961006 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated May 27 03:18:38.961011 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 May 27 03:18:38.961017 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 May 27 03:18:38.961022 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization May 27 03:18:38.961026 kernel: Spectre V2 : Mitigation: Retpolines May 27 03:18:38.961031 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT May 27 03:18:38.961036 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! May 27 03:18:38.961041 kernel: RETBleed: Vulnerable May 27 03:18:38.961045 kernel: Speculative Store Bypass: Vulnerable May 27 03:18:38.961050 kernel: ITS: Mitigation: Aligned branch/return thunks May 27 03:18:38.961055 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' May 27 03:18:38.961059 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' May 27 03:18:38.961064 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' May 27 03:18:38.961069 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' May 27 03:18:38.961074 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' May 27 03:18:38.961079 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' May 27 03:18:38.961083 kernel: x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' May 27 03:18:38.961088 kernel: x86/fpu: Supporting XSAVE feature 0x20000: 'AMX Tile config' May 27 03:18:38.961092 kernel: x86/fpu: Supporting XSAVE feature 0x40000: 'AMX Tile data' May 27 03:18:38.961097 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 May 27 03:18:38.961102 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 May 27 03:18:38.961106 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 May 27 03:18:38.961111 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 May 27 03:18:38.961117 kernel: x86/fpu: xstate_offset[11]: 2432, xstate_sizes[11]: 16 May 27 03:18:38.961123 kernel: x86/fpu: xstate_offset[17]: 2496, xstate_sizes[17]: 64 May 27 03:18:38.961128 kernel: x86/fpu: xstate_offset[18]: 2560, xstate_sizes[18]: 8192 May 27 03:18:38.961133 kernel: x86/fpu: Enabled xstate features 0x608e7, context size is 10752 bytes, using 'compacted' format. May 27 03:18:38.961139 kernel: Freeing SMP alternatives memory: 32K May 27 03:18:38.961144 kernel: pid_max: default: 32768 minimum: 301 May 27 03:18:38.961149 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 27 03:18:38.961154 kernel: landlock: Up and running. May 27 03:18:38.961160 kernel: SELinux: Initializing. May 27 03:18:38.961197 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) May 27 03:18:38.961205 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) May 27 03:18:38.961213 kernel: smpboot: CPU0: Intel INTEL(R) XEON(R) PLATINUM 8573C (family: 0x6, model: 0xcf, stepping: 0x2) May 27 03:18:38.961223 kernel: Performance Events: unsupported p6 CPU model 207 no PMU driver, software events only. May 27 03:18:38.961232 kernel: signal: max sigframe size: 11952 May 27 03:18:38.961241 kernel: rcu: Hierarchical SRCU implementation. May 27 03:18:38.961251 kernel: rcu: Max phase no-delay instances is 400. May 27 03:18:38.961260 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 27 03:18:38.961270 kernel: NMI watchdog: Perf NMI watchdog permanently disabled May 27 03:18:38.961279 kernel: smp: Bringing up secondary CPUs ... May 27 03:18:38.961288 kernel: smpboot: x86: Booting SMP configuration: May 27 03:18:38.961296 kernel: .... node #0, CPUs: #1 May 27 03:18:38.961307 kernel: smp: Brought up 1 node, 2 CPUs May 27 03:18:38.961316 kernel: smpboot: Total of 2 processors activated (9200.00 BogoMIPS) May 27 03:18:38.961325 kernel: Memory: 8082312K/8387508K available (14336K kernel code, 2430K rwdata, 9952K rodata, 54416K init, 2552K bss, 299988K reserved, 0K cma-reserved) May 27 03:18:38.961333 kernel: devtmpfs: initialized May 27 03:18:38.961342 kernel: x86/mm: Memory block size: 128MB May 27 03:18:38.961350 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) May 27 03:18:38.961359 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 27 03:18:38.961369 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 27 03:18:38.961378 kernel: pinctrl core: initialized pinctrl subsystem May 27 03:18:38.961389 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 27 03:18:38.961398 kernel: audit: initializing netlink subsys (disabled) May 27 03:18:38.961407 kernel: audit: type=2000 audit(1748315916.030:1): state=initialized audit_enabled=0 res=1 May 27 03:18:38.961416 kernel: thermal_sys: Registered thermal governor 'step_wise' May 27 03:18:38.961425 kernel: thermal_sys: Registered thermal governor 'user_space' May 27 03:18:38.961434 kernel: cpuidle: using governor menu May 27 03:18:38.961443 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 27 03:18:38.961452 kernel: dca service started, version 1.12.1 May 27 03:18:38.961460 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] May 27 03:18:38.961470 kernel: e820: reserve RAM buffer [mem 0x3ffd1000-0x3fffffff] May 27 03:18:38.961480 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. May 27 03:18:38.961490 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 27 03:18:38.961499 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page May 27 03:18:38.961508 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 27 03:18:38.961517 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page May 27 03:18:38.961527 kernel: ACPI: Added _OSI(Module Device) May 27 03:18:38.961536 kernel: ACPI: Added _OSI(Processor Device) May 27 03:18:38.961544 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 27 03:18:38.961555 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 27 03:18:38.961564 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 27 03:18:38.961572 kernel: ACPI: Interpreter enabled May 27 03:18:38.961581 kernel: ACPI: PM: (supports S0 S5) May 27 03:18:38.961590 kernel: ACPI: Using IOAPIC for interrupt routing May 27 03:18:38.961599 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug May 27 03:18:38.961609 kernel: PCI: Ignoring E820 reservations for host bridge windows May 27 03:18:38.961619 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F May 27 03:18:38.961627 kernel: iommu: Default domain type: Translated May 27 03:18:38.961636 kernel: iommu: DMA domain TLB invalidation policy: lazy mode May 27 03:18:38.961645 kernel: efivars: Registered efivars operations May 27 03:18:38.961653 kernel: PCI: Using ACPI for IRQ routing May 27 03:18:38.961662 kernel: PCI: System does not support PCI May 27 03:18:38.961670 kernel: vgaarb: loaded May 27 03:18:38.961678 kernel: clocksource: Switched to clocksource tsc-early May 27 03:18:38.961686 kernel: VFS: Disk quotas dquot_6.6.0 May 27 03:18:38.961695 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 27 03:18:38.961703 kernel: pnp: PnP ACPI init May 27 03:18:38.961713 kernel: pnp: PnP ACPI: found 3 devices May 27 03:18:38.961722 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns May 27 03:18:38.961732 kernel: NET: Registered PF_INET protocol family May 27 03:18:38.961741 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) May 27 03:18:38.961751 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) May 27 03:18:38.961761 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 27 03:18:38.961770 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) May 27 03:18:38.961779 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) May 27 03:18:38.961788 kernel: TCP: Hash tables configured (established 65536 bind 65536) May 27 03:18:38.961799 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) May 27 03:18:38.961809 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) May 27 03:18:38.961818 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 27 03:18:38.961827 kernel: NET: Registered PF_XDP protocol family May 27 03:18:38.961837 kernel: PCI: CLS 0 bytes, default 64 May 27 03:18:38.961846 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) May 27 03:18:38.961856 kernel: software IO TLB: mapped [mem 0x000000003aa59000-0x000000003ea59000] (64MB) May 27 03:18:38.961865 kernel: RAPL PMU: API unit is 2^-32 Joules, 1 fixed counters, 10737418240 ms ovfl timer May 27 03:18:38.961874 kernel: RAPL PMU: hw unit of domain psys 2^-0 Joules May 27 03:18:38.961885 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212735f0517, max_idle_ns: 440795237604 ns May 27 03:18:38.961894 kernel: clocksource: Switched to clocksource tsc May 27 03:18:38.961904 kernel: Initialise system trusted keyrings May 27 03:18:38.961913 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 May 27 03:18:38.961922 kernel: Key type asymmetric registered May 27 03:18:38.961931 kernel: Asymmetric key parser 'x509' registered May 27 03:18:38.961941 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 27 03:18:38.961950 kernel: io scheduler mq-deadline registered May 27 03:18:38.961958 kernel: io scheduler kyber registered May 27 03:18:38.961969 kernel: io scheduler bfq registered May 27 03:18:38.961978 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 May 27 03:18:38.961988 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 27 03:18:38.961997 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A May 27 03:18:38.962006 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A May 27 03:18:38.962016 kernel: serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a 16550A May 27 03:18:38.962026 kernel: i8042: PNP: No PS/2 controller found. May 27 03:18:38.962158 kernel: rtc_cmos 00:02: registered as rtc0 May 27 03:18:38.962263 kernel: rtc_cmos 00:02: setting system clock to 2025-05-27T03:18:38 UTC (1748315918) May 27 03:18:38.962334 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram May 27 03:18:38.962344 kernel: intel_pstate: Intel P-state driver initializing May 27 03:18:38.962353 kernel: efifb: probing for efifb May 27 03:18:38.962363 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 27 03:18:38.962371 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 27 03:18:38.962380 kernel: efifb: scrolling: redraw May 27 03:18:38.962388 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 27 03:18:38.962400 kernel: Console: switching to colour frame buffer device 128x48 May 27 03:18:38.962408 kernel: fb0: EFI VGA frame buffer device May 27 03:18:38.962417 kernel: pstore: Using crash dump compression: deflate May 27 03:18:38.962426 kernel: pstore: Registered efi_pstore as persistent store backend May 27 03:18:38.962436 kernel: NET: Registered PF_INET6 protocol family May 27 03:18:38.962444 kernel: Segment Routing with IPv6 May 27 03:18:38.962454 kernel: In-situ OAM (IOAM) with IPv6 May 27 03:18:38.962463 kernel: NET: Registered PF_PACKET protocol family May 27 03:18:38.962471 kernel: Key type dns_resolver registered May 27 03:18:38.962483 kernel: IPI shorthand broadcast: enabled May 27 03:18:38.962492 kernel: sched_clock: Marking stable (2769030196, 92425873)->(3166406291, -304950222) May 27 03:18:38.962501 kernel: registered taskstats version 1 May 27 03:18:38.962510 kernel: Loading compiled-in X.509 certificates May 27 03:18:38.962519 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.30-flatcar: ba9eddccb334a70147f3ddfe4fbde029feaa991d' May 27 03:18:38.962527 kernel: Demotion targets for Node 0: null May 27 03:18:38.962536 kernel: Key type .fscrypt registered May 27 03:18:38.962545 kernel: Key type fscrypt-provisioning registered May 27 03:18:38.962554 kernel: ima: No TPM chip found, activating TPM-bypass! May 27 03:18:38.962565 kernel: ima: Allocated hash algorithm: sha1 May 27 03:18:38.962574 kernel: ima: No architecture policies found May 27 03:18:38.962583 kernel: clk: Disabling unused clocks May 27 03:18:38.962591 kernel: Warning: unable to open an initial console. May 27 03:18:38.962600 kernel: Freeing unused kernel image (initmem) memory: 54416K May 27 03:18:38.962609 kernel: Write protecting the kernel read-only data: 24576k May 27 03:18:38.962618 kernel: Freeing unused kernel image (rodata/data gap) memory: 288K May 27 03:18:38.962626 kernel: Run /init as init process May 27 03:18:38.962635 kernel: with arguments: May 27 03:18:38.962647 kernel: /init May 27 03:18:38.962655 kernel: with environment: May 27 03:18:38.962664 kernel: HOME=/ May 27 03:18:38.962672 kernel: TERM=linux May 27 03:18:38.962681 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 27 03:18:38.962691 systemd[1]: Successfully made /usr/ read-only. May 27 03:18:38.962704 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) May 27 03:18:38.962715 systemd[1]: Detected virtualization microsoft. May 27 03:18:38.962727 systemd[1]: Detected architecture x86-64. May 27 03:18:38.962735 systemd[1]: Running in initrd. May 27 03:18:38.962744 systemd[1]: No hostname configured, using default hostname. May 27 03:18:38.962753 systemd[1]: Hostname set to . May 27 03:18:38.962762 systemd[1]: Initializing machine ID from random generator. May 27 03:18:38.962771 systemd[1]: Queued start job for default target initrd.target. May 27 03:18:38.962780 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 03:18:38.962789 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 03:18:38.962802 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 27 03:18:38.962813 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 03:18:38.962823 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 27 03:18:38.962834 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 27 03:18:38.962846 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 27 03:18:38.962856 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 27 03:18:38.962869 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 03:18:38.962878 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 03:18:38.962888 systemd[1]: Reached target paths.target - Path Units. May 27 03:18:38.962897 systemd[1]: Reached target slices.target - Slice Units. May 27 03:18:38.962906 systemd[1]: Reached target swap.target - Swaps. May 27 03:18:38.962915 systemd[1]: Reached target timers.target - Timer Units. May 27 03:18:38.962924 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 27 03:18:38.962933 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 03:18:38.962943 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 27 03:18:38.962955 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 27 03:18:38.962964 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 03:18:38.962974 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 03:18:38.962983 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 03:18:38.962993 systemd[1]: Reached target sockets.target - Socket Units. May 27 03:18:38.963003 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 27 03:18:38.963012 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 03:18:38.963022 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 27 03:18:38.963032 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). May 27 03:18:38.963045 systemd[1]: Starting systemd-fsck-usr.service... May 27 03:18:38.963055 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 03:18:38.963065 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 03:18:38.963085 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:38.963117 systemd-journald[205]: Collecting audit messages is disabled. May 27 03:18:38.963145 systemd-journald[205]: Journal started May 27 03:18:38.963198 systemd-journald[205]: Runtime Journal (/run/log/journal/98b7497510374fdd9570d3836b03a628) is 8M, max 159M, 151M free. May 27 03:18:38.966961 systemd[1]: Started systemd-journald.service - Journal Service. May 27 03:18:38.968150 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 27 03:18:38.973958 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 03:18:38.976180 systemd[1]: Finished systemd-fsck-usr.service. May 27 03:18:38.979907 systemd-modules-load[206]: Inserted module 'overlay' May 27 03:18:38.985275 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 03:18:38.989270 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 03:18:38.996818 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:39.003159 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 27 03:18:39.004029 systemd-tmpfiles[218]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 27 03:18:39.012459 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 03:18:39.016557 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 03:18:39.024266 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 03:18:39.027258 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 27 03:18:39.030913 systemd-modules-load[206]: Inserted module 'br_netfilter' May 27 03:18:39.031173 kernel: Bridge firewalling registered May 27 03:18:39.037266 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 03:18:39.041211 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 03:18:39.044372 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 03:18:39.049735 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 27 03:18:39.056707 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 03:18:39.065281 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 03:18:39.069514 dracut-cmdline[240]: 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=f6c186658a19d5a08471ef76df75f82494b37b46908f9237b2c3cf497da860c6 May 27 03:18:39.085265 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 03:18:39.116837 systemd-resolved[279]: Positive Trust Anchors: May 27 03:18:39.118102 systemd-resolved[279]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 03:18:39.118198 systemd-resolved[279]: 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 May 27 03:18:39.139194 kernel: SCSI subsystem initialized May 27 03:18:39.122984 systemd-resolved[279]: Defaulting to hostname 'linux'. May 27 03:18:39.133645 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 03:18:39.136868 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 03:18:39.148177 kernel: Loading iSCSI transport class v2.0-870. May 27 03:18:39.156180 kernel: iscsi: registered transport (tcp) May 27 03:18:39.171359 kernel: iscsi: registered transport (qla4xxx) May 27 03:18:39.171394 kernel: QLogic iSCSI HBA Driver May 27 03:18:39.182277 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 03:18:39.197724 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 03:18:39.201616 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 03:18:39.229916 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 27 03:18:39.233953 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 27 03:18:39.276178 kernel: raid6: avx512x4 gen() 46655 MB/s May 27 03:18:39.293178 kernel: raid6: avx512x2 gen() 46249 MB/s May 27 03:18:39.310173 kernel: raid6: avx512x1 gen() 30041 MB/s May 27 03:18:39.328174 kernel: raid6: avx2x4 gen() 40267 MB/s May 27 03:18:39.345176 kernel: raid6: avx2x2 gen() 43795 MB/s May 27 03:18:39.362568 kernel: raid6: avx2x1 gen() 31426 MB/s May 27 03:18:39.362586 kernel: raid6: using algorithm avx512x4 gen() 46655 MB/s May 27 03:18:39.381549 kernel: raid6: .... xor() 7894 MB/s, rmw enabled May 27 03:18:39.381570 kernel: raid6: using avx512x2 recovery algorithm May 27 03:18:39.397177 kernel: xor: automatically using best checksumming function avx May 27 03:18:39.503193 kernel: Btrfs loaded, zoned=no, fsverity=no May 27 03:18:39.506613 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 27 03:18:39.509273 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 03:18:39.526037 systemd-udevd[454]: Using default interface naming scheme 'v255'. May 27 03:18:39.529568 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 03:18:39.537011 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 27 03:18:39.558479 dracut-pre-trigger[465]: rd.md=0: removing MD RAID activation May 27 03:18:39.574332 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 27 03:18:39.576294 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 03:18:39.614924 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 03:18:39.620555 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 27 03:18:39.659971 kernel: cryptd: max_cpu_qlen set to 1000 May 27 03:18:39.671182 kernel: AES CTR mode by8 optimization enabled May 27 03:18:39.675843 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:18:39.675936 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:39.681893 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:39.684897 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:39.697320 kernel: hv_vmbus: Vmbus version:5.3 May 27 03:18:39.707320 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:18:39.707494 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:39.724495 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:39.732681 kernel: hv_vmbus: registering driver hyperv_keyboard May 27 03:18:39.734971 kernel: pps_core: LinuxPPS API ver. 1 registered May 27 03:18:39.734999 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 27 03:18:39.735183 kernel: hv_vmbus: registering driver hv_pci May 27 03:18:39.738635 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 May 27 03:18:39.744238 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI VMBus probing: Using version 0x10004 May 27 03:18:39.747191 kernel: PTP clock support registered May 27 03:18:39.753177 kernel: hv_vmbus: registering driver hv_netvsc May 27 03:18:39.758314 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:39.812212 kernel: hv_utils: Registering HyperV Utility Driver May 27 03:18:39.812232 kernel: hv_vmbus: registering driver hv_utils May 27 03:18:39.818190 kernel: hid: raw HID events driver (C) Jiri Kosina May 27 03:18:39.820766 kernel: hv_utils: Shutdown IC version 3.2 May 27 03:18:39.820801 kernel: hv_utils: Heartbeat IC version 3.0 May 27 03:18:39.823480 kernel: hv_utils: TimeSync IC version 4.0 May 27 03:18:39.965133 systemd-resolved[279]: Clock change detected. Flushing caches. May 27 03:18:39.969995 kernel: hv_netvsc f8615163-0000-1000-2000-002248a262d2 (unnamed net_device) (uninitialized): VF slot 1 added May 27 03:18:39.970168 kernel: hv_vmbus: registering driver hid_hyperv May 27 03:18:39.974695 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI host bridge to bus c05b:00 May 27 03:18:39.974880 kernel: pci_bus c05b:00: root bus resource [mem 0xfc0000000-0xfc007ffff window] May 27 03:18:39.984764 kernel: pci_bus c05b:00: No busn resource found for root bus, will use [bus 00-ff] May 27 03:18:39.984924 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 May 27 03:18:39.984945 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 27 03:18:39.985052 kernel: pci c05b:00:00.0: [1414:00a9] type 00 class 0x010802 PCIe Endpoint May 27 03:18:39.987776 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit] May 27 03:18:39.995738 kernel: hv_vmbus: registering driver hv_storvsc May 27 03:18:39.998736 kernel: scsi host0: storvsc_host_t May 27 03:18:40.003231 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 May 27 03:18:40.009771 kernel: pci c05b:00:00.0: 32.000 Gb/s available PCIe bandwidth, limited by 2.5 GT/s PCIe x16 link at c05b:00:00.0 (capable of 1024.000 Gb/s with 64.0 GT/s PCIe x16 link) May 27 03:18:40.012760 kernel: pci_bus c05b:00: busn_res: [bus 00-ff] end is updated to 00 May 27 03:18:40.012901 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit]: assigned May 27 03:18:40.028138 kernel: sr 0:0:0:2: [sr0] scsi-1 drive May 27 03:18:40.028324 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 27 03:18:40.029732 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 May 27 03:18:40.029871 kernel: nvme nvme0: pci function c05b:00:00.0 May 27 03:18:40.033096 kernel: nvme c05b:00:00.0: enabling device (0000 -> 0002) May 27 03:18:40.330757 kernel: nvme nvme0: 2/0/0 default/read/poll queues May 27 03:18:40.336731 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 27 03:18:40.354734 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#227 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 May 27 03:18:40.368741 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#252 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 May 27 03:18:40.557732 kernel: nvme nvme0: using unchecked data buffer May 27 03:18:40.733656 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM. May 27 03:18:40.761281 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - MSFT NVMe Accelerator v1.0 EFI-SYSTEM. May 27 03:18:40.784276 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - MSFT NVMe Accelerator v1.0 ROOT. May 27 03:18:40.866742 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - MSFT NVMe Accelerator v1.0 USR-A. May 27 03:18:40.869819 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - MSFT NVMe Accelerator v1.0 USR-A. May 27 03:18:40.873949 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 27 03:18:40.879235 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 27 03:18:40.879737 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 03:18:40.879763 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 03:18:40.881829 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 27 03:18:40.888647 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 27 03:18:40.905657 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 27 03:18:40.911781 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 27 03:18:41.013761 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI VMBus probing: Using version 0x10004 May 27 03:18:41.019797 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI host bridge to bus 7870:00 May 27 03:18:41.025759 kernel: pci_bus 7870:00: root bus resource [mem 0xfc2000000-0xfc4007fff window] May 27 03:18:41.025918 kernel: pci_bus 7870:00: No busn resource found for root bus, will use [bus 00-ff] May 27 03:18:41.051617 kernel: pci 7870:00:00.0: [1414:00ba] type 00 class 0x020000 PCIe Endpoint May 27 03:18:41.051664 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref] May 27 03:18:41.051681 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref] May 27 03:18:41.051700 kernel: pci 7870:00:00.0: enabling Extended Tags May 27 03:18:41.069768 kernel: pci_bus 7870:00: busn_res: [bus 00-ff] end is updated to 00 May 27 03:18:41.069950 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref]: assigned May 27 03:18:41.070090 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref]: assigned May 27 03:18:41.078280 kernel: mana 7870:00:00.0: enabling device (0000 -> 0002) May 27 03:18:41.087724 kernel: mana 7870:00:00.0: Microsoft Azure Network Adapter protocol version: 0.1.1 May 27 03:18:41.090979 kernel: hv_netvsc f8615163-0000-1000-2000-002248a262d2 eth0: VF registering: eth1 May 27 03:18:41.091231 kernel: mana 7870:00:00.0 eth1: joined to eth0 May 27 03:18:41.093733 kernel: mana 7870:00:00.0 enP30832s1: renamed from eth1 May 27 03:18:41.925676 disk-uuid[675]: The operation has completed successfully. May 27 03:18:41.927861 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 May 27 03:18:41.970849 systemd[1]: disk-uuid.service: Deactivated successfully. May 27 03:18:41.970926 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 27 03:18:42.005861 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 27 03:18:42.024643 sh[712]: Success May 27 03:18:42.052231 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 27 03:18:42.052271 kernel: device-mapper: uevent: version 1.0.3 May 27 03:18:42.052285 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 27 03:18:42.060737 kernel: device-mapper: verity: sha256 using shash "sha256-ni" May 27 03:18:42.287644 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 27 03:18:42.293381 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 27 03:18:42.305824 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 27 03:18:42.318931 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 27 03:18:42.318982 kernel: BTRFS: device fsid f0f66fe8-3990-49eb-980e-559a3dfd3522 devid 1 transid 40 /dev/mapper/usr (254:0) scanned by mount (725) May 27 03:18:42.320733 kernel: BTRFS info (device dm-0): first mount of filesystem f0f66fe8-3990-49eb-980e-559a3dfd3522 May 27 03:18:42.322818 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm May 27 03:18:42.323919 kernel: BTRFS info (device dm-0): using free-space-tree May 27 03:18:42.594077 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 27 03:18:42.598983 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 27 03:18:42.601846 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 27 03:18:42.602405 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 27 03:18:42.615766 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 27 03:18:42.633754 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 (259:5) scanned by mount (748) May 27 03:18:42.636347 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem fd7bb961-7a0f-4c90-a609-3bffeb956d05 May 27 03:18:42.636396 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm May 27 03:18:42.637820 kernel: BTRFS info (device nvme0n1p6): using free-space-tree May 27 03:18:42.673029 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem fd7bb961-7a0f-4c90-a609-3bffeb956d05 May 27 03:18:42.673224 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 27 03:18:42.678193 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 27 03:18:42.690773 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 03:18:42.697969 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 03:18:42.723206 systemd-networkd[894]: lo: Link UP May 27 03:18:42.723212 systemd-networkd[894]: lo: Gained carrier May 27 03:18:42.724738 systemd-networkd[894]: Enumeration completed May 27 03:18:42.732975 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16 May 27 03:18:42.733214 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 May 27 03:18:42.733469 kernel: hv_netvsc f8615163-0000-1000-2000-002248a262d2 eth0: Data path switched to VF: enP30832s1 May 27 03:18:42.725058 systemd-networkd[894]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:18:42.725061 systemd-networkd[894]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 03:18:42.725551 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 03:18:42.730164 systemd[1]: Reached target network.target - Network. May 27 03:18:42.736010 systemd-networkd[894]: enP30832s1: Link UP May 27 03:18:42.736061 systemd-networkd[894]: eth0: Link UP May 27 03:18:42.736135 systemd-networkd[894]: eth0: Gained carrier May 27 03:18:42.736144 systemd-networkd[894]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:18:42.741873 systemd-networkd[894]: enP30832s1: Gained carrier May 27 03:18:42.752752 systemd-networkd[894]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 27 03:18:43.781680 ignition[877]: Ignition 2.21.0 May 27 03:18:43.781692 ignition[877]: Stage: fetch-offline May 27 03:18:43.783670 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 27 03:18:43.781776 ignition[877]: no configs at "/usr/lib/ignition/base.d" May 27 03:18:43.784656 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 27 03:18:43.781782 ignition[877]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:43.781852 ignition[877]: parsed url from cmdline: "" May 27 03:18:43.781854 ignition[877]: no config URL provided May 27 03:18:43.781857 ignition[877]: reading system config file "/usr/lib/ignition/user.ign" May 27 03:18:43.781862 ignition[877]: no config at "/usr/lib/ignition/user.ign" May 27 03:18:43.781867 ignition[877]: failed to fetch config: resource requires networking May 27 03:18:43.782078 ignition[877]: Ignition finished successfully May 27 03:18:43.805494 ignition[903]: Ignition 2.21.0 May 27 03:18:43.805499 ignition[903]: Stage: fetch May 27 03:18:43.805742 ignition[903]: no configs at "/usr/lib/ignition/base.d" May 27 03:18:43.805749 ignition[903]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:43.805827 ignition[903]: parsed url from cmdline: "" May 27 03:18:43.805829 ignition[903]: no config URL provided May 27 03:18:43.805833 ignition[903]: reading system config file "/usr/lib/ignition/user.ign" May 27 03:18:43.805838 ignition[903]: no config at "/usr/lib/ignition/user.ign" May 27 03:18:43.805867 ignition[903]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 27 03:18:43.880450 ignition[903]: GET result: OK May 27 03:18:43.880512 ignition[903]: config has been read from IMDS userdata May 27 03:18:43.880521 ignition[903]: parsing config with SHA512: 386c77ccbd323dbb49c77946fbe6c571fae0d8c5871d1f2f350fa1fa4718f0c769db84267f521247b78b926fcf122bfc7034552d335fe0c1cd9756b118ab6551 May 27 03:18:43.886019 unknown[903]: fetched base config from "system" May 27 03:18:43.886027 unknown[903]: fetched base config from "system" May 27 03:18:43.886152 ignition[903]: fetch: fetch complete May 27 03:18:43.886031 unknown[903]: fetched user config from "azure" May 27 03:18:43.886156 ignition[903]: fetch: fetch passed May 27 03:18:43.888265 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 27 03:18:43.886191 ignition[903]: Ignition finished successfully May 27 03:18:43.889544 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 27 03:18:43.908213 ignition[909]: Ignition 2.21.0 May 27 03:18:43.908223 ignition[909]: Stage: kargs May 27 03:18:43.908560 ignition[909]: no configs at "/usr/lib/ignition/base.d" May 27 03:18:43.908569 ignition[909]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:43.911787 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 27 03:18:43.910302 ignition[909]: kargs: kargs passed May 27 03:18:43.915353 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 27 03:18:43.910337 ignition[909]: Ignition finished successfully May 27 03:18:43.930039 ignition[915]: Ignition 2.21.0 May 27 03:18:43.930048 ignition[915]: Stage: disks May 27 03:18:43.930225 ignition[915]: no configs at "/usr/lib/ignition/base.d" May 27 03:18:43.932058 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 27 03:18:43.930232 ignition[915]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:43.931164 ignition[915]: disks: disks passed May 27 03:18:43.931197 ignition[915]: Ignition finished successfully May 27 03:18:43.940439 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 27 03:18:43.941921 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 27 03:18:43.947062 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 03:18:43.952283 systemd[1]: Reached target sysinit.target - System Initialization. May 27 03:18:43.953582 systemd[1]: Reached target basic.target - Basic System. May 27 03:18:43.959485 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 27 03:18:44.015145 systemd-fsck[923]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks May 27 03:18:44.018898 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 27 03:18:44.022525 systemd[1]: Mounting sysroot.mount - /sysroot... May 27 03:18:44.182825 systemd-networkd[894]: eth0: Gained IPv6LL May 27 03:18:44.249923 kernel: EXT4-fs (nvme0n1p9): mounted filesystem 18301365-b380-45d7-9677-e42472a122bc r/w with ordered data mode. Quota mode: none. May 27 03:18:44.250474 systemd[1]: Mounted sysroot.mount - /sysroot. May 27 03:18:44.250877 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 27 03:18:44.272573 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 03:18:44.275884 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 27 03:18:44.290690 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 27 03:18:44.293106 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 27 03:18:44.294777 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 27 03:18:44.308821 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 (259:5) scanned by mount (932) May 27 03:18:44.300601 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 27 03:18:44.313115 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem fd7bb961-7a0f-4c90-a609-3bffeb956d05 May 27 03:18:44.313141 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm May 27 03:18:44.313152 kernel: BTRFS info (device nvme0n1p6): using free-space-tree May 27 03:18:44.314004 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 27 03:18:44.320216 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 03:18:44.566903 systemd-networkd[894]: enP30832s1: Gained IPv6LL May 27 03:18:45.229103 coreos-metadata[934]: May 27 03:18:45.229 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 27 03:18:45.232931 coreos-metadata[934]: May 27 03:18:45.232 INFO Fetch successful May 27 03:18:45.233984 coreos-metadata[934]: May 27 03:18:45.233 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 27 03:18:45.239535 coreos-metadata[934]: May 27 03:18:45.239 INFO Fetch successful May 27 03:18:45.253562 coreos-metadata[934]: May 27 03:18:45.253 INFO wrote hostname ci-4344.0.0-a-5600cc6f2e to /sysroot/etc/hostname May 27 03:18:45.256895 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 27 03:18:45.290070 initrd-setup-root[962]: cut: /sysroot/etc/passwd: No such file or directory May 27 03:18:45.393212 initrd-setup-root[969]: cut: /sysroot/etc/group: No such file or directory May 27 03:18:45.412656 initrd-setup-root[976]: cut: /sysroot/etc/shadow: No such file or directory May 27 03:18:45.416368 initrd-setup-root[983]: cut: /sysroot/etc/gshadow: No such file or directory May 27 03:18:46.223528 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 27 03:18:46.225837 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 27 03:18:46.235449 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 27 03:18:46.239024 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 27 03:18:46.246900 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem fd7bb961-7a0f-4c90-a609-3bffeb956d05 May 27 03:18:46.261756 ignition[1055]: INFO : Ignition 2.21.0 May 27 03:18:46.262885 ignition[1055]: INFO : Stage: mount May 27 03:18:46.262885 ignition[1055]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 03:18:46.262885 ignition[1055]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:46.262885 ignition[1055]: INFO : mount: mount passed May 27 03:18:46.262885 ignition[1055]: INFO : Ignition finished successfully May 27 03:18:46.264420 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 27 03:18:46.271161 systemd[1]: Starting ignition-files.service - Ignition (files)... May 27 03:18:46.280125 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 27 03:18:46.284630 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 03:18:46.306213 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/nvme0n1p6 (259:5) scanned by mount (1068) May 27 03:18:46.306251 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem fd7bb961-7a0f-4c90-a609-3bffeb956d05 May 27 03:18:46.307126 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm May 27 03:18:46.307968 kernel: BTRFS info (device nvme0n1p6): using free-space-tree May 27 03:18:46.312279 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 03:18:46.332372 ignition[1085]: INFO : Ignition 2.21.0 May 27 03:18:46.332372 ignition[1085]: INFO : Stage: files May 27 03:18:46.335866 ignition[1085]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 03:18:46.335866 ignition[1085]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:46.335866 ignition[1085]: DEBUG : files: compiled without relabeling support, skipping May 27 03:18:46.335866 ignition[1085]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 27 03:18:46.335866 ignition[1085]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 27 03:18:46.367214 ignition[1085]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 27 03:18:46.368993 ignition[1085]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 27 03:18:46.368993 ignition[1085]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 27 03:18:46.367559 unknown[1085]: wrote ssh authorized keys file for user: core May 27 03:18:46.384997 ignition[1085]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 27 03:18:46.387464 ignition[1085]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 27 03:18:46.387464 ignition[1085]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 27 03:18:46.387464 ignition[1085]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 27 03:18:46.387464 ignition[1085]: INFO : files: files passed May 27 03:18:46.387464 ignition[1085]: INFO : Ignition finished successfully May 27 03:18:46.386575 systemd[1]: Finished ignition-files.service - Ignition (files). May 27 03:18:46.400369 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 27 03:18:46.403960 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 27 03:18:46.420079 systemd[1]: ignition-quench.service: Deactivated successfully. May 27 03:18:46.420152 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 27 03:18:46.447469 initrd-setup-root-after-ignition[1115]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 03:18:46.447469 initrd-setup-root-after-ignition[1115]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 27 03:18:46.451990 initrd-setup-root-after-ignition[1119]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 03:18:46.453172 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 03:18:46.456888 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 27 03:18:46.460442 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 27 03:18:46.507054 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 27 03:18:46.507134 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 27 03:18:46.509800 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 27 03:18:46.511981 systemd[1]: Reached target initrd.target - Initrd Default Target. May 27 03:18:46.515789 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 27 03:18:46.516331 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 27 03:18:46.532786 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 03:18:46.534838 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 27 03:18:46.550175 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 27 03:18:46.550319 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 03:18:46.550480 systemd[1]: Stopped target timers.target - Timer Units. May 27 03:18:46.554863 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 27 03:18:46.554975 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 03:18:46.558974 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 27 03:18:46.559795 systemd[1]: Stopped target basic.target - Basic System. May 27 03:18:46.560033 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 27 03:18:46.566267 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 27 03:18:46.570220 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 27 03:18:46.574245 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 27 03:18:46.579821 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 27 03:18:46.584200 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 27 03:18:46.588334 systemd[1]: Stopped target sysinit.target - System Initialization. May 27 03:18:46.592609 systemd[1]: Stopped target local-fs.target - Local File Systems. May 27 03:18:46.594154 systemd[1]: Stopped target swap.target - Swaps. May 27 03:18:46.597324 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 27 03:18:46.597507 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 27 03:18:46.602070 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 27 03:18:46.605871 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 03:18:46.608213 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 27 03:18:46.608531 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 03:18:46.613829 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 27 03:18:46.613951 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 27 03:18:46.619802 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 27 03:18:46.619930 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 03:18:46.622086 systemd[1]: ignition-files.service: Deactivated successfully. May 27 03:18:46.622186 systemd[1]: Stopped ignition-files.service - Ignition (files). May 27 03:18:46.623286 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 27 03:18:46.623377 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 27 03:18:46.624844 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 27 03:18:46.627673 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 27 03:18:46.627801 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 27 03:18:46.658808 ignition[1139]: INFO : Ignition 2.21.0 May 27 03:18:46.658808 ignition[1139]: INFO : Stage: umount May 27 03:18:46.658808 ignition[1139]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 03:18:46.658808 ignition[1139]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 03:18:46.658808 ignition[1139]: INFO : umount: umount passed May 27 03:18:46.658808 ignition[1139]: INFO : Ignition finished successfully May 27 03:18:46.639137 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 27 03:18:46.652058 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 27 03:18:46.652226 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 27 03:18:46.657921 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 27 03:18:46.658039 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 27 03:18:46.663461 systemd[1]: ignition-mount.service: Deactivated successfully. May 27 03:18:46.663549 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 27 03:18:46.668533 systemd[1]: ignition-disks.service: Deactivated successfully. May 27 03:18:46.668694 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 27 03:18:46.671516 systemd[1]: ignition-kargs.service: Deactivated successfully. May 27 03:18:46.671558 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 27 03:18:46.675365 systemd[1]: ignition-fetch.service: Deactivated successfully. May 27 03:18:46.675406 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 27 03:18:46.678120 systemd[1]: Stopped target network.target - Network. May 27 03:18:46.681327 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 27 03:18:46.681369 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 27 03:18:46.684475 systemd[1]: Stopped target paths.target - Path Units. May 27 03:18:46.689415 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 27 03:18:46.695754 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 03:18:46.702764 systemd[1]: Stopped target slices.target - Slice Units. May 27 03:18:46.704593 systemd[1]: Stopped target sockets.target - Socket Units. May 27 03:18:46.705509 systemd[1]: iscsid.socket: Deactivated successfully. May 27 03:18:46.705548 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 27 03:18:46.708774 systemd[1]: iscsiuio.socket: Deactivated successfully. May 27 03:18:46.708807 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 03:18:46.711760 systemd[1]: ignition-setup.service: Deactivated successfully. May 27 03:18:46.711806 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 27 03:18:46.714772 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 27 03:18:46.714806 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 27 03:18:46.717854 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 27 03:18:46.720802 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 27 03:18:46.724075 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 27 03:18:46.724150 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 27 03:18:46.729225 systemd[1]: systemd-resolved.service: Deactivated successfully. May 27 03:18:46.729301 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 27 03:18:46.742414 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 27 03:18:46.742486 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 27 03:18:46.742904 systemd[1]: systemd-networkd.service: Deactivated successfully. May 27 03:18:46.742992 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 27 03:18:46.748426 systemd[1]: sysroot-boot.service: Deactivated successfully. May 27 03:18:46.748485 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 27 03:18:46.749818 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 27 03:18:46.751956 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 27 03:18:46.751991 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 27 03:18:46.754154 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 27 03:18:46.754200 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 27 03:18:46.770546 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 27 03:18:46.775299 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 27 03:18:46.776037 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 03:18:46.778274 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 27 03:18:46.778316 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 27 03:18:46.784039 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 27 03:18:46.784074 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 27 03:18:46.793312 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 27 03:18:46.793360 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 03:18:46.798861 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 03:18:46.815997 systemd[1]: systemd-udevd.service: Deactivated successfully. May 27 03:18:46.821812 kernel: hv_netvsc f8615163-0000-1000-2000-002248a262d2 eth0: Data path switched from VF: enP30832s1 May 27 03:18:46.821968 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 May 27 03:18:46.816120 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 03:18:46.820406 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 27 03:18:46.820452 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 27 03:18:46.823253 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 27 03:18:46.823288 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 27 03:18:46.826218 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 27 03:18:46.826291 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 27 03:18:46.834854 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 27 03:18:46.834904 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 27 03:18:46.838419 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 27 03:18:46.839305 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 03:18:46.844279 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 27 03:18:46.847728 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 27 03:18:46.847786 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 27 03:18:46.856911 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 27 03:18:46.856964 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 03:18:46.859985 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:18:46.860027 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:46.861995 systemd[1]: network-cleanup.service: Deactivated successfully. May 27 03:18:46.862060 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 27 03:18:46.865256 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 27 03:18:46.865308 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 27 03:18:46.869452 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 27 03:18:46.873542 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 27 03:18:46.898531 systemd[1]: Switching root. May 27 03:18:46.967627 systemd-journald[205]: Journal stopped May 27 03:18:50.653895 systemd-journald[205]: Received SIGTERM from PID 1 (systemd). May 27 03:18:50.653922 kernel: SELinux: policy capability network_peer_controls=1 May 27 03:18:50.653933 kernel: SELinux: policy capability open_perms=1 May 27 03:18:50.653941 kernel: SELinux: policy capability extended_socket_class=1 May 27 03:18:50.653948 kernel: SELinux: policy capability always_check_network=0 May 27 03:18:50.654824 kernel: SELinux: policy capability cgroup_seclabel=1 May 27 03:18:50.654850 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 27 03:18:50.654859 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 27 03:18:50.654867 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 27 03:18:50.654876 kernel: SELinux: policy capability userspace_initial_context=0 May 27 03:18:50.654885 kernel: audit: type=1403 audit(1748315927.641:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 27 03:18:50.654899 systemd[1]: Successfully loaded SELinux policy in 188.024ms. May 27 03:18:50.654911 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 6.232ms. May 27 03:18:50.654924 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) May 27 03:18:50.654935 systemd[1]: Detected virtualization microsoft. May 27 03:18:50.654947 systemd[1]: Detected architecture x86-64. May 27 03:18:50.654956 systemd[1]: Detected first boot. May 27 03:18:50.654968 systemd[1]: Hostname set to . May 27 03:18:50.654981 systemd[1]: Initializing machine ID from random generator. May 27 03:18:50.654991 zram_generator::config[1181]: No configuration found. May 27 03:18:50.655003 kernel: Guest personality initialized and is inactive May 27 03:18:50.655013 kernel: VMCI host device registered (name=vmci, major=10, minor=124) May 27 03:18:50.655023 kernel: Initialized host personality May 27 03:18:50.655033 kernel: NET: Registered PF_VSOCK protocol family May 27 03:18:50.655044 systemd[1]: Populated /etc with preset unit settings. May 27 03:18:50.655063 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 27 03:18:50.655074 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 27 03:18:50.655086 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 27 03:18:50.655095 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 27 03:18:50.655105 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 27 03:18:50.655116 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 27 03:18:50.655127 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 27 03:18:50.655139 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 27 03:18:50.655149 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 27 03:18:50.655161 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 27 03:18:50.655170 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 27 03:18:50.655179 systemd[1]: Created slice user.slice - User and Session Slice. May 27 03:18:50.655187 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 03:18:50.655201 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 03:18:50.655209 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 27 03:18:50.655221 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 27 03:18:50.656508 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 27 03:18:50.656537 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 03:18:50.656547 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... May 27 03:18:50.656556 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 03:18:50.656565 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 03:18:50.656574 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 27 03:18:50.656582 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 27 03:18:50.656591 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 27 03:18:50.656597 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 27 03:18:50.656604 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 03:18:50.656609 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 03:18:50.656615 systemd[1]: Reached target slices.target - Slice Units. May 27 03:18:50.656620 systemd[1]: Reached target swap.target - Swaps. May 27 03:18:50.656626 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 27 03:18:50.656632 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 27 03:18:50.656639 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 27 03:18:50.656645 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 03:18:50.656651 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 03:18:50.656657 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 03:18:50.656662 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 27 03:18:50.656669 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 27 03:18:50.656675 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 27 03:18:50.656680 systemd[1]: Mounting media.mount - External Media Directory... May 27 03:18:50.656686 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:50.656692 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 27 03:18:50.656698 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 27 03:18:50.656703 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 27 03:18:50.656709 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). May 27 03:18:50.656745 systemd[1]: Reached target machines.target - Containers. May 27 03:18:50.656751 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 27 03:18:50.656757 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:18:50.656762 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 03:18:50.656768 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 27 03:18:50.656774 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 03:18:50.656779 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 03:18:50.656785 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:18:50.656792 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 27 03:18:50.656798 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 03:18:50.656804 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 27 03:18:50.656809 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 27 03:18:50.656815 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 27 03:18:50.656821 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 27 03:18:50.656827 systemd[1]: Stopped systemd-fsck-usr.service. May 27 03:18:50.656833 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). May 27 03:18:50.656840 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 03:18:50.656845 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 03:18:50.656851 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 03:18:50.656857 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 27 03:18:50.656863 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 27 03:18:50.656868 kernel: loop: module loaded May 27 03:18:50.656874 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 03:18:50.656880 systemd[1]: verity-setup.service: Deactivated successfully. May 27 03:18:50.656886 systemd[1]: Stopped verity-setup.service. May 27 03:18:50.656892 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:50.656898 kernel: fuse: init (API version 7.41) May 27 03:18:50.656904 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 27 03:18:50.656909 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 27 03:18:50.656934 systemd-journald[1285]: Collecting audit messages is disabled. May 27 03:18:50.656950 systemd[1]: Mounted media.mount - External Media Directory. May 27 03:18:50.656956 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 27 03:18:50.656963 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 27 03:18:50.656969 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 27 03:18:50.656975 systemd-journald[1285]: Journal started May 27 03:18:50.656989 systemd-journald[1285]: Runtime Journal (/run/log/journal/6ed234f2f3b147f3b0982d1486664f8f) is 8M, max 159M, 151M free. May 27 03:18:50.267484 systemd[1]: Queued start job for default target multi-user.target. May 27 03:18:50.278086 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. May 27 03:18:50.278416 systemd[1]: systemd-journald.service: Deactivated successfully. May 27 03:18:50.659760 systemd[1]: Started systemd-journald.service - Journal Service. May 27 03:18:50.660967 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 27 03:18:50.663979 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 03:18:50.665442 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 27 03:18:50.665572 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 27 03:18:50.666756 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 03:18:50.666882 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 03:18:50.669901 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:18:50.670020 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:18:50.671475 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 27 03:18:50.671590 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 27 03:18:50.674907 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 03:18:50.675035 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 03:18:50.677938 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 03:18:50.680933 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 27 03:18:50.696806 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 27 03:18:50.700854 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 27 03:18:50.703801 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 27 03:18:50.703832 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 03:18:50.707195 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 27 03:18:50.711782 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 27 03:18:50.713509 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:18:50.715410 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 27 03:18:50.720319 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 27 03:18:50.723808 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 03:18:50.724686 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 27 03:18:50.726319 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 03:18:50.734018 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 03:18:50.739840 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 27 03:18:50.742860 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 27 03:18:50.748778 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 03:18:50.751317 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 27 03:18:50.755672 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 27 03:18:50.759198 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 27 03:18:50.762329 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 03:18:50.770040 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 03:18:50.770466 systemd-journald[1285]: Time spent on flushing to /var/log/journal/6ed234f2f3b147f3b0982d1486664f8f is 9.180ms for 952 entries. May 27 03:18:50.770466 systemd-journald[1285]: System Journal (/var/log/journal/6ed234f2f3b147f3b0982d1486664f8f) is 8M, max 2.6G, 2.6G free. May 27 03:18:50.813649 systemd-journald[1285]: Received client request to flush runtime journal. May 27 03:18:50.777927 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 27 03:18:50.779395 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 27 03:18:50.783831 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 27 03:18:50.814240 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 27 03:18:50.828734 kernel: loop0: detected capacity change from 0 to 146240 May 27 03:18:50.845939 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 27 03:18:50.868014 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 03:18:50.896973 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 27 03:18:50.900695 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 03:18:50.905746 kernel: ACPI: bus type drm_connector registered May 27 03:18:50.905693 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 03:18:50.905971 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 03:18:50.941774 systemd-tmpfiles[1337]: ACLs are not supported, ignoring. May 27 03:18:50.941787 systemd-tmpfiles[1337]: ACLs are not supported, ignoring. May 27 03:18:50.945535 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 03:18:51.279568 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 27 03:18:51.459782 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 27 03:18:51.498730 kernel: loop1: detected capacity change from 0 to 113872 May 27 03:18:51.629908 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 27 03:18:51.633950 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 03:18:51.663446 systemd-udevd[1344]: Using default interface naming scheme 'v255'. May 27 03:18:51.762989 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 03:18:51.769841 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 03:18:51.875928 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. May 27 03:18:51.892727 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#221 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 May 27 03:18:51.911837 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 27 03:18:51.930738 kernel: loop2: detected capacity change from 0 to 28536 May 27 03:18:51.968061 kernel: hv_vmbus: registering driver hv_balloon May 27 03:18:51.972737 kernel: hv_vmbus: registering driver hyperv_fb May 27 03:18:51.981992 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 27 03:18:51.992735 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 27 03:18:52.001735 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 27 03:18:52.004695 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 27 03:18:52.004918 kernel: mousedev: PS/2 mouse device common for all mice May 27 03:18:52.004993 kernel: Console: switching to colour dummy device 80x25 May 27 03:18:52.009225 kernel: Console: switching to colour frame buffer device 128x48 May 27 03:18:52.072549 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:52.077220 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:18:52.077390 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:52.082456 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:52.090015 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 03:18:52.090190 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:52.093458 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 03:18:52.234518 systemd-networkd[1352]: lo: Link UP May 27 03:18:52.234525 systemd-networkd[1352]: lo: Gained carrier May 27 03:18:52.236409 systemd-networkd[1352]: Enumeration completed May 27 03:18:52.236695 systemd-networkd[1352]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:18:52.236705 systemd-networkd[1352]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 03:18:52.239834 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16 May 27 03:18:52.240102 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 May 27 03:18:52.240400 kernel: hv_netvsc f8615163-0000-1000-2000-002248a262d2 eth0: Data path switched to VF: enP30832s1 May 27 03:18:52.241412 systemd-networkd[1352]: enP30832s1: Link UP May 27 03:18:52.241480 systemd-networkd[1352]: eth0: Link UP May 27 03:18:52.241483 systemd-networkd[1352]: eth0: Gained carrier May 27 03:18:52.241495 systemd-networkd[1352]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:18:52.242558 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM. May 27 03:18:52.246098 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 03:18:52.247103 systemd-networkd[1352]: enP30832s1: Gained carrier May 27 03:18:52.249494 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 27 03:18:52.253922 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 27 03:18:52.260048 systemd-networkd[1352]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 27 03:18:52.260904 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 27 03:18:52.294736 kernel: loop3: detected capacity change from 0 to 146240 May 27 03:18:52.307737 kernel: loop4: detected capacity change from 0 to 113872 May 27 03:18:52.318683 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 27 03:18:52.319927 kernel: loop5: detected capacity change from 0 to 28536 May 27 03:18:52.331202 (sd-merge)[1439]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 27 03:18:52.331498 (sd-merge)[1439]: Merged extensions into '/usr'. May 27 03:18:52.333847 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 27 03:18:52.335891 systemd[1]: Starting ensure-sysext.service... May 27 03:18:52.339826 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 03:18:52.342129 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 27 03:18:52.358865 systemd[1]: Reload requested from client PID 1442 ('systemctl') (unit ensure-sysext.service)... May 27 03:18:52.359735 systemd[1]: Reloading... May 27 03:18:52.376732 kernel: kvm_intel: Using Hyper-V Enlightened VMCS May 27 03:18:52.393206 systemd-tmpfiles[1443]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 27 03:18:52.393238 systemd-tmpfiles[1443]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 27 03:18:52.393446 systemd-tmpfiles[1443]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 27 03:18:52.393627 systemd-tmpfiles[1443]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 27 03:18:52.394214 systemd-tmpfiles[1443]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 27 03:18:52.394428 systemd-tmpfiles[1443]: ACLs are not supported, ignoring. May 27 03:18:52.394467 systemd-tmpfiles[1443]: ACLs are not supported, ignoring. May 27 03:18:52.417076 systemd-tmpfiles[1443]: Detected autofs mount point /boot during canonicalization of boot. May 27 03:18:52.417084 systemd-tmpfiles[1443]: Skipping /boot May 27 03:18:52.432765 zram_generator::config[1482]: No configuration found. May 27 03:18:52.439814 systemd-tmpfiles[1443]: Detected autofs mount point /boot during canonicalization of boot. May 27 03:18:52.439825 systemd-tmpfiles[1443]: Skipping /boot May 27 03:18:52.508907 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. May 27 03:18:52.589473 systemd[1]: Reloading finished in 229 ms. May 27 03:18:52.606163 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 03:18:52.617492 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 03:18:52.625467 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 03:18:52.642524 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 27 03:18:52.653171 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 27 03:18:52.658360 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 03:18:52.666670 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 27 03:18:52.672081 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:52.672237 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:18:52.675021 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 03:18:52.680077 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:18:52.684130 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 03:18:52.686744 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:18:52.686856 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). May 27 03:18:52.686933 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:52.695369 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:52.695653 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:18:52.696140 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:18:52.696215 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). May 27 03:18:52.696291 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:52.696995 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 03:18:52.697854 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 03:18:52.700708 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 27 03:18:52.705091 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:18:52.705231 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:18:52.707295 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 03:18:52.707528 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 03:18:52.718610 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:52.719790 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 03:18:52.721933 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 03:18:52.725574 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 03:18:52.729987 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 03:18:52.739148 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 03:18:52.742869 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 03:18:52.742978 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). May 27 03:18:52.743119 systemd[1]: Reached target time-set.target - System Time Set. May 27 03:18:52.746920 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). May 27 03:18:52.749891 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 27 03:18:52.754422 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 03:18:52.754670 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 03:18:52.756432 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 03:18:52.756699 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 03:18:52.760169 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 03:18:52.760319 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 03:18:52.762392 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 03:18:52.762638 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 03:18:52.768569 systemd[1]: Finished ensure-sysext.service. May 27 03:18:52.774929 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 03:18:52.774960 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 03:18:52.779286 systemd-resolved[1542]: Positive Trust Anchors: May 27 03:18:52.779297 systemd-resolved[1542]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 03:18:52.779327 systemd-resolved[1542]: 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 May 27 03:18:52.783227 systemd-resolved[1542]: Using system hostname 'ci-4344.0.0-a-5600cc6f2e'. May 27 03:18:52.784560 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 03:18:52.787823 systemd[1]: Reached target network.target - Network. May 27 03:18:52.788989 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 03:18:52.826631 augenrules[1581]: No rules May 27 03:18:52.827459 systemd[1]: audit-rules.service: Deactivated successfully. May 27 03:18:52.827611 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 03:18:53.145299 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 27 03:18:53.146913 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). May 27 03:18:53.462893 systemd-networkd[1352]: enP30832s1: Gained IPv6LL May 27 03:18:53.526840 systemd-networkd[1352]: eth0: Gained IPv6LL May 27 03:18:53.529033 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 27 03:18:53.530862 systemd[1]: Reached target network-online.target - Network is Online. May 27 03:18:56.705599 ldconfig[1314]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 27 03:18:56.714127 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 27 03:18:56.718844 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 27 03:18:56.734831 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 27 03:18:56.737937 systemd[1]: Reached target sysinit.target - System Initialization. May 27 03:18:56.740828 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 27 03:18:56.743780 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 27 03:18:56.745125 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. May 27 03:18:56.746491 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 27 03:18:56.747812 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 27 03:18:56.750767 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 27 03:18:56.752108 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 27 03:18:56.752140 systemd[1]: Reached target paths.target - Path Units. May 27 03:18:56.754756 systemd[1]: Reached target timers.target - Timer Units. May 27 03:18:56.757735 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 27 03:18:56.759938 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 03:18:56.764514 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 27 03:18:56.766611 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 27 03:18:56.770345 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 27 03:18:56.782143 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 27 03:18:56.783909 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 27 03:18:56.785800 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 03:18:56.787560 systemd[1]: Reached target sockets.target - Socket Units. May 27 03:18:56.788785 systemd[1]: Reached target basic.target - Basic System. May 27 03:18:56.791794 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 27 03:18:56.791818 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 27 03:18:56.793537 systemd[1]: Starting chronyd.service - NTP client/server... May 27 03:18:56.796206 systemd[1]: Starting containerd.service - containerd container runtime... May 27 03:18:56.803836 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 27 03:18:56.806866 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 27 03:18:56.810565 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 27 03:18:56.815002 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 27 03:18:56.818591 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 27 03:18:56.822677 jq[1602]: false May 27 03:18:56.821997 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). May 27 03:18:56.823092 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... May 27 03:18:56.826161 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 27 03:18:56.834600 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 27 03:18:56.841517 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 27 03:18:56.846878 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 27 03:18:56.853878 systemd[1]: Starting systemd-logind.service - User Login Management... May 27 03:18:56.856433 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 27 03:18:56.856814 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 27 03:18:56.861843 systemd[1]: Starting update-engine.service - Update Engine... May 27 03:18:56.864581 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 27 03:18:56.871125 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 27 03:18:56.871927 (chronyd)[1594]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 27 03:18:56.871927 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 27 03:18:56.872154 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 27 03:18:56.872305 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 27 03:18:56.883845 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 27 03:18:56.888660 google_oslogin_nss_cache[1604]: oslogin_cache_refresh[1604]: Refreshing passwd entry cache May 27 03:18:56.889756 oslogin_cache_refresh[1604]: Refreshing passwd entry cache May 27 03:18:56.895499 jq[1615]: true May 27 03:18:56.904343 (ntainerd)[1627]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 03:18:56.906050 google_oslogin_nss_cache[1604]: oslogin_cache_refresh[1604]: Failure getting users, quitting May 27 03:18:56.906050 google_oslogin_nss_cache[1604]: oslogin_cache_refresh[1604]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 27 03:18:56.906050 google_oslogin_nss_cache[1604]: oslogin_cache_refresh[1604]: Refreshing group entry cache May 27 03:18:56.905673 oslogin_cache_refresh[1604]: Failure getting users, quitting May 27 03:18:56.905688 oslogin_cache_refresh[1604]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. May 27 03:18:56.905746 oslogin_cache_refresh[1604]: Refreshing group entry cache May 27 03:18:56.906348 systemd[1]: motdgen.service: Deactivated successfully. May 27 03:18:56.906518 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 27 03:18:56.908597 jq[1633]: true May 27 03:18:56.918643 extend-filesystems[1603]: Found loop3 May 27 03:18:56.923843 extend-filesystems[1603]: Found loop4 May 27 03:18:56.923843 extend-filesystems[1603]: Found loop5 May 27 03:18:56.923843 extend-filesystems[1603]: Found sr0 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p1 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p2 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p3 May 27 03:18:56.923843 extend-filesystems[1603]: Found usr May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p4 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p6 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p7 May 27 03:18:56.923843 extend-filesystems[1603]: Found nvme0n1p9 May 27 03:18:56.923843 extend-filesystems[1603]: Checking size of /dev/nvme0n1p9 May 27 03:18:56.950446 google_oslogin_nss_cache[1604]: oslogin_cache_refresh[1604]: Failure getting groups, quitting May 27 03:18:56.950446 google_oslogin_nss_cache[1604]: oslogin_cache_refresh[1604]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 27 03:18:56.933424 systemd[1]: google-oslogin-cache.service: Deactivated successfully. May 27 03:18:56.931818 oslogin_cache_refresh[1604]: Failure getting groups, quitting May 27 03:18:56.933613 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. May 27 03:18:56.931826 oslogin_cache_refresh[1604]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. May 27 03:18:56.943763 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 27 03:18:56.946561 chronyd[1658]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 27 03:18:56.949476 systemd[1]: Started chronyd.service - NTP client/server. May 27 03:18:56.948331 chronyd[1658]: Timezone right/UTC failed leap second check, ignoring May 27 03:18:56.948460 chronyd[1658]: Loaded seccomp filter (level 2) May 27 03:18:56.960470 extend-filesystems[1603]: Old size kept for /dev/nvme0n1p9 May 27 03:18:56.962130 systemd[1]: extend-filesystems.service: Deactivated successfully. May 27 03:18:56.962313 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 27 03:18:57.012529 update_engine[1613]: I20250527 03:18:57.011443 1613 main.cc:92] Flatcar Update Engine starting May 27 03:18:57.014214 systemd-logind[1610]: New seat seat0. May 27 03:18:57.022544 systemd-logind[1610]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) May 27 03:18:57.022685 systemd[1]: Started systemd-logind.service - User Login Management. May 27 03:18:57.059752 bash[1653]: Updated "/home/core/.ssh/authorized_keys" May 27 03:18:57.060177 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 27 03:18:57.064587 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 27 03:18:57.073254 dbus-daemon[1597]: [system] SELinux support is enabled May 27 03:18:57.073746 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 27 03:18:57.079524 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). May 27 03:18:57.079547 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 27 03:18:57.081991 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). May 27 03:18:57.082008 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 27 03:18:57.085316 update_engine[1613]: I20250527 03:18:57.085191 1613 update_check_scheduler.cc:74] Next update check in 2m37s May 27 03:18:57.086365 systemd[1]: Started update-engine.service - Update Engine. May 27 03:18:57.088380 dbus-daemon[1597]: [system] Successfully activated service 'org.freedesktop.systemd1' May 27 03:18:57.094910 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 27 03:18:57.153999 coreos-metadata[1596]: May 27 03:18:57.153 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 27 03:18:57.161030 coreos-metadata[1596]: May 27 03:18:57.161 INFO Fetch successful May 27 03:18:57.161309 coreos-metadata[1596]: May 27 03:18:57.161 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 27 03:18:57.164549 coreos-metadata[1596]: May 27 03:18:57.164 INFO Fetch successful May 27 03:18:57.164635 coreos-metadata[1596]: May 27 03:18:57.164 INFO Fetching http://168.63.129.16/machine/9bffe454-fbb0-4db1-8be1-9f9310c8aad1/0ca1d212%2D534d%2D4146%2D8713%2Deb73fe271c37.%5Fci%2D4344.0.0%2Da%2D5600cc6f2e?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 27 03:18:57.165864 coreos-metadata[1596]: May 27 03:18:57.165 INFO Fetch successful May 27 03:18:57.166073 coreos-metadata[1596]: May 27 03:18:57.166 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 27 03:18:57.172751 coreos-metadata[1596]: May 27 03:18:57.172 INFO Fetch successful May 27 03:18:57.195890 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 27 03:18:57.200075 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 27 03:18:57.212106 sshd_keygen[1622]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 27 03:18:57.226592 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 27 03:18:57.229511 systemd[1]: Starting issuegen.service - Generate /run/issue... May 27 03:18:57.231875 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 27 03:18:57.250438 systemd[1]: issuegen.service: Deactivated successfully. May 27 03:18:57.250821 systemd[1]: Finished issuegen.service - Generate /run/issue. May 27 03:18:57.254955 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 27 03:18:57.259403 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 27 03:18:57.270012 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 27 03:18:57.272786 systemd[1]: Started getty@tty1.service - Getty on tty1. May 27 03:18:57.277736 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. May 27 03:18:57.282912 systemd[1]: Reached target getty.target - Login Prompts. May 27 03:18:57.693006 locksmithd[1691]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 27 03:18:59.009829 containerd[1627]: time="2025-05-27T03:18:59Z" 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 May 27 03:18:59.010374 containerd[1627]: time="2025-05-27T03:18:59.010346550Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016319521Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.181µs" May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016347427Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016364849Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016483643Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016494487Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016513112Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016553631Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 03:18:59.016736 containerd[1627]: time="2025-05-27T03:18:59.016561296Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 03:18:59.017066 containerd[1627]: time="2025-05-27T03:18:59.017043366Z" 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 May 27 03:18:59.017131 containerd[1627]: time="2025-05-27T03:18:59.017121614Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 03:18:59.017175 containerd[1627]: time="2025-05-27T03:18:59.017166180Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 03:18:59.017217 containerd[1627]: time="2025-05-27T03:18:59.017209169Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 03:18:59.017332 containerd[1627]: time="2025-05-27T03:18:59.017322315Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 03:18:59.017538 containerd[1627]: time="2025-05-27T03:18:59.017525332Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 03:18:59.017593 containerd[1627]: time="2025-05-27T03:18:59.017582422Z" 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 May 27 03:18:59.017627 containerd[1627]: time="2025-05-27T03:18:59.017619730Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 03:18:59.017682 containerd[1627]: time="2025-05-27T03:18:59.017674837Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 03:18:59.018033 containerd[1627]: time="2025-05-27T03:18:59.017889326Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 03:18:59.018033 containerd[1627]: time="2025-05-27T03:18:59.017944255Z" level=info msg="metadata content store policy set" policy=shared May 27 03:18:59.507976 containerd[1627]: time="2025-05-27T03:18:59.507901255Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 03:18:59.507976 containerd[1627]: time="2025-05-27T03:18:59.507953933Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 03:18:59.507976 containerd[1627]: time="2025-05-27T03:18:59.507976153Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.507988606Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.507999279Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508022483Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508039797Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508050410Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508060184Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508069224Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508079222Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 03:18:59.508102 containerd[1627]: time="2025-05-27T03:18:59.508091354Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 03:18:59.508247 containerd[1627]: time="2025-05-27T03:18:59.508194371Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 03:18:59.508247 containerd[1627]: time="2025-05-27T03:18:59.508210309Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 03:18:59.508247 containerd[1627]: time="2025-05-27T03:18:59.508223742Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 03:18:59.508247 containerd[1627]: time="2025-05-27T03:18:59.508239300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508249551Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508258705Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508268483Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508277413Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508288151Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508297635Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 03:18:59.508313 containerd[1627]: time="2025-05-27T03:18:59.508307401Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 03:18:59.508424 containerd[1627]: time="2025-05-27T03:18:59.508360603Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 03:18:59.508424 containerd[1627]: time="2025-05-27T03:18:59.508372439Z" level=info msg="Start snapshots syncer" May 27 03:18:59.508424 containerd[1627]: time="2025-05-27T03:18:59.508388848Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 03:18:59.508864 containerd[1627]: time="2025-05-27T03:18:59.508790312Z" 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\"}" May 27 03:18:59.508995 containerd[1627]: time="2025-05-27T03:18:59.508885686Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.510842655Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.510966589Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511006966Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511022991Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511038900Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511052243Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511073327Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511088656Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511118180Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511131359Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511145530Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 03:18:59.511418 containerd[1627]: time="2025-05-27T03:18:59.511175025Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511527569Z" 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 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511545277Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511560013Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511570809Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511583664Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511595565Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511614825Z" level=info msg="runtime interface created" May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511619963Z" level=info msg="created NRI interface" May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511631331Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 03:18:59.511703 containerd[1627]: time="2025-05-27T03:18:59.511644070Z" level=info msg="Connect containerd service" May 27 03:18:59.512237 containerd[1627]: time="2025-05-27T03:18:59.512219882Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 03:18:59.514048 containerd[1627]: time="2025-05-27T03:18:59.513815157Z" 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" May 27 03:19:00.928742 containerd[1627]: time="2025-05-27T03:19:00.928440720Z" level=info msg="Start subscribing containerd event" May 27 03:19:00.928742 containerd[1627]: time="2025-05-27T03:19:00.928512927Z" level=info msg="Start recovering state" May 27 03:19:00.928742 containerd[1627]: time="2025-05-27T03:19:00.928753185Z" level=info msg="Start event monitor" May 27 03:19:00.929092 containerd[1627]: time="2025-05-27T03:19:00.928770430Z" level=info msg="Start cni network conf syncer for default" May 27 03:19:00.929092 containerd[1627]: time="2025-05-27T03:19:00.928778334Z" level=info msg="Start streaming server" May 27 03:19:00.929092 containerd[1627]: time="2025-05-27T03:19:00.928790805Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 03:19:00.929092 containerd[1627]: time="2025-05-27T03:19:00.928797729Z" level=info msg="runtime interface starting up..." May 27 03:19:00.929092 containerd[1627]: time="2025-05-27T03:19:00.928803379Z" level=info msg="starting plugins..." May 27 03:19:00.929092 containerd[1627]: time="2025-05-27T03:19:00.928826135Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 03:19:00.929213 containerd[1627]: time="2025-05-27T03:19:00.929198702Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 03:19:00.929272 containerd[1627]: time="2025-05-27T03:19:00.929251063Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 03:19:00.929327 containerd[1627]: time="2025-05-27T03:19:00.929301793Z" level=info msg="containerd successfully booted in 1.919904s" May 27 03:19:00.929416 systemd[1]: Started containerd.service - containerd container runtime. May 27 03:19:00.937801 systemd[1]: Reached target multi-user.target - Multi-User System. May 27 03:19:00.941116 systemd[1]: Startup finished in 2.887s (kernel) + 8.612s (initrd) + 13.486s (userspace) = 24.986s. May 27 03:19:01.022106 waagent[1716]: 2025-05-27T03:19:01.022043Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 27 03:19:01.023229 waagent[1716]: 2025-05-27T03:19:01.023193Z INFO Daemon Daemon OS: flatcar 4344.0.0 May 27 03:19:01.024153 waagent[1716]: 2025-05-27T03:19:01.024130Z INFO Daemon Daemon Python: 3.11.12 May 27 03:19:01.025242 waagent[1716]: 2025-05-27T03:19:01.025202Z INFO Daemon Daemon Run daemon May 27 03:19:01.026136 waagent[1716]: 2025-05-27T03:19:01.026110Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.0.0' May 27 03:19:01.027824 waagent[1716]: 2025-05-27T03:19:01.027796Z INFO Daemon Daemon Using waagent for provisioning May 27 03:19:01.028974 waagent[1716]: 2025-05-27T03:19:01.028949Z INFO Daemon Daemon Activate resource disk May 27 03:19:01.030154 waagent[1716]: 2025-05-27T03:19:01.029892Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 27 03:19:01.033306 waagent[1716]: 2025-05-27T03:19:01.033265Z INFO Daemon Daemon Found device: None May 27 03:19:01.034228 waagent[1716]: 2025-05-27T03:19:01.034198Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology May 27 03:19:01.035917 waagent[1716]: 2025-05-27T03:19:01.035855Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 May 27 03:19:01.038306 waagent[1716]: 2025-05-27T03:19:01.038261Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 27 03:19:01.039591 waagent[1716]: 2025-05-27T03:19:01.039565Z INFO Daemon Daemon Running default provisioning handler May 27 03:19:01.045541 waagent[1716]: 2025-05-27T03:19:01.045330Z 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. May 27 03:19:01.047516 waagent[1716]: 2025-05-27T03:19:01.046391Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 27 03:19:01.047516 waagent[1716]: 2025-05-27T03:19:01.046707Z INFO Daemon Daemon cloud-init is enabled: False May 27 03:19:01.047516 waagent[1716]: 2025-05-27T03:19:01.046931Z INFO Daemon Daemon Copying ovf-env.xml May 27 03:19:01.366566 waagent[1716]: 2025-05-27T03:19:01.364901Z INFO Daemon Daemon Successfully mounted dvd May 27 03:19:01.384750 login[1720]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 27 03:19:01.385149 login[1719]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 27 03:19:01.390288 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 27 03:19:01.392093 waagent[1716]: 2025-05-27T03:19:01.391870Z INFO Daemon Daemon Detect protocol endpoint May 27 03:19:01.392513 waagent[1716]: 2025-05-27T03:19:01.392457Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 27 03:19:01.392785 waagent[1716]: 2025-05-27T03:19:01.392763Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 27 03:19:01.392984 waagent[1716]: 2025-05-27T03:19:01.392969Z INFO Daemon Daemon Test for route to 168.63.129.16 May 27 03:19:01.393949 waagent[1716]: 2025-05-27T03:19:01.393372Z INFO Daemon Daemon Route to 168.63.129.16 exists May 27 03:19:01.393949 waagent[1716]: 2025-05-27T03:19:01.393561Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 27 03:19:01.404195 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 27 03:19:01.404993 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 27 03:19:01.414320 waagent[1716]: 2025-05-27T03:19:01.410773Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 27 03:19:01.414320 waagent[1716]: 2025-05-27T03:19:01.413751Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 27 03:19:01.414320 waagent[1716]: 2025-05-27T03:19:01.413875Z INFO Daemon Daemon Server preferred version:2015-04-05 May 27 03:19:01.412579 systemd-logind[1610]: New session 1 of user core. May 27 03:19:01.421955 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 27 03:19:01.423938 systemd[1]: Starting user@500.service - User Manager for UID 500... May 27 03:19:01.432575 (systemd)[1761]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 27 03:19:01.434146 systemd-logind[1610]: New session c1 of user core. May 27 03:19:01.489650 waagent[1716]: 2025-05-27T03:19:01.489605Z INFO Daemon Daemon Initializing goal state during protocol detection May 27 03:19:01.490539 waagent[1716]: 2025-05-27T03:19:01.489777Z INFO Daemon Daemon Forcing an update of the goal state. May 27 03:19:01.492621 waagent[1716]: 2025-05-27T03:19:01.492591Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 27 03:19:01.504999 waagent[1716]: 2025-05-27T03:19:01.504962Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 27 03:19:01.505604 waagent[1716]: 2025-05-27T03:19:01.505402Z INFO Daemon May 27 03:19:01.508378 waagent[1716]: 2025-05-27T03:19:01.505618Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 7f203852-5bab-4386-aeea-ee969cb48402 eTag: 15868185317266475898 source: Fabric] May 27 03:19:01.508378 waagent[1716]: 2025-05-27T03:19:01.505893Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 27 03:19:01.508378 waagent[1716]: 2025-05-27T03:19:01.506353Z INFO Daemon May 27 03:19:01.508378 waagent[1716]: 2025-05-27T03:19:01.506448Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 27 03:19:01.517167 waagent[1716]: 2025-05-27T03:19:01.517144Z INFO Daemon Daemon Downloading artifacts profile blob May 27 03:19:01.588461 waagent[1716]: 2025-05-27T03:19:01.588415Z INFO Daemon Downloaded certificate {'thumbprint': 'DD0B7A987DAA09CE229ECD36D2538E2364F04332', 'hasPrivateKey': True} May 27 03:19:01.590703 waagent[1716]: 2025-05-27T03:19:01.590672Z INFO Daemon Fetch goal state completed May 27 03:19:01.597866 waagent[1716]: 2025-05-27T03:19:01.597843Z INFO Daemon Daemon Starting provisioning May 27 03:19:01.598237 waagent[1716]: 2025-05-27T03:19:01.598212Z INFO Daemon Daemon Handle ovf-env.xml. May 27 03:19:01.599075 waagent[1716]: 2025-05-27T03:19:01.598917Z INFO Daemon Daemon Set hostname [ci-4344.0.0-a-5600cc6f2e] May 27 03:19:01.658080 waagent[1716]: 2025-05-27T03:19:01.658044Z INFO Daemon Daemon Publish hostname [ci-4344.0.0-a-5600cc6f2e] May 27 03:19:01.661746 waagent[1716]: 2025-05-27T03:19:01.658691Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 27 03:19:01.661879 waagent[1716]: 2025-05-27T03:19:01.661840Z INFO Daemon Daemon Primary interface is [eth0] May 27 03:19:01.669235 systemd-networkd[1352]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 03:19:01.669241 systemd-networkd[1352]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 03:19:01.669263 systemd-networkd[1352]: eth0: DHCP lease lost May 27 03:19:01.670063 waagent[1716]: 2025-05-27T03:19:01.670024Z INFO Daemon Daemon Create user account if not exists May 27 03:19:01.671559 waagent[1716]: 2025-05-27T03:19:01.671524Z INFO Daemon Daemon User core already exists, skip useradd May 27 03:19:01.672379 waagent[1716]: 2025-05-27T03:19:01.671644Z INFO Daemon Daemon Configure sudoer May 27 03:19:01.677093 waagent[1716]: 2025-05-27T03:19:01.677051Z INFO Daemon Daemon Configure sshd May 27 03:19:01.680298 waagent[1716]: 2025-05-27T03:19:01.680234Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 27 03:19:01.684711 waagent[1716]: 2025-05-27T03:19:01.680377Z INFO Daemon Daemon Deploy ssh public key. May 27 03:19:01.687830 systemd-networkd[1352]: eth0: DHCPv4 address 10.200.8.20/24, gateway 10.200.8.1 acquired from 168.63.129.16 May 27 03:19:01.786667 systemd[1761]: Queued start job for default target default.target. May 27 03:19:01.797483 systemd[1761]: Created slice app.slice - User Application Slice. May 27 03:19:01.797513 systemd[1761]: Reached target paths.target - Paths. May 27 03:19:01.797600 systemd[1761]: Reached target timers.target - Timers. May 27 03:19:01.798437 systemd[1761]: Starting dbus.socket - D-Bus User Message Bus Socket... May 27 03:19:01.805159 systemd[1761]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 27 03:19:01.805202 systemd[1761]: Reached target sockets.target - Sockets. May 27 03:19:01.805231 systemd[1761]: Reached target basic.target - Basic System. May 27 03:19:01.805255 systemd[1761]: Reached target default.target - Main User Target. May 27 03:19:01.805274 systemd[1761]: Startup finished in 367ms. May 27 03:19:01.805378 systemd[1]: Started user@500.service - User Manager for UID 500. May 27 03:19:01.806479 systemd[1]: Started session-1.scope - Session 1 of User core. May 27 03:19:02.386795 login[1720]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) May 27 03:19:02.391450 systemd-logind[1610]: New session 2 of user core. May 27 03:19:02.398839 systemd[1]: Started session-2.scope - Session 2 of User core. May 27 03:19:02.749680 waagent[1716]: 2025-05-27T03:19:02.749545Z INFO Daemon Daemon Provisioning complete May 27 03:19:02.763275 waagent[1716]: 2025-05-27T03:19:02.763244Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 27 03:19:02.763564 waagent[1716]: 2025-05-27T03:19:02.763535Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 27 03:19:02.763677 waagent[1716]: 2025-05-27T03:19:02.763654Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 27 03:19:02.853372 waagent[1800]: 2025-05-27T03:19:02.853310Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 27 03:19:02.853614 waagent[1800]: 2025-05-27T03:19:02.853394Z INFO ExtHandler ExtHandler OS: flatcar 4344.0.0 May 27 03:19:02.853614 waagent[1800]: 2025-05-27T03:19:02.853429Z INFO ExtHandler ExtHandler Python: 3.11.12 May 27 03:19:02.853614 waagent[1800]: 2025-05-27T03:19:02.853463Z INFO ExtHandler ExtHandler CPU Arch: x86_64 May 27 03:19:03.007513 waagent[1800]: 2025-05-27T03:19:03.007435Z INFO ExtHandler ExtHandler Distro: flatcar-4344.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; May 27 03:19:03.007605 waagent[1800]: 2025-05-27T03:19:03.007568Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 03:19:03.007645 waagent[1800]: 2025-05-27T03:19:03.007632Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 03:19:03.015619 waagent[1800]: 2025-05-27T03:19:03.015570Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 27 03:19:03.020217 waagent[1800]: 2025-05-27T03:19:03.020191Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 27 03:19:03.020534 waagent[1800]: 2025-05-27T03:19:03.020504Z INFO ExtHandler May 27 03:19:03.020591 waagent[1800]: 2025-05-27T03:19:03.020556Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 516b177d-f409-4976-a5cf-ad530e27d1e3 eTag: 15868185317266475898 source: Fabric] May 27 03:19:03.020788 waagent[1800]: 2025-05-27T03:19:03.020763Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 27 03:19:03.021110 waagent[1800]: 2025-05-27T03:19:03.021085Z INFO ExtHandler May 27 03:19:03.021144 waagent[1800]: 2025-05-27T03:19:03.021124Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 27 03:19:03.026012 waagent[1800]: 2025-05-27T03:19:03.025987Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 27 03:19:03.270969 waagent[1800]: 2025-05-27T03:19:03.270865Z INFO ExtHandler Downloaded certificate {'thumbprint': 'DD0B7A987DAA09CE229ECD36D2538E2364F04332', 'hasPrivateKey': True} May 27 03:19:03.271399 waagent[1800]: 2025-05-27T03:19:03.271358Z INFO ExtHandler Fetch goal state completed May 27 03:19:03.281392 waagent[1800]: 2025-05-27T03:19:03.281349Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 27 03:19:03.285001 waagent[1800]: 2025-05-27T03:19:03.284960Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 1800 May 27 03:19:03.285085 waagent[1800]: 2025-05-27T03:19:03.285065Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 27 03:19:03.285286 waagent[1800]: 2025-05-27T03:19:03.285265Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 27 03:19:03.286161 waagent[1800]: 2025-05-27T03:19:03.286135Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 27 03:19:03.286414 waagent[1800]: 2025-05-27T03:19:03.286391Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported May 27 03:19:03.286509 waagent[1800]: 2025-05-27T03:19:03.286492Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 27 03:19:03.286871 waagent[1800]: 2025-05-27T03:19:03.286848Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 27 03:19:03.304287 waagent[1800]: 2025-05-27T03:19:03.304263Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 27 03:19:03.304398 waagent[1800]: 2025-05-27T03:19:03.304379Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 27 03:19:03.309113 waagent[1800]: 2025-05-27T03:19:03.308992Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 27 03:19:03.313635 systemd[1]: Reload requested from client PID 1815 ('systemctl') (unit waagent.service)... May 27 03:19:03.313656 systemd[1]: Reloading... May 27 03:19:03.373773 zram_generator::config[1848]: No configuration found. May 27 03:19:03.451044 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. May 27 03:19:03.533685 systemd[1]: Reloading finished in 219 ms. May 27 03:19:03.550376 waagent[1800]: 2025-05-27T03:19:03.548873Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 27 03:19:03.550376 waagent[1800]: 2025-05-27T03:19:03.548955Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 27 03:19:04.185530 waagent[1800]: 2025-05-27T03:19:04.185470Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 27 03:19:04.185835 waagent[1800]: 2025-05-27T03:19:04.185807Z 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] May 27 03:19:04.186484 waagent[1800]: 2025-05-27T03:19:04.186453Z INFO ExtHandler ExtHandler Starting env monitor service. May 27 03:19:04.186704 waagent[1800]: 2025-05-27T03:19:04.186681Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 03:19:04.186781 waagent[1800]: 2025-05-27T03:19:04.186761Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 03:19:04.186958 waagent[1800]: 2025-05-27T03:19:04.186938Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 27 03:19:04.187292 waagent[1800]: 2025-05-27T03:19:04.187255Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 27 03:19:04.187466 waagent[1800]: 2025-05-27T03:19:04.187435Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 03:19:04.187533 waagent[1800]: 2025-05-27T03:19:04.187493Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 27 03:19:04.187765 waagent[1800]: 2025-05-27T03:19:04.187742Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 27 03:19:04.188002 waagent[1800]: 2025-05-27T03:19:04.187953Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 27 03:19:04.188163 waagent[1800]: 2025-05-27T03:19:04.188142Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 27 03:19:04.188378 waagent[1800]: 2025-05-27T03:19:04.188345Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 27 03:19:04.188378 waagent[1800]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 27 03:19:04.188378 waagent[1800]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 May 27 03:19:04.188378 waagent[1800]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 27 03:19:04.188378 waagent[1800]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 27 03:19:04.188378 waagent[1800]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 27 03:19:04.188378 waagent[1800]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 27 03:19:04.188537 waagent[1800]: 2025-05-27T03:19:04.188520Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 27 03:19:04.189602 waagent[1800]: 2025-05-27T03:19:04.189582Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 03:19:04.189959 waagent[1800]: 2025-05-27T03:19:04.189691Z INFO EnvHandler ExtHandler Configure routes May 27 03:19:04.190429 waagent[1800]: 2025-05-27T03:19:04.190407Z INFO EnvHandler ExtHandler Gateway:None May 27 03:19:04.190478 waagent[1800]: 2025-05-27T03:19:04.190448Z INFO EnvHandler ExtHandler Routes:None May 27 03:19:04.203431 waagent[1800]: 2025-05-27T03:19:04.203400Z INFO ExtHandler ExtHandler May 27 03:19:04.203501 waagent[1800]: 2025-05-27T03:19:04.203450Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 47268311-77d2-4e20-a3ed-97a0d16ab861 correlation 41a4cd02-7941-4ac0-8ba5-32a4b53d3fe4 created: 2025-05-27T03:18:07.947990Z] May 27 03:19:04.203704 waagent[1800]: 2025-05-27T03:19:04.203682Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 27 03:19:04.204046 waagent[1800]: 2025-05-27T03:19:04.204023Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] May 27 03:19:04.308110 waagent[1800]: 2025-05-27T03:19:04.307739Z 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 May 27 03:19:04.308110 waagent[1800]: Try `iptables -h' or 'iptables --help' for more information.) May 27 03:19:04.308110 waagent[1800]: 2025-05-27T03:19:04.308056Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: EB62EB5F-8E9C-4F33-AB46-FC8B6AB903EC;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 27 03:19:04.322879 waagent[1800]: 2025-05-27T03:19:04.322842Z INFO MonitorHandler ExtHandler Network interfaces: May 27 03:19:04.322879 waagent[1800]: Executing ['ip', '-a', '-o', 'link']: May 27 03:19:04.322879 waagent[1800]: 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 May 27 03:19:04.322879 waagent[1800]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:a2:62:d2 brd ff:ff:ff:ff:ff:ff\ alias Network Device May 27 03:19:04.322879 waagent[1800]: 3: enP30832s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:a2:62:d2 brd ff:ff:ff:ff:ff:ff\ altname enP30832p0s0 May 27 03:19:04.322879 waagent[1800]: Executing ['ip', '-4', '-a', '-o', 'address']: May 27 03:19:04.322879 waagent[1800]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 27 03:19:04.322879 waagent[1800]: 2: eth0 inet 10.200.8.20/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever May 27 03:19:04.322879 waagent[1800]: Executing ['ip', '-6', '-a', '-o', 'address']: May 27 03:19:04.322879 waagent[1800]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 27 03:19:04.322879 waagent[1800]: 2: eth0 inet6 fe80::222:48ff:fea2:62d2/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 27 03:19:04.322879 waagent[1800]: 3: enP30832s1 inet6 fe80::222:48ff:fea2:62d2/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 27 03:19:04.381576 waagent[1800]: 2025-05-27T03:19:04.381537Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 27 03:19:04.381576 waagent[1800]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 27 03:19:04.381576 waagent[1800]: pkts bytes target prot opt in out source destination May 27 03:19:04.381576 waagent[1800]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 27 03:19:04.381576 waagent[1800]: pkts bytes target prot opt in out source destination May 27 03:19:04.381576 waagent[1800]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 27 03:19:04.381576 waagent[1800]: pkts bytes target prot opt in out source destination May 27 03:19:04.381576 waagent[1800]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 27 03:19:04.381576 waagent[1800]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 27 03:19:04.381576 waagent[1800]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 27 03:19:04.384198 waagent[1800]: 2025-05-27T03:19:04.384158Z INFO EnvHandler ExtHandler Current Firewall rules: May 27 03:19:04.384198 waagent[1800]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 27 03:19:04.384198 waagent[1800]: pkts bytes target prot opt in out source destination May 27 03:19:04.384198 waagent[1800]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 27 03:19:04.384198 waagent[1800]: pkts bytes target prot opt in out source destination May 27 03:19:04.384198 waagent[1800]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 27 03:19:04.384198 waagent[1800]: pkts bytes target prot opt in out source destination May 27 03:19:04.384198 waagent[1800]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 27 03:19:04.384198 waagent[1800]: 7 883 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 27 03:19:04.384198 waagent[1800]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 27 03:19:20.729581 chronyd[1658]: Selected source PHC0 May 27 03:19:25.221041 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 27 03:19:25.222092 systemd[1]: Started sshd@0-10.200.8.20:22-10.200.16.10:60380.service - OpenSSH per-connection server daemon (10.200.16.10:60380). May 27 03:19:25.921301 sshd[1945]: Accepted publickey for core from 10.200.16.10 port 60380 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:25.922562 sshd-session[1945]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:25.926599 systemd-logind[1610]: New session 3 of user core. May 27 03:19:25.933841 systemd[1]: Started session-3.scope - Session 3 of User core. May 27 03:19:26.481851 systemd[1]: Started sshd@1-10.200.8.20:22-10.200.16.10:60384.service - OpenSSH per-connection server daemon (10.200.16.10:60384). May 27 03:19:27.308497 sshd[1950]: Accepted publickey for core from 10.200.16.10 port 60384 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:27.309832 sshd-session[1950]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:27.313763 systemd-logind[1610]: New session 4 of user core. May 27 03:19:27.320845 systemd[1]: Started session-4.scope - Session 4 of User core. May 27 03:19:27.749469 sshd[1952]: Connection closed by 10.200.16.10 port 60384 May 27 03:19:27.751002 sshd-session[1950]: pam_unix(sshd:session): session closed for user core May 27 03:19:27.754145 systemd[1]: sshd@1-10.200.8.20:22-10.200.16.10:60384.service: Deactivated successfully. May 27 03:19:27.755590 systemd[1]: session-4.scope: Deactivated successfully. May 27 03:19:27.756332 systemd-logind[1610]: Session 4 logged out. Waiting for processes to exit. May 27 03:19:27.757393 systemd-logind[1610]: Removed session 4. May 27 03:19:27.859654 systemd[1]: Started sshd@2-10.200.8.20:22-10.200.16.10:60400.service - OpenSSH per-connection server daemon (10.200.16.10:60400). May 27 03:19:28.493634 sshd[1958]: Accepted publickey for core from 10.200.16.10 port 60400 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:28.494924 sshd-session[1958]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:28.499191 systemd-logind[1610]: New session 5 of user core. May 27 03:19:28.500856 systemd[1]: Started session-5.scope - Session 5 of User core. May 27 03:19:28.943424 sshd[1960]: Connection closed by 10.200.16.10 port 60400 May 27 03:19:28.943972 sshd-session[1958]: pam_unix(sshd:session): session closed for user core May 27 03:19:28.947534 systemd[1]: sshd@2-10.200.8.20:22-10.200.16.10:60400.service: Deactivated successfully. May 27 03:19:28.949014 systemd[1]: session-5.scope: Deactivated successfully. May 27 03:19:28.949566 systemd-logind[1610]: Session 5 logged out. Waiting for processes to exit. May 27 03:19:28.950547 systemd-logind[1610]: Removed session 5. May 27 03:19:29.055881 systemd[1]: Started sshd@3-10.200.8.20:22-10.200.16.10:49420.service - OpenSSH per-connection server daemon (10.200.16.10:49420). May 27 03:19:29.682531 sshd[1966]: Accepted publickey for core from 10.200.16.10 port 49420 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:29.683798 sshd-session[1966]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:29.687881 systemd-logind[1610]: New session 6 of user core. May 27 03:19:29.693851 systemd[1]: Started session-6.scope - Session 6 of User core. May 27 03:19:30.122309 sshd[1968]: Connection closed by 10.200.16.10 port 49420 May 27 03:19:30.122871 sshd-session[1966]: pam_unix(sshd:session): session closed for user core May 27 03:19:30.125765 systemd[1]: sshd@3-10.200.8.20:22-10.200.16.10:49420.service: Deactivated successfully. May 27 03:19:30.127610 systemd-logind[1610]: Session 6 logged out. Waiting for processes to exit. May 27 03:19:30.127887 systemd[1]: session-6.scope: Deactivated successfully. May 27 03:19:30.129245 systemd-logind[1610]: Removed session 6. May 27 03:19:30.239790 systemd[1]: Started sshd@4-10.200.8.20:22-10.200.16.10:49422.service - OpenSSH per-connection server daemon (10.200.16.10:49422). May 27 03:19:30.866830 sshd[1974]: Accepted publickey for core from 10.200.16.10 port 49422 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:30.868010 sshd-session[1974]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:30.872156 systemd-logind[1610]: New session 7 of user core. May 27 03:19:30.876856 systemd[1]: Started session-7.scope - Session 7 of User core. May 27 03:19:31.305375 sudo[1977]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 27 03:19:31.305572 sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:19:31.334489 sudo[1977]: pam_unix(sudo:session): session closed for user root May 27 03:19:31.433951 sshd[1976]: Connection closed by 10.200.16.10 port 49422 May 27 03:19:31.434562 sshd-session[1974]: pam_unix(sshd:session): session closed for user core May 27 03:19:31.437782 systemd[1]: sshd@4-10.200.8.20:22-10.200.16.10:49422.service: Deactivated successfully. May 27 03:19:31.439118 systemd[1]: session-7.scope: Deactivated successfully. May 27 03:19:31.440685 systemd-logind[1610]: Session 7 logged out. Waiting for processes to exit. May 27 03:19:31.441449 systemd-logind[1610]: Removed session 7. May 27 03:19:31.543445 systemd[1]: Started sshd@5-10.200.8.20:22-10.200.16.10:49430.service - OpenSSH per-connection server daemon (10.200.16.10:49430). May 27 03:19:32.176830 sshd[1983]: Accepted publickey for core from 10.200.16.10 port 49430 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:32.178088 sshd-session[1983]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:32.182232 systemd-logind[1610]: New session 8 of user core. May 27 03:19:32.184835 systemd[1]: Started session-8.scope - Session 8 of User core. May 27 03:19:32.518642 sudo[1987]: 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 May 27 03:19:32.519068 sudo[1987]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:19:32.524179 sudo[1987]: pam_unix(sudo:session): session closed for user root May 27 03:19:32.527539 sudo[1986]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 27 03:19:32.527778 sudo[1986]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:19:32.534340 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 03:19:32.562232 augenrules[2009]: No rules May 27 03:19:32.562851 systemd[1]: audit-rules.service: Deactivated successfully. May 27 03:19:32.562985 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 03:19:32.563836 sudo[1986]: pam_unix(sudo:session): session closed for user root May 27 03:19:32.664118 sshd[1985]: Connection closed by 10.200.16.10 port 49430 May 27 03:19:32.664559 sshd-session[1983]: pam_unix(sshd:session): session closed for user core May 27 03:19:32.667256 systemd[1]: sshd@5-10.200.8.20:22-10.200.16.10:49430.service: Deactivated successfully. May 27 03:19:32.668426 systemd[1]: session-8.scope: Deactivated successfully. May 27 03:19:32.669008 systemd-logind[1610]: Session 8 logged out. Waiting for processes to exit. May 27 03:19:32.669886 systemd-logind[1610]: Removed session 8. May 27 03:19:32.777204 systemd[1]: Started sshd@6-10.200.8.20:22-10.200.16.10:49438.service - OpenSSH per-connection server daemon (10.200.16.10:49438). May 27 03:19:33.406518 sshd[2018]: Accepted publickey for core from 10.200.16.10 port 49438 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:33.407783 sshd-session[2018]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:33.412145 systemd-logind[1610]: New session 9 of user core. May 27 03:19:33.417861 systemd[1]: Started session-9.scope - Session 9 of User core. May 27 03:19:33.748171 sudo[2021]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . May 27 03:19:33.748370 sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:19:33.750891 sudo[2021]: pam_unix(sudo:session): session closed for user root May 27 03:19:33.864820 sshd[2020]: Connection closed by 10.200.16.10 port 49438 May 27 03:19:33.865322 sshd-session[2018]: pam_unix(sshd:session): session closed for user core May 27 03:19:33.868015 systemd[1]: sshd@6-10.200.8.20:22-10.200.16.10:49438.service: Deactivated successfully. May 27 03:19:33.869386 systemd[1]: session-9.scope: Deactivated successfully. May 27 03:19:33.870772 systemd-logind[1610]: Session 9 logged out. Waiting for processes to exit. May 27 03:19:33.871397 systemd-logind[1610]: Removed session 9. May 27 03:19:33.978809 systemd[1]: Started sshd@7-10.200.8.20:22-10.200.16.10:49450.service - OpenSSH per-connection server daemon (10.200.16.10:49450). May 27 03:19:34.604924 sshd[2027]: Accepted publickey for core from 10.200.16.10 port 49450 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:34.606136 sshd-session[2027]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:34.610306 systemd-logind[1610]: New session 10 of user core. May 27 03:19:34.617867 systemd[1]: Started session-10.scope - Session 10 of User core. May 27 03:19:34.945583 sudo[2030]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet May 27 03:19:34.945795 sudo[2030]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 03:19:38.207646 sudo[2030]: pam_unix(sudo:session): session closed for user root May 27 03:19:38.307185 sshd[2029]: Connection closed by 10.200.16.10 port 49450 May 27 03:19:38.307808 sshd-session[2027]: pam_unix(sshd:session): session closed for user core May 27 03:19:38.311034 systemd[1]: sshd@7-10.200.8.20:22-10.200.16.10:49450.service: Deactivated successfully. May 27 03:19:38.312622 systemd[1]: session-10.scope: Deactivated successfully. May 27 03:19:38.313755 systemd-logind[1610]: Session 10 logged out. Waiting for processes to exit. May 27 03:19:38.314800 systemd-logind[1610]: Removed session 10. May 27 03:19:38.417534 systemd[1]: Started sshd@8-10.200.8.20:22-10.200.16.10:49458.service - OpenSSH per-connection server daemon (10.200.16.10:49458). May 27 03:19:39.051040 sshd[2036]: Accepted publickey for core from 10.200.16.10 port 49458 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:39.052317 sshd-session[2036]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:39.056729 systemd-logind[1610]: New session 11 of user core. May 27 03:19:39.061846 systemd[1]: Started session-11.scope - Session 11 of User core. May 27 03:19:40.104126 kernel: hv_balloon: Max. dynamic memory size: 8192 MB May 27 03:19:41.957860 update_engine[1613]: I20250527 03:19:41.957758 1613 update_attempter.cc:509] Updating boot flags... May 27 03:19:42.102906 systemd[1]: Starting docker.service - Docker Application Container Engine... May 27 03:19:42.114353 (dockerd)[2076]: 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 May 27 03:19:42.770893 dockerd[2076]: time="2025-05-27T03:19:42.770852814Z" level=info msg="Starting up" May 27 03:19:42.771453 dockerd[2076]: time="2025-05-27T03:19:42.771428219Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" May 27 03:19:42.890007 dockerd[2076]: time="2025-05-27T03:19:42.889883610Z" level=info msg="Loading containers: start." May 27 03:19:42.926746 kernel: Initializing XFRM netlink socket May 27 03:19:43.307231 systemd-networkd[1352]: docker0: Link UP May 27 03:19:43.317900 dockerd[2076]: time="2025-05-27T03:19:43.317877942Z" level=info msg="Loading containers: done." May 27 03:19:43.332618 dockerd[2076]: time="2025-05-27T03:19:43.332590440Z" 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 May 27 03:19:43.332723 dockerd[2076]: time="2025-05-27T03:19:43.332644327Z" level=info msg="Docker daemon" commit=bbd0a17ccc67e48d4a69393287b7fcc4f0578683 containerd-snapshotter=false storage-driver=overlay2 version=28.0.1 May 27 03:19:43.332759 dockerd[2076]: time="2025-05-27T03:19:43.332731009Z" level=info msg="Initializing buildkit" May 27 03:19:43.364659 dockerd[2076]: time="2025-05-27T03:19:43.364631012Z" level=info msg="Completed buildkit initialization" May 27 03:19:43.370183 dockerd[2076]: time="2025-05-27T03:19:43.370146481Z" level=info msg="Daemon has completed initialization" May 27 03:19:43.370311 dockerd[2076]: time="2025-05-27T03:19:43.370195621Z" level=info msg="API listen on /run/docker.sock" May 27 03:19:43.370400 systemd[1]: Started docker.service - Docker Application Container Engine. May 27 03:19:44.444369 containerd[1627]: time="2025-05-27T03:19:44.444322106Z" level=info msg="connecting to shim fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13" address="unix:///run/containerd/s/f8a00f9929ef62ea090f4a0220ecf990b42cff29f181e52226a9d950cfab587f" namespace=moby protocol=ttrpc version=3 May 27 03:19:44.467852 systemd[1]: Started docker-fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13.scope - libcontainer container fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13. May 27 03:19:44.647798 kernel: docker0: port 1(vethee26b50) entered blocking state May 27 03:19:44.647861 kernel: docker0: port 1(vethee26b50) entered disabled state May 27 03:19:44.647876 kernel: vethee26b50: entered allmulticast mode May 27 03:19:44.649267 kernel: vethee26b50: entered promiscuous mode May 27 03:19:44.649890 systemd-networkd[1352]: vethee26b50: Link UP May 27 03:19:44.666401 kernel: eth0: renamed from vethef5f327 May 27 03:19:44.666458 kernel: docker0: port 1(vethee26b50) entered blocking state May 27 03:19:44.666474 kernel: docker0: port 1(vethee26b50) entered forwarding state May 27 03:19:44.666614 systemd-networkd[1352]: vethee26b50: Gained carrier May 27 03:19:44.667224 systemd-networkd[1352]: docker0: Gained carrier May 27 03:19:46.070955 systemd-networkd[1352]: vethee26b50: Gained IPv6LL May 27 03:19:46.262866 systemd-networkd[1352]: docker0: Gained IPv6LL May 27 03:19:47.999734 systemd[1]: docker-fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13.scope: Deactivated successfully. May 27 03:19:48.012193 containerd[1627]: time="2025-05-27T03:19:48.012018414Z" level=info msg="shim disconnected" id=fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13 namespace=moby May 27 03:19:48.012193 containerd[1627]: time="2025-05-27T03:19:48.012052518Z" level=warning msg="cleaning up after shim disconnected" id=fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13 namespace=moby May 27 03:19:48.012193 containerd[1627]: time="2025-05-27T03:19:48.012059658Z" level=info msg="cleaning up dead shim" namespace=moby May 27 03:19:48.012664 dockerd[2076]: time="2025-05-27T03:19:48.012166419Z" level=info msg="ignoring event" container=fede450b529dda646378ae95bf6709cb47f51a7610020171d88c27f76a42ac13 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 27 03:19:48.035047 kernel: docker0: port 1(vethee26b50) entered disabled state May 27 03:19:48.035096 kernel: vethef5f327: renamed from eth0 May 27 03:19:48.034843 systemd-networkd[1352]: vethee26b50: Lost carrier May 27 03:19:48.074880 systemd-networkd[1352]: vethee26b50: Link DOWN May 27 03:19:48.077456 kernel: docker0: port 1(vethee26b50) entered disabled state May 27 03:19:48.077504 kernel: vethee26b50 (unregistering): left allmulticast mode May 27 03:19:48.077607 kernel: vethee26b50 (unregistering): left promiscuous mode May 27 03:19:48.079445 kernel: docker0: port 1(vethee26b50) entered disabled state May 27 03:19:48.089195 systemd[1]: run-docker-netns-480c8e22a3b9.mount: Deactivated successfully. May 27 03:19:48.093077 systemd[1]: var-lib-docker-overlay2-9eab793476da7dc56504a8bc1adf485fb7bc349aec4d08d597d8213a2c07d4ec-merged.mount: Deactivated successfully. May 27 03:19:48.220160 sshd[2038]: Connection closed by 10.200.16.10 port 49458 May 27 03:19:48.220768 sshd-session[2036]: pam_unix(sshd:session): session closed for user core May 27 03:19:48.223603 systemd[1]: sshd@8-10.200.8.20:22-10.200.16.10:49458.service: Deactivated successfully. May 27 03:19:48.224963 systemd[1]: session-11.scope: Deactivated successfully. May 27 03:19:48.226455 systemd-logind[1610]: Session 11 logged out. Waiting for processes to exit. May 27 03:19:48.227295 systemd-logind[1610]: Removed session 11. May 27 03:19:48.330635 systemd[1]: Started sshd@9-10.200.8.20:22-10.200.16.10:50760.service - OpenSSH per-connection server daemon (10.200.16.10:50760). May 27 03:19:48.959124 sshd[2355]: Accepted publickey for core from 10.200.16.10 port 50760 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:48.960342 sshd-session[2355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:48.964640 systemd-logind[1610]: New session 12 of user core. May 27 03:19:48.968818 systemd[1]: Started session-12.scope - Session 12 of User core. May 27 03:19:49.079088 systemd-networkd[1352]: docker0: Lost carrier May 27 03:19:49.341691 systemd[1]: var-lib-docker-overlay2-16f174a1167969d64dc0ae2779b0575715c2cae9836f653f8217b43cd9bf29c4\x2dinit-merged.mount: Deactivated successfully. May 27 03:19:49.409526 containerd[1627]: time="2025-05-27T03:19:49.409479733Z" level=info msg="connecting to shim b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3" address="unix:///run/containerd/s/6d81517822b9a6ae4fd3e99b5ed2aefad663f39bd852816daac869fec00171e2" namespace=moby protocol=ttrpc version=3 May 27 03:19:49.429835 systemd[1]: Started docker-b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3.scope - libcontainer container b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3. May 27 03:19:49.459388 kernel: docker0: port 1(veth7fe9bdf) entered blocking state May 27 03:19:49.459645 kernel: docker0: port 1(veth7fe9bdf) entered disabled state May 27 03:19:49.459710 kernel: veth7fe9bdf: entered allmulticast mode May 27 03:19:49.459747 kernel: veth7fe9bdf: entered promiscuous mode May 27 03:19:49.459988 systemd-networkd[1352]: veth7fe9bdf: Link UP May 27 03:19:49.473240 kernel: eth0: renamed from veth78b3722 May 27 03:19:49.473292 kernel: docker0: port 1(veth7fe9bdf) entered blocking state May 27 03:19:49.473311 kernel: docker0: port 1(veth7fe9bdf) entered forwarding state May 27 03:19:49.473373 systemd-networkd[1352]: veth7fe9bdf: Gained carrier May 27 03:19:49.473647 systemd-networkd[1352]: docker0: Gained carrier May 27 03:19:49.494735 systemd[1]: docker-b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3.scope: Deactivated successfully. May 27 03:19:49.508384 dockerd[2076]: time="2025-05-27T03:19:49.508351444Z" level=info msg="ignoring event" container=b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 27 03:19:49.508610 containerd[1627]: time="2025-05-27T03:19:49.508566321Z" level=info msg="shim disconnected" id=b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3 namespace=moby May 27 03:19:49.508610 containerd[1627]: time="2025-05-27T03:19:49.508597039Z" level=warning msg="cleaning up after shim disconnected" id=b175fe51d8c4c22532869ccb49c0018c2b9bf011889fcfb4a5290a1c2f051ad3 namespace=moby May 27 03:19:49.508664 containerd[1627]: time="2025-05-27T03:19:49.508603844Z" level=info msg="cleaning up dead shim" namespace=moby May 27 03:19:49.527844 kernel: docker0: port 1(veth7fe9bdf) entered disabled state May 27 03:19:49.527895 kernel: veth78b3722: renamed from eth0 May 27 03:19:49.527823 systemd-networkd[1352]: veth7fe9bdf: Lost carrier May 27 03:19:49.541881 systemd-networkd[1352]: veth7fe9bdf: Link DOWN May 27 03:19:49.542822 kernel: docker0: port 1(veth7fe9bdf) entered disabled state May 27 03:19:49.544788 kernel: veth7fe9bdf (unregistering): left allmulticast mode May 27 03:19:49.544832 kernel: veth7fe9bdf (unregistering): left promiscuous mode May 27 03:19:49.544853 kernel: docker0: port 1(veth7fe9bdf) entered disabled state May 27 03:19:49.683348 sshd[2357]: Connection closed by 10.200.16.10 port 50760 May 27 03:19:49.684443 sshd-session[2355]: pam_unix(sshd:session): session closed for user core May 27 03:19:49.687148 systemd[1]: sshd@9-10.200.8.20:22-10.200.16.10:50760.service: Deactivated successfully. May 27 03:19:49.688388 systemd[1]: session-12.scope: Deactivated successfully. May 27 03:19:49.689018 systemd-logind[1610]: Session 12 logged out. Waiting for processes to exit. May 27 03:19:49.690221 systemd-logind[1610]: Removed session 12. May 27 03:19:49.799763 systemd[1]: Started sshd@10-10.200.8.20:22-10.200.16.10:58414.service - OpenSSH per-connection server daemon (10.200.16.10:58414). May 27 03:19:50.103015 systemd-networkd[1352]: docker0: Lost carrier May 27 03:19:50.339596 systemd[1]: run-docker-netns-9f3508fa3328.mount: Deactivated successfully. May 27 03:19:50.339706 systemd[1]: var-lib-docker-overlay2-16f174a1167969d64dc0ae2779b0575715c2cae9836f653f8217b43cd9bf29c4-merged.mount: Deactivated successfully. May 27 03:19:50.428623 sshd[2452]: Accepted publickey for core from 10.200.16.10 port 58414 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:50.430056 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:50.434036 systemd-logind[1610]: New session 13 of user core. May 27 03:19:50.435850 systemd[1]: Started session-13.scope - Session 13 of User core. May 27 03:19:50.818649 ntpdate[2463]: ntpdate 4.2.8p17@1.4004-o Tue May 27 00:37:42 UTC 2025 (1) May 27 03:19:57.612577 ntpdate[2463]: adjust time server 193.1.8.106 offset -0.018470 sec May 27 03:19:57.713635 sshd[2454]: Connection closed by 10.200.16.10 port 58414 May 27 03:19:57.714232 sshd-session[2452]: pam_unix(sshd:session): session closed for user core May 27 03:19:57.718028 systemd[1]: sshd@10-10.200.8.20:22-10.200.16.10:58414.service: Deactivated successfully. May 27 03:19:57.719311 systemd[1]: session-13.scope: Deactivated successfully. May 27 03:19:57.719924 systemd-logind[1610]: Session 13 logged out. Waiting for processes to exit. May 27 03:19:57.721001 systemd-logind[1610]: Removed session 13. May 27 03:19:57.827865 systemd[1]: Started sshd@11-10.200.8.20:22-10.200.16.10:58430.service - OpenSSH per-connection server daemon (10.200.16.10:58430). May 27 03:19:58.454780 sshd[2468]: Accepted publickey for core from 10.200.16.10 port 58430 ssh2: RSA SHA256:+Fe8XoeidIDpreT5xokg3fL/NOTS8jCIdPAwqh1eVaU May 27 03:19:58.456063 sshd-session[2468]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 03:19:58.460614 systemd-logind[1610]: New session 14 of user core. May 27 03:19:58.466867 systemd[1]: Started session-14.scope - Session 14 of User core. May 27 03:19:58.946914 sshd[2470]: Connection closed by 10.200.16.10 port 58430 May 27 03:19:58.947457 sshd-session[2468]: pam_unix(sshd:session): session closed for user core May 27 03:19:58.950808 systemd[1]: sshd@11-10.200.8.20:22-10.200.16.10:58430.service: Deactivated successfully. May 27 03:19:58.952083 systemd[1]: session-14.scope: Deactivated successfully. May 27 03:19:58.952750 systemd-logind[1610]: Session 14 logged out. Waiting for processes to exit. May 27 03:19:58.953766 systemd-logind[1610]: Removed session 14.