May 27 02:45:19.077529 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] May 27 02:45:19.077549 kernel: Linux version 6.12.30-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.44 p1) 2.44.0) #1 SMP PREEMPT Tue May 27 01:20:04 -00 2025 May 27 02:45:19.077556 kernel: KASLR enabled May 27 02:45:19.077560 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') May 27 02:45:19.077565 kernel: printk: legacy bootconsole [pl11] enabled May 27 02:45:19.077569 kernel: efi: EFI v2.7 by EDK II May 27 02:45:19.077574 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f20e018 RNG=0x3fd5f998 MEMRESERVE=0x3e471598 May 27 02:45:19.077578 kernel: random: crng init done May 27 02:45:19.077582 kernel: secureboot: Secure boot disabled May 27 02:45:19.077586 kernel: ACPI: Early table checksum verification disabled May 27 02:45:19.077590 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) May 27 02:45:19.077594 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077597 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077602 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) May 27 02:45:19.077607 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077611 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077616 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077621 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077625 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077629 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077633 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) May 27 02:45:19.077637 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 27 02:45:19.077641 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 May 27 02:45:19.077645 kernel: ACPI: Use ACPI SPCR as default console: Yes May 27 02:45:19.077649 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug May 27 02:45:19.077653 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug May 27 02:45:19.077657 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug May 27 02:45:19.077661 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug May 27 02:45:19.077665 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug May 27 02:45:19.077670 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug May 27 02:45:19.077675 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug May 27 02:45:19.077679 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug May 27 02:45:19.077683 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug May 27 02:45:19.077687 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug May 27 02:45:19.077691 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug May 27 02:45:19.077695 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug May 27 02:45:19.077699 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] May 27 02:45:19.077703 kernel: NODE_DATA(0) allocated [mem 0x1bf7fddc0-0x1bf804fff] May 27 02:45:19.077707 kernel: Zone ranges: May 27 02:45:19.077711 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] May 27 02:45:19.077719 kernel: DMA32 empty May 27 02:45:19.077723 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] May 27 02:45:19.077727 kernel: Device empty May 27 02:45:19.077732 kernel: Movable zone start for each node May 27 02:45:19.077736 kernel: Early memory node ranges May 27 02:45:19.077741 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] May 27 02:45:19.077745 kernel: node 0: [mem 0x0000000000824000-0x000000003e45ffff] May 27 02:45:19.077750 kernel: node 0: [mem 0x000000003e460000-0x000000003e46ffff] May 27 02:45:19.077754 kernel: node 0: [mem 0x000000003e470000-0x000000003e54ffff] May 27 02:45:19.077758 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] May 27 02:45:19.077762 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] May 27 02:45:19.077766 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] May 27 02:45:19.077771 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] May 27 02:45:19.077775 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] May 27 02:45:19.077779 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] May 27 02:45:19.077783 kernel: On node 0, zone DMA: 36 pages in unavailable ranges May 27 02:45:19.077788 kernel: psci: probing for conduit method from ACPI. May 27 02:45:19.077793 kernel: psci: PSCIv1.1 detected in firmware. May 27 02:45:19.077797 kernel: psci: Using standard PSCI v0.2 function IDs May 27 02:45:19.077802 kernel: psci: MIGRATE_INFO_TYPE not supported. May 27 02:45:19.077806 kernel: psci: SMC Calling Convention v1.4 May 27 02:45:19.077810 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 May 27 02:45:19.077815 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 May 27 02:45:19.077819 kernel: percpu: Embedded 33 pages/cpu s98136 r8192 d28840 u135168 May 27 02:45:19.077823 kernel: pcpu-alloc: s98136 r8192 d28840 u135168 alloc=33*4096 May 27 02:45:19.077828 kernel: pcpu-alloc: [0] 0 [0] 1 May 27 02:45:19.077832 kernel: Detected PIPT I-cache on CPU0 May 27 02:45:19.077836 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) May 27 02:45:19.077842 kernel: CPU features: detected: GIC system register CPU interface May 27 02:45:19.077847 kernel: CPU features: detected: Spectre-v4 May 27 02:45:19.077851 kernel: CPU features: detected: Spectre-BHB May 27 02:45:19.077855 kernel: CPU features: kernel page table isolation forced ON by KASLR May 27 02:45:19.077860 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 27 02:45:19.077864 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 May 27 02:45:19.077868 kernel: CPU features: detected: SSBS not fully self-synchronizing May 27 02:45:19.077872 kernel: alternatives: applying boot alternatives May 27 02:45:19.077878 kernel: 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=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=4c3f98aae7a61b3dcbab6391ba922461adab29dbcb79fd6e18169f93c5a4ab5a May 27 02:45:19.077882 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 27 02:45:19.077887 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 27 02:45:19.077892 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 27 02:45:19.077896 kernel: Fallback order for Node 0: 0 May 27 02:45:19.077901 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 May 27 02:45:19.077905 kernel: Policy zone: Normal May 27 02:45:19.077909 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 27 02:45:19.077914 kernel: software IO TLB: area num 2. May 27 02:45:19.077918 kernel: software IO TLB: mapped [mem 0x000000003a460000-0x000000003e460000] (64MB) May 27 02:45:19.077922 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 27 02:45:19.077926 kernel: rcu: Preemptible hierarchical RCU implementation. May 27 02:45:19.077932 kernel: rcu: RCU event tracing is enabled. May 27 02:45:19.077936 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 27 02:45:19.077941 kernel: Trampoline variant of Tasks RCU enabled. May 27 02:45:19.077946 kernel: Tracing variant of Tasks RCU enabled. May 27 02:45:19.077950 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 27 02:45:19.077955 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 27 02:45:19.077959 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 27 02:45:19.077963 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 27 02:45:19.077968 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 27 02:45:19.077972 kernel: GICv3: 960 SPIs implemented May 27 02:45:19.077976 kernel: GICv3: 0 Extended SPIs implemented May 27 02:45:19.077980 kernel: Root IRQ handler: gic_handle_irq May 27 02:45:19.077985 kernel: GICv3: GICv3 features: 16 PPIs, RSS May 27 02:45:19.077989 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 May 27 02:45:19.077994 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 May 27 02:45:19.077998 kernel: ITS: No ITS available, not enabling LPIs May 27 02:45:19.078003 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 27 02:45:19.078007 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). May 27 02:45:19.078012 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns May 27 02:45:19.078016 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns May 27 02:45:19.078021 kernel: Console: colour dummy device 80x25 May 27 02:45:19.078025 kernel: printk: legacy console [tty1] enabled May 27 02:45:19.078030 kernel: ACPI: Core revision 20240827 May 27 02:45:19.078034 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) May 27 02:45:19.078040 kernel: pid_max: default: 32768 minimum: 301 May 27 02:45:19.078044 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima May 27 02:45:19.078049 kernel: landlock: Up and running. May 27 02:45:19.078053 kernel: SELinux: Initializing. May 27 02:45:19.078058 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 27 02:45:19.078062 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 27 02:45:19.078071 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x1a0000e, misc 0x31e1 May 27 02:45:19.078076 kernel: Hyper-V: Host Build 10.0.26100.1254-1-0 May 27 02:45:19.078081 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 27 02:45:19.078086 kernel: rcu: Hierarchical SRCU implementation. May 27 02:45:19.078090 kernel: rcu: Max phase no-delay instances is 400. May 27 02:45:19.078095 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level May 27 02:45:19.078101 kernel: Remapping and enabling EFI services. May 27 02:45:19.078106 kernel: smp: Bringing up secondary CPUs ... May 27 02:45:19.078110 kernel: Detected PIPT I-cache on CPU1 May 27 02:45:19.078115 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 May 27 02:45:19.078120 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] May 27 02:45:19.078125 kernel: smp: Brought up 1 node, 2 CPUs May 27 02:45:19.078130 kernel: SMP: Total of 2 processors activated. May 27 02:45:19.078135 kernel: CPU: All CPU(s) started at EL1 May 27 02:45:19.078139 kernel: CPU features: detected: 32-bit EL0 Support May 27 02:45:19.078144 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence May 27 02:45:19.078149 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 27 02:45:19.078154 kernel: CPU features: detected: Common not Private translations May 27 02:45:19.078158 kernel: CPU features: detected: CRC32 instructions May 27 02:45:19.078163 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) May 27 02:45:19.078169 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 27 02:45:19.078173 kernel: CPU features: detected: LSE atomic instructions May 27 02:45:19.078178 kernel: CPU features: detected: Privileged Access Never May 27 02:45:19.078183 kernel: CPU features: detected: Speculation barrier (SB) May 27 02:45:19.078188 kernel: CPU features: detected: TLB range maintenance instructions May 27 02:45:19.078192 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) May 27 02:45:19.078197 kernel: CPU features: detected: Scalable Vector Extension May 27 02:45:19.078202 kernel: alternatives: applying system-wide alternatives May 27 02:45:19.078207 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 May 27 02:45:19.078212 kernel: SVE: maximum available vector length 16 bytes per vector May 27 02:45:19.078217 kernel: SVE: default vector length 16 bytes per vector May 27 02:45:19.078222 kernel: Memory: 3976112K/4194160K available (11072K kernel code, 2276K rwdata, 8936K rodata, 39424K init, 1034K bss, 213432K reserved, 0K cma-reserved) May 27 02:45:19.078227 kernel: devtmpfs: initialized May 27 02:45:19.078231 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 27 02:45:19.078236 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 27 02:45:19.078241 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 27 02:45:19.078246 kernel: 0 pages in range for non-PLT usage May 27 02:45:19.078250 kernel: 508544 pages in range for PLT usage May 27 02:45:19.078256 kernel: pinctrl core: initialized pinctrl subsystem May 27 02:45:19.078260 kernel: SMBIOS 3.1.0 present. May 27 02:45:19.078265 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 May 27 02:45:19.078270 kernel: DMI: Memory slots populated: 2/2 May 27 02:45:19.078274 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 27 02:45:19.078279 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 27 02:45:19.078284 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 27 02:45:19.078300 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 27 02:45:19.078305 kernel: audit: initializing netlink subsys (disabled) May 27 02:45:19.078311 kernel: audit: type=2000 audit(0.063:1): state=initialized audit_enabled=0 res=1 May 27 02:45:19.078316 kernel: thermal_sys: Registered thermal governor 'step_wise' May 27 02:45:19.078320 kernel: cpuidle: using governor menu May 27 02:45:19.078325 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 27 02:45:19.078330 kernel: ASID allocator initialised with 32768 entries May 27 02:45:19.078334 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 27 02:45:19.078339 kernel: Serial: AMBA PL011 UART driver May 27 02:45:19.078344 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 27 02:45:19.078349 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 27 02:45:19.078355 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 27 02:45:19.078359 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 27 02:45:19.078364 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 27 02:45:19.078369 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 27 02:45:19.078373 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 27 02:45:19.078378 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 27 02:45:19.078383 kernel: ACPI: Added _OSI(Module Device) May 27 02:45:19.078388 kernel: ACPI: Added _OSI(Processor Device) May 27 02:45:19.078392 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 27 02:45:19.078398 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 27 02:45:19.078403 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 27 02:45:19.078407 kernel: ACPI: Interpreter enabled May 27 02:45:19.078412 kernel: ACPI: Using GIC for interrupt routing May 27 02:45:19.078417 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA May 27 02:45:19.078421 kernel: printk: legacy console [ttyAMA0] enabled May 27 02:45:19.078426 kernel: printk: legacy bootconsole [pl11] disabled May 27 02:45:19.078431 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA May 27 02:45:19.078435 kernel: ACPI: CPU0 has been hot-added May 27 02:45:19.078441 kernel: ACPI: CPU1 has been hot-added May 27 02:45:19.078446 kernel: iommu: Default domain type: Translated May 27 02:45:19.078450 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 27 02:45:19.078455 kernel: efivars: Registered efivars operations May 27 02:45:19.078460 kernel: vgaarb: loaded May 27 02:45:19.078465 kernel: clocksource: Switched to clocksource arch_sys_counter May 27 02:45:19.078469 kernel: VFS: Disk quotas dquot_6.6.0 May 27 02:45:19.078474 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 27 02:45:19.078479 kernel: pnp: PnP ACPI init May 27 02:45:19.078484 kernel: pnp: PnP ACPI: found 0 devices May 27 02:45:19.078489 kernel: NET: Registered PF_INET protocol family May 27 02:45:19.078494 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 27 02:45:19.078499 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 27 02:45:19.078503 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 27 02:45:19.078508 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 27 02:45:19.078513 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 27 02:45:19.078518 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 27 02:45:19.078522 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 27 02:45:19.078528 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 27 02:45:19.078532 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 27 02:45:19.078537 kernel: PCI: CLS 0 bytes, default 64 May 27 02:45:19.078542 kernel: kvm [1]: HYP mode not available May 27 02:45:19.078546 kernel: Initialise system trusted keyrings May 27 02:45:19.078551 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 27 02:45:19.078556 kernel: Key type asymmetric registered May 27 02:45:19.078560 kernel: Asymmetric key parser 'x509' registered May 27 02:45:19.078565 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) May 27 02:45:19.078571 kernel: io scheduler mq-deadline registered May 27 02:45:19.078575 kernel: io scheduler kyber registered May 27 02:45:19.078580 kernel: io scheduler bfq registered May 27 02:45:19.078585 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 27 02:45:19.078589 kernel: thunder_xcv, ver 1.0 May 27 02:45:19.078594 kernel: thunder_bgx, ver 1.0 May 27 02:45:19.078599 kernel: nicpf, ver 1.0 May 27 02:45:19.078603 kernel: nicvf, ver 1.0 May 27 02:45:19.078744 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 27 02:45:19.078795 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-27T02:45:18 UTC (1748313918) May 27 02:45:19.078801 kernel: efifb: probing for efifb May 27 02:45:19.078806 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 27 02:45:19.078811 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 27 02:45:19.078816 kernel: efifb: scrolling: redraw May 27 02:45:19.078824 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 27 02:45:19.078829 kernel: Console: switching to colour frame buffer device 128x48 May 27 02:45:19.078834 kernel: fb0: EFI VGA frame buffer device May 27 02:45:19.078840 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... May 27 02:45:19.078844 kernel: hid: raw HID events driver (C) Jiri Kosina May 27 02:45:19.078849 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available May 27 02:45:19.078854 kernel: watchdog: NMI not fully supported May 27 02:45:19.078859 kernel: watchdog: Hard watchdog permanently disabled May 27 02:45:19.078863 kernel: NET: Registered PF_INET6 protocol family May 27 02:45:19.078868 kernel: Segment Routing with IPv6 May 27 02:45:19.078873 kernel: In-situ OAM (IOAM) with IPv6 May 27 02:45:19.078877 kernel: NET: Registered PF_PACKET protocol family May 27 02:45:19.078883 kernel: Key type dns_resolver registered May 27 02:45:19.078888 kernel: registered taskstats version 1 May 27 02:45:19.078893 kernel: Loading compiled-in X.509 certificates May 27 02:45:19.078898 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.30-flatcar: 6bbf5412ef1f8a32378a640b6d048f74e6d74df0' May 27 02:45:19.078902 kernel: Demotion targets for Node 0: null May 27 02:45:19.078907 kernel: Key type .fscrypt registered May 27 02:45:19.078912 kernel: Key type fscrypt-provisioning registered May 27 02:45:19.078916 kernel: ima: No TPM chip found, activating TPM-bypass! May 27 02:45:19.078921 kernel: ima: Allocated hash algorithm: sha1 May 27 02:45:19.078927 kernel: ima: No architecture policies found May 27 02:45:19.078932 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 27 02:45:19.078936 kernel: clk: Disabling unused clocks May 27 02:45:19.078941 kernel: PM: genpd: Disabling unused power domains May 27 02:45:19.078946 kernel: Warning: unable to open an initial console. May 27 02:45:19.078951 kernel: Freeing unused kernel memory: 39424K May 27 02:45:19.078956 kernel: Run /init as init process May 27 02:45:19.078960 kernel: with arguments: May 27 02:45:19.078965 kernel: /init May 27 02:45:19.078970 kernel: with environment: May 27 02:45:19.078975 kernel: HOME=/ May 27 02:45:19.078979 kernel: TERM=linux May 27 02:45:19.078984 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 27 02:45:19.078990 systemd[1]: Successfully made /usr/ read-only. May 27 02:45:19.078997 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 27 02:45:19.079003 systemd[1]: Detected virtualization microsoft. May 27 02:45:19.079009 systemd[1]: Detected architecture arm64. May 27 02:45:19.079014 systemd[1]: Running in initrd. May 27 02:45:19.079019 systemd[1]: No hostname configured, using default hostname. May 27 02:45:19.079024 systemd[1]: Hostname set to . May 27 02:45:19.079029 systemd[1]: Initializing machine ID from random generator. May 27 02:45:19.079034 systemd[1]: Queued start job for default target initrd.target. May 27 02:45:19.079040 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 02:45:19.079045 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 02:45:19.079051 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 27 02:45:19.079056 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 02:45:19.079062 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 27 02:45:19.079067 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 27 02:45:19.079073 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 27 02:45:19.079078 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 27 02:45:19.079084 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 02:45:19.079090 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 02:45:19.079095 systemd[1]: Reached target paths.target - Path Units. May 27 02:45:19.079100 systemd[1]: Reached target slices.target - Slice Units. May 27 02:45:19.079106 systemd[1]: Reached target swap.target - Swaps. May 27 02:45:19.079111 systemd[1]: Reached target timers.target - Timer Units. May 27 02:45:19.079116 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 27 02:45:19.079121 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 02:45:19.079126 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 27 02:45:19.079131 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 27 02:45:19.079137 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 02:45:19.079142 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 02:45:19.079148 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 02:45:19.079153 systemd[1]: Reached target sockets.target - Socket Units. May 27 02:45:19.079158 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 27 02:45:19.079163 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 02:45:19.079168 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 27 02:45:19.079174 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). May 27 02:45:19.079180 systemd[1]: Starting systemd-fsck-usr.service... May 27 02:45:19.079185 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 02:45:19.079190 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 02:45:19.079209 systemd-journald[224]: Collecting audit messages is disabled. May 27 02:45:19.079225 systemd-journald[224]: Journal started May 27 02:45:19.079240 systemd-journald[224]: Runtime Journal (/run/log/journal/c1e058ca9ed9439a8453be51820ab20e) is 8M, max 78.5M, 70.5M free. May 27 02:45:19.088094 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:19.098117 systemd-modules-load[226]: Inserted module 'overlay' May 27 02:45:19.123222 systemd[1]: Started systemd-journald.service - Journal Service. May 27 02:45:19.123306 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 27 02:45:19.116785 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 27 02:45:19.134326 kernel: Bridge firewalling registered May 27 02:45:19.130307 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 02:45:19.139288 systemd-modules-load[226]: Inserted module 'br_netfilter' May 27 02:45:19.140681 systemd[1]: Finished systemd-fsck-usr.service. May 27 02:45:19.153624 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 02:45:19.161552 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 02:45:19.186344 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 27 02:45:19.198155 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 02:45:19.212547 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:19.222719 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 02:45:19.228488 systemd-tmpfiles[244]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. May 27 02:45:19.232570 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 27 02:45:19.243650 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 02:45:19.251014 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 27 02:45:19.276445 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 02:45:19.281876 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 02:45:19.304882 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 02:45:19.316487 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 27 02:45:19.330889 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 02:45:19.355720 dracut-cmdline[264]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=4c3f98aae7a61b3dcbab6391ba922461adab29dbcb79fd6e18169f93c5a4ab5a May 27 02:45:19.353179 systemd-resolved[251]: Positive Trust Anchors: May 27 02:45:19.353190 systemd-resolved[251]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 02:45:19.353211 systemd-resolved[251]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 27 02:45:19.355907 systemd-resolved[251]: Defaulting to hostname 'linux'. May 27 02:45:19.356719 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 02:45:19.385402 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 02:45:19.449318 kernel: SCSI subsystem initialized May 27 02:45:19.455309 kernel: Loading iSCSI transport class v2.0-870. May 27 02:45:19.464350 kernel: iscsi: registered transport (tcp) May 27 02:45:19.477427 kernel: iscsi: registered transport (qla4xxx) May 27 02:45:19.477499 kernel: QLogic iSCSI HBA Driver May 27 02:45:19.492517 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 02:45:19.520909 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 02:45:19.528087 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 02:45:19.577509 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 27 02:45:19.584475 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 27 02:45:19.644317 kernel: raid6: neonx8 gen() 18536 MB/s May 27 02:45:19.663321 kernel: raid6: neonx4 gen() 18566 MB/s May 27 02:45:19.682333 kernel: raid6: neonx2 gen() 17063 MB/s May 27 02:45:19.702306 kernel: raid6: neonx1 gen() 14983 MB/s May 27 02:45:19.721332 kernel: raid6: int64x8 gen() 10536 MB/s May 27 02:45:19.740307 kernel: raid6: int64x4 gen() 10609 MB/s May 27 02:45:19.760303 kernel: raid6: int64x2 gen() 8983 MB/s May 27 02:45:19.781595 kernel: raid6: int64x1 gen() 7006 MB/s May 27 02:45:19.781661 kernel: raid6: using algorithm neonx4 gen() 18566 MB/s May 27 02:45:19.803227 kernel: raid6: .... xor() 15142 MB/s, rmw enabled May 27 02:45:19.803274 kernel: raid6: using neon recovery algorithm May 27 02:45:19.812067 kernel: xor: measuring software checksum speed May 27 02:45:19.812081 kernel: 8regs : 28569 MB/sec May 27 02:45:19.816949 kernel: 32regs : 27700 MB/sec May 27 02:45:19.816958 kernel: arm64_neon : 37635 MB/sec May 27 02:45:19.819845 kernel: xor: using function: arm64_neon (37635 MB/sec) May 27 02:45:19.859319 kernel: Btrfs loaded, zoned=no, fsverity=no May 27 02:45:19.864938 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 27 02:45:19.874452 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 02:45:19.895697 systemd-udevd[472]: Using default interface naming scheme 'v255'. May 27 02:45:19.902817 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 02:45:19.916471 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 27 02:45:19.944508 dracut-pre-trigger[482]: rd.md=0: removing MD RAID activation May 27 02:45:19.966715 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 27 02:45:19.973054 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 02:45:20.013713 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 02:45:20.026572 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 27 02:45:20.092453 kernel: hv_vmbus: Vmbus version:5.3 May 27 02:45:20.088546 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 02:45:20.088681 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:20.098502 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:20.114895 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:20.133187 kernel: hv_vmbus: registering driver hid_hyperv May 27 02:45:20.133241 kernel: hv_vmbus: registering driver hyperv_keyboard May 27 02:45:20.133248 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 May 27 02:45:20.140219 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 27 02:45:20.158313 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 May 27 02:45:20.158385 kernel: hv_vmbus: registering driver hv_storvsc May 27 02:45:20.166916 kernel: pps_core: LinuxPPS API ver. 1 registered May 27 02:45:20.167002 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 27 02:45:20.172057 kernel: scsi host0: storvsc_host_t May 27 02:45:20.172133 kernel: scsi host1: storvsc_host_t May 27 02:45:20.177485 kernel: scsi 1:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 27 02:45:20.177593 kernel: hv_vmbus: registering driver hv_netvsc May 27 02:45:20.185708 kernel: scsi 1:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 May 27 02:45:20.186188 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 02:45:20.186353 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:20.196122 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 27 02:45:19.916613 kernel: PTP clock support registered May 27 02:45:19.926452 kernel: hv_utils: Registering HyperV Utility Driver May 27 02:45:19.926469 kernel: hv_vmbus: registering driver hv_utils May 27 02:45:19.926475 kernel: hv_utils: Shutdown IC version 3.2 May 27 02:45:19.926482 kernel: hv_utils: Heartbeat IC version 3.0 May 27 02:45:19.926487 kernel: hv_utils: TimeSync IC version 4.0 May 27 02:45:19.926493 kernel: sr 1:0:0:2: [sr0] scsi-1 drive May 27 02:45:19.926634 kernel: sd 1:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 27 02:45:19.926717 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 27 02:45:19.926722 kernel: sd 1:0:0:0: [sda] 4096-byte physical blocks May 27 02:45:19.926785 kernel: sr 1:0:0:2: Attached scsi CD-ROM sr0 May 27 02:45:19.926845 systemd-journald[224]: Time jumped backwards, rotating. May 27 02:45:19.926875 kernel: hv_netvsc 000d3ac6-07ba-000d-3ac6-07ba000d3ac6 eth0: VF slot 1 added May 27 02:45:19.926946 kernel: sd 1:0:0:0: [sda] Write Protect is off May 27 02:45:20.198349 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:19.946188 kernel: sd 1:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 27 02:45:19.956535 kernel: sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 27 02:45:19.956611 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#258 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 02:45:19.956693 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#265 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 02:45:19.894165 systemd-resolved[251]: Clock change detected. Flushing caches. May 27 02:45:19.940115 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:19.975888 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 27 02:45:19.975952 kernel: sd 1:0:0:0: [sda] Attached SCSI disk May 27 02:45:19.983230 kernel: hv_vmbus: registering driver hv_pci May 27 02:45:19.983286 kernel: hv_pci 50a5c1ec-470d-40a8-95d2-9639a7b1fcff: PCI VMBus probing: Using version 0x10004 May 27 02:45:19.990069 kernel: hv_pci 50a5c1ec-470d-40a8-95d2-9639a7b1fcff: PCI host bridge to bus 470d:00 May 27 02:45:20.004495 kernel: pci_bus 470d:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] May 27 02:45:20.004819 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#305 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 May 27 02:45:20.005005 kernel: pci_bus 470d:00: No busn resource found for root bus, will use [bus 00-ff] May 27 02:45:20.015486 kernel: pci 470d:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint May 27 02:45:20.022197 kernel: pci 470d:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] May 27 02:45:20.027092 kernel: pci 470d:00:02.0: enabling Extended Tags May 27 02:45:20.047454 kernel: pci 470d:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 470d:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) May 27 02:45:20.047727 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#282 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 May 27 02:45:20.047809 kernel: pci_bus 470d:00: busn_res: [bus 00-ff] end is updated to 00 May 27 02:45:20.056707 kernel: pci 470d:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned May 27 02:45:20.125032 kernel: mlx5_core 470d:00:02.0: enabling device (0000 -> 0002) May 27 02:45:20.133053 kernel: mlx5_core 470d:00:02.0: PTM is not supported by PCIe May 27 02:45:20.133255 kernel: mlx5_core 470d:00:02.0: firmware version: 16.30.5006 May 27 02:45:20.311822 kernel: hv_netvsc 000d3ac6-07ba-000d-3ac6-07ba000d3ac6 eth0: VF registering: eth1 May 27 02:45:20.312075 kernel: mlx5_core 470d:00:02.0 eth1: joined to eth0 May 27 02:45:20.317206 kernel: mlx5_core 470d:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) May 27 02:45:20.330102 kernel: mlx5_core 470d:00:02.0 enP18189s1: renamed from eth1 May 27 02:45:20.718378 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 27 02:45:20.743253 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 27 02:45:20.774235 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 27 02:45:20.780158 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 27 02:45:20.797184 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 27 02:45:20.890937 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 27 02:45:21.024848 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 27 02:45:21.030175 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 27 02:45:21.038754 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 02:45:21.048371 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 02:45:21.058037 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 27 02:45:21.084563 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 27 02:45:21.839070 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#78 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 May 27 02:45:21.851756 disk-uuid[644]: The operation has completed successfully. May 27 02:45:21.855802 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 27 02:45:21.921298 systemd[1]: disk-uuid.service: Deactivated successfully. May 27 02:45:21.923080 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 27 02:45:21.956123 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 27 02:45:21.977624 sh[821]: Success May 27 02:45:22.014520 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 27 02:45:22.014610 kernel: device-mapper: uevent: version 1.0.3 May 27 02:45:22.021113 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev May 27 02:45:22.030080 kernel: device-mapper: verity: sha256 using shash "sha256-ce" May 27 02:45:22.243979 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 27 02:45:22.258776 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 27 02:45:22.268102 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 27 02:45:22.294310 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' May 27 02:45:22.294385 kernel: BTRFS: device fsid 5c6341ea-4eb5-44b6-ac57-c4d29847e384 devid 1 transid 41 /dev/mapper/usr (254:0) scanned by mount (846) May 27 02:45:22.305155 kernel: BTRFS info (device dm-0): first mount of filesystem 5c6341ea-4eb5-44b6-ac57-c4d29847e384 May 27 02:45:22.305168 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 27 02:45:22.308351 kernel: BTRFS info (device dm-0): using free-space-tree May 27 02:45:22.671728 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 27 02:45:22.676113 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. May 27 02:45:22.683103 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 27 02:45:22.684034 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 27 02:45:22.706139 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 27 02:45:22.732075 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (869) May 27 02:45:22.742116 kernel: BTRFS info (device sda6): first mount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 02:45:22.742193 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm May 27 02:45:22.745305 kernel: BTRFS info (device sda6): using free-space-tree May 27 02:45:22.808161 kernel: BTRFS info (device sda6): last unmount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 02:45:22.809761 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 27 02:45:22.818242 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 27 02:45:22.824429 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 02:45:22.834759 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 02:45:22.868634 systemd-networkd[1015]: lo: Link UP May 27 02:45:22.868645 systemd-networkd[1015]: lo: Gained carrier May 27 02:45:22.869927 systemd-networkd[1015]: Enumeration completed May 27 02:45:22.870060 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 02:45:22.871764 systemd-networkd[1015]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 02:45:22.871768 systemd-networkd[1015]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 02:45:22.879441 systemd[1]: Reached target network.target - Network. May 27 02:45:22.932131 kernel: mlx5_core 470d:00:02.0 enP18189s1: Link up May 27 02:45:22.964069 kernel: hv_netvsc 000d3ac6-07ba-000d-3ac6-07ba000d3ac6 eth0: Data path switched to VF: enP18189s1 May 27 02:45:22.964507 systemd-networkd[1015]: enP18189s1: Link UP May 27 02:45:22.964564 systemd-networkd[1015]: eth0: Link UP May 27 02:45:22.964637 systemd-networkd[1015]: eth0: Gained carrier May 27 02:45:22.964647 systemd-networkd[1015]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 02:45:22.971324 systemd-networkd[1015]: enP18189s1: Gained carrier May 27 02:45:22.994098 systemd-networkd[1015]: eth0: DHCPv4 address 10.200.20.30/24, gateway 10.200.20.1 acquired from 168.63.129.16 May 27 02:45:24.146191 systemd-networkd[1015]: enP18189s1: Gained IPv6LL May 27 02:45:24.274239 systemd-networkd[1015]: eth0: Gained IPv6LL May 27 02:45:24.370995 ignition[1013]: Ignition 2.21.0 May 27 02:45:24.371017 ignition[1013]: Stage: fetch-offline May 27 02:45:24.371132 ignition[1013]: no configs at "/usr/lib/ignition/base.d" May 27 02:45:24.377080 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 27 02:45:24.371140 ignition[1013]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:24.388175 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 27 02:45:24.371250 ignition[1013]: parsed url from cmdline: "" May 27 02:45:24.371253 ignition[1013]: no config URL provided May 27 02:45:24.371256 ignition[1013]: reading system config file "/usr/lib/ignition/user.ign" May 27 02:45:24.371261 ignition[1013]: no config at "/usr/lib/ignition/user.ign" May 27 02:45:24.371264 ignition[1013]: failed to fetch config: resource requires networking May 27 02:45:24.371416 ignition[1013]: Ignition finished successfully May 27 02:45:24.425201 ignition[1025]: Ignition 2.21.0 May 27 02:45:24.425215 ignition[1025]: Stage: fetch May 27 02:45:24.425415 ignition[1025]: no configs at "/usr/lib/ignition/base.d" May 27 02:45:24.425422 ignition[1025]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:24.425557 ignition[1025]: parsed url from cmdline: "" May 27 02:45:24.425561 ignition[1025]: no config URL provided May 27 02:45:24.425568 ignition[1025]: reading system config file "/usr/lib/ignition/user.ign" May 27 02:45:24.425581 ignition[1025]: no config at "/usr/lib/ignition/user.ign" May 27 02:45:24.425619 ignition[1025]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 27 02:45:24.524272 ignition[1025]: GET result: OK May 27 02:45:24.524328 ignition[1025]: config has been read from IMDS userdata May 27 02:45:24.524343 ignition[1025]: parsing config with SHA512: b6faa7d47219d5ce3cb46820b6b48ff40982a20549a85734d1438e6499249d45509fd177601b3abea8dda83e6ef82a0f1d03db615caa1b7d5ea0d814f200700e May 27 02:45:24.531588 unknown[1025]: fetched base config from "system" May 27 02:45:24.531603 unknown[1025]: fetched base config from "system" May 27 02:45:24.531758 ignition[1025]: fetch: fetch complete May 27 02:45:24.531608 unknown[1025]: fetched user config from "azure" May 27 02:45:24.531762 ignition[1025]: fetch: fetch passed May 27 02:45:24.536679 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 27 02:45:24.531818 ignition[1025]: Ignition finished successfully May 27 02:45:24.544345 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 27 02:45:24.576558 ignition[1032]: Ignition 2.21.0 May 27 02:45:24.578968 ignition[1032]: Stage: kargs May 27 02:45:24.579373 ignition[1032]: no configs at "/usr/lib/ignition/base.d" May 27 02:45:24.579384 ignition[1032]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:24.586194 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 27 02:45:24.580204 ignition[1032]: kargs: kargs passed May 27 02:45:24.595856 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 27 02:45:24.580264 ignition[1032]: Ignition finished successfully May 27 02:45:24.622427 ignition[1038]: Ignition 2.21.0 May 27 02:45:24.622442 ignition[1038]: Stage: disks May 27 02:45:24.622643 ignition[1038]: no configs at "/usr/lib/ignition/base.d" May 27 02:45:24.628796 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 27 02:45:24.622650 ignition[1038]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:24.636095 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 27 02:45:24.623263 ignition[1038]: disks: disks passed May 27 02:45:24.645142 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 27 02:45:24.623322 ignition[1038]: Ignition finished successfully May 27 02:45:24.654532 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 02:45:24.662490 systemd[1]: Reached target sysinit.target - System Initialization. May 27 02:45:24.669340 systemd[1]: Reached target basic.target - Basic System. May 27 02:45:24.678282 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 27 02:45:25.205937 systemd-fsck[1047]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks May 27 02:45:25.214887 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 27 02:45:25.222199 systemd[1]: Mounting sysroot.mount - /sysroot... May 27 02:45:25.443754 kernel: EXT4-fs (sda9): mounted filesystem 5656cec4-efbd-4a2d-be98-2263e6ae16bd r/w with ordered data mode. Quota mode: none. May 27 02:45:25.444245 systemd[1]: Mounted sysroot.mount - /sysroot. May 27 02:45:25.451690 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 27 02:45:25.471734 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 02:45:25.488811 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 27 02:45:25.496817 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 27 02:45:25.511064 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1061) May 27 02:45:25.513882 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 27 02:45:25.539747 kernel: BTRFS info (device sda6): first mount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 02:45:25.539769 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm May 27 02:45:25.539776 kernel: BTRFS info (device sda6): using free-space-tree May 27 02:45:25.513924 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 27 02:45:25.527896 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 27 02:45:25.552472 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 27 02:45:25.568262 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 02:45:26.101156 coreos-metadata[1063]: May 27 02:45:26.101 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 27 02:45:26.111451 coreos-metadata[1063]: May 27 02:45:26.111 INFO Fetch successful May 27 02:45:26.115849 coreos-metadata[1063]: May 27 02:45:26.112 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 27 02:45:26.125169 coreos-metadata[1063]: May 27 02:45:26.121 INFO Fetch successful May 27 02:45:26.141789 coreos-metadata[1063]: May 27 02:45:26.141 INFO wrote hostname ci-4344.0.0-a-55e4dd2994 to /sysroot/etc/hostname May 27 02:45:26.149210 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 27 02:45:26.460001 initrd-setup-root[1092]: cut: /sysroot/etc/passwd: No such file or directory May 27 02:45:26.491142 initrd-setup-root[1099]: cut: /sysroot/etc/group: No such file or directory May 27 02:45:26.499068 initrd-setup-root[1106]: cut: /sysroot/etc/shadow: No such file or directory May 27 02:45:26.505126 initrd-setup-root[1113]: cut: /sysroot/etc/gshadow: No such file or directory May 27 02:45:27.483824 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 27 02:45:27.490343 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 27 02:45:27.515781 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 27 02:45:27.528369 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 27 02:45:27.540304 kernel: BTRFS info (device sda6): last unmount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 02:45:27.560086 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 27 02:45:27.570302 ignition[1180]: INFO : Ignition 2.21.0 May 27 02:45:27.570302 ignition[1180]: INFO : Stage: mount May 27 02:45:27.570302 ignition[1180]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 02:45:27.570302 ignition[1180]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:27.570302 ignition[1180]: INFO : mount: mount passed May 27 02:45:27.570302 ignition[1180]: INFO : Ignition finished successfully May 27 02:45:27.571552 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 27 02:45:27.579986 systemd[1]: Starting ignition-files.service - Ignition (files)... May 27 02:45:27.617289 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 27 02:45:27.643069 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 (8:6) scanned by mount (1194) May 27 02:45:27.653357 kernel: BTRFS info (device sda6): first mount of filesystem eabe2c18-04ac-4289-8962-26387aada3f9 May 27 02:45:27.653424 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm May 27 02:45:27.657202 kernel: BTRFS info (device sda6): using free-space-tree May 27 02:45:27.661651 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 27 02:45:27.693887 ignition[1211]: INFO : Ignition 2.21.0 May 27 02:45:27.693887 ignition[1211]: INFO : Stage: files May 27 02:45:27.702824 ignition[1211]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 02:45:27.702824 ignition[1211]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:27.702824 ignition[1211]: DEBUG : files: compiled without relabeling support, skipping May 27 02:45:27.718124 ignition[1211]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 27 02:45:27.718124 ignition[1211]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 27 02:45:27.730538 ignition[1211]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 27 02:45:27.736471 ignition[1211]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 27 02:45:27.736471 ignition[1211]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 27 02:45:27.731548 unknown[1211]: wrote ssh authorized keys file for user: core May 27 02:45:27.769293 ignition[1211]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 27 02:45:27.777747 ignition[1211]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 27 02:45:27.777747 ignition[1211]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 27 02:45:27.777747 ignition[1211]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 27 02:45:27.777747 ignition[1211]: INFO : files: files passed May 27 02:45:27.777747 ignition[1211]: INFO : Ignition finished successfully May 27 02:45:27.778421 systemd[1]: Finished ignition-files.service - Ignition (files). May 27 02:45:27.793281 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 27 02:45:27.824196 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 27 02:45:27.832269 systemd[1]: ignition-quench.service: Deactivated successfully. May 27 02:45:27.832398 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 27 02:45:27.878096 initrd-setup-root-after-ignition[1241]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 02:45:27.878096 initrd-setup-root-after-ignition[1241]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 27 02:45:27.892461 initrd-setup-root-after-ignition[1245]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 27 02:45:27.886038 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 02:45:27.898028 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 27 02:45:27.909497 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 27 02:45:27.958522 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 27 02:45:27.958671 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 27 02:45:27.968438 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 27 02:45:27.977875 systemd[1]: Reached target initrd.target - Initrd Default Target. May 27 02:45:27.986524 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 27 02:45:27.987475 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 27 02:45:28.024560 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 02:45:28.031613 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 27 02:45:28.061548 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 27 02:45:28.070033 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 02:45:28.083597 systemd[1]: Stopped target timers.target - Timer Units. May 27 02:45:28.093606 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 27 02:45:28.093734 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 27 02:45:28.107740 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 27 02:45:28.112549 systemd[1]: Stopped target basic.target - Basic System. May 27 02:45:28.121629 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 27 02:45:28.130760 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 27 02:45:28.139421 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 27 02:45:28.148920 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. May 27 02:45:28.158177 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 27 02:45:28.167179 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 27 02:45:28.177133 systemd[1]: Stopped target sysinit.target - System Initialization. May 27 02:45:28.185352 systemd[1]: Stopped target local-fs.target - Local File Systems. May 27 02:45:28.195102 systemd[1]: Stopped target swap.target - Swaps. May 27 02:45:28.203053 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 27 02:45:28.203223 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 27 02:45:28.215902 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 27 02:45:28.224173 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 02:45:28.233210 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 27 02:45:28.233307 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 02:45:28.243106 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 27 02:45:28.243280 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 27 02:45:28.256476 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 27 02:45:28.256632 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 27 02:45:28.267472 systemd[1]: ignition-files.service: Deactivated successfully. May 27 02:45:28.267591 systemd[1]: Stopped ignition-files.service - Ignition (files). May 27 02:45:28.275735 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 27 02:45:28.275844 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 27 02:45:28.348453 ignition[1265]: INFO : Ignition 2.21.0 May 27 02:45:28.348453 ignition[1265]: INFO : Stage: umount May 27 02:45:28.348453 ignition[1265]: INFO : no configs at "/usr/lib/ignition/base.d" May 27 02:45:28.348453 ignition[1265]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 27 02:45:28.348453 ignition[1265]: INFO : umount: umount passed May 27 02:45:28.348453 ignition[1265]: INFO : Ignition finished successfully May 27 02:45:28.287182 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 27 02:45:28.303777 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 27 02:45:28.304024 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 27 02:45:28.316201 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 27 02:45:28.329305 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 27 02:45:28.329599 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 27 02:45:28.343248 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 27 02:45:28.343395 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 27 02:45:28.358717 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 27 02:45:28.359661 systemd[1]: ignition-mount.service: Deactivated successfully. May 27 02:45:28.359756 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 27 02:45:28.367716 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 27 02:45:28.367832 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 27 02:45:28.379922 systemd[1]: sysroot-boot.service: Deactivated successfully. May 27 02:45:28.380031 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 27 02:45:28.387772 systemd[1]: ignition-disks.service: Deactivated successfully. May 27 02:45:28.387827 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 27 02:45:28.395824 systemd[1]: ignition-kargs.service: Deactivated successfully. May 27 02:45:28.395883 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 27 02:45:28.404393 systemd[1]: ignition-fetch.service: Deactivated successfully. May 27 02:45:28.404430 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 27 02:45:28.412348 systemd[1]: Stopped target network.target - Network. May 27 02:45:28.421364 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 27 02:45:28.421439 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 27 02:45:28.430780 systemd[1]: Stopped target paths.target - Path Units. May 27 02:45:28.438243 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 27 02:45:28.442062 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 02:45:28.447902 systemd[1]: Stopped target slices.target - Slice Units. May 27 02:45:28.456443 systemd[1]: Stopped target sockets.target - Socket Units. May 27 02:45:28.465780 systemd[1]: iscsid.socket: Deactivated successfully. May 27 02:45:28.465829 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 27 02:45:28.473166 systemd[1]: iscsiuio.socket: Deactivated successfully. May 27 02:45:28.473193 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 27 02:45:28.481740 systemd[1]: ignition-setup.service: Deactivated successfully. May 27 02:45:28.481798 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 27 02:45:28.489764 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 27 02:45:28.489791 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 27 02:45:28.498450 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 27 02:45:28.498484 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 27 02:45:28.507957 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 27 02:45:28.516187 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 27 02:45:28.535640 systemd[1]: systemd-resolved.service: Deactivated successfully. May 27 02:45:28.535902 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 27 02:45:28.743975 kernel: hv_netvsc 000d3ac6-07ba-000d-3ac6-07ba000d3ac6 eth0: Data path switched from VF: enP18189s1 May 27 02:45:28.550242 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 27 02:45:28.550471 systemd[1]: systemd-networkd.service: Deactivated successfully. May 27 02:45:28.550597 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 27 02:45:28.565662 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 27 02:45:28.566654 systemd[1]: Stopped target network-pre.target - Preparation for Network. May 27 02:45:28.574165 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 27 02:45:28.574229 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 27 02:45:28.598901 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 27 02:45:28.605413 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 27 02:45:28.605497 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 27 02:45:28.617179 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 27 02:45:28.617242 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 27 02:45:28.629222 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 27 02:45:28.629307 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 27 02:45:28.638136 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 27 02:45:28.638207 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 02:45:28.651386 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 02:45:28.659492 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 27 02:45:28.659568 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 27 02:45:28.680978 systemd[1]: systemd-udevd.service: Deactivated successfully. May 27 02:45:28.681260 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 02:45:28.690532 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 27 02:45:28.690579 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 27 02:45:28.699790 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 27 02:45:28.699820 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 27 02:45:29.002694 systemd-journald[224]: Received SIGTERM from PID 1 (systemd). May 27 02:45:28.708727 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 27 02:45:28.708779 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 27 02:45:28.730840 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 27 02:45:28.730904 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 27 02:45:28.744089 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 27 02:45:28.744160 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 27 02:45:28.760306 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 27 02:45:28.780598 systemd[1]: systemd-network-generator.service: Deactivated successfully. May 27 02:45:28.780693 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. May 27 02:45:28.796022 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 27 02:45:28.796095 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 02:45:28.809773 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 02:45:28.809832 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:28.820715 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. May 27 02:45:28.820781 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 27 02:45:28.820807 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 27 02:45:28.821179 systemd[1]: network-cleanup.service: Deactivated successfully. May 27 02:45:28.821294 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 27 02:45:28.829332 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 27 02:45:28.829415 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 27 02:45:28.839291 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 27 02:45:28.848072 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 27 02:45:28.873681 systemd[1]: Switching root. May 27 02:45:29.021191 systemd-journald[224]: Journal stopped May 27 02:45:32.974080 kernel: SELinux: policy capability network_peer_controls=1 May 27 02:45:32.974102 kernel: SELinux: policy capability open_perms=1 May 27 02:45:32.974110 kernel: SELinux: policy capability extended_socket_class=1 May 27 02:45:32.974115 kernel: SELinux: policy capability always_check_network=0 May 27 02:45:32.974123 kernel: SELinux: policy capability cgroup_seclabel=1 May 27 02:45:32.974128 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 27 02:45:32.974134 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 27 02:45:32.974141 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 27 02:45:32.974147 kernel: SELinux: policy capability userspace_initial_context=0 May 27 02:45:32.974152 kernel: audit: type=1403 audit(1748313929.676:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 27 02:45:32.974159 systemd[1]: Successfully loaded SELinux policy in 112.173ms. May 27 02:45:32.974167 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.477ms. May 27 02:45:32.974174 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 27 02:45:32.974180 systemd[1]: Detected virtualization microsoft. May 27 02:45:32.974186 systemd[1]: Detected architecture arm64. May 27 02:45:32.974193 systemd[1]: Detected first boot. May 27 02:45:32.974199 systemd[1]: Hostname set to . May 27 02:45:32.974205 systemd[1]: Initializing machine ID from random generator. May 27 02:45:32.974211 zram_generator::config[1308]: No configuration found. May 27 02:45:32.974217 kernel: NET: Registered PF_VSOCK protocol family May 27 02:45:32.974223 systemd[1]: Populated /etc with preset unit settings. May 27 02:45:32.974229 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 27 02:45:32.974236 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 27 02:45:32.974242 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 27 02:45:32.974248 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 27 02:45:32.974253 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 27 02:45:32.974260 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 27 02:45:32.974266 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 27 02:45:32.974273 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 27 02:45:32.974280 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 27 02:45:32.974286 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 27 02:45:32.974292 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 27 02:45:32.974298 systemd[1]: Created slice user.slice - User and Session Slice. May 27 02:45:32.974304 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 27 02:45:32.974310 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 27 02:45:32.974315 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 27 02:45:32.974321 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 27 02:45:32.974327 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 27 02:45:32.974334 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 27 02:45:32.974340 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 27 02:45:32.974348 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 27 02:45:32.974354 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 27 02:45:32.974360 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 27 02:45:32.974366 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 27 02:45:32.974372 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 27 02:45:32.974379 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 27 02:45:32.974385 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 27 02:45:32.974391 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 27 02:45:32.974397 systemd[1]: Reached target slices.target - Slice Units. May 27 02:45:32.974404 systemd[1]: Reached target swap.target - Swaps. May 27 02:45:32.974410 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 27 02:45:32.974416 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 27 02:45:32.974423 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 27 02:45:32.974429 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 27 02:45:32.974435 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 27 02:45:32.974441 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 27 02:45:32.974448 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 27 02:45:32.974454 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 27 02:45:32.974461 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 27 02:45:32.974467 systemd[1]: Mounting media.mount - External Media Directory... May 27 02:45:32.974473 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 27 02:45:32.974479 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 27 02:45:32.974485 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 27 02:45:32.974492 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 27 02:45:32.974498 systemd[1]: Reached target machines.target - Containers. May 27 02:45:32.974504 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 27 02:45:32.974511 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 02:45:32.974517 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 27 02:45:32.974523 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 27 02:45:32.974529 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 02:45:32.974536 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 02:45:32.974542 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 02:45:32.974548 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 27 02:45:32.974554 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 02:45:32.974561 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 27 02:45:32.974568 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 27 02:45:32.974574 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 27 02:45:32.974580 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 27 02:45:32.974586 systemd[1]: Stopped systemd-fsck-usr.service. May 27 02:45:32.974593 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 27 02:45:32.974599 systemd[1]: Starting systemd-journald.service - Journal Service... May 27 02:45:32.974605 kernel: fuse: init (API version 7.41) May 27 02:45:32.974610 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 27 02:45:32.974617 kernel: loop: module loaded May 27 02:45:32.974623 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 27 02:45:32.974629 kernel: ACPI: bus type drm_connector registered May 27 02:45:32.974635 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 27 02:45:32.974641 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 27 02:45:32.974647 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 27 02:45:32.974654 systemd[1]: verity-setup.service: Deactivated successfully. May 27 02:45:32.974660 systemd[1]: Stopped verity-setup.service. May 27 02:45:32.974668 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 27 02:45:32.974690 systemd-journald[1405]: Collecting audit messages is disabled. May 27 02:45:32.974705 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 27 02:45:32.974712 systemd-journald[1405]: Journal started May 27 02:45:32.974729 systemd-journald[1405]: Runtime Journal (/run/log/journal/7c4707f44b12494b89e118d8e4104a13) is 8M, max 78.5M, 70.5M free. May 27 02:45:32.212173 systemd[1]: Queued start job for default target multi-user.target. May 27 02:45:32.216721 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 27 02:45:32.217206 systemd[1]: systemd-journald.service: Deactivated successfully. May 27 02:45:32.217556 systemd[1]: systemd-journald.service: Consumed 2.288s CPU time. May 27 02:45:32.989920 systemd[1]: Started systemd-journald.service - Journal Service. May 27 02:45:32.990666 systemd[1]: Mounted media.mount - External Media Directory. May 27 02:45:32.994840 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 27 02:45:33.000753 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 27 02:45:33.005303 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 27 02:45:33.009705 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 27 02:45:33.015021 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 27 02:45:33.020340 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 27 02:45:33.020496 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 27 02:45:33.025783 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 02:45:33.025930 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 02:45:33.031244 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 02:45:33.031389 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 02:45:33.036207 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 02:45:33.036340 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 02:45:33.041610 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 27 02:45:33.041762 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 27 02:45:33.046954 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 02:45:33.047107 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 02:45:33.052244 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 27 02:45:33.057943 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 27 02:45:33.064682 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 27 02:45:33.070326 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 27 02:45:33.076321 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 27 02:45:33.091409 systemd[1]: Reached target network-pre.target - Preparation for Network. May 27 02:45:33.097641 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 27 02:45:33.112243 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 27 02:45:33.116935 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 27 02:45:33.116978 systemd[1]: Reached target local-fs.target - Local File Systems. May 27 02:45:33.122408 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 27 02:45:33.132271 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 27 02:45:33.137937 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 02:45:33.145376 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 27 02:45:33.151025 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 27 02:45:33.155954 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 02:45:33.157475 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 27 02:45:33.162359 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 02:45:33.163989 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 27 02:45:33.171285 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 27 02:45:33.184557 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 27 02:45:33.190390 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 27 02:45:33.195521 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 27 02:45:33.212424 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 27 02:45:33.219747 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 27 02:45:33.223948 systemd-journald[1405]: Time spent on flushing to /var/log/journal/7c4707f44b12494b89e118d8e4104a13 is 71.475ms for 913 entries. May 27 02:45:33.223948 systemd-journald[1405]: System Journal (/var/log/journal/7c4707f44b12494b89e118d8e4104a13) is 11.8M, max 2.6G, 2.6G free. May 27 02:45:33.349238 systemd-journald[1405]: Received client request to flush runtime journal. May 27 02:45:33.349302 systemd-journald[1405]: /var/log/journal/7c4707f44b12494b89e118d8e4104a13/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. May 27 02:45:33.349321 systemd-journald[1405]: Rotating system journal. May 27 02:45:33.349338 kernel: loop0: detected capacity change from 0 to 138376 May 27 02:45:33.232651 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 27 02:45:33.299127 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 27 02:45:33.351419 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 27 02:45:33.358717 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 27 02:45:33.359458 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 27 02:45:33.615831 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 27 02:45:33.623406 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 27 02:45:33.753925 systemd-tmpfiles[1463]: ACLs are not supported, ignoring. May 27 02:45:33.754359 systemd-tmpfiles[1463]: ACLs are not supported, ignoring. May 27 02:45:33.759402 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 27 02:45:33.982079 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 27 02:45:34.021188 kernel: loop1: detected capacity change from 0 to 107312 May 27 02:45:34.270765 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 27 02:45:34.281992 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 27 02:45:34.313670 systemd-udevd[1469]: Using default interface naming scheme 'v255'. May 27 02:45:34.367077 kernel: loop2: detected capacity change from 0 to 28640 May 27 02:45:34.507997 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 27 02:45:34.517903 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 27 02:45:34.617989 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 27 02:45:34.629609 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 27 02:45:34.690145 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#292 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 May 27 02:45:34.696129 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 27 02:45:34.744139 kernel: mousedev: PS/2 mouse device common for all mice May 27 02:45:34.796555 kernel: hv_vmbus: registering driver hyperv_fb May 27 02:45:34.796667 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 27 02:45:34.802068 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 27 02:45:34.808579 kernel: Console: switching to colour dummy device 80x25 May 27 02:45:34.818033 kernel: Console: switching to colour frame buffer device 128x48 May 27 02:45:34.819695 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:34.847612 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 02:45:34.847841 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:34.861577 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:34.881151 kernel: hv_vmbus: registering driver hv_balloon May 27 02:45:34.881271 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 27 02:45:34.881284 kernel: hv_balloon: Memory hot add disabled on ARM64 May 27 02:45:34.881606 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 27 02:45:34.882563 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:34.891312 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 27 02:45:34.913071 kernel: loop3: detected capacity change from 0 to 138376 May 27 02:45:34.933092 kernel: loop4: detected capacity change from 0 to 107312 May 27 02:45:34.946150 kernel: loop5: detected capacity change from 0 to 28640 May 27 02:45:34.951196 (sd-merge)[1550]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 27 02:45:34.952063 (sd-merge)[1550]: Merged extensions into '/usr'. May 27 02:45:34.959690 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 27 02:45:34.976562 systemd[1]: Starting ensure-sysext.service... May 27 02:45:34.987298 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 27 02:45:35.002985 systemd-networkd[1480]: lo: Link UP May 27 02:45:35.005098 systemd-networkd[1480]: lo: Gained carrier May 27 02:45:35.007017 systemd-networkd[1480]: Enumeration completed May 27 02:45:35.011674 systemd[1]: Started systemd-networkd.service - Network Configuration. May 27 02:45:35.021473 systemd-networkd[1480]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 02:45:35.022086 systemd-networkd[1480]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 02:45:35.022414 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 27 02:45:35.034352 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 27 02:45:35.051471 systemd[1]: Reload requested from client PID 1572 ('systemctl') (unit ensure-sysext.service)... May 27 02:45:35.051633 systemd[1]: Reloading... May 27 02:45:35.087808 systemd-tmpfiles[1575]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. May 27 02:45:35.087835 systemd-tmpfiles[1575]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. May 27 02:45:35.087992 systemd-tmpfiles[1575]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 27 02:45:35.088594 systemd-tmpfiles[1575]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 27 02:45:35.090185 systemd-tmpfiles[1575]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 27 02:45:35.090387 systemd-tmpfiles[1575]: ACLs are not supported, ignoring. May 27 02:45:35.090415 systemd-tmpfiles[1575]: ACLs are not supported, ignoring. May 27 02:45:35.117092 kernel: mlx5_core 470d:00:02.0 enP18189s1: Link up May 27 02:45:35.129655 systemd-tmpfiles[1575]: Detected autofs mount point /boot during canonicalization of boot. May 27 02:45:35.129854 systemd-tmpfiles[1575]: Skipping /boot May 27 02:45:35.141462 zram_generator::config[1640]: No configuration found. May 27 02:45:35.145979 kernel: hv_netvsc 000d3ac6-07ba-000d-3ac6-07ba000d3ac6 eth0: Data path switched to VF: enP18189s1 May 27 02:45:35.144458 systemd-networkd[1480]: enP18189s1: Link UP May 27 02:45:35.144537 systemd-networkd[1480]: eth0: Link UP May 27 02:45:35.144540 systemd-networkd[1480]: eth0: Gained carrier May 27 02:45:35.144561 systemd-networkd[1480]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 02:45:35.149464 systemd-networkd[1480]: enP18189s1: Gained carrier May 27 02:45:35.153462 systemd-tmpfiles[1575]: Detected autofs mount point /boot during canonicalization of boot. May 27 02:45:35.153987 systemd-tmpfiles[1575]: Skipping /boot May 27 02:45:35.155174 systemd-networkd[1480]: eth0: DHCPv4 address 10.200.20.30/24, gateway 10.200.20.1 acquired from 168.63.129.16 May 27 02:45:35.189082 kernel: MACsec IEEE 802.1AE May 27 02:45:35.265256 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 27 02:45:35.344568 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 27 02:45:35.351942 systemd[1]: Reloading finished in 299 ms. May 27 02:45:35.373634 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 27 02:45:35.395006 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 27 02:45:35.422332 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 02:45:35.431197 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 27 02:45:35.439474 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 02:45:35.442400 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 02:45:35.460466 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 02:45:35.470870 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 02:45:35.478997 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 02:45:35.489386 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 27 02:45:35.495034 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 27 02:45:35.499515 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 27 02:45:35.511371 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 27 02:45:35.517357 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 27 02:45:35.527614 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 27 02:45:35.534585 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 02:45:35.535276 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 02:45:35.542002 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 02:45:35.542200 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 02:45:35.548578 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 02:45:35.548751 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 02:45:35.559950 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 27 02:45:35.578123 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 02:45:35.581185 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 02:45:35.589378 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 02:45:35.599221 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 02:45:35.605786 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 02:45:35.605968 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 27 02:45:35.609611 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 02:45:35.611079 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 02:45:35.616948 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 02:45:35.617160 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 02:45:35.623035 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 02:45:35.623229 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 02:45:35.631226 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 27 02:45:35.642324 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 27 02:45:35.645396 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 27 02:45:35.654393 augenrules[1749]: No rules May 27 02:45:35.659410 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 27 02:45:35.666359 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 27 02:45:35.678331 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 27 02:45:35.683478 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 27 02:45:35.683626 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 27 02:45:35.683734 systemd[1]: Reached target time-set.target - System Time Set. May 27 02:45:35.691556 systemd[1]: audit-rules.service: Deactivated successfully. May 27 02:45:35.691800 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 02:45:35.696781 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 27 02:45:35.701238 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 27 02:45:35.706797 systemd[1]: modprobe@drm.service: Deactivated successfully. May 27 02:45:35.708197 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 27 02:45:35.713422 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 27 02:45:35.713583 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 27 02:45:35.714256 systemd-resolved[1719]: Positive Trust Anchors: May 27 02:45:35.714575 systemd-resolved[1719]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 27 02:45:35.714652 systemd-resolved[1719]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 27 02:45:35.719149 systemd[1]: modprobe@loop.service: Deactivated successfully. May 27 02:45:35.719294 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 27 02:45:35.720794 systemd-resolved[1719]: Using system hostname 'ci-4344.0.0-a-55e4dd2994'. May 27 02:45:35.724306 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 27 02:45:35.735099 systemd[1]: Finished ensure-sysext.service. May 27 02:45:35.741448 systemd[1]: Reached target network.target - Network. May 27 02:45:35.745807 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 27 02:45:35.753580 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 27 02:45:35.753657 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 27 02:45:35.756108 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 27 02:45:36.059102 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 27 02:45:36.065739 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 27 02:45:36.242193 systemd-networkd[1480]: eth0: Gained IPv6LL May 27 02:45:36.245091 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 27 02:45:36.250587 systemd[1]: Reached target network-online.target - Network is Online. May 27 02:45:36.498319 systemd-networkd[1480]: enP18189s1: Gained IPv6LL May 27 02:45:40.932084 ldconfig[1442]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 27 02:45:40.946537 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 27 02:45:40.954545 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 27 02:45:40.975380 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 27 02:45:40.981120 systemd[1]: Reached target sysinit.target - System Initialization. May 27 02:45:40.985769 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 27 02:45:40.991507 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 27 02:45:40.997654 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 27 02:45:41.002452 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 27 02:45:41.008224 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 27 02:45:41.013910 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 27 02:45:41.013945 systemd[1]: Reached target paths.target - Path Units. May 27 02:45:41.017807 systemd[1]: Reached target timers.target - Timer Units. May 27 02:45:41.022985 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 27 02:45:41.029298 systemd[1]: Starting docker.socket - Docker Socket for the API... May 27 02:45:41.035660 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 27 02:45:41.041387 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 27 02:45:41.046923 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 27 02:45:41.061958 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 27 02:45:41.067172 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 27 02:45:41.073633 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 27 02:45:41.080393 systemd[1]: Reached target sockets.target - Socket Units. May 27 02:45:41.084558 systemd[1]: Reached target basic.target - Basic System. May 27 02:45:41.088781 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 27 02:45:41.088804 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 27 02:45:41.091236 systemd[1]: Starting chronyd.service - NTP client/server... May 27 02:45:41.097855 systemd[1]: Starting containerd.service - containerd container runtime... May 27 02:45:41.115826 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 27 02:45:41.126369 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 27 02:45:41.139869 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 27 02:45:41.146558 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 27 02:45:41.156892 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 27 02:45:41.163768 (chronyd)[1772]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 27 02:45:41.164393 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). May 27 02:45:41.166810 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 27 02:45:41.174266 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 27 02:45:41.176544 jq[1780]: false May 27 02:45:41.189320 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 27 02:45:41.191390 chronyd[1787]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 27 02:45:41.198459 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 27 02:45:41.207747 systemd[1]: Starting systemd-logind.service - User Login Management... May 27 02:45:41.215536 chronyd[1787]: Timezone right/UTC failed leap second check, ignoring May 27 02:45:41.216734 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 27 02:45:41.215726 chronyd[1787]: Loaded seccomp filter (level 2) May 27 02:45:41.217860 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 27 02:45:41.219793 systemd[1]: Starting update-engine.service - Update Engine... May 27 02:45:41.232105 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 27 02:45:41.238721 systemd[1]: Started chronyd.service - NTP client/server. May 27 02:45:41.257068 extend-filesystems[1781]: Found loop3 May 27 02:45:41.257068 extend-filesystems[1781]: Found loop4 May 27 02:45:41.257068 extend-filesystems[1781]: Found loop5 May 27 02:45:41.257068 extend-filesystems[1781]: Found sda May 27 02:45:41.257068 extend-filesystems[1781]: Found sda1 May 27 02:45:41.257068 extend-filesystems[1781]: Found sda2 May 27 02:45:41.257068 extend-filesystems[1781]: Found sda3 May 27 02:45:41.257068 extend-filesystems[1781]: Found usr May 27 02:45:41.257068 extend-filesystems[1781]: Found sda4 May 27 02:45:41.257068 extend-filesystems[1781]: Found sda6 May 27 02:45:41.257068 extend-filesystems[1781]: Found sda7 May 27 02:45:41.257068 extend-filesystems[1781]: Found sda9 May 27 02:45:41.257068 extend-filesystems[1781]: Checking size of /dev/sda9 May 27 02:45:41.260843 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 27 02:45:41.365472 update_engine[1792]: I20250527 02:45:41.295383 1792 main.cc:92] Flatcar Update Engine starting May 27 02:45:41.365736 jq[1799]: true May 27 02:45:41.288131 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 27 02:45:41.288322 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 27 02:45:41.288547 systemd[1]: motdgen.service: Deactivated successfully. May 27 02:45:41.288689 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 27 02:45:41.366296 jq[1809]: true May 27 02:45:41.296463 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 27 02:45:41.302994 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 27 02:45:41.303301 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 27 02:45:41.340139 (ntainerd)[1810]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 27 02:45:41.345889 systemd-logind[1791]: New seat seat0. May 27 02:45:41.357786 systemd-logind[1791]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) May 27 02:45:41.358039 systemd[1]: Started systemd-logind.service - User Login Management. May 27 02:45:41.422588 extend-filesystems[1781]: Old size kept for /dev/sda9 May 27 02:45:41.422588 extend-filesystems[1781]: Found sr0 May 27 02:45:41.423002 systemd[1]: extend-filesystems.service: Deactivated successfully. May 27 02:45:41.424114 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 27 02:45:41.466827 dbus-daemon[1775]: [system] SELinux support is enabled May 27 02:45:41.467832 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 27 02:45:41.476036 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 27 02:45:41.477186 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 27 02:45:41.483650 update_engine[1792]: I20250527 02:45:41.483590 1792 update_check_scheduler.cc:74] Next update check in 6m15s May 27 02:45:41.486465 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 27 02:45:41.486578 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 27 02:45:41.497556 systemd[1]: Started update-engine.service - Update Engine. May 27 02:45:41.497822 dbus-daemon[1775]: [system] Successfully activated service 'org.freedesktop.systemd1' May 27 02:45:41.510015 bash[1844]: Updated "/home/core/.ssh/authorized_keys" May 27 02:45:41.515468 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 27 02:45:41.526036 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 27 02:45:41.532513 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 27 02:45:41.545110 coreos-metadata[1774]: May 27 02:45:41.544 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 27 02:45:41.551282 coreos-metadata[1774]: May 27 02:45:41.550 INFO Fetch successful May 27 02:45:41.551282 coreos-metadata[1774]: May 27 02:45:41.551 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 27 02:45:41.558601 coreos-metadata[1774]: May 27 02:45:41.558 INFO Fetch successful May 27 02:45:41.558601 coreos-metadata[1774]: May 27 02:45:41.558 INFO Fetching http://168.63.129.16/machine/3b190129-7b5e-4179-9817-db886cdcd147/26a41cca%2Db29b%2D4982%2D807f%2Df27f694b5a47.%5Fci%2D4344.0.0%2Da%2D55e4dd2994?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 27 02:45:41.560826 coreos-metadata[1774]: May 27 02:45:41.560 INFO Fetch successful May 27 02:45:41.561723 coreos-metadata[1774]: May 27 02:45:41.561 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 27 02:45:41.573196 coreos-metadata[1774]: May 27 02:45:41.572 INFO Fetch successful May 27 02:45:41.593034 sshd_keygen[1798]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 27 02:45:41.625026 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 27 02:45:41.630634 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 27 02:45:41.662140 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 27 02:45:41.672582 systemd[1]: Starting issuegen.service - Generate /run/issue... May 27 02:45:41.684376 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 27 02:45:41.699811 systemd[1]: issuegen.service: Deactivated successfully. May 27 02:45:41.701131 systemd[1]: Finished issuegen.service - Generate /run/issue. May 27 02:45:41.713472 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 27 02:45:41.733684 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 27 02:45:41.751111 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 27 02:45:41.762454 systemd[1]: Started getty@tty1.service - Getty on tty1. May 27 02:45:41.770356 locksmithd[1861]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 27 02:45:41.772762 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 27 02:45:41.781497 systemd[1]: Reached target getty.target - Login Prompts. May 27 02:45:42.086146 containerd[1810]: time="2025-05-27T02:45:42Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 May 27 02:45:42.087667 containerd[1810]: time="2025-05-27T02:45:42.087616312Z" level=info msg="starting containerd" revision=06b99ca80cdbfbc6cc8bd567021738c9af2b36ce version=v2.0.4 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097613264Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="12.08µs" May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097664128Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097682456Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097876712Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097889088Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097911248Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097959392Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 May 27 02:45:42.098144 containerd[1810]: time="2025-05-27T02:45:42.097966568Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 02:45:42.098377 containerd[1810]: time="2025-05-27T02:45:42.098240256Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 May 27 02:45:42.098377 containerd[1810]: time="2025-05-27T02:45:42.098254424Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 02:45:42.098377 containerd[1810]: time="2025-05-27T02:45:42.098263096Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 May 27 02:45:42.098377 containerd[1810]: time="2025-05-27T02:45:42.098268928Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 May 27 02:45:42.098461 containerd[1810]: time="2025-05-27T02:45:42.098429616Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 May 27 02:45:42.098653 containerd[1810]: time="2025-05-27T02:45:42.098628624Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 02:45:42.098690 containerd[1810]: time="2025-05-27T02:45:42.098658768Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 May 27 02:45:42.098690 containerd[1810]: time="2025-05-27T02:45:42.098665888Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 May 27 02:45:42.099127 containerd[1810]: time="2025-05-27T02:45:42.099099232Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 May 27 02:45:42.099401 containerd[1810]: time="2025-05-27T02:45:42.099379224Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 May 27 02:45:42.099499 containerd[1810]: time="2025-05-27T02:45:42.099483816Z" level=info msg="metadata content store policy set" policy=shared May 27 02:45:42.113626 containerd[1810]: time="2025-05-27T02:45:42.113524464Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 May 27 02:45:42.113626 containerd[1810]: time="2025-05-27T02:45:42.113608496Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 May 27 02:45:42.113626 containerd[1810]: time="2025-05-27T02:45:42.113620152Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 May 27 02:45:42.113626 containerd[1810]: time="2025-05-27T02:45:42.113629008Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 May 27 02:45:42.113626 containerd[1810]: time="2025-05-27T02:45:42.113637784Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113649720Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113658536Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113666896Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113677168Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113684392Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113690872Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 May 27 02:45:42.113823 containerd[1810]: time="2025-05-27T02:45:42.113700432Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 May 27 02:45:42.113903 containerd[1810]: time="2025-05-27T02:45:42.113896056Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 May 27 02:45:42.113943 containerd[1810]: time="2025-05-27T02:45:42.113918728Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 May 27 02:45:42.113943 containerd[1810]: time="2025-05-27T02:45:42.113942448Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 May 27 02:45:42.113971 containerd[1810]: time="2025-05-27T02:45:42.113950312Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 May 27 02:45:42.113971 containerd[1810]: time="2025-05-27T02:45:42.113957776Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 May 27 02:45:42.113971 containerd[1810]: time="2025-05-27T02:45:42.113966512Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 May 27 02:45:42.114086 containerd[1810]: time="2025-05-27T02:45:42.113974768Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 May 27 02:45:42.114086 containerd[1810]: time="2025-05-27T02:45:42.113981984Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 May 27 02:45:42.114086 containerd[1810]: time="2025-05-27T02:45:42.113989464Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 May 27 02:45:42.114086 containerd[1810]: time="2025-05-27T02:45:42.113996896Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 May 27 02:45:42.114086 containerd[1810]: time="2025-05-27T02:45:42.114005192Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 May 27 02:45:42.114169 containerd[1810]: time="2025-05-27T02:45:42.114103704Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" May 27 02:45:42.114169 containerd[1810]: time="2025-05-27T02:45:42.114117184Z" level=info msg="Start snapshots syncer" May 27 02:45:42.114169 containerd[1810]: time="2025-05-27T02:45:42.114137504Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 May 27 02:45:42.115079 containerd[1810]: time="2025-05-27T02:45:42.114339720Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" May 27 02:45:42.115079 containerd[1810]: time="2025-05-27T02:45:42.114598440Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 May 27 02:45:42.115229 containerd[1810]: time="2025-05-27T02:45:42.114900088Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 May 27 02:45:42.116256 containerd[1810]: time="2025-05-27T02:45:42.116214688Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 May 27 02:45:42.116420 containerd[1810]: time="2025-05-27T02:45:42.116403256Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 May 27 02:45:42.116491 containerd[1810]: time="2025-05-27T02:45:42.116478872Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 May 27 02:45:42.116565 containerd[1810]: time="2025-05-27T02:45:42.116555232Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 May 27 02:45:42.116614 containerd[1810]: time="2025-05-27T02:45:42.116600856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 May 27 02:45:42.116668 containerd[1810]: time="2025-05-27T02:45:42.116657472Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 May 27 02:45:42.116735 containerd[1810]: time="2025-05-27T02:45:42.116712552Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 May 27 02:45:42.116816 containerd[1810]: time="2025-05-27T02:45:42.116805208Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 May 27 02:45:42.116879 containerd[1810]: time="2025-05-27T02:45:42.116861160Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 May 27 02:45:42.116925 containerd[1810]: time="2025-05-27T02:45:42.116916360Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 May 27 02:45:42.117008 containerd[1810]: time="2025-05-27T02:45:42.116996816Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 02:45:42.117084 containerd[1810]: time="2025-05-27T02:45:42.117072472Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 May 27 02:45:42.117155 containerd[1810]: time="2025-05-27T02:45:42.117133880Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 02:45:42.117195 containerd[1810]: time="2025-05-27T02:45:42.117185752Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 May 27 02:45:42.117245 containerd[1810]: time="2025-05-27T02:45:42.117235120Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 May 27 02:45:42.117282 containerd[1810]: time="2025-05-27T02:45:42.117272792Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 May 27 02:45:42.117331 containerd[1810]: time="2025-05-27T02:45:42.117322472Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 May 27 02:45:42.117379 containerd[1810]: time="2025-05-27T02:45:42.117371040Z" level=info msg="runtime interface created" May 27 02:45:42.117418 containerd[1810]: time="2025-05-27T02:45:42.117410984Z" level=info msg="created NRI interface" May 27 02:45:42.117465 containerd[1810]: time="2025-05-27T02:45:42.117453616Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 May 27 02:45:42.117525 containerd[1810]: time="2025-05-27T02:45:42.117515640Z" level=info msg="Connect containerd service" May 27 02:45:42.117619 containerd[1810]: time="2025-05-27T02:45:42.117606728Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 27 02:45:42.118704 containerd[1810]: time="2025-05-27T02:45:42.118665888Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 27 02:45:43.345069 containerd[1810]: time="2025-05-27T02:45:43.344945880Z" level=info msg="Start subscribing containerd event" May 27 02:45:43.345069 containerd[1810]: time="2025-05-27T02:45:43.345033680Z" level=info msg="Start recovering state" May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345147512Z" level=info msg="Start event monitor" May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345161672Z" level=info msg="Start cni network conf syncer for default" May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345169288Z" level=info msg="Start streaming server" May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345177656Z" level=info msg="Registered namespace \"k8s.io\" with NRI" May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345183840Z" level=info msg="runtime interface starting up..." May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345187928Z" level=info msg="starting plugins..." May 27 02:45:43.345474 containerd[1810]: time="2025-05-27T02:45:43.345204544Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" May 27 02:45:43.346034 containerd[1810]: time="2025-05-27T02:45:43.345766224Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 27 02:45:43.346034 containerd[1810]: time="2025-05-27T02:45:43.345834176Z" level=info msg=serving... address=/run/containerd/containerd.sock May 27 02:45:43.346034 containerd[1810]: time="2025-05-27T02:45:43.345973624Z" level=info msg="containerd successfully booted in 1.260836s" May 27 02:45:43.346139 systemd[1]: Started containerd.service - containerd container runtime. May 27 02:45:43.352396 systemd[1]: Reached target multi-user.target - Multi-User System. May 27 02:45:43.362159 systemd[1]: Startup finished in 1.662s (kernel) + 11.275s (initrd) + 13.796s (userspace) = 26.735s. May 27 02:45:43.733742 login[1937]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying May 27 02:45:43.734633 login[1935]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) May 27 02:45:43.782915 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 27 02:45:43.784440 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 27 02:45:43.791112 systemd-logind[1791]: New session 2 of user core. May 27 02:45:43.820636 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 27 02:45:43.824127 systemd[1]: Starting user@500.service - User Manager for UID 500... May 27 02:45:43.839101 (systemd)[1962]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 27 02:45:43.841531 systemd-logind[1791]: New session c1 of user core. May 27 02:45:43.991023 systemd[1962]: Queued start job for default target default.target. May 27 02:45:44.002000 systemd[1962]: Created slice app.slice - User Application Slice. May 27 02:45:44.002031 systemd[1962]: Reached target paths.target - Paths. May 27 02:45:44.002091 systemd[1962]: Reached target timers.target - Timers. May 27 02:45:44.003367 systemd[1962]: Starting dbus.socket - D-Bus User Message Bus Socket... May 27 02:45:44.012278 systemd[1962]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 27 02:45:44.012351 systemd[1962]: Reached target sockets.target - Sockets. May 27 02:45:44.012399 systemd[1962]: Reached target basic.target - Basic System. May 27 02:45:44.012421 systemd[1962]: Reached target default.target - Main User Target. May 27 02:45:44.012445 systemd[1962]: Startup finished in 165ms. May 27 02:45:44.012603 systemd[1]: Started user@500.service - User Manager for UID 500. May 27 02:45:44.014398 systemd[1]: Started session-2.scope - Session 2 of User core. May 27 02:45:44.735032 login[1937]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) May 27 02:45:44.740120 systemd-logind[1791]: New session 1 of user core. May 27 02:45:44.749314 systemd[1]: Started session-1.scope - Session 1 of User core. May 27 02:45:46.060900 waagent[1929]: 2025-05-27T02:45:46.060805Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 May 27 02:45:46.069589 waagent[1929]: 2025-05-27T02:45:46.066215Z INFO Daemon Daemon OS: flatcar 4344.0.0 May 27 02:45:46.069838 waagent[1929]: 2025-05-27T02:45:46.069770Z INFO Daemon Daemon Python: 3.11.12 May 27 02:45:46.073077 waagent[1929]: 2025-05-27T02:45:46.072986Z INFO Daemon Daemon Run daemon May 27 02:45:46.080080 waagent[1929]: 2025-05-27T02:45:46.076823Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4344.0.0' May 27 02:45:46.084857 waagent[1929]: 2025-05-27T02:45:46.084766Z INFO Daemon Daemon Using waagent for provisioning May 27 02:45:46.090680 waagent[1929]: 2025-05-27T02:45:46.090608Z INFO Daemon Daemon Activate resource disk May 27 02:45:46.094828 waagent[1929]: 2025-05-27T02:45:46.094763Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 27 02:45:46.104036 waagent[1929]: 2025-05-27T02:45:46.103968Z INFO Daemon Daemon Found device: None May 27 02:45:46.107710 waagent[1929]: 2025-05-27T02:45:46.107640Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology May 27 02:45:46.114367 waagent[1929]: 2025-05-27T02:45:46.114310Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 May 27 02:45:46.123635 waagent[1929]: 2025-05-27T02:45:46.123578Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 27 02:45:46.128896 waagent[1929]: 2025-05-27T02:45:46.128833Z INFO Daemon Daemon Running default provisioning handler May 27 02:45:46.139836 waagent[1929]: 2025-05-27T02:45:46.139756Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. May 27 02:45:46.151751 waagent[1929]: 2025-05-27T02:45:46.151681Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 27 02:45:46.160317 waagent[1929]: 2025-05-27T02:45:46.160223Z INFO Daemon Daemon cloud-init is enabled: False May 27 02:45:46.165384 waagent[1929]: 2025-05-27T02:45:46.165281Z INFO Daemon Daemon Copying ovf-env.xml May 27 02:45:46.244661 waagent[1929]: 2025-05-27T02:45:46.241071Z INFO Daemon Daemon Successfully mounted dvd May 27 02:45:46.296900 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 27 02:45:46.299882 waagent[1929]: 2025-05-27T02:45:46.299810Z INFO Daemon Daemon Detect protocol endpoint May 27 02:45:46.303838 waagent[1929]: 2025-05-27T02:45:46.303770Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 27 02:45:46.308481 waagent[1929]: 2025-05-27T02:45:46.308406Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 27 02:45:46.314186 waagent[1929]: 2025-05-27T02:45:46.313471Z INFO Daemon Daemon Test for route to 168.63.129.16 May 27 02:45:46.317762 waagent[1929]: 2025-05-27T02:45:46.317609Z INFO Daemon Daemon Route to 168.63.129.16 exists May 27 02:45:46.321488 waagent[1929]: 2025-05-27T02:45:46.321440Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 27 02:45:46.370491 waagent[1929]: 2025-05-27T02:45:46.370425Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 27 02:45:46.375385 waagent[1929]: 2025-05-27T02:45:46.375359Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 27 02:45:46.379144 waagent[1929]: 2025-05-27T02:45:46.379107Z INFO Daemon Daemon Server preferred version:2015-04-05 May 27 02:45:46.506933 waagent[1929]: 2025-05-27T02:45:46.506800Z INFO Daemon Daemon Initializing goal state during protocol detection May 27 02:45:46.513322 waagent[1929]: 2025-05-27T02:45:46.513256Z INFO Daemon Daemon Forcing an update of the goal state. May 27 02:45:46.523359 waagent[1929]: 2025-05-27T02:45:46.523305Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 27 02:45:46.586255 waagent[1929]: 2025-05-27T02:45:46.586138Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 27 02:45:46.590734 waagent[1929]: 2025-05-27T02:45:46.590671Z INFO Daemon May 27 02:45:46.592797 waagent[1929]: 2025-05-27T02:45:46.592744Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 4dfcdb11-a155-48de-86d5-8bb760ca26fb eTag: 8886806775090966154 source: Fabric] May 27 02:45:46.601481 waagent[1929]: 2025-05-27T02:45:46.601404Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 27 02:45:46.606577 waagent[1929]: 2025-05-27T02:45:46.606509Z INFO Daemon May 27 02:45:46.608576 waagent[1929]: 2025-05-27T02:45:46.608508Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 27 02:45:46.625760 waagent[1929]: 2025-05-27T02:45:46.625688Z INFO Daemon Daemon Downloading artifacts profile blob May 27 02:45:46.705221 waagent[1929]: 2025-05-27T02:45:46.705126Z INFO Daemon Downloaded certificate {'thumbprint': '7BC8BEC8B25B98B03E0644A0F165200BC6A04C33', 'hasPrivateKey': True} May 27 02:45:46.712337 waagent[1929]: 2025-05-27T02:45:46.712284Z INFO Daemon Downloaded certificate {'thumbprint': '671246EED73AA7C0AC2B4FC798E7BB3CE4BA2778', 'hasPrivateKey': False} May 27 02:45:46.720304 waagent[1929]: 2025-05-27T02:45:46.720250Z INFO Daemon Fetch goal state completed May 27 02:45:46.736233 waagent[1929]: 2025-05-27T02:45:46.736176Z INFO Daemon Daemon Starting provisioning May 27 02:45:46.740071 waagent[1929]: 2025-05-27T02:45:46.740013Z INFO Daemon Daemon Handle ovf-env.xml. May 27 02:45:46.743834 waagent[1929]: 2025-05-27T02:45:46.743797Z INFO Daemon Daemon Set hostname [ci-4344.0.0-a-55e4dd2994] May 27 02:45:46.766664 waagent[1929]: 2025-05-27T02:45:46.766590Z INFO Daemon Daemon Publish hostname [ci-4344.0.0-a-55e4dd2994] May 27 02:45:46.771394 waagent[1929]: 2025-05-27T02:45:46.771341Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 27 02:45:46.779470 waagent[1929]: 2025-05-27T02:45:46.779398Z INFO Daemon Daemon Primary interface is [eth0] May 27 02:45:46.791182 systemd-networkd[1480]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 27 02:45:46.791192 systemd-networkd[1480]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 27 02:45:46.791232 systemd-networkd[1480]: eth0: DHCP lease lost May 27 02:45:46.792604 waagent[1929]: 2025-05-27T02:45:46.792523Z INFO Daemon Daemon Create user account if not exists May 27 02:45:46.796775 waagent[1929]: 2025-05-27T02:45:46.796722Z INFO Daemon Daemon User core already exists, skip useradd May 27 02:45:46.801212 waagent[1929]: 2025-05-27T02:45:46.801166Z INFO Daemon Daemon Configure sudoer May 27 02:45:46.809494 waagent[1929]: 2025-05-27T02:45:46.809407Z INFO Daemon Daemon Configure sshd May 27 02:45:46.816150 waagent[1929]: 2025-05-27T02:45:46.816068Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 27 02:45:46.817119 systemd-networkd[1480]: eth0: DHCPv4 address 10.200.20.30/24, gateway 10.200.20.1 acquired from 168.63.129.16 May 27 02:45:46.827012 waagent[1929]: 2025-05-27T02:45:46.826931Z INFO Daemon Daemon Deploy ssh public key. May 27 02:45:47.914384 waagent[1929]: 2025-05-27T02:45:47.914327Z INFO Daemon Daemon Provisioning complete May 27 02:45:47.929409 waagent[1929]: 2025-05-27T02:45:47.929335Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 27 02:45:47.934522 waagent[1929]: 2025-05-27T02:45:47.934416Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 27 02:45:47.941527 waagent[1929]: 2025-05-27T02:45:47.941442Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent May 27 02:45:48.054083 waagent[2017]: 2025-05-27T02:45:48.053703Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) May 27 02:45:48.054083 waagent[2017]: 2025-05-27T02:45:48.053869Z INFO ExtHandler ExtHandler OS: flatcar 4344.0.0 May 27 02:45:48.054083 waagent[2017]: 2025-05-27T02:45:48.053909Z INFO ExtHandler ExtHandler Python: 3.11.12 May 27 02:45:48.054083 waagent[2017]: 2025-05-27T02:45:48.053948Z INFO ExtHandler ExtHandler CPU Arch: aarch64 May 27 02:45:48.089081 waagent[2017]: 2025-05-27T02:45:48.088606Z INFO ExtHandler ExtHandler Distro: flatcar-4344.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; May 27 02:45:48.089081 waagent[2017]: 2025-05-27T02:45:48.088858Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 02:45:48.089081 waagent[2017]: 2025-05-27T02:45:48.088912Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 02:45:48.096021 waagent[2017]: 2025-05-27T02:45:48.095944Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 27 02:45:48.102233 waagent[2017]: 2025-05-27T02:45:48.102188Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 27 02:45:48.102892 waagent[2017]: 2025-05-27T02:45:48.102853Z INFO ExtHandler May 27 02:45:48.103092 waagent[2017]: 2025-05-27T02:45:48.103066Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 200c6680-f4b7-456a-81a6-223c66f80566 eTag: 8886806775090966154 source: Fabric] May 27 02:45:48.103447 waagent[2017]: 2025-05-27T02:45:48.103419Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 27 02:45:48.103987 waagent[2017]: 2025-05-27T02:45:48.103954Z INFO ExtHandler May 27 02:45:48.104109 waagent[2017]: 2025-05-27T02:45:48.104089Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 27 02:45:48.111065 waagent[2017]: 2025-05-27T02:45:48.111001Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 27 02:45:48.184178 waagent[2017]: 2025-05-27T02:45:48.183767Z INFO ExtHandler Downloaded certificate {'thumbprint': '7BC8BEC8B25B98B03E0644A0F165200BC6A04C33', 'hasPrivateKey': True} May 27 02:45:48.184318 waagent[2017]: 2025-05-27T02:45:48.184275Z INFO ExtHandler Downloaded certificate {'thumbprint': '671246EED73AA7C0AC2B4FC798E7BB3CE4BA2778', 'hasPrivateKey': False} May 27 02:45:48.184688 waagent[2017]: 2025-05-27T02:45:48.184654Z INFO ExtHandler Fetch goal state completed May 27 02:45:48.198511 waagent[2017]: 2025-05-27T02:45:48.198428Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) May 27 02:45:48.202881 waagent[2017]: 2025-05-27T02:45:48.202796Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2017 May 27 02:45:48.203011 waagent[2017]: 2025-05-27T02:45:48.202984Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 27 02:45:48.203379 waagent[2017]: 2025-05-27T02:45:48.203345Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** May 27 02:45:48.204658 waagent[2017]: 2025-05-27T02:45:48.204609Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4344.0.0', '', 'Flatcar Container Linux by Kinvolk'] May 27 02:45:48.205026 waagent[2017]: 2025-05-27T02:45:48.204993Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4344.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported May 27 02:45:48.205191 waagent[2017]: 2025-05-27T02:45:48.205165Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False May 27 02:45:48.205667 waagent[2017]: 2025-05-27T02:45:48.205633Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 27 02:45:48.395246 waagent[2017]: 2025-05-27T02:45:48.395196Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 27 02:45:48.395466 waagent[2017]: 2025-05-27T02:45:48.395437Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 27 02:45:48.400663 waagent[2017]: 2025-05-27T02:45:48.400608Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 27 02:45:48.413765 systemd[1]: Reload requested from client PID 2034 ('systemctl') (unit waagent.service)... May 27 02:45:48.413783 systemd[1]: Reloading... May 27 02:45:48.489187 zram_generator::config[2071]: No configuration found. May 27 02:45:48.566635 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 27 02:45:48.649570 systemd[1]: Reloading finished in 235 ms. May 27 02:45:48.673084 waagent[2017]: 2025-05-27T02:45:48.671188Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 27 02:45:48.673084 waagent[2017]: 2025-05-27T02:45:48.671360Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 27 02:45:48.910617 waagent[2017]: 2025-05-27T02:45:48.910523Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 27 02:45:48.910938 waagent[2017]: 2025-05-27T02:45:48.910901Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] May 27 02:45:48.911730 waagent[2017]: 2025-05-27T02:45:48.911647Z INFO ExtHandler ExtHandler Starting env monitor service. May 27 02:45:48.912076 waagent[2017]: 2025-05-27T02:45:48.912025Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 27 02:45:48.912345 waagent[2017]: 2025-05-27T02:45:48.912307Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 02:45:48.913074 waagent[2017]: 2025-05-27T02:45:48.912484Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 27 02:45:48.913074 waagent[2017]: 2025-05-27T02:45:48.912549Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 02:45:48.913074 waagent[2017]: 2025-05-27T02:45:48.912670Z INFO EnvHandler ExtHandler Configure routes May 27 02:45:48.913074 waagent[2017]: 2025-05-27T02:45:48.912711Z INFO EnvHandler ExtHandler Gateway:None May 27 02:45:48.913074 waagent[2017]: 2025-05-27T02:45:48.912735Z INFO EnvHandler ExtHandler Routes:None May 27 02:45:48.913203 waagent[2017]: 2025-05-27T02:45:48.913161Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 27 02:45:48.913355 waagent[2017]: 2025-05-27T02:45:48.913322Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 27 02:45:48.913488 waagent[2017]: 2025-05-27T02:45:48.913440Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 27 02:45:48.914560 waagent[2017]: 2025-05-27T02:45:48.913849Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 27 02:45:48.914560 waagent[2017]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 27 02:45:48.914560 waagent[2017]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 May 27 02:45:48.914560 waagent[2017]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 27 02:45:48.914560 waagent[2017]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 27 02:45:48.914560 waagent[2017]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 27 02:45:48.914560 waagent[2017]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 27 02:45:48.914560 waagent[2017]: 2025-05-27T02:45:48.913949Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 27 02:45:48.914560 waagent[2017]: 2025-05-27T02:45:48.914173Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 27 02:45:48.914560 waagent[2017]: 2025-05-27T02:45:48.914264Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 27 02:45:48.915004 waagent[2017]: 2025-05-27T02:45:48.914970Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 27 02:45:48.922727 waagent[2017]: 2025-05-27T02:45:48.922161Z INFO ExtHandler ExtHandler May 27 02:45:48.922727 waagent[2017]: 2025-05-27T02:45:48.922265Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 7e33f6f8-08c2-42ad-96d7-6831663363ef correlation 0a581412-1b60-4ef2-8b14-d4043abac4d2 created: 2025-05-27T02:44:08.804726Z] May 27 02:45:48.922727 waagent[2017]: 2025-05-27T02:45:48.922591Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 27 02:45:48.923068 waagent[2017]: 2025-05-27T02:45:48.923019Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] May 27 02:45:48.956294 waagent[2017]: 2025-05-27T02:45:48.956216Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command May 27 02:45:48.956294 waagent[2017]: Try `iptables -h' or 'iptables --help' for more information.) May 27 02:45:48.956723 waagent[2017]: 2025-05-27T02:45:48.956684Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: B900C966-B3EE-4291-90CC-1EC811146D6C;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] May 27 02:45:49.016653 waagent[2017]: 2025-05-27T02:45:49.016571Z INFO MonitorHandler ExtHandler Network interfaces: May 27 02:45:49.016653 waagent[2017]: Executing ['ip', '-a', '-o', 'link']: May 27 02:45:49.016653 waagent[2017]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 May 27 02:45:49.016653 waagent[2017]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c6:07:ba brd ff:ff:ff:ff:ff:ff May 27 02:45:49.016653 waagent[2017]: 3: enP18189s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:c6:07:ba brd ff:ff:ff:ff:ff:ff\ altname enP18189p0s2 May 27 02:45:49.016653 waagent[2017]: Executing ['ip', '-4', '-a', '-o', 'address']: May 27 02:45:49.016653 waagent[2017]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 27 02:45:49.016653 waagent[2017]: 2: eth0 inet 10.200.20.30/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever May 27 02:45:49.016653 waagent[2017]: Executing ['ip', '-6', '-a', '-o', 'address']: May 27 02:45:49.016653 waagent[2017]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 27 02:45:49.016653 waagent[2017]: 2: eth0 inet6 fe80::20d:3aff:fec6:7ba/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 27 02:45:49.016653 waagent[2017]: 3: enP18189s1 inet6 fe80::20d:3aff:fec6:7ba/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 27 02:45:49.039308 waagent[2017]: 2025-05-27T02:45:49.039237Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: May 27 02:45:49.039308 waagent[2017]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 27 02:45:49.039308 waagent[2017]: pkts bytes target prot opt in out source destination May 27 02:45:49.039308 waagent[2017]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 27 02:45:49.039308 waagent[2017]: pkts bytes target prot opt in out source destination May 27 02:45:49.039308 waagent[2017]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 27 02:45:49.039308 waagent[2017]: pkts bytes target prot opt in out source destination May 27 02:45:49.039308 waagent[2017]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 27 02:45:49.039308 waagent[2017]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 27 02:45:49.039308 waagent[2017]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 27 02:45:49.043098 waagent[2017]: 2025-05-27T02:45:49.042998Z INFO EnvHandler ExtHandler Current Firewall rules: May 27 02:45:49.043098 waagent[2017]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 27 02:45:49.043098 waagent[2017]: pkts bytes target prot opt in out source destination May 27 02:45:49.043098 waagent[2017]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 27 02:45:49.043098 waagent[2017]: pkts bytes target prot opt in out source destination May 27 02:45:49.043098 waagent[2017]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 27 02:45:49.043098 waagent[2017]: pkts bytes target prot opt in out source destination May 27 02:45:49.043098 waagent[2017]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 27 02:45:49.043098 waagent[2017]: 4 416 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 27 02:45:49.043098 waagent[2017]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 27 02:45:49.043932 waagent[2017]: 2025-05-27T02:45:49.043832Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 27 02:45:56.095021 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 27 02:45:56.096769 systemd[1]: Started sshd@0-10.200.20.30:22-10.200.16.10:51200.service - OpenSSH per-connection server daemon (10.200.16.10:51200). May 27 02:45:56.660901 sshd[2160]: Accepted publickey for core from 10.200.16.10 port 51200 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:45:56.662256 sshd-session[2160]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:45:56.666545 systemd-logind[1791]: New session 3 of user core. May 27 02:45:56.676253 systemd[1]: Started session-3.scope - Session 3 of User core. May 27 02:45:57.062407 systemd[1]: Started sshd@1-10.200.20.30:22-10.200.16.10:51216.service - OpenSSH per-connection server daemon (10.200.16.10:51216). May 27 02:45:57.516384 sshd[2165]: Accepted publickey for core from 10.200.16.10 port 51216 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:45:57.517650 sshd-session[2165]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:45:57.521886 systemd-logind[1791]: New session 4 of user core. May 27 02:45:57.532241 systemd[1]: Started session-4.scope - Session 4 of User core. May 27 02:45:57.838412 sshd[2167]: Connection closed by 10.200.16.10 port 51216 May 27 02:45:57.838991 sshd-session[2165]: pam_unix(sshd:session): session closed for user core May 27 02:45:57.842666 systemd[1]: sshd@1-10.200.20.30:22-10.200.16.10:51216.service: Deactivated successfully. May 27 02:45:57.844370 systemd[1]: session-4.scope: Deactivated successfully. May 27 02:45:57.845935 systemd-logind[1791]: Session 4 logged out. Waiting for processes to exit. May 27 02:45:57.849341 systemd-logind[1791]: Removed session 4. May 27 02:45:57.919298 systemd[1]: Started sshd@2-10.200.20.30:22-10.200.16.10:51218.service - OpenSSH per-connection server daemon (10.200.16.10:51218). May 27 02:45:58.366557 sshd[2173]: Accepted publickey for core from 10.200.16.10 port 51218 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:45:58.367886 sshd-session[2173]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:45:58.372029 systemd-logind[1791]: New session 5 of user core. May 27 02:45:58.380237 systemd[1]: Started session-5.scope - Session 5 of User core. May 27 02:45:58.686074 sshd[2175]: Connection closed by 10.200.16.10 port 51218 May 27 02:45:58.686760 sshd-session[2173]: pam_unix(sshd:session): session closed for user core May 27 02:45:58.690336 systemd[1]: sshd@2-10.200.20.30:22-10.200.16.10:51218.service: Deactivated successfully. May 27 02:45:58.692042 systemd[1]: session-5.scope: Deactivated successfully. May 27 02:45:58.692826 systemd-logind[1791]: Session 5 logged out. Waiting for processes to exit. May 27 02:45:58.694189 systemd-logind[1791]: Removed session 5. May 27 02:45:58.772259 systemd[1]: Started sshd@3-10.200.20.30:22-10.200.16.10:54654.service - OpenSSH per-connection server daemon (10.200.16.10:54654). May 27 02:45:59.254809 sshd[2181]: Accepted publickey for core from 10.200.16.10 port 54654 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:45:59.256123 sshd-session[2181]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:45:59.260278 systemd-logind[1791]: New session 6 of user core. May 27 02:45:59.271485 systemd[1]: Started session-6.scope - Session 6 of User core. May 27 02:45:59.602130 sshd[2183]: Connection closed by 10.200.16.10 port 54654 May 27 02:45:59.601851 sshd-session[2181]: pam_unix(sshd:session): session closed for user core May 27 02:45:59.605720 systemd[1]: sshd@3-10.200.20.30:22-10.200.16.10:54654.service: Deactivated successfully. May 27 02:45:59.607295 systemd[1]: session-6.scope: Deactivated successfully. May 27 02:45:59.607900 systemd-logind[1791]: Session 6 logged out. Waiting for processes to exit. May 27 02:45:59.609327 systemd-logind[1791]: Removed session 6. May 27 02:45:59.688303 systemd[1]: Started sshd@4-10.200.20.30:22-10.200.16.10:54660.service - OpenSSH per-connection server daemon (10.200.16.10:54660). May 27 02:46:00.171452 sshd[2189]: Accepted publickey for core from 10.200.16.10 port 54660 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:46:00.172744 sshd-session[2189]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:46:00.176932 systemd-logind[1791]: New session 7 of user core. May 27 02:46:00.186927 systemd[1]: Started session-7.scope - Session 7 of User core. May 27 02:46:00.584286 sudo[2192]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 27 02:46:00.584528 sudo[2192]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 02:46:00.612954 sudo[2192]: pam_unix(sudo:session): session closed for user root May 27 02:46:00.689760 sshd[2191]: Connection closed by 10.200.16.10 port 54660 May 27 02:46:00.690631 sshd-session[2189]: pam_unix(sshd:session): session closed for user core May 27 02:46:00.694638 systemd[1]: sshd@4-10.200.20.30:22-10.200.16.10:54660.service: Deactivated successfully. May 27 02:46:00.696274 systemd[1]: session-7.scope: Deactivated successfully. May 27 02:46:00.696999 systemd-logind[1791]: Session 7 logged out. Waiting for processes to exit. May 27 02:46:00.698661 systemd-logind[1791]: Removed session 7. May 27 02:46:00.780544 systemd[1]: Started sshd@5-10.200.20.30:22-10.200.16.10:54662.service - OpenSSH per-connection server daemon (10.200.16.10:54662). May 27 02:46:01.264001 sshd[2198]: Accepted publickey for core from 10.200.16.10 port 54662 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:46:01.265337 sshd-session[2198]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:46:01.269767 systemd-logind[1791]: New session 8 of user core. May 27 02:46:01.281518 systemd[1]: Started session-8.scope - Session 8 of User core. May 27 02:46:01.535039 sudo[2202]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 27 02:46:01.535501 sudo[2202]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 02:46:01.542089 sudo[2202]: pam_unix(sudo:session): session closed for user root May 27 02:46:01.546834 sudo[2201]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 27 02:46:01.547579 sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 02:46:01.555824 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 27 02:46:01.590525 augenrules[2224]: No rules May 27 02:46:01.591989 systemd[1]: audit-rules.service: Deactivated successfully. May 27 02:46:01.592261 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 27 02:46:01.593637 sudo[2201]: pam_unix(sudo:session): session closed for user root May 27 02:46:01.672109 sshd[2200]: Connection closed by 10.200.16.10 port 54662 May 27 02:46:01.672845 sshd-session[2198]: pam_unix(sshd:session): session closed for user core May 27 02:46:01.677267 systemd[1]: sshd@5-10.200.20.30:22-10.200.16.10:54662.service: Deactivated successfully. May 27 02:46:01.678858 systemd[1]: session-8.scope: Deactivated successfully. May 27 02:46:01.679541 systemd-logind[1791]: Session 8 logged out. Waiting for processes to exit. May 27 02:46:01.680774 systemd-logind[1791]: Removed session 8. May 27 02:46:01.759292 systemd[1]: Started sshd@6-10.200.20.30:22-10.200.16.10:54672.service - OpenSSH per-connection server daemon (10.200.16.10:54672). May 27 02:46:02.240747 sshd[2233]: Accepted publickey for core from 10.200.16.10 port 54672 ssh2: RSA SHA256:dQ5n/TEF0B0Zd9LSQs6hU1XnlCWpvGObsk/6/QN9ItE May 27 02:46:02.241999 sshd-session[2233]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 27 02:46:02.246293 systemd-logind[1791]: New session 9 of user core. May 27 02:46:02.254369 systemd[1]: Started session-9.scope - Session 9 of User core. May 27 02:46:02.511948 sudo[2238]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/tee /etc/flatcar/update.conf May 27 02:46:02.512243 sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 27 02:46:02.532797 sudo[2238]: pam_unix(sudo:session): session closed for user root May 27 02:46:02.534894 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 27 02:46:02.557236 systemd[1]: motdgen.service: Deactivated successfully. May 27 02:46:02.557450 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 27 02:46:02.612934 sshd[2235]: Connection closed by 10.200.16.10 port 54672 May 27 02:46:02.612109 sshd-session[2233]: pam_unix(sshd:session): session closed for user core May 27 02:46:02.615798 systemd-logind[1791]: Session 9 logged out. Waiting for processes to exit. May 27 02:46:02.616352 systemd[1]: sshd@6-10.200.20.30:22-10.200.16.10:54672.service: Deactivated successfully. May 27 02:46:02.618515 systemd[1]: session-9.scope: Deactivated successfully. May 27 02:46:02.620207 systemd-logind[1791]: Removed session 9. May 27 02:46:05.003494 chronyd[1787]: Selected source PHC0