Nov 4 04:58:58.484474 kernel: Linux version 6.12.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Tue Nov 4 03:00:51 -00 2025 Nov 4 04:58:58.484501 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=c479bf273e218e23ca82ede45f2bfcd1a1714a33fe5860e964ed0aea09538f01 Nov 4 04:58:58.484513 kernel: BIOS-provided physical RAM map: Nov 4 04:58:58.484521 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Nov 4 04:58:58.484528 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Nov 4 04:58:58.484534 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000044fdfff] usable Nov 4 04:58:58.484543 kernel: BIOS-e820: [mem 0x00000000044fe000-0x00000000048fdfff] reserved Nov 4 04:58:58.484551 kernel: BIOS-e820: [mem 0x00000000048fe000-0x000000003ff1efff] usable Nov 4 04:58:58.484558 kernel: BIOS-e820: [mem 0x000000003ff1f000-0x000000003ffc8fff] reserved Nov 4 04:58:58.484567 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Nov 4 04:58:58.484574 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Nov 4 04:58:58.484581 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Nov 4 04:58:58.484588 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable Nov 4 04:58:58.484596 kernel: printk: legacy bootconsole [earlyser0] enabled Nov 4 04:58:58.484607 kernel: NX (Execute Disable) protection: active Nov 4 04:58:58.484614 kernel: APIC: Static calls initialized Nov 4 04:58:58.484622 kernel: efi: EFI v2.7 by Microsoft Nov 4 04:58:58.484630 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff88000 SMBIOS 3.0=0x3ff86000 MEMATTR=0x3eab5518 RNG=0x3ffd2018 Nov 4 04:58:58.484638 kernel: random: crng init done Nov 4 04:58:58.484646 kernel: secureboot: Secure boot disabled Nov 4 04:58:58.484653 kernel: SMBIOS 3.1.0 present. Nov 4 04:58:58.484661 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 01/28/2025 Nov 4 04:58:58.484669 kernel: DMI: Memory slots populated: 2/2 Nov 4 04:58:58.484677 kernel: Hypervisor detected: Microsoft Hyper-V Nov 4 04:58:58.484686 kernel: Hyper-V: privilege flags low 0xae7f, high 0x3b8030, hints 0x9e4e24, misc 0xe0bed7b2 Nov 4 04:58:58.484694 kernel: Hyper-V: Nested features: 0x3e0101 Nov 4 04:58:58.484702 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Nov 4 04:58:58.484709 kernel: Hyper-V: Using hypercall for remote TLB flush Nov 4 04:58:58.484717 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 4 04:58:58.484725 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Nov 4 04:58:58.484733 kernel: tsc: Detected 2300.000 MHz processor Nov 4 04:58:58.484741 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Nov 4 04:58:58.484750 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Nov 4 04:58:58.484758 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x10000000000 Nov 4 04:58:58.484768 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs Nov 4 04:58:58.484777 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Nov 4 04:58:58.484786 kernel: e820: update [mem 0x48000000-0xffffffff] usable ==> reserved Nov 4 04:58:58.484794 kernel: last_pfn = 0x40000 max_arch_pfn = 0x10000000000 Nov 4 04:58:58.484802 kernel: Using GB pages for direct mapping Nov 4 04:58:58.484810 kernel: ACPI: Early table checksum verification disabled Nov 4 04:58:58.484824 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Nov 4 04:58:58.484833 kernel: ACPI: XSDT 0x000000003FFF90E8 00005C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:58:58.484841 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:58:58.484850 kernel: ACPI: DSDT 0x000000003FFD6000 01E27A (v02 MSFTVM DSDT01 00000001 INTL 20230628) Nov 4 04:58:58.484859 kernel: ACPI: FACS 0x000000003FFFE000 000040 Nov 4 04:58:58.484868 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:58:58.484878 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:58:58.484887 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:58:58.484895 kernel: ACPI: APIC 0x000000003FFD5000 000052 (v05 HVLITE HVLITETB 00000000 MSHV 00000000) Nov 4 04:58:58.484904 kernel: ACPI: SRAT 0x000000003FFD4000 0000A0 (v03 HVLITE HVLITETB 00000000 MSHV 00000000) Nov 4 04:58:58.484913 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:58:58.484922 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Nov 4 04:58:58.484932 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4279] Nov 4 04:58:58.484941 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Nov 4 04:58:58.484950 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Nov 4 04:58:58.484958 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Nov 4 04:58:58.484967 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Nov 4 04:58:58.484976 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5051] Nov 4 04:58:58.484984 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd409f] Nov 4 04:58:58.484994 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Nov 4 04:58:58.485003 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Nov 4 04:58:58.485012 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff] Nov 4 04:58:58.485020 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00001000-0x2bfffffff] Nov 4 04:58:58.485048 kernel: NODE_DATA(0) allocated [mem 0x2bfff8dc0-0x2bfffffff] Nov 4 04:58:58.485058 kernel: Zone ranges: Nov 4 04:58:58.485067 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Nov 4 04:58:58.485077 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Nov 4 04:58:58.485086 kernel: Normal [mem 0x0000000100000000-0x00000002bfffffff] Nov 4 04:58:58.485095 kernel: Device empty Nov 4 04:58:58.485103 kernel: Movable zone start for each node Nov 4 04:58:58.485112 kernel: Early memory node ranges Nov 4 04:58:58.485121 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Nov 4 04:58:58.485130 kernel: node 0: [mem 0x0000000000100000-0x00000000044fdfff] Nov 4 04:58:58.485140 kernel: node 0: [mem 0x00000000048fe000-0x000000003ff1efff] Nov 4 04:58:58.485149 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Nov 4 04:58:58.485157 kernel: node 0: [mem 0x0000000100000000-0x00000002bfffffff] Nov 4 04:58:58.485166 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff] Nov 4 04:58:58.485175 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Nov 4 04:58:58.485184 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Nov 4 04:58:58.485192 kernel: On node 0, zone DMA32: 1024 pages in unavailable ranges Nov 4 04:58:58.485203 kernel: On node 0, zone DMA32: 224 pages in unavailable ranges Nov 4 04:58:58.485212 kernel: ACPI: PM-Timer IO Port: 0x408 Nov 4 04:58:58.485220 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 4 04:58:58.485229 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 4 04:58:58.485238 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Nov 4 04:58:58.485247 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Nov 4 04:58:58.485256 kernel: TSC deadline timer available Nov 4 04:58:58.485266 kernel: CPU topo: Max. logical packages: 1 Nov 4 04:58:58.485275 kernel: CPU topo: Max. logical dies: 1 Nov 4 04:58:58.485284 kernel: CPU topo: Max. dies per package: 1 Nov 4 04:58:58.485292 kernel: CPU topo: Max. threads per core: 2 Nov 4 04:58:58.485301 kernel: CPU topo: Num. cores per package: 1 Nov 4 04:58:58.485310 kernel: CPU topo: Num. threads per package: 2 Nov 4 04:58:58.485319 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Nov 4 04:58:58.485328 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Nov 4 04:58:58.485338 kernel: Booting paravirtualized kernel on Hyper-V Nov 4 04:58:58.485347 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Nov 4 04:58:58.485356 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Nov 4 04:58:58.485365 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576 Nov 4 04:58:58.485374 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152 Nov 4 04:58:58.485383 kernel: pcpu-alloc: [0] 0 1 Nov 4 04:58:58.485392 kernel: Hyper-V: PV spinlocks enabled Nov 4 04:58:58.485402 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Nov 4 04:58:58.485412 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=c479bf273e218e23ca82ede45f2bfcd1a1714a33fe5860e964ed0aea09538f01 Nov 4 04:58:58.485422 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Nov 4 04:58:58.485430 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 4 04:58:58.485439 kernel: Fallback order for Node 0: 0 Nov 4 04:58:58.485448 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2095807 Nov 4 04:58:58.485458 kernel: Policy zone: Normal Nov 4 04:58:58.485467 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 4 04:58:58.485475 kernel: software IO TLB: area num 2. Nov 4 04:58:58.485484 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 4 04:58:58.485493 kernel: ftrace: allocating 40092 entries in 157 pages Nov 4 04:58:58.485501 kernel: ftrace: allocated 157 pages with 5 groups Nov 4 04:58:58.485510 kernel: Dynamic Preempt: voluntary Nov 4 04:58:58.485520 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 4 04:58:58.485530 kernel: rcu: RCU event tracing is enabled. Nov 4 04:58:58.485539 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 4 04:58:58.485555 kernel: Trampoline variant of Tasks RCU enabled. Nov 4 04:58:58.485566 kernel: Rude variant of Tasks RCU enabled. Nov 4 04:58:58.485575 kernel: Tracing variant of Tasks RCU enabled. Nov 4 04:58:58.485585 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 4 04:58:58.485594 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 4 04:58:58.485604 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 04:58:58.485613 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 04:58:58.485624 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 04:58:58.485634 kernel: Using NULL legacy PIC Nov 4 04:58:58.485643 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0 Nov 4 04:58:58.485654 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 4 04:58:58.485664 kernel: Console: colour dummy device 80x25 Nov 4 04:58:58.485673 kernel: printk: legacy console [tty1] enabled Nov 4 04:58:58.485682 kernel: printk: legacy console [ttyS0] enabled Nov 4 04:58:58.485692 kernel: printk: legacy bootconsole [earlyser0] disabled Nov 4 04:58:58.485701 kernel: ACPI: Core revision 20240827 Nov 4 04:58:58.485710 kernel: Failed to register legacy timer interrupt Nov 4 04:58:58.485720 kernel: APIC: Switch to symmetric I/O mode setup Nov 4 04:58:58.485731 kernel: x2apic enabled Nov 4 04:58:58.485740 kernel: APIC: Switched APIC routing to: physical x2apic Nov 4 04:58:58.485750 kernel: Hyper-V: Host Build 10.0.26100.1381-1-0 Nov 4 04:58:58.485759 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 4 04:58:58.485768 kernel: Hyper-V: Disabling IBT because of Hyper-V bug Nov 4 04:58:58.485778 kernel: Hyper-V: Using IPI hypercalls Nov 4 04:58:58.485787 kernel: APIC: send_IPI() replaced with hv_send_ipi() Nov 4 04:58:58.485798 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Nov 4 04:58:58.485807 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Nov 4 04:58:58.485817 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Nov 4 04:58:58.485826 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Nov 4 04:58:58.485836 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Nov 4 04:58:58.485845 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212735223b2, max_idle_ns: 440795277976 ns Nov 4 04:58:58.485855 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4600.00 BogoMIPS (lpj=2300000) Nov 4 04:58:58.485866 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated Nov 4 04:58:58.485875 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Nov 4 04:58:58.485884 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Nov 4 04:58:58.485893 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Nov 4 04:58:58.485902 kernel: Spectre V2 : Mitigation: Retpolines Nov 4 04:58:58.485911 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Nov 4 04:58:58.485920 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible! Nov 4 04:58:58.485930 kernel: RETBleed: Vulnerable Nov 4 04:58:58.485938 kernel: Speculative Store Bypass: Vulnerable Nov 4 04:58:58.485949 kernel: active return thunk: its_return_thunk Nov 4 04:58:58.485958 kernel: ITS: Mitigation: Aligned branch/return thunks Nov 4 04:58:58.485966 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Nov 4 04:58:58.485975 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Nov 4 04:58:58.485984 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Nov 4 04:58:58.485993 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask' Nov 4 04:58:58.486002 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256' Nov 4 04:58:58.486011 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256' Nov 4 04:58:58.486020 kernel: x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers' Nov 4 04:58:58.486041 kernel: x86/fpu: Supporting XSAVE feature 0x20000: 'AMX Tile config' Nov 4 04:58:58.486049 kernel: x86/fpu: Supporting XSAVE feature 0x40000: 'AMX Tile data' Nov 4 04:58:58.486057 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Nov 4 04:58:58.486065 kernel: x86/fpu: xstate_offset[5]: 832, xstate_sizes[5]: 64 Nov 4 04:58:58.486074 kernel: x86/fpu: xstate_offset[6]: 896, xstate_sizes[6]: 512 Nov 4 04:58:58.486082 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024 Nov 4 04:58:58.486090 kernel: x86/fpu: xstate_offset[11]: 2432, xstate_sizes[11]: 16 Nov 4 04:58:58.486098 kernel: x86/fpu: xstate_offset[17]: 2496, xstate_sizes[17]: 64 Nov 4 04:58:58.486106 kernel: x86/fpu: xstate_offset[18]: 2560, xstate_sizes[18]: 8192 Nov 4 04:58:58.486113 kernel: x86/fpu: Enabled xstate features 0x608e7, context size is 10752 bytes, using 'compacted' format. Nov 4 04:58:58.486121 kernel: Freeing SMP alternatives memory: 32K Nov 4 04:58:58.486130 kernel: pid_max: default: 32768 minimum: 301 Nov 4 04:58:58.486140 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 4 04:58:58.486148 kernel: landlock: Up and running. Nov 4 04:58:58.486156 kernel: SELinux: Initializing. Nov 4 04:58:58.486164 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 4 04:58:58.486173 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Nov 4 04:58:58.486182 kernel: smpboot: CPU0: Intel INTEL(R) XEON(R) PLATINUM 8573C (family: 0x6, model: 0xcf, stepping: 0x2) Nov 4 04:58:58.486191 kernel: Performance Events: unsupported p6 CPU model 207 no PMU driver, software events only. Nov 4 04:58:58.486200 kernel: signal: max sigframe size: 11952 Nov 4 04:58:58.486208 kernel: rcu: Hierarchical SRCU implementation. Nov 4 04:58:58.486220 kernel: rcu: Max phase no-delay instances is 400. Nov 4 04:58:58.486230 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 4 04:58:58.486239 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Nov 4 04:58:58.486248 kernel: smp: Bringing up secondary CPUs ... Nov 4 04:58:58.486257 kernel: smpboot: x86: Booting SMP configuration: Nov 4 04:58:58.486267 kernel: .... node #0, CPUs: #1 Nov 4 04:58:58.486276 kernel: smp: Brought up 1 node, 2 CPUs Nov 4 04:58:58.486287 kernel: smpboot: Total of 2 processors activated (9200.00 BogoMIPS) Nov 4 04:58:58.486297 kernel: Memory: 8095536K/8383228K available (14336K kernel code, 2443K rwdata, 29892K rodata, 15360K init, 2684K bss, 281556K reserved, 0K cma-reserved) Nov 4 04:58:58.486305 kernel: devtmpfs: initialized Nov 4 04:58:58.486314 kernel: x86/mm: Memory block size: 128MB Nov 4 04:58:58.486322 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Nov 4 04:58:58.486330 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 4 04:58:58.486339 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 4 04:58:58.486349 kernel: pinctrl core: initialized pinctrl subsystem Nov 4 04:58:58.486357 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 4 04:58:58.486365 kernel: audit: initializing netlink subsys (disabled) Nov 4 04:58:58.486375 kernel: audit: type=2000 audit(1762232333.028:1): state=initialized audit_enabled=0 res=1 Nov 4 04:58:58.486384 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 4 04:58:58.486393 kernel: thermal_sys: Registered thermal governor 'user_space' Nov 4 04:58:58.486402 kernel: cpuidle: using governor menu Nov 4 04:58:58.486413 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 4 04:58:58.486422 kernel: dca service started, version 1.12.1 Nov 4 04:58:58.486431 kernel: e820: reserve RAM buffer [mem 0x044fe000-0x07ffffff] Nov 4 04:58:58.486441 kernel: e820: reserve RAM buffer [mem 0x3ff1f000-0x3fffffff] Nov 4 04:58:58.486449 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Nov 4 04:58:58.486458 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 4 04:58:58.486467 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Nov 4 04:58:58.486478 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 4 04:58:58.486487 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Nov 4 04:58:58.486496 kernel: ACPI: Added _OSI(Module Device) Nov 4 04:58:58.486505 kernel: ACPI: Added _OSI(Processor Device) Nov 4 04:58:58.486514 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 4 04:58:58.486523 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 4 04:58:58.486532 kernel: ACPI: Interpreter enabled Nov 4 04:58:58.486541 kernel: ACPI: PM: (supports S0 S5) Nov 4 04:58:58.486552 kernel: ACPI: Using IOAPIC for interrupt routing Nov 4 04:58:58.486561 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Nov 4 04:58:58.486570 kernel: PCI: Ignoring E820 reservations for host bridge windows Nov 4 04:58:58.486579 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Nov 4 04:58:58.486588 kernel: iommu: Default domain type: Translated Nov 4 04:58:58.486597 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Nov 4 04:58:58.486606 kernel: efivars: Registered efivars operations Nov 4 04:58:58.486617 kernel: PCI: Using ACPI for IRQ routing Nov 4 04:58:58.486626 kernel: PCI: System does not support PCI Nov 4 04:58:58.486635 kernel: vgaarb: loaded Nov 4 04:58:58.486644 kernel: clocksource: Switched to clocksource tsc-early Nov 4 04:58:58.486653 kernel: VFS: Disk quotas dquot_6.6.0 Nov 4 04:58:58.486661 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 4 04:58:58.486671 kernel: pnp: PnP ACPI init Nov 4 04:58:58.486682 kernel: pnp: PnP ACPI: found 3 devices Nov 4 04:58:58.486691 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Nov 4 04:58:58.486701 kernel: NET: Registered PF_INET protocol family Nov 4 04:58:58.486710 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Nov 4 04:58:58.486719 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Nov 4 04:58:58.486728 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 4 04:58:58.486737 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 4 04:58:58.486748 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Nov 4 04:58:58.486758 kernel: TCP: Hash tables configured (established 65536 bind 65536) Nov 4 04:58:58.486767 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Nov 4 04:58:58.486775 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Nov 4 04:58:58.486785 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 4 04:58:58.486793 kernel: NET: Registered PF_XDP protocol family Nov 4 04:58:58.486802 kernel: PCI: CLS 0 bytes, default 64 Nov 4 04:58:58.486813 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Nov 4 04:58:58.486822 kernel: software IO TLB: mapped [mem 0x000000003a9b9000-0x000000003e9b9000] (64MB) Nov 4 04:58:58.486831 kernel: RAPL PMU: API unit is 2^-32 Joules, 1 fixed counters, 10737418240 ms ovfl timer Nov 4 04:58:58.486840 kernel: RAPL PMU: hw unit of domain psys 2^-0 Joules Nov 4 04:58:58.486850 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212735223b2, max_idle_ns: 440795277976 ns Nov 4 04:58:58.486859 kernel: clocksource: Switched to clocksource tsc Nov 4 04:58:58.486868 kernel: Initialise system trusted keyrings Nov 4 04:58:58.486879 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Nov 4 04:58:58.486888 kernel: Key type asymmetric registered Nov 4 04:58:58.486896 kernel: Asymmetric key parser 'x509' registered Nov 4 04:58:58.486905 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 4 04:58:58.486915 kernel: io scheduler mq-deadline registered Nov 4 04:58:58.486924 kernel: io scheduler kyber registered Nov 4 04:58:58.486933 kernel: io scheduler bfq registered Nov 4 04:58:58.486944 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Nov 4 04:58:58.486953 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 4 04:58:58.486962 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Nov 4 04:58:58.486971 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Nov 4 04:58:58.486980 kernel: serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a 16550A Nov 4 04:58:58.486989 kernel: i8042: PNP: No PS/2 controller found. Nov 4 04:58:58.487191 kernel: rtc_cmos 00:02: registered as rtc0 Nov 4 04:58:58.487298 kernel: rtc_cmos 00:02: setting system clock to 2025-11-04T04:58:55 UTC (1762232335) Nov 4 04:58:58.487395 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Nov 4 04:58:58.487406 kernel: intel_pstate: Intel P-state driver initializing Nov 4 04:58:58.487416 kernel: efifb: probing for efifb Nov 4 04:58:58.487425 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 4 04:58:58.487434 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 4 04:58:58.487445 kernel: efifb: scrolling: redraw Nov 4 04:58:58.487454 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 4 04:58:58.487463 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 04:58:58.487472 kernel: fb0: EFI VGA frame buffer device Nov 4 04:58:58.487482 kernel: pstore: Using crash dump compression: deflate Nov 4 04:58:58.487491 kernel: pstore: Registered efi_pstore as persistent store backend Nov 4 04:58:58.487500 kernel: NET: Registered PF_INET6 protocol family Nov 4 04:58:58.487511 kernel: Segment Routing with IPv6 Nov 4 04:58:58.487520 kernel: In-situ OAM (IOAM) with IPv6 Nov 4 04:58:58.487529 kernel: NET: Registered PF_PACKET protocol family Nov 4 04:58:58.487538 kernel: Key type dns_resolver registered Nov 4 04:58:58.487546 kernel: IPI shorthand broadcast: enabled Nov 4 04:58:58.487555 kernel: sched_clock: Marking stable (1796026458, 96071646)->(2184859233, -292761129) Nov 4 04:58:58.487564 kernel: registered taskstats version 1 Nov 4 04:58:58.487573 kernel: Loading compiled-in X.509 certificates Nov 4 04:58:58.487585 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: dafbe857b8ef9eaad4381fdddb57853ce023547e' Nov 4 04:58:58.487594 kernel: Demotion targets for Node 0: null Nov 4 04:58:58.487603 kernel: Key type .fscrypt registered Nov 4 04:58:58.487612 kernel: Key type fscrypt-provisioning registered Nov 4 04:58:58.487620 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 4 04:58:58.487629 kernel: ima: Allocated hash algorithm: sha1 Nov 4 04:58:58.487638 kernel: ima: No architecture policies found Nov 4 04:58:58.487649 kernel: clk: Disabling unused clocks Nov 4 04:58:58.487658 kernel: Freeing unused kernel image (initmem) memory: 15360K Nov 4 04:58:58.487667 kernel: Write protecting the kernel read-only data: 45056k Nov 4 04:58:58.487676 kernel: Freeing unused kernel image (rodata/data gap) memory: 828K Nov 4 04:58:58.487684 kernel: Run /init as init process Nov 4 04:58:58.487693 kernel: with arguments: Nov 4 04:58:58.487702 kernel: /init Nov 4 04:58:58.487712 kernel: with environment: Nov 4 04:58:58.487721 kernel: HOME=/ Nov 4 04:58:58.487730 kernel: TERM=linux Nov 4 04:58:58.487739 kernel: hv_vmbus: Vmbus version:5.3 Nov 4 04:58:58.487748 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 4 04:58:58.487757 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 4 04:58:58.487766 kernel: PTP clock support registered Nov 4 04:58:58.487776 kernel: hv_utils: Registering HyperV Utility Driver Nov 4 04:58:58.487785 kernel: hv_vmbus: registering driver hv_utils Nov 4 04:58:58.487794 kernel: hv_utils: Shutdown IC version 3.2 Nov 4 04:58:58.487803 kernel: hv_utils: Heartbeat IC version 3.0 Nov 4 04:58:58.487813 kernel: hv_utils: TimeSync IC version 4.0 Nov 4 04:58:58.487822 kernel: SCSI subsystem initialized Nov 4 04:58:58.487831 kernel: hv_vmbus: registering driver hv_pci Nov 4 04:58:58.487964 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI VMBus probing: Using version 0x10004 Nov 4 04:58:58.488084 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI host bridge to bus c05b:00 Nov 4 04:58:58.488210 kernel: pci_bus c05b:00: root bus resource [mem 0xfc0000000-0xfc007ffff window] Nov 4 04:58:58.488317 kernel: pci_bus c05b:00: No busn resource found for root bus, will use [bus 00-ff] Nov 4 04:58:58.488446 kernel: pci c05b:00:00.0: [1414:00a9] type 00 class 0x010802 PCIe Endpoint Nov 4 04:58:58.488568 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit] Nov 4 04:58:58.488677 kernel: pci_bus c05b:00: busn_res: [bus 00-ff] end is updated to 00 Nov 4 04:58:58.488793 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit]: assigned Nov 4 04:58:58.488804 kernel: hv_vmbus: registering driver hv_storvsc Nov 4 04:58:58.488921 kernel: scsi host0: storvsc_host_t Nov 4 04:58:58.489051 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Nov 4 04:58:58.489065 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 4 04:58:58.489074 kernel: hv_vmbus: registering driver hid_hyperv Nov 4 04:58:58.489083 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 4 04:58:58.489190 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 4 04:58:58.489202 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 4 04:58:58.489211 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Nov 4 04:58:58.489308 kernel: nvme nvme0: pci function c05b:00:00.0 Nov 4 04:58:58.489422 kernel: nvme c05b:00:00.0: enabling device (0000 -> 0002) Nov 4 04:58:58.489506 kernel: nvme nvme0: 2/0/0 default/read/poll queues Nov 4 04:58:58.489517 kernel: nvme0n1: p1 p2 p3 p4 p6 p7 p9 Nov 4 04:58:58.489626 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 4 04:58:58.489638 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 4 04:58:58.489745 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 4 04:58:58.489756 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 4 04:58:58.489765 kernel: device-mapper: uevent: version 1.0.3 Nov 4 04:58:58.489773 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 4 04:58:58.489782 kernel: device-mapper: verity: sha256 using shash "sha256-generic" Nov 4 04:58:58.489791 kernel: raid6: avx512x4 gen() 45038 MB/s Nov 4 04:58:58.489812 kernel: raid6: avx512x2 gen() 44474 MB/s Nov 4 04:58:58.489823 kernel: raid6: avx512x1 gen() 30004 MB/s Nov 4 04:58:58.489832 kernel: raid6: avx2x4 gen() 40921 MB/s Nov 4 04:58:58.489841 kernel: raid6: avx2x2 gen() 43996 MB/s Nov 4 04:58:58.489850 kernel: raid6: avx2x1 gen() 34737 MB/s Nov 4 04:58:58.489858 kernel: raid6: using algorithm avx512x4 gen() 45038 MB/s Nov 4 04:58:58.489867 kernel: raid6: .... xor() 7518 MB/s, rmw enabled Nov 4 04:58:58.489878 kernel: raid6: using avx512x2 recovery algorithm Nov 4 04:58:58.489887 kernel: xor: automatically using best checksumming function avx Nov 4 04:58:58.489896 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 4 04:58:58.489905 kernel: BTRFS: device fsid 6f0a5369-79b6-4a87-b9a6-85ec05be306c devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (959) Nov 4 04:58:58.489914 kernel: BTRFS info (device dm-0): first mount of filesystem 6f0a5369-79b6-4a87-b9a6-85ec05be306c Nov 4 04:58:58.489923 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:58:58.489932 kernel: BTRFS info (device dm-0): enabling ssd optimizations Nov 4 04:58:58.489942 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 4 04:58:58.489951 kernel: BTRFS info (device dm-0): enabling free space tree Nov 4 04:58:58.489960 kernel: loop: module loaded Nov 4 04:58:58.489971 kernel: loop0: detected capacity change from 0 to 100136 Nov 4 04:58:58.489980 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 4 04:58:58.489990 systemd[1]: Successfully made /usr/ read-only. Nov 4 04:58:58.490003 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 4 04:58:58.490015 systemd[1]: Detected virtualization microsoft. Nov 4 04:58:58.490024 systemd[1]: Detected architecture x86-64. Nov 4 04:58:58.490042 systemd[1]: Running in initrd. Nov 4 04:58:58.490052 systemd[1]: No hostname configured, using default hostname. Nov 4 04:58:58.490061 systemd[1]: Hostname set to . Nov 4 04:58:58.490078 systemd[1]: Initializing machine ID from random generator. Nov 4 04:58:58.490089 systemd[1]: Queued start job for default target initrd.target. Nov 4 04:58:58.490099 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 04:58:58.490108 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:58:58.490117 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:58:58.490127 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 4 04:58:58.490137 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 04:58:58.490149 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 4 04:58:58.490158 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 4 04:58:58.490168 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:58:58.490179 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:58:58.490213 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 4 04:58:58.490223 systemd[1]: Reached target paths.target - Path Units. Nov 4 04:58:58.490233 systemd[1]: Reached target slices.target - Slice Units. Nov 4 04:58:58.490242 systemd[1]: Reached target swap.target - Swaps. Nov 4 04:58:58.490252 systemd[1]: Reached target timers.target - Timer Units. Nov 4 04:58:58.490263 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 04:58:58.490276 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 04:58:58.490286 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 4 04:58:58.490298 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 4 04:58:58.490309 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:58:58.490320 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 04:58:58.490330 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:58:58.490340 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 04:58:58.490352 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 4 04:58:58.490362 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 4 04:58:58.490372 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 04:58:58.490385 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 4 04:58:58.490400 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 4 04:58:58.490412 systemd[1]: Starting systemd-fsck-usr.service... Nov 4 04:58:58.490422 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 04:58:58.490435 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 04:58:58.490446 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:58:58.490456 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 4 04:58:58.490471 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:58:58.490483 systemd[1]: Finished systemd-fsck-usr.service. Nov 4 04:58:58.490494 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 4 04:58:58.490527 systemd-journald[1092]: Collecting audit messages is disabled. Nov 4 04:58:58.490560 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 04:58:58.490573 systemd-journald[1092]: Journal started Nov 4 04:58:58.490597 systemd-journald[1092]: Runtime Journal (/run/log/journal/226c27627da54b7f89e13398a4761fe9) is 8M, max 158.5M, 150.5M free. Nov 4 04:58:58.499051 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 04:58:58.504134 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 04:58:58.512537 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 04:58:58.515366 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 4 04:58:58.522050 kernel: Bridge firewalling registered Nov 4 04:58:58.521812 systemd-modules-load[1097]: Inserted module 'br_netfilter' Nov 4 04:58:58.525233 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 04:58:58.527864 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 04:58:58.537391 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:58:58.537554 systemd-tmpfiles[1110]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 4 04:58:58.563389 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:58:58.581508 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:58:58.584140 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 04:58:58.591600 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:58:58.595472 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 4 04:58:58.701088 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 04:58:58.729344 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 4 04:58:58.732806 systemd-resolved[1121]: Positive Trust Anchors: Nov 4 04:58:58.732813 systemd-resolved[1121]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 04:58:58.732816 systemd-resolved[1121]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 04:58:58.732849 systemd-resolved[1121]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 4 04:58:58.750972 systemd-resolved[1121]: Defaulting to hostname 'linux'. Nov 4 04:58:58.751843 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 04:58:58.767193 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:58:58.793814 dracut-cmdline[1135]: 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=c479bf273e218e23ca82ede45f2bfcd1a1714a33fe5860e964ed0aea09538f01 Nov 4 04:58:58.921047 kernel: Loading iSCSI transport class v2.0-870. Nov 4 04:58:58.992059 kernel: iscsi: registered transport (tcp) Nov 4 04:58:59.038058 kernel: iscsi: registered transport (qla4xxx) Nov 4 04:58:59.038102 kernel: QLogic iSCSI HBA Driver Nov 4 04:58:59.086732 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 04:58:59.105873 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:58:59.111383 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 04:58:59.140211 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 4 04:58:59.143146 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 4 04:58:59.147342 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 4 04:58:59.172357 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 4 04:58:59.180148 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:58:59.208591 systemd-udevd[1386]: Using default interface naming scheme 'v257'. Nov 4 04:58:59.219670 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:58:59.226701 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 4 04:58:59.235124 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 04:58:59.240163 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 04:58:59.249631 dracut-pre-trigger[1468]: rd.md=0: removing MD RAID activation Nov 4 04:58:59.272085 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 04:58:59.275186 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 04:58:59.291743 systemd-networkd[1474]: lo: Link UP Nov 4 04:58:59.291935 systemd-networkd[1474]: lo: Gained carrier Nov 4 04:58:59.292714 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 04:58:59.301074 systemd[1]: Reached target network.target - Network. Nov 4 04:58:59.321936 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:58:59.329389 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 4 04:58:59.390278 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:58:59.390412 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:58:59.396098 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:58:59.401937 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:58:59.431060 kernel: cryptd: max_cpu_qlen set to 1000 Nov 4 04:58:59.456062 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#223 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 4 04:58:59.473686 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:58:59.485051 kernel: AES CTR mode by8 optimization enabled Nov 4 04:58:59.487102 kernel: hv_vmbus: registering driver hv_netvsc Nov 4 04:58:59.500047 kernel: hv_netvsc f8615163-0000-1000-2000-7c1e5235b9ce (unnamed net_device) (uninitialized): VF slot 1 added Nov 4 04:58:59.521514 systemd-networkd[1474]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:58:59.521897 systemd-networkd[1474]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:58:59.523097 systemd-networkd[1474]: eth0: Link UP Nov 4 04:58:59.523238 systemd-networkd[1474]: eth0: Gained carrier Nov 4 04:58:59.523248 systemd-networkd[1474]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:58:59.559061 systemd-networkd[1474]: eth0: DHCPv4 address 10.200.8.32/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 04:58:59.653053 kernel: nvme nvme0: using unchecked data buffer Nov 4 04:58:59.740956 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - MSFT NVMe Accelerator v1.0 USR-A. Nov 4 04:58:59.742266 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 4 04:58:59.864529 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - MSFT NVMe Accelerator v1.0 ROOT. Nov 4 04:58:59.880647 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - MSFT NVMe Accelerator v1.0 EFI-SYSTEM. Nov 4 04:58:59.900369 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM. Nov 4 04:58:59.970624 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 4 04:58:59.976209 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 04:58:59.976776 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:58:59.988349 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 04:58:59.992485 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 4 04:59:00.025356 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 4 04:59:00.518628 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI VMBus probing: Using version 0x10004 Nov 4 04:59:00.518827 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI host bridge to bus 7870:00 Nov 4 04:59:00.521530 kernel: pci_bus 7870:00: root bus resource [mem 0xfc2000000-0xfc4007fff window] Nov 4 04:59:00.523130 kernel: pci_bus 7870:00: No busn resource found for root bus, will use [bus 00-ff] Nov 4 04:59:00.528065 kernel: pci 7870:00:00.0: [1414:00ba] type 00 class 0x020000 PCIe Endpoint Nov 4 04:59:00.532135 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref] Nov 4 04:59:00.536522 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref] Nov 4 04:59:00.536589 kernel: pci 7870:00:00.0: enabling Extended Tags Nov 4 04:59:00.550042 kernel: pci_bus 7870:00: busn_res: [bus 00-ff] end is updated to 00 Nov 4 04:59:00.550259 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref]: assigned Nov 4 04:59:00.554101 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref]: assigned Nov 4 04:59:00.573301 kernel: mana 7870:00:00.0: enabling device (0000 -> 0002) Nov 4 04:59:00.583045 kernel: mana 7870:00:00.0: Microsoft Azure Network Adapter protocol version: 0.1.1 Nov 4 04:59:00.586735 kernel: hv_netvsc f8615163-0000-1000-2000-7c1e5235b9ce eth0: VF registering: eth1 Nov 4 04:59:00.586877 kernel: mana 7870:00:00.0 eth1: joined to eth0 Nov 4 04:59:00.590708 systemd-networkd[1474]: eth1: Interface name change detected, renamed to enP30832s1. Nov 4 04:59:00.593530 kernel: mana 7870:00:00.0 enP30832s1: renamed from eth1 Nov 4 04:59:00.690077 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16 Nov 4 04:59:00.693491 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 Nov 4 04:59:00.693735 kernel: hv_netvsc f8615163-0000-1000-2000-7c1e5235b9ce eth0: Data path switched to VF: enP30832s1 Nov 4 04:59:00.693988 systemd-networkd[1474]: enP30832s1: Link UP Nov 4 04:59:00.695138 systemd-networkd[1474]: enP30832s1: Gained carrier Nov 4 04:59:00.903171 systemd-networkd[1474]: eth0: Gained IPv6LL Nov 4 04:59:00.984431 disk-uuid[1656]: Warning: The kernel is still using the old partition table. Nov 4 04:59:00.984431 disk-uuid[1656]: The new table will be used at the next reboot or after you Nov 4 04:59:00.984431 disk-uuid[1656]: run partprobe(8) or kpartx(8) Nov 4 04:59:00.984431 disk-uuid[1656]: The operation has completed successfully. Nov 4 04:59:00.994747 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 4 04:59:00.994841 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 4 04:59:00.995700 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 4 04:59:01.062046 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 (259:5) scanned by mount (1703) Nov 4 04:59:01.065530 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:01.065557 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:01.114333 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 4 04:59:01.114384 kernel: BTRFS info (device nvme0n1p6): turning on async discard Nov 4 04:59:01.116490 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Nov 4 04:59:01.122849 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 4 04:59:01.124567 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:01.127940 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 4 04:59:01.930307 ignition[1722]: Ignition 2.22.0 Nov 4 04:59:01.930317 ignition[1722]: Stage: fetch-offline Nov 4 04:59:01.932503 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 04:59:01.930412 ignition[1722]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:01.930420 ignition[1722]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:01.940706 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 4 04:59:01.930497 ignition[1722]: parsed url from cmdline: "" Nov 4 04:59:01.930499 ignition[1722]: no config URL provided Nov 4 04:59:01.930503 ignition[1722]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 04:59:01.930510 ignition[1722]: no config at "/usr/lib/ignition/user.ign" Nov 4 04:59:01.930515 ignition[1722]: failed to fetch config: resource requires networking Nov 4 04:59:01.931132 ignition[1722]: Ignition finished successfully Nov 4 04:59:01.957554 ignition[1729]: Ignition 2.22.0 Nov 4 04:59:01.957559 ignition[1729]: Stage: fetch Nov 4 04:59:01.957731 ignition[1729]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:01.957737 ignition[1729]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:01.957807 ignition[1729]: parsed url from cmdline: "" Nov 4 04:59:01.957810 ignition[1729]: no config URL provided Nov 4 04:59:01.957814 ignition[1729]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 04:59:01.957819 ignition[1729]: no config at "/usr/lib/ignition/user.ign" Nov 4 04:59:01.957836 ignition[1729]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 4 04:59:02.013891 ignition[1729]: GET result: OK Nov 4 04:59:02.013931 ignition[1729]: config has been read from IMDS userdata Nov 4 04:59:02.013940 ignition[1729]: parsing config with SHA512: 3ce5e85bbcbe0e5d9557ba1edb87df34f9c4e059abaaedaa9a3e85914ef398991d8f150957af620e249b97efa5b3e97f7ac62b55adc78e04753be9396883b39e Nov 4 04:59:02.019875 unknown[1729]: fetched base config from "system" Nov 4 04:59:02.019884 unknown[1729]: fetched base config from "system" Nov 4 04:59:02.020014 ignition[1729]: fetch: fetch complete Nov 4 04:59:02.019894 unknown[1729]: fetched user config from "azure" Nov 4 04:59:02.020018 ignition[1729]: fetch: fetch passed Nov 4 04:59:02.021656 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 4 04:59:02.020063 ignition[1729]: Ignition finished successfully Nov 4 04:59:02.025140 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 4 04:59:02.047747 ignition[1735]: Ignition 2.22.0 Nov 4 04:59:02.047756 ignition[1735]: Stage: kargs Nov 4 04:59:02.049485 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 4 04:59:02.047929 ignition[1735]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:02.052831 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 4 04:59:02.047937 ignition[1735]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:02.048397 ignition[1735]: kargs: kargs passed Nov 4 04:59:02.048423 ignition[1735]: Ignition finished successfully Nov 4 04:59:02.076187 ignition[1742]: Ignition 2.22.0 Nov 4 04:59:02.076196 ignition[1742]: Stage: disks Nov 4 04:59:02.078194 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 4 04:59:02.076373 ignition[1742]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:02.082221 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 4 04:59:02.076379 ignition[1742]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:02.087082 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 4 04:59:02.076914 ignition[1742]: disks: disks passed Nov 4 04:59:02.087345 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 04:59:02.076943 ignition[1742]: Ignition finished successfully Nov 4 04:59:02.087367 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 04:59:02.087385 systemd[1]: Reached target basic.target - Basic System. Nov 4 04:59:02.088256 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 4 04:59:02.221885 systemd-fsck[1750]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Nov 4 04:59:02.225878 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 4 04:59:02.230158 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 4 04:59:04.203048 kernel: EXT4-fs (nvme0n1p9): mounted filesystem c35327fb-3cdd-496e-85aa-9e1b4133507f r/w with ordered data mode. Quota mode: none. Nov 4 04:59:04.203308 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 4 04:59:04.205780 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 4 04:59:04.262706 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 04:59:04.285590 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 4 04:59:04.289168 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 4 04:59:04.300498 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 (259:5) scanned by mount (1759) Nov 4 04:59:04.300532 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:04.300548 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:04.293139 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 4 04:59:04.308177 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 4 04:59:04.293172 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 04:59:04.316158 kernel: BTRFS info (device nvme0n1p6): turning on async discard Nov 4 04:59:04.316179 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Nov 4 04:59:04.306590 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 4 04:59:04.311718 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 04:59:04.316143 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 4 04:59:04.862646 coreos-metadata[1761]: Nov 04 04:59:04.862 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 04:59:04.868401 coreos-metadata[1761]: Nov 04 04:59:04.868 INFO Fetch successful Nov 4 04:59:04.869904 coreos-metadata[1761]: Nov 04 04:59:04.869 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 4 04:59:04.877937 coreos-metadata[1761]: Nov 04 04:59:04.877 INFO Fetch successful Nov 4 04:59:04.879665 coreos-metadata[1761]: Nov 04 04:59:04.878 INFO wrote hostname ci-4508.0.0-n-d9799edf85 to /sysroot/etc/hostname Nov 4 04:59:04.879680 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 04:59:05.224771 initrd-setup-root[1789]: cut: /sysroot/etc/passwd: No such file or directory Nov 4 04:59:05.282559 initrd-setup-root[1796]: cut: /sysroot/etc/group: No such file or directory Nov 4 04:59:05.300500 initrd-setup-root[1803]: cut: /sysroot/etc/shadow: No such file or directory Nov 4 04:59:05.304621 initrd-setup-root[1810]: cut: /sysroot/etc/gshadow: No such file or directory Nov 4 04:59:06.316960 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 4 04:59:06.320822 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 4 04:59:06.327733 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 4 04:59:06.353970 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 4 04:59:06.360059 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:06.373161 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 4 04:59:06.387781 ignition[1878]: INFO : Ignition 2.22.0 Nov 4 04:59:06.387781 ignition[1878]: INFO : Stage: mount Nov 4 04:59:06.395193 ignition[1878]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:06.395193 ignition[1878]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:06.395193 ignition[1878]: INFO : mount: mount passed Nov 4 04:59:06.395193 ignition[1878]: INFO : Ignition finished successfully Nov 4 04:59:06.390462 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 4 04:59:06.393125 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 4 04:59:06.405153 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 04:59:06.431045 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 (259:5) scanned by mount (1890) Nov 4 04:59:06.433243 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem c6585032-901f-4e89-912e-5749e07725ea Nov 4 04:59:06.433282 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm Nov 4 04:59:06.439755 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations Nov 4 04:59:06.439806 kernel: BTRFS info (device nvme0n1p6): turning on async discard Nov 4 04:59:06.439819 kernel: BTRFS info (device nvme0n1p6): enabling free space tree Nov 4 04:59:06.441362 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 04:59:06.470248 ignition[1907]: INFO : Ignition 2.22.0 Nov 4 04:59:06.470248 ignition[1907]: INFO : Stage: files Nov 4 04:59:06.474067 ignition[1907]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:06.474067 ignition[1907]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:06.474067 ignition[1907]: DEBUG : files: compiled without relabeling support, skipping Nov 4 04:59:06.484433 ignition[1907]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 4 04:59:06.484433 ignition[1907]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 4 04:59:06.608484 ignition[1907]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 4 04:59:06.612108 ignition[1907]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 4 04:59:06.612108 ignition[1907]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 4 04:59:06.608754 unknown[1907]: wrote ssh authorized keys file for user: core Nov 4 04:59:06.677362 ignition[1907]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 04:59:06.680166 ignition[1907]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 04:59:06.682754 ignition[1907]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 4 04:59:06.688171 ignition[1907]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 4 04:59:06.688171 ignition[1907]: INFO : files: files passed Nov 4 04:59:06.688171 ignition[1907]: INFO : Ignition finished successfully Nov 4 04:59:06.686217 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 4 04:59:06.695551 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 4 04:59:06.703147 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 4 04:59:06.707360 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 4 04:59:06.707420 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 4 04:59:06.753558 initrd-setup-root-after-ignition[1938]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:59:06.753558 initrd-setup-root-after-ignition[1938]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:59:06.759292 initrd-setup-root-after-ignition[1942]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:59:06.761768 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 04:59:06.765408 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 4 04:59:06.770141 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 4 04:59:06.800423 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 4 04:59:06.800503 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 4 04:59:06.803525 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 4 04:59:06.808144 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 4 04:59:06.812769 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 4 04:59:06.815347 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 4 04:59:06.834944 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 04:59:06.842296 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 4 04:59:06.860708 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 04:59:06.861873 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:59:06.868161 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:59:06.870270 systemd[1]: Stopped target timers.target - Timer Units. Nov 4 04:59:06.875124 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 4 04:59:06.875183 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 04:59:06.877969 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 4 04:59:06.879341 systemd[1]: Stopped target basic.target - Basic System. Nov 4 04:59:06.879361 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 4 04:59:06.879378 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 04:59:06.879645 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 4 04:59:06.879863 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 4 04:59:06.879883 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 4 04:59:06.879900 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 04:59:06.879928 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 4 04:59:06.880170 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 4 04:59:06.880207 systemd[1]: Stopped target swap.target - Swaps. Nov 4 04:59:06.880233 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 4 04:59:06.880272 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 4 04:59:06.880504 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:59:06.880744 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:59:06.880765 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 4 04:59:06.881499 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:59:06.881524 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 4 04:59:06.881555 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 4 04:59:06.882135 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 4 04:59:06.882164 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 04:59:06.882383 systemd[1]: ignition-files.service: Deactivated successfully. Nov 4 04:59:06.882409 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 4 04:59:06.882445 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 4 04:59:06.882476 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 04:59:06.885118 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 4 04:59:06.885654 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 4 04:59:06.885829 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 4 04:59:06.885868 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:59:06.886167 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 4 04:59:06.886197 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:59:06.886489 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 4 04:59:06.886519 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 04:59:06.918252 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 4 04:59:06.934119 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 4 04:59:06.970829 ignition[1962]: INFO : Ignition 2.22.0 Nov 4 04:59:06.970829 ignition[1962]: INFO : Stage: umount Nov 4 04:59:06.970829 ignition[1962]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:59:06.970829 ignition[1962]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:59:06.970829 ignition[1962]: INFO : umount: umount passed Nov 4 04:59:06.970829 ignition[1962]: INFO : Ignition finished successfully Nov 4 04:59:06.972349 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 4 04:59:06.979120 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 4 04:59:06.987414 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 4 04:59:06.987465 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 4 04:59:06.987712 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 4 04:59:06.987741 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 4 04:59:06.988007 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 4 04:59:06.988055 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 4 04:59:06.988543 systemd[1]: Stopped target network.target - Network. Nov 4 04:59:06.988565 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 4 04:59:06.988593 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 04:59:06.988767 systemd[1]: Stopped target paths.target - Path Units. Nov 4 04:59:06.988784 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 4 04:59:06.989288 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:59:06.998124 systemd[1]: Stopped target slices.target - Slice Units. Nov 4 04:59:07.000994 systemd[1]: Stopped target sockets.target - Socket Units. Nov 4 04:59:07.005111 systemd[1]: iscsid.socket: Deactivated successfully. Nov 4 04:59:07.005152 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 04:59:07.014118 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 4 04:59:07.014149 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 04:59:07.021117 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 4 04:59:07.021169 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 4 04:59:07.039256 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 4 04:59:07.039312 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 4 04:59:07.044335 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 4 04:59:07.048140 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 4 04:59:07.053005 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 4 04:59:07.053105 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 4 04:59:07.058257 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 4 04:59:07.063975 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 4 04:59:07.064012 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:59:07.069967 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 4 04:59:07.076840 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 4 04:59:07.076899 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 04:59:07.080177 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:59:07.092355 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 4 04:59:07.092452 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 4 04:59:07.096517 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 4 04:59:07.096642 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:59:07.103984 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 4 04:59:07.104079 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 4 04:59:07.108819 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 4 04:59:07.108860 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:59:07.112134 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 4 04:59:07.112188 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 4 04:59:07.112749 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 4 04:59:07.112785 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 4 04:59:07.113095 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 4 04:59:07.113128 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 04:59:07.118506 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 4 04:59:07.126159 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 4 04:59:07.127407 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:59:07.130051 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 4 04:59:07.130092 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:59:07.130365 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 4 04:59:07.130392 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 4 04:59:07.130687 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 4 04:59:07.130716 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:59:07.130744 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Nov 4 04:59:07.130768 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 04:59:07.130972 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 4 04:59:07.130999 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:59:07.131024 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:59:07.131060 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:07.176242 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 4 04:59:07.176324 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 4 04:59:07.194449 kernel: hv_netvsc f8615163-0000-1000-2000-7c1e5235b9ce eth0: Data path switched from VF: enP30832s1 Nov 4 04:59:07.194656 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 Nov 4 04:59:07.196149 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 4 04:59:07.196228 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 4 04:59:07.237009 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 4 04:59:07.237123 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 4 04:59:07.242400 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 4 04:59:07.247061 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 4 04:59:07.247100 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 4 04:59:07.252138 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 4 04:59:07.290100 systemd[1]: Switching root. Nov 4 04:59:07.354725 systemd-journald[1092]: Journal stopped Nov 4 04:59:13.910758 systemd-journald[1092]: Received SIGTERM from PID 1 (systemd). Nov 4 04:59:13.910787 kernel: SELinux: policy capability network_peer_controls=1 Nov 4 04:59:13.910800 kernel: SELinux: policy capability open_perms=1 Nov 4 04:59:13.910808 kernel: SELinux: policy capability extended_socket_class=1 Nov 4 04:59:13.910816 kernel: SELinux: policy capability always_check_network=0 Nov 4 04:59:13.910825 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 4 04:59:13.910834 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 4 04:59:13.910844 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 4 04:59:13.910852 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 4 04:59:13.910860 kernel: SELinux: policy capability userspace_initial_context=0 Nov 4 04:59:13.910868 kernel: audit: type=1403 audit(1762232348.216:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 4 04:59:13.910878 systemd[1]: Successfully loaded SELinux policy in 201.821ms. Nov 4 04:59:13.910887 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 3.989ms. Nov 4 04:59:13.910899 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 4 04:59:13.910908 systemd[1]: Detected virtualization microsoft. Nov 4 04:59:13.910918 systemd[1]: Detected architecture x86-64. Nov 4 04:59:13.910927 systemd[1]: Detected first boot. Nov 4 04:59:13.910937 systemd[1]: Hostname set to . Nov 4 04:59:13.910945 systemd[1]: Initializing machine ID from random generator. Nov 4 04:59:13.910954 zram_generator::config[2008]: No configuration found. Nov 4 04:59:13.910963 kernel: Guest personality initialized and is inactive Nov 4 04:59:13.910972 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Nov 4 04:59:13.910980 kernel: Initialized host personality Nov 4 04:59:13.910990 kernel: NET: Registered PF_VSOCK protocol family Nov 4 04:59:13.910998 systemd[1]: Populated /etc with preset unit settings. Nov 4 04:59:13.911007 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 4 04:59:13.911017 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 4 04:59:13.911026 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 4 04:59:13.911931 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 4 04:59:13.911947 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 4 04:59:13.911959 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 4 04:59:13.911971 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 4 04:59:13.911983 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 4 04:59:13.911995 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 4 04:59:13.912006 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 4 04:59:13.912020 systemd[1]: Created slice user.slice - User and Session Slice. Nov 4 04:59:13.912043 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:59:13.912057 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:59:13.912067 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 4 04:59:13.912078 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 4 04:59:13.912093 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 4 04:59:13.912106 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 04:59:13.912120 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Nov 4 04:59:13.912132 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:59:13.912143 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:59:13.912152 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 4 04:59:13.912165 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 4 04:59:13.912179 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 4 04:59:13.912196 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 4 04:59:13.912209 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:59:13.912221 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 04:59:13.912230 systemd[1]: Reached target slices.target - Slice Units. Nov 4 04:59:13.912242 systemd[1]: Reached target swap.target - Swaps. Nov 4 04:59:13.912255 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 4 04:59:13.912267 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 4 04:59:13.912283 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 4 04:59:13.912293 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:59:13.912303 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 04:59:13.912315 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:59:13.912327 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 4 04:59:13.912340 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 4 04:59:13.912354 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 4 04:59:13.912365 systemd[1]: Mounting media.mount - External Media Directory... Nov 4 04:59:13.912376 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:13.912389 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 4 04:59:13.912404 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 4 04:59:13.912417 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 4 04:59:13.912430 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 4 04:59:13.912442 systemd[1]: Reached target machines.target - Containers. Nov 4 04:59:13.912457 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 4 04:59:13.912468 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:59:13.912479 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 04:59:13.912491 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 4 04:59:13.912500 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:59:13.912510 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 04:59:13.912519 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:59:13.912529 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 4 04:59:13.912538 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 04:59:13.912549 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 4 04:59:13.912559 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 4 04:59:13.912569 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 4 04:59:13.912578 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 4 04:59:13.912588 systemd[1]: Stopped systemd-fsck-usr.service. Nov 4 04:59:13.912598 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 4 04:59:13.912608 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 04:59:13.912619 kernel: fuse: init (API version 7.41) Nov 4 04:59:13.912628 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 04:59:13.912638 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 04:59:13.912648 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 4 04:59:13.912658 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 4 04:59:13.912669 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 04:59:13.912679 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:13.912689 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 4 04:59:13.912699 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 4 04:59:13.912709 systemd[1]: Mounted media.mount - External Media Directory. Nov 4 04:59:13.912738 systemd-journald[2091]: Collecting audit messages is disabled. Nov 4 04:59:13.912760 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 4 04:59:13.912771 systemd-journald[2091]: Journal started Nov 4 04:59:13.912792 systemd-journald[2091]: Runtime Journal (/run/log/journal/7a5683b6ad7646e2b7aa564b78471d12) is 8M, max 158.5M, 150.5M free. Nov 4 04:59:13.408165 systemd[1]: Queued start job for default target multi-user.target. Nov 4 04:59:13.412426 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6. Nov 4 04:59:13.412758 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 4 04:59:13.919106 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 04:59:13.922326 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 4 04:59:13.924132 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 4 04:59:13.926498 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:59:13.930331 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 4 04:59:13.933242 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 4 04:59:13.933368 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 4 04:59:13.940757 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:59:13.940957 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:59:13.943282 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:59:13.943453 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:59:13.945318 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 4 04:59:13.945447 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 4 04:59:13.947062 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 04:59:13.947177 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 04:59:13.948957 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:59:13.951858 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 4 04:59:13.957811 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 04:59:13.972481 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 4 04:59:13.974980 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 4 04:59:13.979124 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 4 04:59:13.983237 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 4 04:59:13.983271 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 04:59:13.986555 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 4 04:59:14.000399 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:59:14.015571 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 4 04:59:14.027324 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 4 04:59:14.030144 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:59:14.030952 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 4 04:59:14.033483 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 04:59:14.034394 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 4 04:59:14.037819 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 4 04:59:14.042156 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 04:59:14.046604 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 4 04:59:14.050222 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 4 04:59:14.055153 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 04:59:14.060964 systemd-journald[2091]: Time spent on flushing to /var/log/journal/7a5683b6ad7646e2b7aa564b78471d12 is 15.628ms for 941 entries. Nov 4 04:59:14.060964 systemd-journald[2091]: System Journal (/var/log/journal/7a5683b6ad7646e2b7aa564b78471d12) is 8M, max 2.6G, 2.6G free. Nov 4 04:59:14.105026 systemd-journald[2091]: Received client request to flush runtime journal. Nov 4 04:59:14.105080 kernel: loop1: detected capacity change from 0 to 119080 Nov 4 04:59:14.064337 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 4 04:59:14.065956 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 4 04:59:14.071355 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 4 04:59:14.083224 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 4 04:59:14.106792 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 4 04:59:14.124591 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:59:14.192053 kernel: ACPI: bus type drm_connector registered Nov 4 04:59:14.192702 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 04:59:14.192847 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 04:59:14.279433 systemd-tmpfiles[2146]: ACLs are not supported, ignoring. Nov 4 04:59:14.279449 systemd-tmpfiles[2146]: ACLs are not supported, ignoring. Nov 4 04:59:14.282063 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 04:59:14.287082 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 4 04:59:14.309930 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:59:14.369949 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 4 04:59:14.413660 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 4 04:59:14.956437 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 4 04:59:14.963174 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 04:59:14.967359 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 04:59:14.994736 systemd-tmpfiles[2168]: ACLs are not supported, ignoring. Nov 4 04:59:14.994755 systemd-tmpfiles[2168]: ACLs are not supported, ignoring. Nov 4 04:59:14.997181 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:59:15.033054 kernel: loop2: detected capacity change from 0 to 27736 Nov 4 04:59:15.072667 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 4 04:59:15.097819 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 4 04:59:15.102408 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:59:15.122291 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 4 04:59:15.127092 systemd-udevd[2174]: Using default interface naming scheme 'v257'. Nov 4 04:59:15.221978 systemd-resolved[2167]: Positive Trust Anchors: Nov 4 04:59:15.221993 systemd-resolved[2167]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 04:59:15.221997 systemd-resolved[2167]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 04:59:15.222026 systemd-resolved[2167]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 4 04:59:15.400337 systemd-resolved[2167]: Using system hostname 'ci-4508.0.0-n-d9799edf85'. Nov 4 04:59:15.417179 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 04:59:15.420128 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:59:15.996091 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:59:16.002155 kernel: loop3: detected capacity change from 0 to 111544 Nov 4 04:59:16.001175 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 04:59:16.063479 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Nov 4 04:59:16.113053 kernel: hv_vmbus: registering driver hyperv_fb Nov 4 04:59:16.115701 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 4 04:59:16.115758 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 4 04:59:16.116292 kernel: Console: switching to colour dummy device 80x25 Nov 4 04:59:16.121011 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 04:59:16.134095 kernel: hv_vmbus: registering driver hv_balloon Nov 4 04:59:16.136057 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 4 04:59:16.182091 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#215 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 4 04:59:16.218052 kernel: mousedev: PS/2 mouse device common for all mice Nov 4 04:59:16.241228 systemd-networkd[2184]: lo: Link UP Nov 4 04:59:16.241234 systemd-networkd[2184]: lo: Gained carrier Nov 4 04:59:16.243465 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 04:59:16.245821 systemd-networkd[2184]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:16.245830 systemd-networkd[2184]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:59:16.246405 systemd[1]: Reached target network.target - Network. Nov 4 04:59:16.250046 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16 Nov 4 04:59:16.251059 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 4 04:59:16.257785 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64 Nov 4 04:59:16.256208 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 4 04:59:16.265095 kernel: hv_netvsc f8615163-0000-1000-2000-7c1e5235b9ce eth0: Data path switched to VF: enP30832s1 Nov 4 04:59:16.264283 systemd-networkd[2184]: enP30832s1: Link UP Nov 4 04:59:16.264361 systemd-networkd[2184]: eth0: Link UP Nov 4 04:59:16.264364 systemd-networkd[2184]: eth0: Gained carrier Nov 4 04:59:16.264375 systemd-networkd[2184]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:16.275552 systemd-networkd[2184]: enP30832s1: Gained carrier Nov 4 04:59:16.283091 systemd-networkd[2184]: eth0: DHCPv4 address 10.200.8.32/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 04:59:16.292274 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:16.302273 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:59:16.302435 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:16.307803 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:16.322796 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:59:16.322931 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:16.327589 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:59:16.343136 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 4 04:59:16.491006 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM. Nov 4 04:59:16.501310 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 4 04:59:16.536257 kernel: kvm_intel: Using Hyper-V Enlightened VMCS Nov 4 04:59:16.548048 kernel: loop4: detected capacity change from 0 to 119080 Nov 4 04:59:16.558044 kernel: loop5: detected capacity change from 0 to 27736 Nov 4 04:59:16.574052 kernel: loop6: detected capacity change from 0 to 111544 Nov 4 04:59:16.583175 (sd-merge)[2265]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Nov 4 04:59:16.586108 (sd-merge)[2265]: Merged extensions into '/usr'. Nov 4 04:59:16.588586 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 4 04:59:16.591338 systemd[1]: Starting ensure-sysext.service... Nov 4 04:59:16.593133 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 04:59:16.604177 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 4 04:59:16.611657 systemd[1]: Reload requested from client PID 2268 ('systemctl') (unit ensure-sysext.service)... Nov 4 04:59:16.611760 systemd[1]: Reloading... Nov 4 04:59:16.655058 zram_generator::config[2299]: No configuration found. Nov 4 04:59:16.675111 systemd-tmpfiles[2269]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 4 04:59:16.675136 systemd-tmpfiles[2269]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 4 04:59:16.675363 systemd-tmpfiles[2269]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 4 04:59:16.675578 systemd-tmpfiles[2269]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 4 04:59:16.676257 systemd-tmpfiles[2269]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 4 04:59:16.676526 systemd-tmpfiles[2269]: ACLs are not supported, ignoring. Nov 4 04:59:16.676609 systemd-tmpfiles[2269]: ACLs are not supported, ignoring. Nov 4 04:59:16.767782 systemd-tmpfiles[2269]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 04:59:16.767791 systemd-tmpfiles[2269]: Skipping /boot Nov 4 04:59:16.774358 systemd-tmpfiles[2269]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 04:59:16.774443 systemd-tmpfiles[2269]: Skipping /boot Nov 4 04:59:16.840048 systemd[1]: Reloading finished in 228 ms. Nov 4 04:59:16.876273 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:59:16.882889 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 04:59:16.886055 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 4 04:59:16.892310 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 4 04:59:16.894581 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 4 04:59:16.897227 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 4 04:59:16.901466 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:59:16.903535 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:59:16.907692 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:59:16.917008 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 04:59:16.919210 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:59:16.919552 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 4 04:59:16.922551 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:59:16.922684 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:59:16.923138 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:59:16.923265 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:59:16.925705 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:59:16.930494 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 04:59:16.930794 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 04:59:16.932472 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:59:16.934127 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:59:16.937852 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 04:59:16.946230 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:59:16.948734 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:59:16.948776 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 4 04:59:16.948825 systemd[1]: Reached target time-set.target - System Time Set. Nov 4 04:59:16.951878 systemd[1]: Finished ensure-sysext.service. Nov 4 04:59:16.954067 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:59:16.954219 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:59:16.954492 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 04:59:16.954657 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 04:59:16.955746 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:59:16.955909 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:59:16.964596 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 4 04:59:16.965107 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:59:16.965182 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 04:59:17.019587 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:17.019800 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Nov 4 04:59:17.200694 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 4 04:59:17.438311 augenrules[2402]: No rules Nov 4 04:59:17.438971 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 04:59:17.439214 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 04:59:17.822828 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:59:18.183171 systemd-networkd[2184]: eth0: Gained IPv6LL Nov 4 04:59:18.184778 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 4 04:59:18.186752 systemd[1]: Reached target network-online.target - Network is Online. Nov 4 04:59:19.650206 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 4 04:59:19.652234 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 4 04:59:26.395421 ldconfig[2365]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 04:59:26.408429 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 4 04:59:26.411286 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 4 04:59:26.558536 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 4 04:59:26.561467 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 04:59:26.565195 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 4 04:59:26.566807 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 4 04:59:26.570079 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Nov 4 04:59:26.571801 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 4 04:59:26.573262 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 4 04:59:26.576084 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 4 04:59:26.579076 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 4 04:59:26.579116 systemd[1]: Reached target paths.target - Path Units. Nov 4 04:59:26.582082 systemd[1]: Reached target timers.target - Timer Units. Nov 4 04:59:29.935739 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 4 04:59:29.938518 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 4 04:59:29.942678 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 4 04:59:29.944847 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 4 04:59:29.946759 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 4 04:59:29.958500 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 4 04:59:29.960614 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 4 04:59:29.965665 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 4 04:59:29.968185 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 04:59:29.969718 systemd[1]: Reached target basic.target - Basic System. Nov 4 04:59:29.973118 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 4 04:59:29.973150 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 4 04:59:29.988874 systemd[1]: Starting chronyd.service - NTP client/server... Nov 4 04:59:29.993010 systemd[1]: Starting containerd.service - containerd container runtime... Nov 4 04:59:29.997816 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 4 04:59:30.002084 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 4 04:59:30.013340 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 4 04:59:30.018881 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 4 04:59:30.023140 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 4 04:59:30.027116 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 4 04:59:30.028509 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Nov 4 04:59:30.030920 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Nov 4 04:59:30.035372 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 4 04:59:30.037157 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 4 04:59:30.040160 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 4 04:59:30.043382 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 4 04:59:30.049093 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 4 04:59:30.055219 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 4 04:59:30.059056 jq[2423]: false Nov 4 04:59:30.062161 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 4 04:59:30.062168 chronyd[2418]: chronyd version 4.8 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 4 04:59:30.064071 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 4 04:59:30.064468 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 4 04:59:30.065291 systemd[1]: Starting update-engine.service - Update Engine... Nov 4 04:59:30.068888 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 4 04:59:30.074947 KVP[2429]: KVP starting; pid is:2429 Nov 4 04:59:30.077503 google_oslogin_nss_cache[2428]: oslogin_cache_refresh[2428]: Refreshing passwd entry cache Nov 4 04:59:30.078190 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 4 04:59:30.080084 oslogin_cache_refresh[2428]: Refreshing passwd entry cache Nov 4 04:59:30.086198 jq[2440]: true Nov 4 04:59:30.090054 kernel: hv_utils: KVP IC version 4.0 Nov 4 04:59:30.090111 KVP[2429]: KVP LIC Version: 3.1 Nov 4 04:59:30.093498 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 4 04:59:30.093821 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 4 04:59:30.103170 oslogin_cache_refresh[2428]: Failure getting users, quitting Nov 4 04:59:30.100096 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 4 04:59:30.103392 google_oslogin_nss_cache[2428]: oslogin_cache_refresh[2428]: Failure getting users, quitting Nov 4 04:59:30.103392 google_oslogin_nss_cache[2428]: oslogin_cache_refresh[2428]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 4 04:59:30.103392 google_oslogin_nss_cache[2428]: oslogin_cache_refresh[2428]: Refreshing group entry cache Nov 4 04:59:30.103184 oslogin_cache_refresh[2428]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Nov 4 04:59:30.100241 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 4 04:59:30.103220 oslogin_cache_refresh[2428]: Refreshing group entry cache Nov 4 04:59:30.111728 chronyd[2418]: Timezone right/UTC failed leap second check, ignoring Nov 4 04:59:30.112209 chronyd[2418]: Loaded seccomp filter (level 2) Nov 4 04:59:30.115502 jq[2446]: true Nov 4 04:59:30.112289 systemd[1]: Started chronyd.service - NTP client/server. Nov 4 04:59:30.118432 extend-filesystems[2427]: Found /dev/nvme0n1p6 Nov 4 04:59:30.125353 google_oslogin_nss_cache[2428]: oslogin_cache_refresh[2428]: Failure getting groups, quitting Nov 4 04:59:30.125353 google_oslogin_nss_cache[2428]: oslogin_cache_refresh[2428]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 4 04:59:30.125291 oslogin_cache_refresh[2428]: Failure getting groups, quitting Nov 4 04:59:30.125299 oslogin_cache_refresh[2428]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Nov 4 04:59:30.126595 systemd[1]: motdgen.service: Deactivated successfully. Nov 4 04:59:30.129448 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 4 04:59:30.133052 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Nov 4 04:59:30.133213 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Nov 4 04:59:30.149764 update_engine[2438]: I20251104 04:59:30.149235 2438 main.cc:92] Flatcar Update Engine starting Nov 4 04:59:30.151269 extend-filesystems[2427]: Found /dev/nvme0n1p9 Nov 4 04:59:30.159053 extend-filesystems[2427]: Checking size of /dev/nvme0n1p9 Nov 4 04:59:30.168139 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 4 04:59:30.182536 extend-filesystems[2427]: Resized partition /dev/nvme0n1p9 Nov 4 04:59:30.203846 systemd-logind[2434]: New seat seat0. Nov 4 04:59:30.206717 systemd-logind[2434]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 4 04:59:30.207221 systemd[1]: Started systemd-logind.service - User Login Management. Nov 4 04:59:30.218359 extend-filesystems[2497]: resize2fs 1.47.3 (8-Jul-2025) Nov 4 04:59:30.245687 bash[2486]: Updated "/home/core/.ssh/authorized_keys" Nov 4 04:59:30.248196 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 4 04:59:30.252502 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 4 04:59:30.313050 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 7359488 to 7376891 blocks Nov 4 04:59:30.322050 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 7376891 Nov 4 04:59:30.358820 extend-filesystems[2497]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required Nov 4 04:59:30.358820 extend-filesystems[2497]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 4 04:59:30.358820 extend-filesystems[2497]: The filesystem on /dev/nvme0n1p9 is now 7376891 (4k) blocks long. Nov 4 04:59:30.368116 extend-filesystems[2427]: Resized filesystem in /dev/nvme0n1p9 Nov 4 04:59:30.360166 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 4 04:59:30.360450 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 4 04:59:30.396195 sshd_keygen[2453]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 4 04:59:30.410580 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 4 04:59:30.412467 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 4 04:59:30.414419 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 4 04:59:30.429251 systemd[1]: issuegen.service: Deactivated successfully. Nov 4 04:59:30.429495 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 4 04:59:30.433272 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 4 04:59:30.446730 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 4 04:59:30.464301 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 4 04:59:30.466487 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 4 04:59:30.470213 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Nov 4 04:59:30.472460 systemd[1]: Reached target getty.target - Login Prompts. Nov 4 04:59:30.506085 dbus-daemon[2421]: [system] SELinux support is enabled Nov 4 04:59:30.506225 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 4 04:59:30.508780 update_engine[2438]: I20251104 04:59:30.508709 2438 update_check_scheduler.cc:74] Next update check in 8m11s Nov 4 04:59:30.511567 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 4 04:59:30.511843 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 4 04:59:30.513446 dbus-daemon[2421]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 4 04:59:30.517108 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 4 04:59:30.517122 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 4 04:59:30.518908 systemd[1]: Started update-engine.service - Update Engine. Nov 4 04:59:30.523137 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 4 04:59:30.673576 coreos-metadata[2420]: Nov 04 04:59:30.673 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 04:59:30.675440 coreos-metadata[2420]: Nov 04 04:59:30.675 INFO Fetch successful Nov 4 04:59:30.675495 coreos-metadata[2420]: Nov 04 04:59:30.675 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 4 04:59:30.678765 coreos-metadata[2420]: Nov 04 04:59:30.678 INFO Fetch successful Nov 4 04:59:30.678765 coreos-metadata[2420]: Nov 04 04:59:30.678 INFO Fetching http://168.63.129.16/machine/d0965a40-2795-4eba-93a2-5e041e47a971/c65821e0%2D2da2%2D410d%2Da26e%2Dbea89d01a744.%5Fci%2D4508.0.0%2Dn%2Dd9799edf85?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 4 04:59:30.679707 coreos-metadata[2420]: Nov 04 04:59:30.679 INFO Fetch successful Nov 4 04:59:30.679707 coreos-metadata[2420]: Nov 04 04:59:30.679 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 4 04:59:30.686229 coreos-metadata[2420]: Nov 04 04:59:30.686 INFO Fetch successful Nov 4 04:59:30.716513 locksmithd[2550]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 4 04:59:31.073979 containerd[2457]: time="2025-11-04T04:59:31Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 4 04:59:31.074453 containerd[2457]: time="2025-11-04T04:59:31.074413972Z" level=info msg="starting containerd" revision=75cb2b7193e4e490e9fbdc236c0e811ccaba3376 version=v2.1.4 Nov 4 04:59:31.082533 containerd[2457]: time="2025-11-04T04:59:31.082498037Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.249µs" Nov 4 04:59:31.082533 containerd[2457]: time="2025-11-04T04:59:31.082527122Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 4 04:59:31.082622 containerd[2457]: time="2025-11-04T04:59:31.082560533Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 4 04:59:31.082622 containerd[2457]: time="2025-11-04T04:59:31.082570898Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 4 04:59:31.082699 containerd[2457]: time="2025-11-04T04:59:31.082683308Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 4 04:59:31.082722 containerd[2457]: time="2025-11-04T04:59:31.082697101Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 04:59:31.082774 containerd[2457]: time="2025-11-04T04:59:31.082747131Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 04:59:31.082774 containerd[2457]: time="2025-11-04T04:59:31.082770410Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.082925 containerd[2457]: time="2025-11-04T04:59:31.082908418Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.082925 containerd[2457]: time="2025-11-04T04:59:31.082919695Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 04:59:31.082970 containerd[2457]: time="2025-11-04T04:59:31.082929944Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 04:59:31.082970 containerd[2457]: time="2025-11-04T04:59:31.082936907Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.083093 containerd[2457]: time="2025-11-04T04:59:31.083078106Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.083093 containerd[2457]: time="2025-11-04T04:59:31.083088755Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 4 04:59:31.083162 containerd[2457]: time="2025-11-04T04:59:31.083148314Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.083284 containerd[2457]: time="2025-11-04T04:59:31.083270315Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.083318 containerd[2457]: time="2025-11-04T04:59:31.083292051Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 04:59:31.083318 containerd[2457]: time="2025-11-04T04:59:31.083301618Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 4 04:59:31.083356 containerd[2457]: time="2025-11-04T04:59:31.083329814Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 4 04:59:31.083535 containerd[2457]: time="2025-11-04T04:59:31.083523119Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 4 04:59:31.083587 containerd[2457]: time="2025-11-04T04:59:31.083570755Z" level=info msg="metadata content store policy set" policy=shared Nov 4 04:59:31.097968 containerd[2457]: time="2025-11-04T04:59:31.097920883Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 4 04:59:31.098127 containerd[2457]: time="2025-11-04T04:59:31.098112476Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 4 04:59:31.098386 containerd[2457]: time="2025-11-04T04:59:31.098358442Z" level=info msg="skip loading plugin" error="could not find mkfs.erofs: exec: \"mkfs.erofs\": executable file not found in $PATH: skip plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 4 04:59:31.098459 containerd[2457]: time="2025-11-04T04:59:31.098425738Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 4 04:59:31.098459 containerd[2457]: time="2025-11-04T04:59:31.098441798Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 4 04:59:31.098592 containerd[2457]: time="2025-11-04T04:59:31.098518951Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 4 04:59:31.098592 containerd[2457]: time="2025-11-04T04:59:31.098533851Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 4 04:59:31.098592 containerd[2457]: time="2025-11-04T04:59:31.098543898Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 4 04:59:31.098592 containerd[2457]: time="2025-11-04T04:59:31.098555850Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 4 04:59:31.098592 containerd[2457]: time="2025-11-04T04:59:31.098574696Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 4 04:59:31.098860 containerd[2457]: time="2025-11-04T04:59:31.098721129Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 4 04:59:31.098860 containerd[2457]: time="2025-11-04T04:59:31.098735063Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 4 04:59:31.098860 containerd[2457]: time="2025-11-04T04:59:31.098755791Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 4 04:59:31.098860 containerd[2457]: time="2025-11-04T04:59:31.098786679Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 4 04:59:31.099006 containerd[2457]: time="2025-11-04T04:59:31.098994985Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 4 04:59:31.099091 containerd[2457]: time="2025-11-04T04:59:31.099082424Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 4 04:59:31.099224 containerd[2457]: time="2025-11-04T04:59:31.099131366Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 4 04:59:31.099224 containerd[2457]: time="2025-11-04T04:59:31.099158374Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 4 04:59:31.099224 containerd[2457]: time="2025-11-04T04:59:31.099169131Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 4 04:59:31.099224 containerd[2457]: time="2025-11-04T04:59:31.099179103Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 4 04:59:31.099224 containerd[2457]: time="2025-11-04T04:59:31.099190671Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 4 04:59:31.099224 containerd[2457]: time="2025-11-04T04:59:31.099201856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 4 04:59:31.099444 containerd[2457]: time="2025-11-04T04:59:31.099376514Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 4 04:59:31.099444 containerd[2457]: time="2025-11-04T04:59:31.099390558Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 4 04:59:31.099444 containerd[2457]: time="2025-11-04T04:59:31.099402175Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 4 04:59:31.099444 containerd[2457]: time="2025-11-04T04:59:31.099424102Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 4 04:59:31.099853 containerd[2457]: time="2025-11-04T04:59:31.099561936Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 4 04:59:31.099853 containerd[2457]: time="2025-11-04T04:59:31.099575545Z" level=info msg="Start snapshots syncer" Nov 4 04:59:31.099853 containerd[2457]: time="2025-11-04T04:59:31.099610085Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 4 04:59:31.100339 containerd[2457]: time="2025-11-04T04:59:31.100208056Z" 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,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 4 04:59:31.100529 containerd[2457]: time="2025-11-04T04:59:31.100513620Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 4 04:59:31.101307 containerd[2457]: time="2025-11-04T04:59:31.101254971Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 4 04:59:31.101523 containerd[2457]: time="2025-11-04T04:59:31.101449369Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 4 04:59:31.101523 containerd[2457]: time="2025-11-04T04:59:31.101481412Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 4 04:59:31.101523 containerd[2457]: time="2025-11-04T04:59:31.101498300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 4 04:59:31.101599 containerd[2457]: time="2025-11-04T04:59:31.101512020Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 4 04:59:31.101643 containerd[2457]: time="2025-11-04T04:59:31.101630749Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 4 04:59:31.101702 containerd[2457]: time="2025-11-04T04:59:31.101690576Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 4 04:59:31.101824 containerd[2457]: time="2025-11-04T04:59:31.101736359Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 4 04:59:31.101824 containerd[2457]: time="2025-11-04T04:59:31.101763180Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 4 04:59:31.101824 containerd[2457]: time="2025-11-04T04:59:31.101776022Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 4 04:59:31.101937 containerd[2457]: time="2025-11-04T04:59:31.101815507Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 04:59:31.101968 containerd[2457]: time="2025-11-04T04:59:31.101939179Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 04:59:31.101968 containerd[2457]: time="2025-11-04T04:59:31.101955305Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 04:59:31.102010 containerd[2457]: time="2025-11-04T04:59:31.101969565Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 04:59:31.102010 containerd[2457]: time="2025-11-04T04:59:31.101978516Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 4 04:59:31.102010 containerd[2457]: time="2025-11-04T04:59:31.101992571Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 4 04:59:31.102098 containerd[2457]: time="2025-11-04T04:59:31.102014579Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 4 04:59:31.102098 containerd[2457]: time="2025-11-04T04:59:31.102027511Z" level=info msg="runtime interface created" Nov 4 04:59:31.102098 containerd[2457]: time="2025-11-04T04:59:31.102047974Z" level=info msg="created NRI interface" Nov 4 04:59:31.102098 containerd[2457]: time="2025-11-04T04:59:31.102057471Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 4 04:59:31.102098 containerd[2457]: time="2025-11-04T04:59:31.102074461Z" level=info msg="Connect containerd service" Nov 4 04:59:31.102211 containerd[2457]: time="2025-11-04T04:59:31.102101973Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 4 04:59:31.102764 containerd[2457]: time="2025-11-04T04:59:31.102734299Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 4 04:59:31.107737 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 4 04:59:31.109848 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 4 04:59:31.664401 containerd[2457]: time="2025-11-04T04:59:31.664364225Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 4 04:59:31.664401 containerd[2457]: time="2025-11-04T04:59:31.664407822Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 4 04:59:31.664540 containerd[2457]: time="2025-11-04T04:59:31.664428127Z" level=info msg="Start subscribing containerd event" Nov 4 04:59:31.664540 containerd[2457]: time="2025-11-04T04:59:31.664453873Z" level=info msg="Start recovering state" Nov 4 04:59:31.664581 containerd[2457]: time="2025-11-04T04:59:31.664539868Z" level=info msg="Start event monitor" Nov 4 04:59:31.664581 containerd[2457]: time="2025-11-04T04:59:31.664550329Z" level=info msg="Start cni network conf syncer for default" Nov 4 04:59:31.664581 containerd[2457]: time="2025-11-04T04:59:31.664558745Z" level=info msg="Start streaming server" Nov 4 04:59:31.664581 containerd[2457]: time="2025-11-04T04:59:31.664566489Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 4 04:59:31.664581 containerd[2457]: time="2025-11-04T04:59:31.664573061Z" level=info msg="runtime interface starting up..." Nov 4 04:59:31.664581 containerd[2457]: time="2025-11-04T04:59:31.664578833Z" level=info msg="starting plugins..." Nov 4 04:59:31.664689 containerd[2457]: time="2025-11-04T04:59:31.664590125Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 4 04:59:31.664708 containerd[2457]: time="2025-11-04T04:59:31.664686191Z" level=info msg="containerd successfully booted in 0.590993s" Nov 4 04:59:31.665182 systemd[1]: Started containerd.service - containerd container runtime. Nov 4 04:59:31.667663 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 4 04:59:31.669555 systemd[1]: Startup finished in 4.180s (kernel) + 10.453s (initrd) + 23.653s (userspace) = 38.287s. Nov 4 04:59:32.684302 login[2544]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 4 04:59:32.698801 login[2543]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 4 04:59:32.709525 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 4 04:59:32.710511 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 4 04:59:32.713013 systemd-logind[2434]: New session 1 of user core. Nov 4 04:59:32.753785 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 4 04:59:32.755460 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 4 04:59:32.778284 (systemd)[2583]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 4 04:59:32.779838 systemd-logind[2434]: New session c1 of user core. Nov 4 04:59:32.822721 waagent[2541]: 2025-11-04T04:59:32.822649Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 4 04:59:32.824527 waagent[2541]: 2025-11-04T04:59:32.822955Z INFO Daemon Daemon OS: flatcar 4508.0.0 Nov 4 04:59:32.824527 waagent[2541]: 2025-11-04T04:59:32.823330Z INFO Daemon Daemon Python: 3.11.13 Nov 4 04:59:32.824527 waagent[2541]: 2025-11-04T04:59:32.823913Z INFO Daemon Daemon Run daemon Nov 4 04:59:32.824527 waagent[2541]: 2025-11-04T04:59:32.824438Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4508.0.0' Nov 4 04:59:32.824805 waagent[2541]: 2025-11-04T04:59:32.824769Z INFO Daemon Daemon Using waagent for provisioning Nov 4 04:59:32.825025 waagent[2541]: 2025-11-04T04:59:32.824990Z INFO Daemon Daemon Activate resource disk Nov 4 04:59:32.825180 waagent[2541]: 2025-11-04T04:59:32.825153Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 4 04:59:32.826907 waagent[2541]: 2025-11-04T04:59:32.826855Z INFO Daemon Daemon Found device: None Nov 4 04:59:32.827024 waagent[2541]: 2025-11-04T04:59:32.826997Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Nov 4 04:59:32.827124 waagent[2541]: 2025-11-04T04:59:32.827104Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Nov 4 04:59:32.827694 waagent[2541]: 2025-11-04T04:59:32.827653Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 04:59:32.827822 waagent[2541]: 2025-11-04T04:59:32.827797Z INFO Daemon Daemon Running default provisioning handler Nov 4 04:59:32.841424 waagent[2541]: 2025-11-04T04:59:32.840028Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 4 04:59:32.843049 waagent[2541]: 2025-11-04T04:59:32.841674Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 4 04:59:32.843049 waagent[2541]: 2025-11-04T04:59:32.841941Z INFO Daemon Daemon cloud-init is enabled: False Nov 4 04:59:32.843049 waagent[2541]: 2025-11-04T04:59:32.842608Z INFO Daemon Daemon Copying ovf-env.xml Nov 4 04:59:32.975579 waagent[2541]: 2025-11-04T04:59:32.975510Z INFO Daemon Daemon Successfully mounted dvd Nov 4 04:59:33.032954 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 4 04:59:33.035871 waagent[2541]: 2025-11-04T04:59:33.035824Z INFO Daemon Daemon Detect protocol endpoint Nov 4 04:59:33.038185 waagent[2541]: 2025-11-04T04:59:33.038144Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 04:59:33.040792 waagent[2541]: 2025-11-04T04:59:33.040752Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 4 04:59:33.043641 waagent[2541]: 2025-11-04T04:59:33.043543Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 4 04:59:33.046009 waagent[2541]: 2025-11-04T04:59:33.045975Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 4 04:59:33.047797 waagent[2541]: 2025-11-04T04:59:33.047765Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 4 04:59:33.074052 waagent[2541]: 2025-11-04T04:59:33.073831Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 4 04:59:33.074688 waagent[2541]: 2025-11-04T04:59:33.074669Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 4 04:59:33.074767 waagent[2541]: 2025-11-04T04:59:33.074750Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 4 04:59:33.119058 systemd[2583]: Queued start job for default target default.target. Nov 4 04:59:33.130320 systemd[2583]: Created slice app.slice - User Application Slice. Nov 4 04:59:33.130346 systemd[2583]: Reached target paths.target - Paths. Nov 4 04:59:33.130373 systemd[2583]: Reached target timers.target - Timers. Nov 4 04:59:33.131140 systemd[2583]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 04:59:33.138182 systemd[2583]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 04:59:33.138235 systemd[2583]: Reached target sockets.target - Sockets. Nov 4 04:59:33.138264 systemd[2583]: Reached target basic.target - Basic System. Nov 4 04:59:33.138287 systemd[2583]: Reached target default.target - Main User Target. Nov 4 04:59:33.138306 systemd[2583]: Startup finished in 354ms. Nov 4 04:59:33.138473 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 4 04:59:33.139439 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 4 04:59:33.206926 waagent[2541]: 2025-11-04T04:59:33.206872Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 4 04:59:33.207616 waagent[2541]: 2025-11-04T04:59:33.207290Z INFO Daemon Daemon Forcing an update of the goal state. Nov 4 04:59:33.216902 waagent[2541]: 2025-11-04T04:59:33.216870Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 04:59:33.227850 waagent[2541]: 2025-11-04T04:59:33.227792Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 4 04:59:33.231883 waagent[2541]: 2025-11-04T04:59:33.228234Z INFO Daemon Nov 4 04:59:33.231883 waagent[2541]: 2025-11-04T04:59:33.228363Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b0d38e43-0868-42b2-88e3-9e4eb4fc4e50 eTag: 7759824490462835072 source: Fabric] Nov 4 04:59:33.231883 waagent[2541]: 2025-11-04T04:59:33.228594Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 4 04:59:33.231883 waagent[2541]: 2025-11-04T04:59:33.228832Z INFO Daemon Nov 4 04:59:33.231883 waagent[2541]: 2025-11-04T04:59:33.229028Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 4 04:59:33.237100 waagent[2541]: 2025-11-04T04:59:33.237073Z INFO Daemon Daemon Downloading artifacts profile blob Nov 4 04:59:33.301877 waagent[2541]: 2025-11-04T04:59:33.301837Z INFO Daemon Downloaded certificate {'thumbprint': '27F0327EC7AF7630E01C3F053DC2D4FC8764FA45', 'hasPrivateKey': True} Nov 4 04:59:33.304957 waagent[2541]: 2025-11-04T04:59:33.302252Z INFO Daemon Fetch goal state completed Nov 4 04:59:33.309876 waagent[2541]: 2025-11-04T04:59:33.309824Z INFO Daemon Daemon Starting provisioning Nov 4 04:59:33.310446 waagent[2541]: 2025-11-04T04:59:33.309978Z INFO Daemon Daemon Handle ovf-env.xml. Nov 4 04:59:33.310446 waagent[2541]: 2025-11-04T04:59:33.310168Z INFO Daemon Daemon Set hostname [ci-4508.0.0-n-d9799edf85] Nov 4 04:59:33.370585 waagent[2541]: 2025-11-04T04:59:33.370546Z INFO Daemon Daemon Publish hostname [ci-4508.0.0-n-d9799edf85] Nov 4 04:59:33.371993 waagent[2541]: 2025-11-04T04:59:33.371963Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 4 04:59:33.373526 waagent[2541]: 2025-11-04T04:59:33.373502Z INFO Daemon Daemon Primary interface is [eth0] Nov 4 04:59:33.379647 systemd-networkd[2184]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:59:33.379653 systemd-networkd[2184]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:59:33.379699 systemd-networkd[2184]: eth0: DHCP lease lost Nov 4 04:59:33.395109 waagent[2541]: 2025-11-04T04:59:33.395068Z INFO Daemon Daemon Create user account if not exists Nov 4 04:59:33.396473 waagent[2541]: 2025-11-04T04:59:33.396439Z INFO Daemon Daemon User core already exists, skip useradd Nov 4 04:59:33.397937 waagent[2541]: 2025-11-04T04:59:33.397866Z INFO Daemon Daemon Configure sudoer Nov 4 04:59:33.402702 waagent[2541]: 2025-11-04T04:59:33.402665Z INFO Daemon Daemon Configure sshd Nov 4 04:59:33.404087 systemd-networkd[2184]: eth0: DHCPv4 address 10.200.8.32/24, gateway 10.200.8.1 acquired from 168.63.129.16 Nov 4 04:59:33.406969 waagent[2541]: 2025-11-04T04:59:33.406932Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 4 04:59:33.409883 waagent[2541]: 2025-11-04T04:59:33.409809Z INFO Daemon Daemon Deploy ssh public key. Nov 4 04:59:33.685655 login[2544]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Nov 4 04:59:33.689749 systemd-logind[2434]: New session 2 of user core. Nov 4 04:59:33.698155 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 4 04:59:34.522472 waagent[2541]: 2025-11-04T04:59:34.522429Z INFO Daemon Daemon Provisioning complete Nov 4 04:59:34.530627 waagent[2541]: 2025-11-04T04:59:34.530599Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 4 04:59:34.532230 waagent[2541]: 2025-11-04T04:59:34.532202Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 4 04:59:34.533523 waagent[2541]: 2025-11-04T04:59:34.533498Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 4 04:59:34.624112 waagent[2631]: 2025-11-04T04:59:34.624056Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 4 04:59:34.624320 waagent[2631]: 2025-11-04T04:59:34.624138Z INFO ExtHandler ExtHandler OS: flatcar 4508.0.0 Nov 4 04:59:34.624320 waagent[2631]: 2025-11-04T04:59:34.624175Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 4 04:59:34.624320 waagent[2631]: 2025-11-04T04:59:34.624211Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Nov 4 04:59:34.792953 waagent[2631]: 2025-11-04T04:59:34.792870Z INFO ExtHandler ExtHandler Distro: flatcar-4508.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 4 04:59:34.793083 waagent[2631]: 2025-11-04T04:59:34.793010Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:59:34.793147 waagent[2631]: 2025-11-04T04:59:34.793130Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:59:34.801384 waagent[2631]: 2025-11-04T04:59:34.801332Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 04:59:34.805710 waagent[2631]: 2025-11-04T04:59:34.805681Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 4 04:59:34.806015 waagent[2631]: 2025-11-04T04:59:34.805989Z INFO ExtHandler Nov 4 04:59:34.806068 waagent[2631]: 2025-11-04T04:59:34.806048Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 7bcfd9ed-2c2b-471e-bd79-8e7029818a68 eTag: 7759824490462835072 source: Fabric] Nov 4 04:59:34.806244 waagent[2631]: 2025-11-04T04:59:34.806223Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 4 04:59:34.806540 waagent[2631]: 2025-11-04T04:59:34.806516Z INFO ExtHandler Nov 4 04:59:34.806571 waagent[2631]: 2025-11-04T04:59:34.806552Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 4 04:59:34.810171 waagent[2631]: 2025-11-04T04:59:34.810149Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 4 04:59:34.876111 waagent[2631]: 2025-11-04T04:59:34.876069Z INFO ExtHandler Downloaded certificate {'thumbprint': '27F0327EC7AF7630E01C3F053DC2D4FC8764FA45', 'hasPrivateKey': True} Nov 4 04:59:34.876405 waagent[2631]: 2025-11-04T04:59:34.876381Z INFO ExtHandler Fetch goal state completed Nov 4 04:59:34.887642 waagent[2631]: 2025-11-04T04:59:34.887602Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.3 30 Sep 2025 (Library: OpenSSL 3.4.3 30 Sep 2025) Nov 4 04:59:34.891270 waagent[2631]: 2025-11-04T04:59:34.891229Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2631 Nov 4 04:59:34.891381 waagent[2631]: 2025-11-04T04:59:34.891342Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 4 04:59:34.891592 waagent[2631]: 2025-11-04T04:59:34.891571Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 4 04:59:34.892534 waagent[2631]: 2025-11-04T04:59:34.892503Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4508.0.0', '', 'Flatcar Container Linux by Kinvolk'] Nov 4 04:59:34.892789 waagent[2631]: 2025-11-04T04:59:34.892763Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4508.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 4 04:59:34.892880 waagent[2631]: 2025-11-04T04:59:34.892861Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 4 04:59:34.893246 waagent[2631]: 2025-11-04T04:59:34.893223Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 4 04:59:34.981214 waagent[2631]: 2025-11-04T04:59:34.981189Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 4 04:59:34.981348 waagent[2631]: 2025-11-04T04:59:34.981327Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 4 04:59:34.987008 waagent[2631]: 2025-11-04T04:59:34.986652Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 4 04:59:34.992018 systemd[1]: Reload requested from client PID 2646 ('systemctl') (unit waagent.service)... Nov 4 04:59:34.992051 systemd[1]: Reloading... Nov 4 04:59:35.075075 zram_generator::config[2692]: No configuration found. Nov 4 04:59:35.089072 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#289 cmd 0x4a status: scsi 0x0 srb 0x20 hv 0xc0000001 Nov 4 04:59:35.228564 systemd[1]: Reloading finished in 236 ms. Nov 4 04:59:35.251336 waagent[2631]: 2025-11-04T04:59:35.250150Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 4 04:59:35.251336 waagent[2631]: 2025-11-04T04:59:35.250238Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 4 04:59:35.646581 waagent[2631]: 2025-11-04T04:59:35.646530Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 4 04:59:35.646837 waagent[2631]: 2025-11-04T04:59:35.646807Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 4 04:59:35.647447 waagent[2631]: 2025-11-04T04:59:35.647419Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 4 04:59:35.647691 waagent[2631]: 2025-11-04T04:59:35.647662Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 4 04:59:35.647801 waagent[2631]: 2025-11-04T04:59:35.647743Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:59:35.648084 waagent[2631]: 2025-11-04T04:59:35.648049Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:59:35.648144 waagent[2631]: 2025-11-04T04:59:35.648112Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 4 04:59:35.648218 waagent[2631]: 2025-11-04T04:59:35.648173Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:59:35.648218 waagent[2631]: 2025-11-04T04:59:35.648204Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 4 04:59:35.648471 waagent[2631]: 2025-11-04T04:59:35.648451Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:59:35.648577 waagent[2631]: 2025-11-04T04:59:35.648557Z INFO EnvHandler ExtHandler Configure routes Nov 4 04:59:35.648617 waagent[2631]: 2025-11-04T04:59:35.648600Z INFO EnvHandler ExtHandler Gateway:None Nov 4 04:59:35.648664 waagent[2631]: 2025-11-04T04:59:35.648634Z INFO EnvHandler ExtHandler Routes:None Nov 4 04:59:35.650080 waagent[2631]: 2025-11-04T04:59:35.649102Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 4 04:59:35.650080 waagent[2631]: 2025-11-04T04:59:35.649234Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 4 04:59:35.650080 waagent[2631]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 4 04:59:35.650080 waagent[2631]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Nov 4 04:59:35.650080 waagent[2631]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 4 04:59:35.650080 waagent[2631]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:59:35.650080 waagent[2631]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:59:35.650080 waagent[2631]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:59:35.650373 waagent[2631]: 2025-11-04T04:59:35.650338Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 4 04:59:35.650412 waagent[2631]: 2025-11-04T04:59:35.650385Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 4 04:59:35.650568 waagent[2631]: 2025-11-04T04:59:35.650552Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 4 04:59:35.655895 waagent[2631]: 2025-11-04T04:59:35.655871Z INFO ExtHandler ExtHandler Nov 4 04:59:35.656097 waagent[2631]: 2025-11-04T04:59:35.656080Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: a3e379f9-e99c-4801-90a9-8bc654e1b352 correlation 88b57e7e-37e6-4c9c-b9a4-cc3898755733 created: 2025-11-04T04:58:25.370087Z] Nov 4 04:59:35.656402 waagent[2631]: 2025-11-04T04:59:35.656383Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 4 04:59:35.656863 waagent[2631]: 2025-11-04T04:59:35.656846Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Nov 4 04:59:35.700619 waagent[2631]: 2025-11-04T04:59:35.700584Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 4 04:59:35.700619 waagent[2631]: Try `iptables -h' or 'iptables --help' for more information.) Nov 4 04:59:35.701416 waagent[2631]: 2025-11-04T04:59:35.701383Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 89288386-CC62-42D3-B990-0B1C74470560;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 4 04:59:35.728710 waagent[2631]: 2025-11-04T04:59:35.728671Z INFO MonitorHandler ExtHandler Network interfaces: Nov 4 04:59:35.728710 waagent[2631]: Executing ['ip', '-a', '-o', 'link']: Nov 4 04:59:35.728710 waagent[2631]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 4 04:59:35.728710 waagent[2631]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:35:b9:ce brd ff:ff:ff:ff:ff:ff\ alias Network Device\ altname enx7c1e5235b9ce Nov 4 04:59:35.728710 waagent[2631]: 3: enP30832s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 7c:1e:52:35:b9:ce brd ff:ff:ff:ff:ff:ff\ altname enP30832p0s0 Nov 4 04:59:35.728710 waagent[2631]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 4 04:59:35.728710 waagent[2631]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 4 04:59:35.728710 waagent[2631]: 2: eth0 inet 10.200.8.32/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 4 04:59:35.728710 waagent[2631]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 4 04:59:35.728710 waagent[2631]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 4 04:59:35.728710 waagent[2631]: 2: eth0 inet6 fe80::7e1e:52ff:fe35:b9ce/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 4 04:59:35.832048 waagent[2631]: 2025-11-04T04:59:35.832000Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 4 04:59:35.832048 waagent[2631]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:35.832048 waagent[2631]: pkts bytes target prot opt in out source destination Nov 4 04:59:35.832048 waagent[2631]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:35.832048 waagent[2631]: pkts bytes target prot opt in out source destination Nov 4 04:59:35.832048 waagent[2631]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:35.832048 waagent[2631]: pkts bytes target prot opt in out source destination Nov 4 04:59:35.832048 waagent[2631]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 04:59:35.832048 waagent[2631]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 04:59:35.832048 waagent[2631]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 04:59:35.834310 waagent[2631]: 2025-11-04T04:59:35.834269Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 4 04:59:35.834310 waagent[2631]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:35.834310 waagent[2631]: pkts bytes target prot opt in out source destination Nov 4 04:59:35.834310 waagent[2631]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:35.834310 waagent[2631]: pkts bytes target prot opt in out source destination Nov 4 04:59:35.834310 waagent[2631]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:59:35.834310 waagent[2631]: pkts bytes target prot opt in out source destination Nov 4 04:59:35.834310 waagent[2631]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 04:59:35.834310 waagent[2631]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 04:59:35.834310 waagent[2631]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 04:59:41.586513 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 4 04:59:41.587463 systemd[1]: Started sshd@0-10.200.8.32:22-10.200.16.10:45614.service - OpenSSH per-connection server daemon (10.200.16.10:45614). Nov 4 04:59:42.292899 sshd[2777]: Accepted publickey for core from 10.200.16.10 port 45614 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:42.293787 sshd-session[2777]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:42.297692 systemd-logind[2434]: New session 3 of user core. Nov 4 04:59:42.300153 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 4 04:59:42.703212 systemd[1]: Started sshd@1-10.200.8.32:22-10.200.16.10:45624.service - OpenSSH per-connection server daemon (10.200.16.10:45624). Nov 4 04:59:43.272691 sshd[2783]: Accepted publickey for core from 10.200.16.10 port 45624 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:43.273742 sshd-session[2783]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:43.277811 systemd-logind[2434]: New session 4 of user core. Nov 4 04:59:43.284172 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 4 04:59:43.577938 sshd[2786]: Connection closed by 10.200.16.10 port 45624 Nov 4 04:59:43.578344 sshd-session[2783]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:43.581338 systemd[1]: sshd@1-10.200.8.32:22-10.200.16.10:45624.service: Deactivated successfully. Nov 4 04:59:43.582853 systemd[1]: session-4.scope: Deactivated successfully. Nov 4 04:59:43.584176 systemd-logind[2434]: Session 4 logged out. Waiting for processes to exit. Nov 4 04:59:43.585260 systemd-logind[2434]: Removed session 4. Nov 4 04:59:43.693330 systemd[1]: Started sshd@2-10.200.8.32:22-10.200.16.10:45632.service - OpenSSH per-connection server daemon (10.200.16.10:45632). Nov 4 04:59:44.224067 sshd[2792]: Accepted publickey for core from 10.200.16.10 port 45632 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:44.224931 sshd-session[2792]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:44.228586 systemd-logind[2434]: New session 5 of user core. Nov 4 04:59:44.233134 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 4 04:59:44.523352 sshd[2795]: Connection closed by 10.200.16.10 port 45632 Nov 4 04:59:44.523683 sshd-session[2792]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:44.526333 systemd[1]: sshd@2-10.200.8.32:22-10.200.16.10:45632.service: Deactivated successfully. Nov 4 04:59:44.527533 systemd[1]: session-5.scope: Deactivated successfully. Nov 4 04:59:44.528122 systemd-logind[2434]: Session 5 logged out. Waiting for processes to exit. Nov 4 04:59:44.528952 systemd-logind[2434]: Removed session 5. Nov 4 04:59:44.633166 systemd[1]: Started sshd@3-10.200.8.32:22-10.200.16.10:45636.service - OpenSSH per-connection server daemon (10.200.16.10:45636). Nov 4 04:59:45.162125 sshd[2801]: Accepted publickey for core from 10.200.16.10 port 45636 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:45.163027 sshd-session[2801]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:45.166804 systemd-logind[2434]: New session 6 of user core. Nov 4 04:59:45.173150 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 4 04:59:45.463825 sshd[2804]: Connection closed by 10.200.16.10 port 45636 Nov 4 04:59:45.464349 sshd-session[2801]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:45.467150 systemd[1]: sshd@3-10.200.8.32:22-10.200.16.10:45636.service: Deactivated successfully. Nov 4 04:59:45.468527 systemd[1]: session-6.scope: Deactivated successfully. Nov 4 04:59:45.469172 systemd-logind[2434]: Session 6 logged out. Waiting for processes to exit. Nov 4 04:59:45.470130 systemd-logind[2434]: Removed session 6. Nov 4 04:59:45.580273 systemd[1]: Started sshd@4-10.200.8.32:22-10.200.16.10:45646.service - OpenSSH per-connection server daemon (10.200.16.10:45646). Nov 4 04:59:46.110367 sshd[2810]: Accepted publickey for core from 10.200.16.10 port 45646 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:46.111313 sshd-session[2810]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:46.115084 systemd-logind[2434]: New session 7 of user core. Nov 4 04:59:46.124138 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 4 04:59:46.491058 sudo[2814]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 4 04:59:46.491259 sudo[2814]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:46.521668 sudo[2814]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:46.621417 sshd[2813]: Connection closed by 10.200.16.10 port 45646 Nov 4 04:59:46.621905 sshd-session[2810]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:46.624327 systemd[1]: sshd@4-10.200.8.32:22-10.200.16.10:45646.service: Deactivated successfully. Nov 4 04:59:46.625657 systemd[1]: session-7.scope: Deactivated successfully. Nov 4 04:59:46.627094 systemd-logind[2434]: Session 7 logged out. Waiting for processes to exit. Nov 4 04:59:46.627736 systemd-logind[2434]: Removed session 7. Nov 4 04:59:46.734361 systemd[1]: Started sshd@5-10.200.8.32:22-10.200.16.10:45658.service - OpenSSH per-connection server daemon (10.200.16.10:45658). Nov 4 04:59:47.263216 sshd[2820]: Accepted publickey for core from 10.200.16.10 port 45658 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:47.264163 sshd-session[2820]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:47.268305 systemd-logind[2434]: New session 8 of user core. Nov 4 04:59:47.278150 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 4 04:59:47.465926 sudo[2825]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 4 04:59:47.466142 sudo[2825]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:47.472186 sudo[2825]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:47.476383 sudo[2824]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 4 04:59:47.476583 sudo[2824]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:47.483482 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 04:59:47.514930 augenrules[2847]: No rules Nov 4 04:59:47.515902 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 04:59:47.516094 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 04:59:47.516909 sudo[2824]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:47.616410 sshd[2823]: Connection closed by 10.200.16.10 port 45658 Nov 4 04:59:47.616754 sshd-session[2820]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:47.619106 systemd[1]: sshd@5-10.200.8.32:22-10.200.16.10:45658.service: Deactivated successfully. Nov 4 04:59:47.620283 systemd[1]: session-8.scope: Deactivated successfully. Nov 4 04:59:47.621681 systemd-logind[2434]: Session 8 logged out. Waiting for processes to exit. Nov 4 04:59:47.622261 systemd-logind[2434]: Removed session 8. Nov 4 04:59:47.726320 systemd[1]: Started sshd@6-10.200.8.32:22-10.200.16.10:45670.service - OpenSSH per-connection server daemon (10.200.16.10:45670). Nov 4 04:59:48.257569 sshd[2857]: Accepted publickey for core from 10.200.16.10 port 45670 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:48.258367 sshd-session[2857]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:48.262044 systemd-logind[2434]: New session 9 of user core. Nov 4 04:59:48.268179 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 4 04:59:48.461398 sudo[2861]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Nov 4 04:59:48.461620 sudo[2861]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:48.464145 sudo[2861]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:48.563775 sshd[2860]: Connection closed by 10.200.16.10 port 45670 Nov 4 04:59:48.564365 sshd-session[2857]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:48.566375 systemd[1]: sshd@6-10.200.8.32:22-10.200.16.10:45670.service: Deactivated successfully. Nov 4 04:59:48.567568 systemd[1]: session-9.scope: Deactivated successfully. Nov 4 04:59:48.568552 systemd-logind[2434]: Session 9 logged out. Waiting for processes to exit. Nov 4 04:59:48.569500 systemd-logind[2434]: Removed session 9. Nov 4 04:59:48.681948 systemd[1]: Started sshd@7-10.200.8.32:22-10.200.16.10:45682.service - OpenSSH per-connection server daemon (10.200.16.10:45682). Nov 4 04:59:49.211573 sshd[2867]: Accepted publickey for core from 10.200.16.10 port 45682 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:49.212371 sshd-session[2867]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:49.216068 systemd-logind[2434]: New session 10 of user core. Nov 4 04:59:49.225162 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 4 04:59:49.415430 sudo[2871]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Nov 4 04:59:49.415616 sudo[2871]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:53.095083 sudo[2871]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:53.195134 sshd[2870]: Connection closed by 10.200.16.10 port 45682 Nov 4 04:59:53.195622 sshd-session[2867]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:53.198394 systemd[1]: sshd@7-10.200.8.32:22-10.200.16.10:45682.service: Deactivated successfully. Nov 4 04:59:53.199796 systemd[1]: session-10.scope: Deactivated successfully. Nov 4 04:59:53.200939 systemd-logind[2434]: Session 10 logged out. Waiting for processes to exit. Nov 4 04:59:53.201955 systemd-logind[2434]: Removed session 10. Nov 4 04:59:53.308351 systemd[1]: Started sshd@8-10.200.8.32:22-10.200.16.10:37986.service - OpenSSH per-connection server daemon (10.200.16.10:37986). Nov 4 04:59:53.844592 sshd[2877]: Accepted publickey for core from 10.200.16.10 port 37986 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:53.845568 sshd-session[2877]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:53.849786 systemd-logind[2434]: New session 11 of user core. Nov 4 04:59:53.855209 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 4 04:59:53.895341 chronyd[2418]: Selected source PHC0 Nov 4 04:59:54.223991 sshd[2880]: Connection closed by 10.200.16.10 port 37986 Nov 4 04:59:54.224383 sshd-session[2877]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:54.226983 systemd[1]: sshd@8-10.200.8.32:22-10.200.16.10:37986.service: Deactivated successfully. Nov 4 04:59:54.228219 systemd[1]: session-11.scope: Deactivated successfully. Nov 4 04:59:54.228823 systemd-logind[2434]: Session 11 logged out. Waiting for processes to exit. Nov 4 04:59:54.229782 systemd-logind[2434]: Removed session 11. Nov 4 04:59:54.347109 systemd[1]: Started sshd@9-10.200.8.32:22-10.200.16.10:37992.service - OpenSSH per-connection server daemon (10.200.16.10:37992). Nov 4 04:59:54.872225 sshd[2891]: Accepted publickey for core from 10.200.16.10 port 37992 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:54.873015 sshd-session[2891]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:54.876074 systemd-logind[2434]: New session 12 of user core. Nov 4 04:59:54.886163 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 4 04:59:55.191916 sshd[2894]: Connection closed by 10.200.16.10 port 37992 Nov 4 04:59:55.192529 sshd-session[2891]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:55.195156 systemd[1]: sshd@9-10.200.8.32:22-10.200.16.10:37992.service: Deactivated successfully. Nov 4 04:59:55.196362 systemd[1]: session-12.scope: Deactivated successfully. Nov 4 04:59:55.196896 systemd-logind[2434]: Session 12 logged out. Waiting for processes to exit. Nov 4 04:59:55.197838 systemd-logind[2434]: Removed session 12. Nov 4 04:59:55.300977 systemd[1]: Started sshd@10-10.200.8.32:22-10.200.16.10:38004.service - OpenSSH per-connection server daemon (10.200.16.10:38004). Nov 4 04:59:55.833067 sshd[2904]: Accepted publickey for core from 10.200.16.10 port 38004 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:55.833824 sshd-session[2904]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:55.837632 systemd-logind[2434]: New session 13 of user core. Nov 4 04:59:55.846144 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 4 04:59:56.071017 sudo[2915]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/useradd -p * -U -m user1 -G sudo Nov 4 04:59:56.071241 sudo[2915]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:56.181386 useradd[2916]: new group: name=user1, GID=1000 Nov 4 04:59:56.181400 useradd[2916]: new user: name=user1, UID=1000, GID=1000, home=/home/user1, shell=/bin/bash, from=none Nov 4 04:59:56.182472 useradd[2916]: add 'user1' to group 'sudo' Nov 4 04:59:56.182481 useradd[2916]: add 'user1' to shadow group 'sudo' Nov 4 04:59:56.868906 useradd[2916]: useradd: sss_cache exited with status 5 Nov 4 04:59:56.868912 useradd[2916]: useradd: Failed to flush the sssd cache. Nov 4 04:59:56.872258 useradd[2916]: useradd: sss_cache exited with status 5 Nov 4 04:59:56.872265 useradd[2916]: useradd: Failed to flush the sssd cache. Nov 4 04:59:56.872807 sudo[2915]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:56.993132 sshd[2907]: Connection closed by 10.200.16.10 port 38004 Nov 4 04:59:56.993495 sshd-session[2904]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:56.996402 systemd[1]: sshd@10-10.200.8.32:22-10.200.16.10:38004.service: Deactivated successfully. Nov 4 04:59:56.997675 systemd[1]: session-13.scope: Deactivated successfully. Nov 4 04:59:56.998325 systemd-logind[2434]: Session 13 logged out. Waiting for processes to exit. Nov 4 04:59:56.999289 systemd-logind[2434]: Removed session 13. Nov 4 04:59:57.113255 systemd[1]: Started sshd@11-10.200.8.32:22-10.200.16.10:38018.service - OpenSSH per-connection server daemon (10.200.16.10:38018). Nov 4 04:59:57.640389 sshd[2928]: Accepted publickey for core from 10.200.16.10 port 38018 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:57.641215 sshd-session[2928]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:57.644750 systemd-logind[2434]: New session 14 of user core. Nov 4 04:59:57.651144 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 4 04:59:57.994211 sshd[2931]: Connection closed by 10.200.16.10 port 38018 Nov 4 04:59:57.994763 sshd-session[2928]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:57.996979 systemd[1]: sshd@11-10.200.8.32:22-10.200.16.10:38018.service: Deactivated successfully. Nov 4 04:59:57.998994 systemd-logind[2434]: Session 14 logged out. Waiting for processes to exit. Nov 4 04:59:57.999121 systemd[1]: session-14.scope: Deactivated successfully. Nov 4 04:59:57.999910 systemd-logind[2434]: Removed session 14. Nov 4 04:59:58.104160 systemd[1]: Started sshd@12-10.200.8.32:22-10.200.16.10:38024.service - OpenSSH per-connection server daemon (10.200.16.10:38024). Nov 4 04:59:58.633927 sshd[2956]: Accepted publickey for core from 10.200.16.10 port 38024 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 04:59:58.634767 sshd-session[2956]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:59:58.638246 systemd-logind[2434]: New session 15 of user core. Nov 4 04:59:58.643133 systemd[1]: Started session-15.scope - Session 15 of User core. Nov 4 04:59:58.873659 sudo[2967]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/coreos-cloudinit --workspace /tmp/coretest-cloudinit-3198402052 --from-file /tmp/coretest-1123598020 Nov 4 04:59:58.873853 sudo[2967]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:59.176108 systemd[1]: Started coreos-cloudinit-3927553076.service - Unit generated and executed by coreos-cloudinit on behalf of user. Nov 4 04:59:59.178011 sudo[2967]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:59.188790 dbus-daemon[2421]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.5' (uid=0 pid=1 comm="/usr/lib/systemd/systemd --switched-root --system" label="system_u:system_r:kernel_t:s0") Nov 4 04:59:59.191845 systemd[1]: Starting polkit.service - Authorization Manager... Nov 4 04:59:59.263125 polkitd[2977]: Started polkitd version 126 Nov 4 04:59:59.292879 polkitd[2977]: Loading rules from directory /etc/polkit-1/rules.d Nov 4 04:59:59.293151 polkitd[2977]: Loading rules from directory /run/polkit-1/rules.d Nov 4 04:59:59.293188 polkitd[2977]: Error opening rules directory: Error opening directory “/run/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Nov 4 04:59:59.293364 polkitd[2977]: Loading rules from directory /usr/local/share/polkit-1/rules.d Nov 4 04:59:59.293381 polkitd[2977]: Error opening rules directory: Error opening directory “/usr/local/share/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Nov 4 04:59:59.293409 polkitd[2977]: Loading rules from directory /usr/share/polkit-1/rules.d Nov 4 04:59:59.293751 polkitd[2977]: Finished loading, compiling and executing 2 rules Nov 4 04:59:59.293956 systemd[1]: Started polkit.service - Authorization Manager. Nov 4 04:59:59.294209 dbus-daemon[2421]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 4 04:59:59.294462 polkitd[2977]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Nov 4 04:59:59.463518 sudo[2987]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /tmp/coretest-cloudinit-3198402052 Nov 4 04:59:59.463717 sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:59:59.466446 sudo[2987]: pam_unix(sudo:session): session closed for user root Nov 4 04:59:59.566712 sshd[2959]: Connection closed by 10.200.16.10 port 38024 Nov 4 04:59:59.567079 sshd-session[2956]: pam_unix(sshd:session): session closed for user core Nov 4 04:59:59.569963 systemd[1]: sshd@12-10.200.8.32:22-10.200.16.10:38024.service: Deactivated successfully. Nov 4 04:59:59.571260 systemd[1]: session-15.scope: Deactivated successfully. Nov 4 04:59:59.571964 systemd-logind[2434]: Session 15 logged out. Waiting for processes to exit. Nov 4 04:59:59.572966 systemd-logind[2434]: Removed session 15. Nov 4 04:59:59.679901 systemd[1]: Started sshd@13-10.200.8.32:22-10.200.16.10:38038.service - OpenSSH per-connection server daemon (10.200.16.10:38038). Nov 4 05:00:00.208743 sshd[2993]: Accepted publickey for core from 10.200.16.10 port 38038 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:00.209670 sshd-session[2993]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:00.213876 systemd-logind[2434]: New session 16 of user core. Nov 4 05:00:00.220164 systemd[1]: Started session-16.scope - Session 16 of User core. Nov 4 05:00:00.528261 sshd[2996]: Connection closed by 10.200.16.10 port 38038 Nov 4 05:00:00.528818 sshd-session[2993]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:00.531592 systemd[1]: sshd@13-10.200.8.32:22-10.200.16.10:38038.service: Deactivated successfully. Nov 4 05:00:00.532848 systemd[1]: session-16.scope: Deactivated successfully. Nov 4 05:00:00.533439 systemd-logind[2434]: Session 16 logged out. Waiting for processes to exit. Nov 4 05:00:00.534333 systemd-logind[2434]: Removed session 16. Nov 4 05:00:00.646235 systemd[1]: Started sshd@14-10.200.8.32:22-10.200.16.10:54696.service - OpenSSH per-connection server daemon (10.200.16.10:54696). Nov 4 05:00:01.176287 sshd[3007]: Accepted publickey for core from 10.200.16.10 port 54696 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:01.177102 sshd-session[3007]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:01.180898 systemd-logind[2434]: New session 17 of user core. Nov 4 05:00:01.187149 systemd[1]: Started session-17.scope - Session 17 of User core. Nov 4 05:00:01.512468 sshd[3010]: Connection closed by 10.200.16.10 port 54696 Nov 4 05:00:01.515143 sshd-session[3007]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:01.517619 systemd[1]: sshd@14-10.200.8.32:22-10.200.16.10:54696.service: Deactivated successfully. Nov 4 05:00:01.518825 systemd[1]: session-17.scope: Deactivated successfully. Nov 4 05:00:01.519403 systemd-logind[2434]: Session 17 logged out. Waiting for processes to exit. Nov 4 05:00:01.520369 systemd-logind[2434]: Removed session 17. Nov 4 05:00:01.622307 systemd[1]: Started sshd@15-10.200.8.32:22-10.200.16.10:54706.service - OpenSSH per-connection server daemon (10.200.16.10:54706). Nov 4 05:00:02.186305 sshd[3019]: Accepted publickey for core from 10.200.16.10 port 54706 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:02.187118 sshd-session[3019]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:02.190916 systemd-logind[2434]: New session 18 of user core. Nov 4 05:00:02.204159 systemd[1]: Started session-18.scope - Session 18 of User core. Nov 4 05:00:02.534809 sshd[3022]: Connection closed by 10.200.16.10 port 54706 Nov 4 05:00:02.535366 sshd-session[3019]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:02.537600 systemd[1]: sshd@15-10.200.8.32:22-10.200.16.10:54706.service: Deactivated successfully. Nov 4 05:00:02.539416 systemd[1]: session-18.scope: Deactivated successfully. Nov 4 05:00:02.540004 systemd-logind[2434]: Session 18 logged out. Waiting for processes to exit. Nov 4 05:00:02.540596 systemd-logind[2434]: Removed session 18. Nov 4 05:00:02.645211 systemd[1]: Started sshd@16-10.200.8.32:22-10.200.16.10:54720.service - OpenSSH per-connection server daemon (10.200.16.10:54720). Nov 4 05:00:03.176233 sshd[3038]: Accepted publickey for core from 10.200.16.10 port 54720 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:03.177238 sshd-session[3038]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:03.181565 systemd-logind[2434]: New session 19 of user core. Nov 4 05:00:03.191173 systemd[1]: Started session-19.scope - Session 19 of User core. Nov 4 05:00:03.478140 sudo[3049]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/blkid -o value -s PTUUID /dev/nvme0n1 Nov 4 05:00:03.478527 sudo[3049]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 05:00:03.562529 sudo[3049]: pam_unix(sudo:session): session closed for user root Nov 4 05:00:03.662878 sshd[3041]: Connection closed by 10.200.16.10 port 54720 Nov 4 05:00:03.663295 sshd-session[3038]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:03.666217 systemd[1]: sshd@16-10.200.8.32:22-10.200.16.10:54720.service: Deactivated successfully. Nov 4 05:00:03.667545 systemd[1]: session-19.scope: Deactivated successfully. Nov 4 05:00:03.668158 systemd-logind[2434]: Session 19 logged out. Waiting for processes to exit. Nov 4 05:00:03.669457 systemd-logind[2434]: Removed session 19. Nov 4 05:00:03.791998 systemd[1]: Started sshd@17-10.200.8.32:22-10.200.16.10:54732.service - OpenSSH per-connection server daemon (10.200.16.10:54732). Nov 4 05:00:04.241142 kernel: hv_balloon: Max. dynamic memory size: 8192 MB Nov 4 05:00:04.322538 sshd[3057]: Accepted publickey for core from 10.200.16.10 port 54732 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:04.323438 sshd-session[3057]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:04.327325 systemd-logind[2434]: New session 20 of user core. Nov 4 05:00:04.335160 systemd[1]: Started session-20.scope - Session 20 of User core. Nov 4 05:00:04.642191 sshd[3060]: Connection closed by 10.200.16.10 port 54732 Nov 4 05:00:04.642538 sshd-session[3057]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:04.645479 systemd[1]: sshd@17-10.200.8.32:22-10.200.16.10:54732.service: Deactivated successfully. Nov 4 05:00:04.646728 systemd[1]: session-20.scope: Deactivated successfully. Nov 4 05:00:04.647421 systemd-logind[2434]: Session 20 logged out. Waiting for processes to exit. Nov 4 05:00:04.648325 systemd-logind[2434]: Removed session 20. Nov 4 05:00:04.754998 systemd[1]: Started sshd@18-10.200.8.32:22-10.200.16.10:54736.service - OpenSSH per-connection server daemon (10.200.16.10:54736). Nov 4 05:00:05.284659 sshd[3071]: Accepted publickey for core from 10.200.16.10 port 54736 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:05.285556 sshd-session[3071]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:05.289147 systemd-logind[2434]: New session 21 of user core. Nov 4 05:00:05.296156 systemd[1]: Started session-21.scope - Session 21 of User core. Nov 4 05:00:05.509477 sudo[3081]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/coreos-cloudinit --workspace /tmp/coretest-cloudinit-1708220081 --from-file /tmp/coretest-2827914437 --ssh-key-name coretest Nov 4 05:00:05.509679 sudo[3081]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 05:00:05.535382 systemd[1]: Reload requested from client PID 3082 ('coreos-cloudini') (unit session-21.scope)... Nov 4 05:00:05.535505 systemd[1]: Reloading... Nov 4 05:00:05.614063 zram_generator::config[3138]: No configuration found. Nov 4 05:00:05.758256 systemd[1]: Reloading finished in 222 ms. Nov 4 05:00:05.773100 sudo[3081]: pam_unix(sudo:session): session closed for user root Nov 4 05:00:05.793158 sudo[3193]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /tmp/coretest-cloudinit-1708220081 Nov 4 05:00:05.793346 sudo[3193]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 05:00:05.795664 sudo[3193]: pam_unix(sudo:session): session closed for user root Nov 4 05:00:05.896441 sshd[3074]: Connection closed by 10.200.16.10 port 54736 Nov 4 05:00:05.896768 sshd-session[3071]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:05.899404 systemd[1]: sshd@18-10.200.8.32:22-10.200.16.10:54736.service: Deactivated successfully. Nov 4 05:00:05.906473 systemd[1]: session-21.scope: Deactivated successfully. Nov 4 05:00:05.907481 systemd-logind[2434]: Session 21 logged out. Waiting for processes to exit. Nov 4 05:00:05.908328 systemd-logind[2434]: Removed session 21. Nov 4 05:00:06.005998 systemd[1]: Started sshd@19-10.200.8.32:22-10.200.16.10:54744.service - OpenSSH per-connection server daemon (10.200.16.10:54744). Nov 4 05:00:06.533442 sshd[3199]: Accepted publickey for core from 10.200.16.10 port 54744 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:06.534232 sshd-session[3199]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:06.537919 systemd-logind[2434]: New session 22 of user core. Nov 4 05:00:06.543196 systemd[1]: Started session-22.scope - Session 22 of User core. Nov 4 05:00:06.762309 systemd[1]: Started sshd@20-127.0.0.1:22-127.0.0.1:59866.service - OpenSSH per-connection server daemon (127.0.0.1:59866). Nov 4 05:00:06.773888 sshd[3209]: Connection closed by 127.0.0.1 port 59866 Nov 4 05:00:06.774295 systemd[1]: sshd@20-127.0.0.1:22-127.0.0.1:59866.service: Deactivated successfully. Nov 4 05:00:06.852770 sshd[3202]: Connection closed by 10.200.16.10 port 54744 Nov 4 05:00:06.853289 sshd-session[3199]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:06.855669 systemd[1]: sshd@19-10.200.8.32:22-10.200.16.10:54744.service: Deactivated successfully. Nov 4 05:00:06.857452 systemd-logind[2434]: Session 22 logged out. Waiting for processes to exit. Nov 4 05:00:06.857454 systemd[1]: session-22.scope: Deactivated successfully. Nov 4 05:00:06.858768 systemd-logind[2434]: Removed session 22. Nov 4 05:00:06.962770 systemd[1]: Started sshd@21-10.200.8.32:22-10.200.16.10:54756.service - OpenSSH per-connection server daemon (10.200.16.10:54756). Nov 4 05:00:07.491916 sshd[3217]: Accepted publickey for core from 10.200.16.10 port 54756 ssh2: RSA SHA256:JldBg114e1ET+kYr4E2vY6i7Ywm1DYHSQTIfelEy444 Nov 4 05:00:07.492747 sshd-session[3217]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 05:00:07.496214 systemd-logind[2434]: New session 23 of user core. Nov 4 05:00:07.505149 systemd[1]: Started session-23.scope - Session 23 of User core. Nov 4 05:00:07.731019 sudo[3229]: core : PWD=/home/core ; USER=core ; COMMAND=/usr/sbin/gdbus call --system --dest org.freedesktop.systemd1 --object-path /org/freedesktop/systemd1 --method org.freedesktop.systemd1.Manager.RestartUnit ntpd.service replace Nov 4 05:00:07.731249 sudo[3229]: pam_unix(sudo:session): session opened for user core(uid=500) by core(uid=500) Nov 4 05:00:07.792199 sudo[3229]: pam_unix(sudo:session): session closed for user core Nov 4 05:00:07.797422 sudo[3236]: core : PWD=/home/core ; USER=core ; COMMAND=/usr/sbin/gdbus call --system --dest org.freedesktop.systemd1 --object-path /org/freedesktop/systemd1/unit/ntpd_2eservice --method org.freedesktop.DBus.Properties.GetAll org.freedesktop.systemd1.Unit Nov 4 05:00:07.797605 sudo[3236]: pam_unix(sudo:session): session opened for user core(uid=500) by core(uid=500) Nov 4 05:00:07.807566 sudo[3236]: pam_unix(sudo:session): session closed for user core Nov 4 05:00:07.907959 sshd[3220]: Connection closed by 10.200.16.10 port 54756 Nov 4 05:00:07.908293 sshd-session[3217]: pam_unix(sshd:session): session closed for user core Nov 4 05:00:07.910885 systemd[1]: sshd@21-10.200.8.32:22-10.200.16.10:54756.service: Deactivated successfully. Nov 4 05:00:07.912132 systemd[1]: session-23.scope: Deactivated successfully. Nov 4 05:00:07.912881 systemd-logind[2434]: Session 23 logged out. Waiting for processes to exit. Nov 4 05:00:07.913579 systemd-logind[2434]: Removed session 23. Nov 4 05:00:09.203765 systemd[1]: coreos-cloudinit-3927553076.service: Deactivated successfully.