Jan 19 12:31:56.839070 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Jan 19 12:31:56.839088 kernel: Linux version 6.12.66-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT Mon Jan 19 09:39:06 -00 2026 Jan 19 12:31:56.839096 kernel: KASLR enabled Jan 19 12:31:56.839100 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Jan 19 12:31:56.839105 kernel: printk: legacy bootconsole [pl11] enabled Jan 19 12:31:56.839109 kernel: efi: EFI v2.7 by EDK II Jan 19 12:31:56.839114 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e89d018 RNG=0x3f979998 MEMRESERVE=0x3db83598 Jan 19 12:31:56.839119 kernel: random: crng init done Jan 19 12:31:56.839123 kernel: secureboot: Secure boot disabled Jan 19 12:31:56.839127 kernel: ACPI: Early table checksum verification disabled Jan 19 12:31:56.839131 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Jan 19 12:31:56.839136 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839140 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839145 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Jan 19 12:31:56.839151 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839155 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839160 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839165 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839170 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839174 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839179 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Jan 19 12:31:56.839183 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jan 19 12:31:56.839188 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Jan 19 12:31:56.839192 kernel: ACPI: Use ACPI SPCR as default console: Yes Jan 19 12:31:56.839197 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jan 19 12:31:56.839201 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Jan 19 12:31:56.839206 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Jan 19 12:31:56.839211 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Jan 19 12:31:56.839216 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Jan 19 12:31:56.839220 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Jan 19 12:31:56.839224 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Jan 19 12:31:56.839229 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Jan 19 12:31:56.839233 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Jan 19 12:31:56.839238 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Jan 19 12:31:56.839242 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Jan 19 12:31:56.839246 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Jan 19 12:31:56.839251 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Jan 19 12:31:56.839276 kernel: NODE_DATA(0) allocated [mem 0x1bf800a00-0x1bf807fff] Jan 19 12:31:56.839283 kernel: Zone ranges: Jan 19 12:31:56.839287 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Jan 19 12:31:56.839294 kernel: DMA32 empty Jan 19 12:31:56.839298 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Jan 19 12:31:56.839303 kernel: Device empty Jan 19 12:31:56.839309 kernel: Movable zone start for each node Jan 19 12:31:56.839313 kernel: Early memory node ranges Jan 19 12:31:56.839318 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Jan 19 12:31:56.839323 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Jan 19 12:31:56.839327 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Jan 19 12:31:56.839332 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Jan 19 12:31:56.839337 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Jan 19 12:31:56.839341 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Jan 19 12:31:56.839346 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Jan 19 12:31:56.839352 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Jan 19 12:31:56.839356 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Jan 19 12:31:56.839361 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Jan 19 12:31:56.839366 kernel: psci: probing for conduit method from ACPI. Jan 19 12:31:56.839370 kernel: psci: PSCIv1.3 detected in firmware. Jan 19 12:31:56.839375 kernel: psci: Using standard PSCI v0.2 function IDs Jan 19 12:31:56.839380 kernel: psci: MIGRATE_INFO_TYPE not supported. Jan 19 12:31:56.839384 kernel: psci: SMC Calling Convention v1.4 Jan 19 12:31:56.839389 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Jan 19 12:31:56.839394 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Jan 19 12:31:56.839398 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Jan 19 12:31:56.839403 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Jan 19 12:31:56.839409 kernel: pcpu-alloc: [0] 0 [0] 1 Jan 19 12:31:56.839413 kernel: Detected PIPT I-cache on CPU0 Jan 19 12:31:56.839418 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Jan 19 12:31:56.839423 kernel: CPU features: detected: GIC system register CPU interface Jan 19 12:31:56.839428 kernel: CPU features: detected: Spectre-v4 Jan 19 12:31:56.839432 kernel: CPU features: detected: Spectre-BHB Jan 19 12:31:56.839437 kernel: CPU features: kernel page table isolation forced ON by KASLR Jan 19 12:31:56.839442 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jan 19 12:31:56.839446 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Jan 19 12:31:56.839451 kernel: CPU features: detected: SSBS not fully self-synchronizing Jan 19 12:31:56.839456 kernel: alternatives: applying boot alternatives Jan 19 12:31:56.839462 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=bcc9d39e48dfabbde665714a6de520952cf3c8e400bc444da1955f6cf2fc19a7 Jan 19 12:31:56.839467 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jan 19 12:31:56.839472 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jan 19 12:31:56.839476 kernel: Fallback order for Node 0: 0 Jan 19 12:31:56.839481 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Jan 19 12:31:56.839486 kernel: Policy zone: Normal Jan 19 12:31:56.839490 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jan 19 12:31:56.839495 kernel: software IO TLB: area num 2. Jan 19 12:31:56.839500 kernel: software IO TLB: mapped [mem 0x0000000037360000-0x000000003b360000] (64MB) Jan 19 12:31:56.839505 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Jan 19 12:31:56.839510 kernel: rcu: Preemptible hierarchical RCU implementation. Jan 19 12:31:56.839516 kernel: rcu: RCU event tracing is enabled. Jan 19 12:31:56.839520 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Jan 19 12:31:56.839525 kernel: Trampoline variant of Tasks RCU enabled. Jan 19 12:31:56.839530 kernel: Tracing variant of Tasks RCU enabled. Jan 19 12:31:56.839535 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jan 19 12:31:56.839539 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Jan 19 12:31:56.839544 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 19 12:31:56.839549 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Jan 19 12:31:56.839554 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jan 19 12:31:56.839558 kernel: GICv3: 960 SPIs implemented Jan 19 12:31:56.839564 kernel: GICv3: 0 Extended SPIs implemented Jan 19 12:31:56.839568 kernel: Root IRQ handler: gic_handle_irq Jan 19 12:31:56.839573 kernel: GICv3: GICv3 features: 16 PPIs, RSS Jan 19 12:31:56.839578 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Jan 19 12:31:56.839582 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Jan 19 12:31:56.839587 kernel: ITS: No ITS available, not enabling LPIs Jan 19 12:31:56.839592 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jan 19 12:31:56.839597 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Jan 19 12:31:56.839601 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Jan 19 12:31:56.839606 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Jan 19 12:31:56.839611 kernel: Console: colour dummy device 80x25 Jan 19 12:31:56.839617 kernel: printk: legacy console [tty1] enabled Jan 19 12:31:56.839622 kernel: ACPI: Core revision 20240827 Jan 19 12:31:56.839627 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Jan 19 12:31:56.839632 kernel: pid_max: default: 32768 minimum: 301 Jan 19 12:31:56.839637 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jan 19 12:31:56.839642 kernel: landlock: Up and running. Jan 19 12:31:56.839647 kernel: SELinux: Initializing. Jan 19 12:31:56.839653 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jan 19 12:31:56.839658 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jan 19 12:31:56.839663 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Jan 19 12:31:56.839668 kernel: Hyper-V: Host Build 10.0.26102.1172-1-0 Jan 19 12:31:56.839676 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jan 19 12:31:56.839682 kernel: rcu: Hierarchical SRCU implementation. Jan 19 12:31:56.839687 kernel: rcu: Max phase no-delay instances is 400. Jan 19 12:31:56.839692 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jan 19 12:31:56.839697 kernel: Remapping and enabling EFI services. Jan 19 12:31:56.839703 kernel: smp: Bringing up secondary CPUs ... Jan 19 12:31:56.839709 kernel: Detected PIPT I-cache on CPU1 Jan 19 12:31:56.839714 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Jan 19 12:31:56.839719 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Jan 19 12:31:56.839725 kernel: smp: Brought up 1 node, 2 CPUs Jan 19 12:31:56.839730 kernel: SMP: Total of 2 processors activated. Jan 19 12:31:56.839735 kernel: CPU: All CPU(s) started at EL1 Jan 19 12:31:56.839740 kernel: CPU features: detected: 32-bit EL0 Support Jan 19 12:31:56.839746 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Jan 19 12:31:56.839751 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jan 19 12:31:56.839756 kernel: CPU features: detected: Common not Private translations Jan 19 12:31:56.839762 kernel: CPU features: detected: CRC32 instructions Jan 19 12:31:56.839767 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Jan 19 12:31:56.839772 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jan 19 12:31:56.839778 kernel: CPU features: detected: LSE atomic instructions Jan 19 12:31:56.839783 kernel: CPU features: detected: Privileged Access Never Jan 19 12:31:56.839788 kernel: CPU features: detected: Speculation barrier (SB) Jan 19 12:31:56.839793 kernel: CPU features: detected: TLB range maintenance instructions Jan 19 12:31:56.839799 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jan 19 12:31:56.839805 kernel: CPU features: detected: Scalable Vector Extension Jan 19 12:31:56.839810 kernel: alternatives: applying system-wide alternatives Jan 19 12:31:56.839815 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Jan 19 12:31:56.839820 kernel: SVE: maximum available vector length 16 bytes per vector Jan 19 12:31:56.839825 kernel: SVE: default vector length 16 bytes per vector Jan 19 12:31:56.839831 kernel: Memory: 3979840K/4194160K available (11200K kernel code, 2458K rwdata, 9092K rodata, 12480K init, 1038K bss, 193132K reserved, 16384K cma-reserved) Jan 19 12:31:56.839837 kernel: devtmpfs: initialized Jan 19 12:31:56.839842 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jan 19 12:31:56.839847 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Jan 19 12:31:56.839853 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jan 19 12:31:56.839858 kernel: 0 pages in range for non-PLT usage Jan 19 12:31:56.839863 kernel: 515152 pages in range for PLT usage Jan 19 12:31:56.839868 kernel: pinctrl core: initialized pinctrl subsystem Jan 19 12:31:56.839874 kernel: SMBIOS 3.1.0 present. Jan 19 12:31:56.839879 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Jan 19 12:31:56.839885 kernel: DMI: Memory slots populated: 2/2 Jan 19 12:31:56.839890 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jan 19 12:31:56.839895 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jan 19 12:31:56.839900 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jan 19 12:31:56.839905 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jan 19 12:31:56.839911 kernel: audit: initializing netlink subsys (disabled) Jan 19 12:31:56.839916 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Jan 19 12:31:56.839922 kernel: thermal_sys: Registered thermal governor 'step_wise' Jan 19 12:31:56.839927 kernel: cpuidle: using governor menu Jan 19 12:31:56.839932 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jan 19 12:31:56.839937 kernel: ASID allocator initialised with 32768 entries Jan 19 12:31:56.839942 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jan 19 12:31:56.839948 kernel: Serial: AMBA PL011 UART driver Jan 19 12:31:56.839954 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jan 19 12:31:56.839959 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jan 19 12:31:56.839964 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jan 19 12:31:56.839969 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jan 19 12:31:56.839974 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jan 19 12:31:56.839980 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jan 19 12:31:56.839985 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jan 19 12:31:56.839991 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jan 19 12:31:56.839996 kernel: ACPI: Added _OSI(Module Device) Jan 19 12:31:56.840001 kernel: ACPI: Added _OSI(Processor Device) Jan 19 12:31:56.840006 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jan 19 12:31:56.840011 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jan 19 12:31:56.840017 kernel: ACPI: Interpreter enabled Jan 19 12:31:56.840022 kernel: ACPI: Using GIC for interrupt routing Jan 19 12:31:56.840028 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Jan 19 12:31:56.840033 kernel: printk: legacy console [ttyAMA0] enabled Jan 19 12:31:56.840038 kernel: printk: legacy bootconsole [pl11] disabled Jan 19 12:31:56.840043 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Jan 19 12:31:56.840048 kernel: ACPI: CPU0 has been hot-added Jan 19 12:31:56.840059 kernel: ACPI: CPU1 has been hot-added Jan 19 12:31:56.840065 kernel: iommu: Default domain type: Translated Jan 19 12:31:56.840071 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jan 19 12:31:56.840076 kernel: efivars: Registered efivars operations Jan 19 12:31:56.840081 kernel: vgaarb: loaded Jan 19 12:31:56.840086 kernel: clocksource: Switched to clocksource arch_sys_counter Jan 19 12:31:56.840091 kernel: VFS: Disk quotas dquot_6.6.0 Jan 19 12:31:56.840096 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jan 19 12:31:56.840102 kernel: pnp: PnP ACPI init Jan 19 12:31:56.840108 kernel: pnp: PnP ACPI: found 0 devices Jan 19 12:31:56.840113 kernel: NET: Registered PF_INET protocol family Jan 19 12:31:56.840118 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jan 19 12:31:56.840123 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jan 19 12:31:56.840129 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jan 19 12:31:56.840134 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jan 19 12:31:56.840139 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jan 19 12:31:56.840145 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jan 19 12:31:56.840150 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jan 19 12:31:56.840156 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jan 19 12:31:56.840161 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jan 19 12:31:56.840166 kernel: PCI: CLS 0 bytes, default 64 Jan 19 12:31:56.840171 kernel: kvm [1]: HYP mode not available Jan 19 12:31:56.840176 kernel: Initialise system trusted keyrings Jan 19 12:31:56.840181 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jan 19 12:31:56.840187 kernel: Key type asymmetric registered Jan 19 12:31:56.840192 kernel: Asymmetric key parser 'x509' registered Jan 19 12:31:56.840198 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jan 19 12:31:56.840203 kernel: io scheduler mq-deadline registered Jan 19 12:31:56.840208 kernel: io scheduler kyber registered Jan 19 12:31:56.840213 kernel: io scheduler bfq registered Jan 19 12:31:56.840218 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jan 19 12:31:56.840224 kernel: thunder_xcv, ver 1.0 Jan 19 12:31:56.840230 kernel: thunder_bgx, ver 1.0 Jan 19 12:31:56.840235 kernel: nicpf, ver 1.0 Jan 19 12:31:56.840240 kernel: nicvf, ver 1.0 Jan 19 12:31:56.840386 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jan 19 12:31:56.840458 kernel: rtc-efi rtc-efi.0: setting system clock to 2026-01-19T12:31:53 UTC (1768825913) Jan 19 12:31:56.840467 kernel: efifb: probing for efifb Jan 19 12:31:56.840472 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jan 19 12:31:56.840478 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jan 19 12:31:56.840483 kernel: efifb: scrolling: redraw Jan 19 12:31:56.840488 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jan 19 12:31:56.840493 kernel: Console: switching to colour frame buffer device 128x48 Jan 19 12:31:56.840498 kernel: fb0: EFI VGA frame buffer device Jan 19 12:31:56.840505 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Jan 19 12:31:56.840510 kernel: hid: raw HID events driver (C) Jiri Kosina Jan 19 12:31:56.840515 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Jan 19 12:31:56.840520 kernel: watchdog: NMI not fully supported Jan 19 12:31:56.840526 kernel: watchdog: Hard watchdog permanently disabled Jan 19 12:31:56.840531 kernel: NET: Registered PF_INET6 protocol family Jan 19 12:31:56.840536 kernel: Segment Routing with IPv6 Jan 19 12:31:56.840542 kernel: In-situ OAM (IOAM) with IPv6 Jan 19 12:31:56.840548 kernel: NET: Registered PF_PACKET protocol family Jan 19 12:31:56.840553 kernel: Key type dns_resolver registered Jan 19 12:31:56.840558 kernel: registered taskstats version 1 Jan 19 12:31:56.840563 kernel: Loading compiled-in X.509 certificates Jan 19 12:31:56.840569 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.66-flatcar: e8918082b9e38771b53dc84f96d99c870c2fb74a' Jan 19 12:31:56.840574 kernel: Demotion targets for Node 0: null Jan 19 12:31:56.840580 kernel: Key type .fscrypt registered Jan 19 12:31:56.840585 kernel: Key type fscrypt-provisioning registered Jan 19 12:31:56.840590 kernel: ima: No TPM chip found, activating TPM-bypass! Jan 19 12:31:56.840595 kernel: ima: Allocated hash algorithm: sha1 Jan 19 12:31:56.840601 kernel: ima: No architecture policies found Jan 19 12:31:56.840606 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jan 19 12:31:56.840611 kernel: clk: Disabling unused clocks Jan 19 12:31:56.840616 kernel: PM: genpd: Disabling unused power domains Jan 19 12:31:56.840622 kernel: Freeing unused kernel memory: 12480K Jan 19 12:31:56.840627 kernel: Run /init as init process Jan 19 12:31:56.840633 kernel: with arguments: Jan 19 12:31:56.840638 kernel: /init Jan 19 12:31:56.840643 kernel: with environment: Jan 19 12:31:56.840648 kernel: HOME=/ Jan 19 12:31:56.840653 kernel: TERM=linux Jan 19 12:31:56.840659 kernel: hv_vmbus: Vmbus version:5.3 Jan 19 12:31:56.840664 kernel: SCSI subsystem initialized Jan 19 12:31:56.840669 kernel: hv_vmbus: registering driver hid_hyperv Jan 19 12:31:56.840674 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Jan 19 12:31:56.840759 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jan 19 12:31:56.840766 kernel: hv_vmbus: registering driver hyperv_keyboard Jan 19 12:31:56.840773 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 Jan 19 12:31:56.840778 kernel: pps_core: LinuxPPS API ver. 1 registered Jan 19 12:31:56.840784 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jan 19 12:31:56.840789 kernel: PTP clock support registered Jan 19 12:31:56.840794 kernel: hv_utils: Registering HyperV Utility Driver Jan 19 12:31:56.840799 kernel: hv_vmbus: registering driver hv_utils Jan 19 12:31:56.840805 kernel: hv_utils: Heartbeat IC version 3.0 Jan 19 12:31:56.840811 kernel: hv_utils: Shutdown IC version 3.2 Jan 19 12:31:56.840816 kernel: hv_utils: TimeSync IC version 4.0 Jan 19 12:31:56.840821 kernel: hv_vmbus: registering driver hv_storvsc Jan 19 12:31:56.840911 kernel: scsi host0: storvsc_host_t Jan 19 12:31:56.840989 kernel: scsi host1: storvsc_host_t Jan 19 12:31:56.841074 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jan 19 12:31:56.841156 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Jan 19 12:31:56.841229 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jan 19 12:31:56.841327 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jan 19 12:31:56.841403 kernel: sd 0:0:0:0: [sda] Write Protect is off Jan 19 12:31:56.841477 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jan 19 12:31:56.841550 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jan 19 12:31:56.841632 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#61 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 19 12:31:56.841701 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#4 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jan 19 12:31:56.841708 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jan 19 12:31:56.841780 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jan 19 12:31:56.841854 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jan 19 12:31:56.841862 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jan 19 12:31:56.841934 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jan 19 12:31:56.841940 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jan 19 12:31:56.841946 kernel: device-mapper: uevent: version 1.0.3 Jan 19 12:31:56.841951 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jan 19 12:31:56.841956 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Jan 19 12:31:56.841962 kernel: raid6: neonx8 gen() 18533 MB/s Jan 19 12:31:56.841968 kernel: raid6: neonx4 gen() 18572 MB/s Jan 19 12:31:56.841973 kernel: raid6: neonx2 gen() 17100 MB/s Jan 19 12:31:56.841978 kernel: raid6: neonx1 gen() 15021 MB/s Jan 19 12:31:56.841983 kernel: raid6: int64x8 gen() 10520 MB/s Jan 19 12:31:56.841989 kernel: raid6: int64x4 gen() 10615 MB/s Jan 19 12:31:56.841994 kernel: raid6: int64x2 gen() 8982 MB/s Jan 19 12:31:56.841999 kernel: raid6: int64x1 gen() 7041 MB/s Jan 19 12:31:56.842004 kernel: raid6: using algorithm neonx4 gen() 18572 MB/s Jan 19 12:31:56.842010 kernel: raid6: .... xor() 15134 MB/s, rmw enabled Jan 19 12:31:56.842015 kernel: raid6: using neon recovery algorithm Jan 19 12:31:56.842021 kernel: xor: measuring software checksum speed Jan 19 12:31:56.842026 kernel: 8regs : 28633 MB/sec Jan 19 12:31:56.842031 kernel: 32regs : 28804 MB/sec Jan 19 12:31:56.842037 kernel: arm64_neon : 34210 MB/sec Jan 19 12:31:56.842042 kernel: xor: using function: arm64_neon (34210 MB/sec) Jan 19 12:31:56.842048 kernel: Btrfs loaded, zoned=no, fsverity=no Jan 19 12:31:56.842054 kernel: BTRFS: device fsid 7831b0c0-cbf6-493c-b211-c1247995e6b3 devid 1 transid 34 /dev/mapper/usr (254:0) scanned by mount (453) Jan 19 12:31:56.842059 kernel: BTRFS info (device dm-0): first mount of filesystem 7831b0c0-cbf6-493c-b211-c1247995e6b3 Jan 19 12:31:56.842065 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jan 19 12:31:56.842070 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jan 19 12:31:56.842075 kernel: BTRFS info (device dm-0): enabling free space tree Jan 19 12:31:56.842080 kernel: loop: module loaded Jan 19 12:31:56.842086 kernel: loop0: detected capacity change from 0 to 91832 Jan 19 12:31:56.842091 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 19 12:31:56.842098 systemd[1]: Successfully made /usr/ read-only. Jan 19 12:31:56.842105 systemd[1]: systemd 257.9 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jan 19 12:31:56.842111 systemd[1]: Detected virtualization microsoft. Jan 19 12:31:56.842117 systemd[1]: Detected architecture arm64. Jan 19 12:31:56.842123 systemd[1]: Running in initrd. Jan 19 12:31:56.842128 systemd[1]: No hostname configured, using default hostname. Jan 19 12:31:56.842134 systemd[1]: Hostname set to . Jan 19 12:31:56.842140 systemd[1]: Initializing machine ID from random generator. Jan 19 12:31:56.842145 systemd[1]: Queued start job for default target initrd.target. Jan 19 12:31:56.842151 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Jan 19 12:31:56.842157 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 19 12:31:56.842163 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 19 12:31:56.842169 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jan 19 12:31:56.842174 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 19 12:31:56.842180 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jan 19 12:31:56.842186 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jan 19 12:31:56.842193 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 19 12:31:56.842199 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 19 12:31:56.842204 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jan 19 12:31:56.842210 systemd[1]: Reached target paths.target - Path Units. Jan 19 12:31:56.842215 systemd[1]: Reached target slices.target - Slice Units. Jan 19 12:31:56.842221 systemd[1]: Reached target swap.target - Swaps. Jan 19 12:31:56.842226 systemd[1]: Reached target timers.target - Timer Units. Jan 19 12:31:56.842233 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jan 19 12:31:56.842239 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 19 12:31:56.842244 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jan 19 12:31:56.842250 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jan 19 12:31:56.842265 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jan 19 12:31:56.842271 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 19 12:31:56.842282 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 19 12:31:56.842289 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 19 12:31:56.842294 systemd[1]: Reached target sockets.target - Socket Units. Jan 19 12:31:56.842301 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jan 19 12:31:56.842306 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jan 19 12:31:56.842313 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 19 12:31:56.842319 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jan 19 12:31:56.842326 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). Jan 19 12:31:56.842332 systemd[1]: Starting systemd-fsck-usr.service... Jan 19 12:31:56.842337 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 19 12:31:56.842343 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 19 12:31:56.842363 systemd-journald[590]: Collecting audit messages is enabled. Jan 19 12:31:56.842378 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:31:56.842385 systemd-journald[590]: Journal started Jan 19 12:31:56.842399 systemd-journald[590]: Runtime Journal (/run/log/journal/04b57f2417db4edda90e0ff220b5eeec) is 8M, max 78.3M, 70.3M free. Jan 19 12:31:56.856703 systemd[1]: Started systemd-journald.service - Journal Service. Jan 19 12:31:56.857667 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jan 19 12:31:56.892632 kernel: audit: type=1130 audit(1768825916.857:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.892655 kernel: audit: type=1130 audit(1768825916.872:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup-pre comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.857000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.872000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup-pre comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.877513 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 19 12:31:56.944837 kernel: audit: type=1130 audit(1768825916.899:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.944861 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jan 19 12:31:56.944869 kernel: Bridge firewalling registered Jan 19 12:31:56.944876 kernel: audit: type=1130 audit(1768825916.927:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.899000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.927000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.899758 systemd[1]: Finished systemd-fsck-usr.service. Jan 19 12:31:56.967783 kernel: audit: type=1130 audit(1768825916.950:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.950000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:56.938926 systemd-modules-load[593]: Inserted module 'br_netfilter' Jan 19 12:31:56.944296 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 19 12:31:56.967450 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 19 12:31:56.976404 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 19 12:31:56.984852 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 19 12:31:57.012000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.006871 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:31:57.035352 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 19 12:31:57.040601 systemd-tmpfiles[605]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jan 19 12:31:57.053832 kernel: audit: type=1130 audit(1768825917.012:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.053718 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 19 12:31:57.088546 kernel: audit: type=1130 audit(1768825917.047:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.088562 kernel: audit: type=1130 audit(1768825917.070:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.047000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.070000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.089566 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 19 12:31:57.111764 kernel: audit: type=1130 audit(1768825917.094:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.094000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.113899 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jan 19 12:31:57.126000 audit: BPF prog-id=6 op=LOAD Jan 19 12:31:57.130405 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 19 12:31:57.140078 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 19 12:31:57.158592 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 19 12:31:57.165000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.172471 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 19 12:31:57.180000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.187446 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jan 19 12:31:57.261483 systemd-resolved[614]: Positive Trust Anchors: Jan 19 12:31:57.261497 systemd-resolved[614]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 19 12:31:57.261500 systemd-resolved[614]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Jan 19 12:31:57.261519 systemd-resolved[614]: 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 Jan 19 12:31:57.309538 dracut-cmdline[629]: dracut-109 Jan 19 12:31:57.309538 dracut-cmdline[629]: Using kernel command line parameters: 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=bcc9d39e48dfabbde665714a6de520952cf3c8e400bc444da1955f6cf2fc19a7 Jan 19 12:31:57.313889 systemd-resolved[614]: Defaulting to hostname 'linux'. Jan 19 12:31:57.395000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.314602 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 19 12:31:57.396052 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 19 12:31:57.522286 kernel: Loading iSCSI transport class v2.0-870. Jan 19 12:31:57.560281 kernel: iscsi: registered transport (tcp) Jan 19 12:31:57.590282 kernel: iscsi: registered transport (qla4xxx) Jan 19 12:31:57.590298 kernel: QLogic iSCSI HBA Driver Jan 19 12:31:57.649175 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 19 12:31:57.668347 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 19 12:31:57.673000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.674840 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 19 12:31:57.723988 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jan 19 12:31:57.728000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.730555 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jan 19 12:31:57.753945 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jan 19 12:31:57.776824 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jan 19 12:31:57.787000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.788000 audit: BPF prog-id=7 op=LOAD Jan 19 12:31:57.788000 audit: BPF prog-id=8 op=LOAD Jan 19 12:31:57.789887 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 19 12:31:57.881844 systemd-udevd[852]: Using default interface naming scheme 'v257'. Jan 19 12:31:57.887023 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 19 12:31:57.894000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.900323 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 19 12:31:57.907000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.908922 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jan 19 12:31:57.933000 audit: BPF prog-id=9 op=LOAD Jan 19 12:31:57.935406 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 19 12:31:57.946112 dracut-pre-trigger[962]: rd.md=0: removing MD RAID activation Jan 19 12:31:57.966963 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jan 19 12:31:57.976000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.978506 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 19 12:31:57.995786 systemd-networkd[963]: lo: Link UP Jan 19 12:31:57.998477 systemd-networkd[963]: lo: Gained carrier Jan 19 12:31:58.006000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:57.998948 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 19 12:31:58.006858 systemd[1]: Reached target network.target - Network. Jan 19 12:31:58.029660 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 19 12:31:58.039000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.042403 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jan 19 12:31:58.110290 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#209 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Jan 19 12:31:58.130451 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 19 12:31:58.144949 kernel: hv_vmbus: registering driver hv_netvsc Jan 19 12:31:58.135064 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:31:58.153467 kernel: kauditd_printk_skb: 15 callbacks suppressed Jan 19 12:31:58.153682 kernel: audit: type=1131 audit(1768825918.147:26): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.147000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.159346 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:31:58.178486 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:31:58.198317 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 19 12:31:58.198400 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:31:58.207000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.207000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.226767 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:31:58.255317 kernel: audit: type=1130 audit(1768825918.207:27): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.255639 kernel: audit: type=1131 audit(1768825918.207:28): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.255658 kernel: hv_netvsc 7ced8dbf-e19e-7ced-8dbf-e19e7ced8dbf eth0: VF slot 1 added Jan 19 12:31:58.252994 systemd-networkd[963]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Jan 19 12:31:58.252998 systemd-networkd[963]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 19 12:31:58.254220 systemd-networkd[963]: eth0: Link UP Jan 19 12:31:58.285507 kernel: hv_vmbus: registering driver hv_pci Jan 19 12:31:58.285540 kernel: hv_pci 3b09d962-022a-4d09-8f4c-41a3f5f0f54e: PCI VMBus probing: Using version 0x10004 Jan 19 12:31:58.254836 systemd-networkd[963]: eth0: Gained carrier Jan 19 12:31:58.254845 systemd-networkd[963]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Jan 19 12:31:58.329147 kernel: hv_pci 3b09d962-022a-4d09-8f4c-41a3f5f0f54e: PCI host bridge to bus 022a:00 Jan 19 12:31:58.329377 kernel: pci_bus 022a:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Jan 19 12:31:58.329486 kernel: audit: type=1130 audit(1768825918.303:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.329495 kernel: pci_bus 022a:00: No busn resource found for root bus, will use [bus 00-ff] Jan 19 12:31:58.303000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.286330 systemd-networkd[963]: eth0: DHCPv4 address 10.200.20.31/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jan 19 12:31:58.340160 kernel: pci 022a:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Jan 19 12:31:58.289833 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:31:58.348715 kernel: pci 022a:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Jan 19 12:31:58.353366 kernel: pci 022a:00:02.0: enabling Extended Tags Jan 19 12:31:58.369432 kernel: pci 022a:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 022a:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Jan 19 12:31:58.380476 kernel: pci_bus 022a:00: busn_res: [bus 00-ff] end is updated to 00 Jan 19 12:31:58.380669 kernel: pci 022a:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Jan 19 12:31:58.537393 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jan 19 12:31:58.551468 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jan 19 12:31:58.587846 kernel: mlx5_core 022a:00:02.0: enabling device (0000 -> 0002) Jan 19 12:31:58.597504 kernel: mlx5_core 022a:00:02.0: PTM is not supported by PCIe Jan 19 12:31:58.597728 kernel: mlx5_core 022a:00:02.0: firmware version: 16.30.5026 Jan 19 12:31:58.643108 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jan 19 12:31:58.666530 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 19 12:31:58.740676 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jan 19 12:31:58.819464 kernel: hv_netvsc 7ced8dbf-e19e-7ced-8dbf-e19e7ced8dbf eth0: VF registering: eth1 Jan 19 12:31:58.819678 kernel: mlx5_core 022a:00:02.0 eth1: joined to eth0 Jan 19 12:31:58.826318 kernel: mlx5_core 022a:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Jan 19 12:31:58.836735 systemd-networkd[963]: eth1: Interface name change detected, renamed to enP554s1. Jan 19 12:31:58.841768 kernel: mlx5_core 022a:00:02.0 enP554s1: renamed from eth1 Jan 19 12:31:58.855483 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jan 19 12:31:58.864000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.865226 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jan 19 12:31:58.887721 kernel: audit: type=1130 audit(1768825918.864:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.882383 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 19 12:31:58.892827 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 19 12:31:58.902847 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jan 19 12:31:58.929982 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jan 19 12:31:58.934000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.952311 kernel: audit: type=1130 audit(1768825918.934:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:58.985275 kernel: mlx5_core 022a:00:02.0 enP554s1: Link up Jan 19 12:31:59.023204 systemd-networkd[963]: enP554s1: Link UP Jan 19 12:31:59.026403 kernel: hv_netvsc 7ced8dbf-e19e-7ced-8dbf-e19e7ced8dbf eth0: Data path switched to VF: enP554s1 Jan 19 12:31:59.256825 systemd-networkd[963]: enP554s1: Gained carrier Jan 19 12:31:59.273045 systemd-networkd[963]: eth0: Gained IPv6LL Jan 19 12:31:59.755797 disk-uuid[1078]: Warning: The kernel is still using the old partition table. Jan 19 12:31:59.755797 disk-uuid[1078]: The new table will be used at the next reboot or after you Jan 19 12:31:59.755797 disk-uuid[1078]: run partprobe(8) or kpartx(8) Jan 19 12:31:59.755797 disk-uuid[1078]: The operation has completed successfully. Jan 19 12:31:59.773050 systemd[1]: disk-uuid.service: Deactivated successfully. Jan 19 12:31:59.773169 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jan 19 12:31:59.782000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:59.800805 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jan 19 12:31:59.819675 kernel: audit: type=1130 audit(1768825919.782:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:59.819698 kernel: audit: type=1131 audit(1768825919.782:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:59.782000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:59.858292 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1188) Jan 19 12:31:59.858340 kernel: BTRFS info (device sda6): first mount of filesystem 77246cac-3c22-4e58-9449-139fed7ca62d Jan 19 12:31:59.867730 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Jan 19 12:31:59.907150 kernel: BTRFS info (device sda6): turning on async discard Jan 19 12:31:59.907200 kernel: BTRFS info (device sda6): enabling free space tree Jan 19 12:31:59.916282 kernel: BTRFS info (device sda6): last unmount of filesystem 77246cac-3c22-4e58-9449-139fed7ca62d Jan 19 12:31:59.917301 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jan 19 12:31:59.921000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:31:59.937575 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jan 19 12:31:59.948525 kernel: audit: type=1130 audit(1768825919.921:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.066060 ignition[1207]: Ignition 2.24.0 Jan 19 12:32:01.066076 ignition[1207]: Stage: fetch-offline Jan 19 12:32:01.070361 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jan 19 12:32:01.096379 kernel: audit: type=1130 audit(1768825921.077:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.077000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.066205 ignition[1207]: no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:01.095452 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jan 19 12:32:01.066214 ignition[1207]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:01.067164 ignition[1207]: parsed url from cmdline: "" Jan 19 12:32:01.067169 ignition[1207]: no config URL provided Jan 19 12:32:01.067226 ignition[1207]: reading system config file "/usr/lib/ignition/user.ign" Jan 19 12:32:01.067242 ignition[1207]: no config at "/usr/lib/ignition/user.ign" Jan 19 12:32:01.067246 ignition[1207]: failed to fetch config: resource requires networking Jan 19 12:32:01.067707 ignition[1207]: Ignition finished successfully Jan 19 12:32:01.127368 ignition[1214]: Ignition 2.24.0 Jan 19 12:32:01.127373 ignition[1214]: Stage: fetch Jan 19 12:32:01.127617 ignition[1214]: no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:01.127623 ignition[1214]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:01.127725 ignition[1214]: parsed url from cmdline: "" Jan 19 12:32:01.127728 ignition[1214]: no config URL provided Jan 19 12:32:01.127731 ignition[1214]: reading system config file "/usr/lib/ignition/user.ign" Jan 19 12:32:01.127736 ignition[1214]: no config at "/usr/lib/ignition/user.ign" Jan 19 12:32:01.127753 ignition[1214]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jan 19 12:32:01.232724 ignition[1214]: GET result: OK Jan 19 12:32:01.232804 ignition[1214]: config has been read from IMDS userdata Jan 19 12:32:01.232811 ignition[1214]: parsing config with SHA512: b1ce44486e50bc6a799ee5dbda6057b7ef683242fd5bc577810bf4070fc0bb410d7fcb780ff7d92553536cde3bf52bc8fb00054afc76b24fda3fbcf2393c497a Jan 19 12:32:01.238182 unknown[1214]: fetched base config from "system" Jan 19 12:32:01.245000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.238330 ignition[1214]: fetch: fetch complete Jan 19 12:32:01.238188 unknown[1214]: fetched base config from "system" Jan 19 12:32:01.238334 ignition[1214]: fetch: fetch passed Jan 19 12:32:01.238191 unknown[1214]: fetched user config from "azure" Jan 19 12:32:01.238383 ignition[1214]: Ignition finished successfully Jan 19 12:32:01.240536 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jan 19 12:32:01.247329 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jan 19 12:32:01.288018 ignition[1220]: Ignition 2.24.0 Jan 19 12:32:01.288314 ignition[1220]: Stage: kargs Jan 19 12:32:01.291651 ignition[1220]: no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:01.298278 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jan 19 12:32:01.303000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.291661 ignition[1220]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:01.304610 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jan 19 12:32:01.292068 ignition[1220]: kargs: kargs passed Jan 19 12:32:01.292114 ignition[1220]: Ignition finished successfully Jan 19 12:32:01.333834 ignition[1226]: Ignition 2.24.0 Jan 19 12:32:01.333850 ignition[1226]: Stage: disks Jan 19 12:32:01.337836 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jan 19 12:32:01.344000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.334060 ignition[1226]: no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:01.344698 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jan 19 12:32:01.334067 ignition[1226]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:01.353058 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jan 19 12:32:01.334517 ignition[1226]: disks: disks passed Jan 19 12:32:01.363429 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 19 12:32:01.334562 ignition[1226]: Ignition finished successfully Jan 19 12:32:01.374014 systemd[1]: Reached target sysinit.target - System Initialization. Jan 19 12:32:01.384462 systemd[1]: Reached target basic.target - Basic System. Jan 19 12:32:01.395410 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jan 19 12:32:01.475171 systemd-fsck[1234]: ROOT: clean, 15/6361680 files, 408771/6359552 blocks Jan 19 12:32:01.481600 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jan 19 12:32:01.486000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:01.487938 systemd[1]: Mounting sysroot.mount - /sysroot... Jan 19 12:32:01.747282 kernel: EXT4-fs (sda9): mounted filesystem 187c17ed-9b16-4195-b8cd-cab411873dde r/w with ordered data mode. Quota mode: none. Jan 19 12:32:01.748494 systemd[1]: Mounted sysroot.mount - /sysroot. Jan 19 12:32:01.752281 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jan 19 12:32:01.789124 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 19 12:32:01.802925 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jan 19 12:32:01.811579 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jan 19 12:32:01.822203 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jan 19 12:32:01.822253 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jan 19 12:32:01.828015 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jan 19 12:32:01.841898 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jan 19 12:32:01.872232 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1248) Jan 19 12:32:01.872300 kernel: BTRFS info (device sda6): first mount of filesystem 77246cac-3c22-4e58-9449-139fed7ca62d Jan 19 12:32:01.876978 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Jan 19 12:32:01.886940 kernel: BTRFS info (device sda6): turning on async discard Jan 19 12:32:01.886999 kernel: BTRFS info (device sda6): enabling free space tree Jan 19 12:32:01.888207 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 19 12:32:02.424002 coreos-metadata[1250]: Jan 19 12:32:02.423 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 19 12:32:02.431727 coreos-metadata[1250]: Jan 19 12:32:02.431 INFO Fetch successful Jan 19 12:32:02.435760 coreos-metadata[1250]: Jan 19 12:32:02.431 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jan 19 12:32:02.444209 coreos-metadata[1250]: Jan 19 12:32:02.444 INFO Fetch successful Jan 19 12:32:02.458016 coreos-metadata[1250]: Jan 19 12:32:02.457 INFO wrote hostname ci-9999.9.100-4df6ab753d to /sysroot/etc/hostname Jan 19 12:32:02.459552 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 19 12:32:02.469000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.553639 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jan 19 12:32:03.578357 kernel: kauditd_printk_skb: 5 callbacks suppressed Jan 19 12:32:03.578379 kernel: audit: type=1130 audit(1768825923.558:41): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.558000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.560020 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jan 19 12:32:03.582611 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jan 19 12:32:03.615964 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jan 19 12:32:03.626281 kernel: BTRFS info (device sda6): last unmount of filesystem 77246cac-3c22-4e58-9449-139fed7ca62d Jan 19 12:32:03.636613 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jan 19 12:32:03.644000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.648367 ignition[1351]: INFO : Ignition 2.24.0 Jan 19 12:32:03.648367 ignition[1351]: INFO : Stage: mount Jan 19 12:32:03.648367 ignition[1351]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:03.648367 ignition[1351]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:03.648367 ignition[1351]: INFO : mount: mount passed Jan 19 12:32:03.648367 ignition[1351]: INFO : Ignition finished successfully Jan 19 12:32:03.703460 kernel: audit: type=1130 audit(1768825923.644:42): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.703495 kernel: audit: type=1130 audit(1768825923.660:43): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.660000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:03.648701 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jan 19 12:32:03.662305 systemd[1]: Starting ignition-files.service - Ignition (files)... Jan 19 12:32:03.698371 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jan 19 12:32:03.734451 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1362) Jan 19 12:32:03.734487 kernel: BTRFS info (device sda6): first mount of filesystem 77246cac-3c22-4e58-9449-139fed7ca62d Jan 19 12:32:03.739187 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Jan 19 12:32:03.749085 kernel: BTRFS info (device sda6): turning on async discard Jan 19 12:32:03.749124 kernel: BTRFS info (device sda6): enabling free space tree Jan 19 12:32:03.751114 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jan 19 12:32:03.778848 ignition[1379]: INFO : Ignition 2.24.0 Jan 19 12:32:03.778848 ignition[1379]: INFO : Stage: files Jan 19 12:32:03.785179 ignition[1379]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:03.785179 ignition[1379]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:03.785179 ignition[1379]: DEBUG : files: compiled without relabeling support, skipping Jan 19 12:32:03.785179 ignition[1379]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jan 19 12:32:03.785179 ignition[1379]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jan 19 12:32:03.870494 ignition[1379]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jan 19 12:32:03.876244 ignition[1379]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jan 19 12:32:03.876244 ignition[1379]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jan 19 12:32:03.871655 unknown[1379]: wrote ssh authorized keys file for user: core Jan 19 12:32:04.231334 ignition[1379]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jan 19 12:32:04.238902 ignition[1379]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jan 19 12:32:04.238902 ignition[1379]: INFO : files: op(4): [started] processing unit "etcd-member.service" Jan 19 12:32:04.292705 ignition[1379]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Jan 19 12:32:04.327886 ignition[1379]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Jan 19 12:32:04.327886 ignition[1379]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Jan 19 12:32:04.343593 ignition[1379]: INFO : files: op(6): [started] setting preset to enabled for "etcd-member.service" Jan 19 12:32:04.343593 ignition[1379]: INFO : files: op(6): [finished] setting preset to enabled for "etcd-member.service" Jan 19 12:32:04.343593 ignition[1379]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Jan 19 12:32:04.343593 ignition[1379]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Jan 19 12:32:04.343593 ignition[1379]: INFO : files: files passed Jan 19 12:32:04.343593 ignition[1379]: INFO : Ignition finished successfully Jan 19 12:32:04.434511 kernel: audit: type=1130 audit(1768825924.347:44): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.434541 kernel: audit: type=1130 audit(1768825924.408:45): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.434560 kernel: audit: type=1131 audit(1768825924.408:46): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.347000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.408000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.408000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.341541 systemd[1]: Finished ignition-files.service - Ignition (files). Jan 19 12:32:04.369423 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jan 19 12:32:04.386458 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jan 19 12:32:04.392806 systemd[1]: ignition-quench.service: Deactivated successfully. Jan 19 12:32:04.392880 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jan 19 12:32:04.484309 initrd-setup-root-after-ignition[1410]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 19 12:32:04.484309 initrd-setup-root-after-ignition[1410]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jan 19 12:32:04.498279 initrd-setup-root-after-ignition[1414]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jan 19 12:32:04.503000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.492538 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 19 12:32:04.533359 kernel: audit: type=1130 audit(1768825924.503:47): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.503773 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jan 19 12:32:04.532426 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jan 19 12:32:04.582834 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jan 19 12:32:04.587121 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jan 19 12:32:04.592000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.592000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.593639 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jan 19 12:32:04.629582 kernel: audit: type=1130 audit(1768825924.592:48): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.629607 kernel: audit: type=1131 audit(1768825924.592:49): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.629441 systemd[1]: Reached target initrd.target - Initrd Default Target. Jan 19 12:32:04.634206 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jan 19 12:32:04.638407 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jan 19 12:32:04.670311 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 19 12:32:04.675000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.693287 kernel: audit: type=1130 audit(1768825924.675:50): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.693410 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jan 19 12:32:04.717791 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Jan 19 12:32:04.717899 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jan 19 12:32:04.728438 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 19 12:32:04.738415 systemd[1]: Stopped target timers.target - Timer Units. Jan 19 12:32:04.746692 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jan 19 12:32:04.755000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.746817 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jan 19 12:32:04.759058 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jan 19 12:32:04.763718 systemd[1]: Stopped target basic.target - Basic System. Jan 19 12:32:04.772765 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jan 19 12:32:04.781206 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jan 19 12:32:04.789664 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jan 19 12:32:04.798280 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jan 19 12:32:04.808083 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jan 19 12:32:04.817374 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jan 19 12:32:04.826821 systemd[1]: Stopped target sysinit.target - System Initialization. Jan 19 12:32:04.835552 systemd[1]: Stopped target local-fs.target - Local File Systems. Jan 19 12:32:04.844374 systemd[1]: Stopped target swap.target - Swaps. Jan 19 12:32:04.860000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.852034 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jan 19 12:32:04.852170 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jan 19 12:32:04.864022 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jan 19 12:32:04.869120 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 19 12:32:04.896000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.878025 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jan 19 12:32:04.878089 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 19 12:32:04.910000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.887892 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jan 19 12:32:04.921000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.887996 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jan 19 12:32:04.930000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.901624 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jan 19 12:32:04.901734 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jan 19 12:32:04.911113 systemd[1]: ignition-files.service: Deactivated successfully. Jan 19 12:32:04.911185 systemd[1]: Stopped ignition-files.service - Ignition (files). Jan 19 12:32:04.921494 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jan 19 12:32:04.921586 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jan 19 12:32:04.980000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.986204 ignition[1434]: INFO : Ignition 2.24.0 Jan 19 12:32:04.986204 ignition[1434]: INFO : Stage: umount Jan 19 12:32:04.986204 ignition[1434]: INFO : no configs at "/usr/lib/ignition/base.d" Jan 19 12:32:04.986204 ignition[1434]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jan 19 12:32:04.986204 ignition[1434]: INFO : umount: umount passed Jan 19 12:32:04.986204 ignition[1434]: INFO : Ignition finished successfully Jan 19 12:32:04.994000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.002000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.016000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.931866 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jan 19 12:32:05.030000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.030000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.958149 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jan 19 12:32:05.038000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.972679 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jan 19 12:32:05.047000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.972830 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 19 12:32:05.057000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.983484 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jan 19 12:32:05.072000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:04.983576 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jan 19 12:32:04.995361 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jan 19 12:32:04.995582 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jan 19 12:32:05.008558 systemd[1]: ignition-mount.service: Deactivated successfully. Jan 19 12:32:05.008657 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jan 19 12:32:05.020120 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jan 19 12:32:05.021292 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jan 19 12:32:05.030978 systemd[1]: ignition-disks.service: Deactivated successfully. Jan 19 12:32:05.140000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.031080 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jan 19 12:32:05.149000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup-pre comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.039299 systemd[1]: ignition-kargs.service: Deactivated successfully. Jan 19 12:32:05.039348 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jan 19 12:32:05.047867 systemd[1]: ignition-fetch.service: Deactivated successfully. Jan 19 12:32:05.047919 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jan 19 12:32:05.057562 systemd[1]: Stopped target network.target - Network. Jan 19 12:32:05.182000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.067546 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jan 19 12:32:05.067617 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jan 19 12:32:05.194000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.072943 systemd[1]: Stopped target paths.target - Path Units. Jan 19 12:32:05.080648 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jan 19 12:32:05.085159 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 19 12:32:05.212000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.090471 systemd[1]: Stopped target slices.target - Slice Units. Jan 19 12:32:05.222000 audit: BPF prog-id=6 op=UNLOAD Jan 19 12:32:05.222000 audit: BPF prog-id=9 op=UNLOAD Jan 19 12:32:05.098114 systemd[1]: Stopped target sockets.target - Socket Units. Jan 19 12:32:05.106761 systemd[1]: iscsid.socket: Deactivated successfully. Jan 19 12:32:05.106810 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jan 19 12:32:05.244000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.114818 systemd[1]: iscsiuio.socket: Deactivated successfully. Jan 19 12:32:05.114853 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jan 19 12:32:05.122949 systemd[1]: systemd-journald-audit.socket: Deactivated successfully. Jan 19 12:32:05.267000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.122973 systemd[1]: Closed systemd-journald-audit.socket - Journal Audit Socket. Jan 19 12:32:05.277000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.131504 systemd[1]: ignition-setup.service: Deactivated successfully. Jan 19 12:32:05.131559 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jan 19 12:32:05.292000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.140436 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jan 19 12:32:05.140468 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jan 19 12:32:05.149398 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jan 19 12:32:05.157545 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jan 19 12:32:05.167367 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jan 19 12:32:05.167917 systemd[1]: sysroot-boot.service: Deactivated successfully. Jan 19 12:32:05.332000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.168000 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jan 19 12:32:05.182614 systemd[1]: systemd-resolved.service: Deactivated successfully. Jan 19 12:32:05.182706 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jan 19 12:32:05.357000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.203454 systemd[1]: systemd-networkd.service: Deactivated successfully. Jan 19 12:32:05.203546 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jan 19 12:32:05.370000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.221249 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jan 19 12:32:05.228344 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jan 19 12:32:05.389000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.228385 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jan 19 12:32:05.405156 kernel: hv_netvsc 7ced8dbf-e19e-7ced-8dbf-e19e7ced8dbf eth0: Data path switched from VF: enP554s1 Jan 19 12:32:05.236705 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jan 19 12:32:05.236768 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jan 19 12:32:05.414000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.249416 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jan 19 12:32:05.424000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.258734 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jan 19 12:32:05.436000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.258809 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jan 19 12:32:05.445000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.268351 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jan 19 12:32:05.456000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.268403 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jan 19 12:32:05.464000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.464000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.277920 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jan 19 12:32:05.277964 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jan 19 12:32:05.295543 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 19 12:32:05.323469 systemd[1]: systemd-udevd.service: Deactivated successfully. Jan 19 12:32:05.323921 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 19 12:32:05.332924 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jan 19 12:32:05.332965 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jan 19 12:32:05.340986 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jan 19 12:32:05.341009 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jan 19 12:32:05.509000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:05.348827 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jan 19 12:32:05.348873 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jan 19 12:32:05.362395 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jan 19 12:32:05.362450 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jan 19 12:32:05.375315 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jan 19 12:32:05.375370 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jan 19 12:32:05.395159 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jan 19 12:32:05.406328 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jan 19 12:32:05.406401 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jan 19 12:32:05.415014 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jan 19 12:32:05.415065 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 19 12:32:05.424632 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jan 19 12:32:05.424691 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 19 12:32:05.436652 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jan 19 12:32:05.436695 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jan 19 12:32:05.446305 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 19 12:32:05.446352 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:32:05.456944 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jan 19 12:32:05.457040 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jan 19 12:32:05.501822 systemd[1]: network-cleanup.service: Deactivated successfully. Jan 19 12:32:05.501970 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jan 19 12:32:05.628452 systemd-journald[590]: Received SIGTERM from PID 1 (systemd). Jan 19 12:32:05.510329 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jan 19 12:32:05.519189 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jan 19 12:32:05.545252 systemd[1]: Switching root. Jan 19 12:32:05.639845 systemd-journald[590]: Journal stopped Jan 19 12:32:10.180836 kernel: SELinux: policy capability network_peer_controls=1 Jan 19 12:32:10.180855 kernel: SELinux: policy capability open_perms=1 Jan 19 12:32:10.180863 kernel: SELinux: policy capability extended_socket_class=1 Jan 19 12:32:10.180869 kernel: SELinux: policy capability always_check_network=0 Jan 19 12:32:10.180877 kernel: SELinux: policy capability cgroup_seclabel=1 Jan 19 12:32:10.180882 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jan 19 12:32:10.180889 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jan 19 12:32:10.180895 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jan 19 12:32:10.180900 kernel: SELinux: policy capability userspace_initial_context=0 Jan 19 12:32:10.180907 systemd[1]: Successfully loaded SELinux policy in 174.209ms. Jan 19 12:32:10.180915 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.570ms. Jan 19 12:32:10.180922 systemd[1]: systemd 257.9 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Jan 19 12:32:10.180929 systemd[1]: Detected virtualization microsoft. Jan 19 12:32:10.180937 systemd[1]: Detected architecture arm64. Jan 19 12:32:10.180945 systemd[1]: Detected first boot. Jan 19 12:32:10.180952 systemd[1]: Hostname set to . Jan 19 12:32:10.180958 systemd[1]: Initializing machine ID from random generator. Jan 19 12:32:10.180964 zram_generator::config[1476]: No configuration found. Jan 19 12:32:10.180971 kernel: NET: Registered PF_VSOCK protocol family Jan 19 12:32:10.180978 systemd[1]: Populated /etc with preset unit settings. Jan 19 12:32:10.180984 kernel: kauditd_printk_skb: 45 callbacks suppressed Jan 19 12:32:10.180990 kernel: audit: type=1334 audit(1768825929.181:96): prog-id=12 op=LOAD Jan 19 12:32:10.180996 kernel: audit: type=1334 audit(1768825929.181:97): prog-id=3 op=UNLOAD Jan 19 12:32:10.181002 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jan 19 12:32:10.181009 kernel: audit: type=1334 audit(1768825929.181:98): prog-id=13 op=LOAD Jan 19 12:32:10.181015 kernel: audit: type=1334 audit(1768825929.181:99): prog-id=14 op=LOAD Jan 19 12:32:10.181021 kernel: audit: type=1334 audit(1768825929.181:100): prog-id=4 op=UNLOAD Jan 19 12:32:10.181027 kernel: audit: type=1334 audit(1768825929.181:101): prog-id=5 op=UNLOAD Jan 19 12:32:10.181034 kernel: audit: type=1131 audit(1768825929.185:102): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.181040 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jan 19 12:32:10.181046 kernel: audit: type=1334 audit(1768825929.225:103): prog-id=12 op=UNLOAD Jan 19 12:32:10.181053 kernel: audit: type=1130 audit(1768825929.238:104): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.181059 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jan 19 12:32:10.181066 kernel: audit: type=1131 audit(1768825929.238:105): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.181073 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jan 19 12:32:10.181080 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jan 19 12:32:10.181087 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 19 12:32:10.181094 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jan 19 12:32:10.181101 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jan 19 12:32:10.181107 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jan 19 12:32:10.181116 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jan 19 12:32:10.181122 systemd[1]: Created slice user.slice - User and Session Slice. Jan 19 12:32:10.181129 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jan 19 12:32:10.181136 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jan 19 12:32:10.181143 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jan 19 12:32:10.181150 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jan 19 12:32:10.181157 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jan 19 12:32:10.181164 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jan 19 12:32:10.181170 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jan 19 12:32:10.181177 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jan 19 12:32:10.181184 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jan 19 12:32:10.181191 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jan 19 12:32:10.181197 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jan 19 12:32:10.181204 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jan 19 12:32:10.181211 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jan 19 12:32:10.181219 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jan 19 12:32:10.181226 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jan 19 12:32:10.181233 systemd[1]: Reached target remote-veritysetup.target - Remote Verity Protected Volumes. Jan 19 12:32:10.181240 systemd[1]: Reached target slices.target - Slice Units. Jan 19 12:32:10.181246 systemd[1]: Reached target swap.target - Swaps. Jan 19 12:32:10.181253 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jan 19 12:32:10.181473 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jan 19 12:32:10.181492 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jan 19 12:32:10.181500 systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. Jan 19 12:32:10.181508 systemd[1]: Listening on systemd-mountfsd.socket - DDI File System Mounter Socket. Jan 19 12:32:10.181515 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jan 19 12:32:10.181523 systemd[1]: Listening on systemd-nsresourced.socket - Namespace Resource Manager Socket. Jan 19 12:32:10.181530 systemd[1]: Listening on systemd-oomd.socket - Userspace Out-Of-Memory (OOM) Killer Socket. Jan 19 12:32:10.181537 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jan 19 12:32:10.181544 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jan 19 12:32:10.181551 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jan 19 12:32:10.181560 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jan 19 12:32:10.181566 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jan 19 12:32:10.181574 systemd[1]: Mounting media.mount - External Media Directory... Jan 19 12:32:10.181581 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jan 19 12:32:10.181588 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jan 19 12:32:10.181594 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jan 19 12:32:10.181602 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). Jan 19 12:32:10.181609 systemd[1]: Reached target machines.target - Containers. Jan 19 12:32:10.181615 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jan 19 12:32:10.181623 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 19 12:32:10.181630 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jan 19 12:32:10.181637 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jan 19 12:32:10.181644 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 19 12:32:10.181651 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 19 12:32:10.181658 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 19 12:32:10.181665 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jan 19 12:32:10.181672 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 19 12:32:10.181679 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jan 19 12:32:10.181686 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jan 19 12:32:10.181692 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jan 19 12:32:10.181699 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jan 19 12:32:10.181707 systemd[1]: Stopped systemd-fsck-usr.service. Jan 19 12:32:10.181715 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). Jan 19 12:32:10.181722 kernel: ACPI: bus type drm_connector registered Jan 19 12:32:10.181728 systemd[1]: Starting systemd-journald.service - Journal Service... Jan 19 12:32:10.181735 kernel: fuse: init (API version 7.41) Jan 19 12:32:10.181741 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jan 19 12:32:10.181748 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jan 19 12:32:10.181755 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jan 19 12:32:10.181763 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jan 19 12:32:10.181769 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jan 19 12:32:10.181776 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jan 19 12:32:10.181783 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jan 19 12:32:10.181789 systemd[1]: Mounted media.mount - External Media Directory. Jan 19 12:32:10.181796 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jan 19 12:32:10.181804 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jan 19 12:32:10.181810 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jan 19 12:32:10.181838 systemd-journald[1558]: Collecting audit messages is enabled. Jan 19 12:32:10.181854 systemd-journald[1558]: Journal started Jan 19 12:32:10.181870 systemd-journald[1558]: Runtime Journal (/run/log/journal/ec56a0cd5c6c48a58bbfeaf070e35031) is 8M, max 78.3M, 70.3M free. Jan 19 12:32:09.567000 audit[1]: EVENT_LISTENER pid=1 uid=0 auid=4294967295 tty=(none) ses=4294967295 subj=system_u:system_r:kernel_t:s0 comm="systemd" exe="/usr/lib/systemd/systemd" nl-mcgrp=1 op=connect res=1 Jan 19 12:32:09.988000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:09.999000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.012000 audit: BPF prog-id=14 op=UNLOAD Jan 19 12:32:10.012000 audit: BPF prog-id=13 op=UNLOAD Jan 19 12:32:10.013000 audit: BPF prog-id=15 op=LOAD Jan 19 12:32:10.013000 audit: BPF prog-id=16 op=LOAD Jan 19 12:32:10.013000 audit: BPF prog-id=17 op=LOAD Jan 19 12:32:09.173716 systemd[1]: Queued start job for default target multi-user.target. Jan 19 12:32:09.181893 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jan 19 12:32:09.186237 systemd[1]: systemd-journald.service: Deactivated successfully. Jan 19 12:32:09.186579 systemd[1]: systemd-journald.service: Consumed 2.522s CPU time. Jan 19 12:32:10.178000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jan 19 12:32:10.178000 audit[1558]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=5 a1=ffffc68e5e40 a2=4000 a3=0 items=0 ppid=1 pid=1558 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Jan 19 12:32:10.178000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jan 19 12:32:10.192961 systemd[1]: Started systemd-journald.service - Journal Service. Jan 19 12:32:10.192000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.194095 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jan 19 12:32:10.202000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.202803 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jan 19 12:32:10.204311 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jan 19 12:32:10.209000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.209000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.209696 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 19 12:32:10.211366 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 19 12:32:10.218000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.218000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.218578 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 19 12:32:10.218711 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 19 12:32:10.225000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.225000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.225615 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 19 12:32:10.225768 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 19 12:32:10.230000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.230000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.231062 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jan 19 12:32:10.231191 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jan 19 12:32:10.235000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.235000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.236134 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 19 12:32:10.236300 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 19 12:32:10.240000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.240000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.241089 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jan 19 12:32:10.245000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.246211 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jan 19 12:32:10.251000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.252705 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jan 19 12:32:10.257000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.258490 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jan 19 12:32:10.263000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-load-credentials comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.264609 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jan 19 12:32:10.269000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.278797 systemd[1]: Reached target network-pre.target - Preparation for Network. Jan 19 12:32:10.284548 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Jan 19 12:32:10.290871 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jan 19 12:32:10.305393 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jan 19 12:32:10.310298 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jan 19 12:32:10.310334 systemd[1]: Reached target local-fs.target - Local File Systems. Jan 19 12:32:10.315844 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jan 19 12:32:10.321384 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 19 12:32:10.321560 systemd[1]: systemd-confext.service - Merge System Configuration Images into /etc/ was skipped because no trigger condition checks were met. Jan 19 12:32:10.327473 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jan 19 12:32:10.333002 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jan 19 12:32:10.338138 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 19 12:32:10.339088 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jan 19 12:32:10.344221 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 19 12:32:10.345336 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jan 19 12:32:10.366837 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jan 19 12:32:10.376915 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jan 19 12:32:10.391686 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jan 19 12:32:10.393617 systemd-journald[1558]: Time spent on flushing to /var/log/journal/ec56a0cd5c6c48a58bbfeaf070e35031 is 14.661ms for 1061 entries. Jan 19 12:32:10.393617 systemd-journald[1558]: System Journal (/var/log/journal/ec56a0cd5c6c48a58bbfeaf070e35031) is 8M, max 2.2G, 2.2G free. Jan 19 12:32:10.454466 systemd-journald[1558]: Received client request to flush runtime journal. Jan 19 12:32:10.454532 kernel: loop1: detected capacity change from 0 to 27544 Jan 19 12:32:10.400000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.418000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.446000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.401625 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jan 19 12:32:10.407382 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jan 19 12:32:10.413521 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jan 19 12:32:10.420125 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jan 19 12:32:10.428414 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jan 19 12:32:10.442336 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jan 19 12:32:10.456077 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jan 19 12:32:10.461000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.504391 systemd-tmpfiles[1617]: ACLs are not supported, ignoring. Jan 19 12:32:10.504655 systemd-tmpfiles[1617]: ACLs are not supported, ignoring. Jan 19 12:32:10.510346 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jan 19 12:32:10.515000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.520408 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jan 19 12:32:10.550893 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jan 19 12:32:10.555000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.613961 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jan 19 12:32:10.618000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.619000 audit: BPF prog-id=18 op=LOAD Jan 19 12:32:10.619000 audit: BPF prog-id=19 op=LOAD Jan 19 12:32:10.619000 audit: BPF prog-id=20 op=LOAD Jan 19 12:32:10.621417 systemd[1]: Starting systemd-oomd.service - Userspace Out-Of-Memory (OOM) Killer... Jan 19 12:32:10.629000 audit: BPF prog-id=21 op=LOAD Jan 19 12:32:10.630841 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jan 19 12:32:10.636830 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jan 19 12:32:10.642000 audit: BPF prog-id=22 op=LOAD Jan 19 12:32:10.642000 audit: BPF prog-id=23 op=LOAD Jan 19 12:32:10.642000 audit: BPF prog-id=24 op=LOAD Jan 19 12:32:10.646409 systemd[1]: Starting systemd-nsresourced.service - Namespace Resource Manager... Jan 19 12:32:10.653000 audit: BPF prog-id=25 op=LOAD Jan 19 12:32:10.654000 audit: BPF prog-id=26 op=LOAD Jan 19 12:32:10.654000 audit: BPF prog-id=27 op=LOAD Jan 19 12:32:10.654976 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jan 19 12:32:10.677536 systemd-tmpfiles[1637]: ACLs are not supported, ignoring. Jan 19 12:32:10.677547 systemd-tmpfiles[1637]: ACLs are not supported, ignoring. Jan 19 12:32:10.682319 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jan 19 12:32:10.687000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.699857 systemd-nsresourced[1638]: Not setting up BPF subsystem, as functionality has been disabled at compile time. Jan 19 12:32:10.700731 systemd[1]: Started systemd-nsresourced.service - Namespace Resource Manager. Jan 19 12:32:10.707000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-nsresourced comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.720931 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jan 19 12:32:10.725000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.784997 systemd-oomd[1635]: No swap; memory pressure usage will be degraded Jan 19 12:32:10.785626 systemd[1]: Started systemd-oomd.service - Userspace Out-Of-Memory (OOM) Killer. Jan 19 12:32:10.790000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-oomd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.816008 systemd-resolved[1636]: Positive Trust Anchors: Jan 19 12:32:10.816025 systemd-resolved[1636]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jan 19 12:32:10.816028 systemd-resolved[1636]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Jan 19 12:32:10.816047 systemd-resolved[1636]: 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 Jan 19 12:32:10.873885 systemd-resolved[1636]: Using system hostname 'ci-9999.9.100-4df6ab753d'. Jan 19 12:32:10.875206 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jan 19 12:32:10.879000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.880211 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jan 19 12:32:10.894291 kernel: loop2: detected capacity change from 0 to 100192 Jan 19 12:32:10.988301 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jan 19 12:32:10.993000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:10.993000 audit: BPF prog-id=8 op=UNLOAD Jan 19 12:32:10.993000 audit: BPF prog-id=7 op=UNLOAD Jan 19 12:32:10.994000 audit: BPF prog-id=28 op=LOAD Jan 19 12:32:10.994000 audit: BPF prog-id=29 op=LOAD Jan 19 12:32:10.995108 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jan 19 12:32:11.020514 systemd-udevd[1660]: Using default interface naming scheme 'v257'. Jan 19 12:32:11.176603 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jan 19 12:32:11.183000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.186000 audit: BPF prog-id=30 op=LOAD Jan 19 12:32:11.187363 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jan 19 12:32:11.252675 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jan 19 12:32:11.257872 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jan 19 12:32:11.272285 kernel: mousedev: PS/2 mouse device common for all mice Jan 19 12:32:11.279288 kernel: loop3: detected capacity change from 0 to 45344 Jan 19 12:32:11.304315 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#32 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Jan 19 12:32:11.305152 systemd-networkd[1673]: lo: Link UP Jan 19 12:32:11.305493 systemd-networkd[1673]: lo: Gained carrier Jan 19 12:32:11.307100 systemd-networkd[1673]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Jan 19 12:32:11.307144 systemd[1]: Started systemd-networkd.service - Network Configuration. Jan 19 12:32:11.307709 systemd-networkd[1673]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jan 19 12:32:11.314000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.314540 systemd[1]: Reached target network.target - Network. Jan 19 12:32:11.325596 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jan 19 12:32:11.332294 kernel: hv_vmbus: registering driver hv_balloon Jan 19 12:32:11.339983 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jan 19 12:32:11.340052 kernel: hv_balloon: Memory hot add disabled on ARM64 Jan 19 12:32:11.341802 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jan 19 12:32:11.361296 kernel: mlx5_core 022a:00:02.0 enP554s1: Link up Jan 19 12:32:11.361543 kernel: hv_vmbus: registering driver hyperv_fb Jan 19 12:32:11.372624 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jan 19 12:32:11.372695 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jan 19 12:32:11.380754 kernel: Console: switching to colour dummy device 80x25 Jan 19 12:32:11.386470 kernel: hv_netvsc 7ced8dbf-e19e-7ced-8dbf-e19e7ced8dbf eth0: Data path switched to VF: enP554s1 Jan 19 12:32:11.386734 kernel: Console: switching to colour frame buffer device 128x48 Jan 19 12:32:11.390878 systemd-networkd[1673]: enP554s1: Link UP Jan 19 12:32:11.391172 systemd-networkd[1673]: eth0: Link UP Jan 19 12:32:11.391344 systemd-networkd[1673]: eth0: Gained carrier Jan 19 12:32:11.391423 systemd-networkd[1673]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Jan 19 12:32:11.396669 systemd-networkd[1673]: enP554s1: Gained carrier Jan 19 12:32:11.405409 systemd-networkd[1673]: eth0: DHCPv4 address 10.200.20.31/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jan 19 12:32:11.409302 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jan 19 12:32:11.414000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd-persistent-storage comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.436754 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:32:11.459424 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 19 12:32:11.459647 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:32:11.466000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.466000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.467699 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:32:11.479858 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jan 19 12:32:11.480187 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:32:11.485000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.485000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.489425 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jan 19 12:32:11.522275 kernel: MACsec IEEE 802.1AE Jan 19 12:32:11.639339 kernel: loop4: detected capacity change from 0 to 27544 Jan 19 12:32:11.655366 kernel: loop5: detected capacity change from 0 to 100192 Jan 19 12:32:11.663092 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jan 19 12:32:11.670092 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jan 19 12:32:11.678287 kernel: loop6: detected capacity change from 0 to 45344 Jan 19 12:32:11.696587 (sd-merge)[1788]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Jan 19 12:32:11.698919 (sd-merge)[1788]: Merged extensions into '/usr'. Jan 19 12:32:11.701797 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jan 19 12:32:11.706000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.709357 systemd[1]: Starting ensure-sysext.service... Jan 19 12:32:11.713395 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jan 19 12:32:11.733872 systemd[1]: Reload requested from client PID 1793 ('systemctl') (unit ensure-sysext.service)... Jan 19 12:32:11.734029 systemd[1]: Reloading... Jan 19 12:32:11.776143 systemd-tmpfiles[1794]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jan 19 12:32:11.777555 systemd-tmpfiles[1794]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jan 19 12:32:11.777815 systemd-tmpfiles[1794]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jan 19 12:32:11.779901 systemd-tmpfiles[1794]: ACLs are not supported, ignoring. Jan 19 12:32:11.779938 systemd-tmpfiles[1794]: ACLs are not supported, ignoring. Jan 19 12:32:11.801286 zram_generator::config[1830]: No configuration found. Jan 19 12:32:11.815217 systemd-tmpfiles[1794]: Detected autofs mount point /boot during canonicalization of boot. Jan 19 12:32:11.815231 systemd-tmpfiles[1794]: Skipping /boot Jan 19 12:32:11.824032 systemd-tmpfiles[1794]: Detected autofs mount point /boot during canonicalization of boot. Jan 19 12:32:11.824195 systemd-tmpfiles[1794]: Skipping /boot Jan 19 12:32:11.964725 systemd[1]: Reloading finished in 230 ms. Jan 19 12:32:11.979746 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jan 19 12:32:11.985000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:11.986715 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jan 19 12:32:11.992000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.001000 audit: BPF prog-id=31 op=LOAD Jan 19 12:32:12.001000 audit: BPF prog-id=30 op=UNLOAD Jan 19 12:32:12.002000 audit: BPF prog-id=32 op=LOAD Jan 19 12:32:12.002000 audit: BPF prog-id=21 op=UNLOAD Jan 19 12:32:12.003000 audit: BPF prog-id=33 op=LOAD Jan 19 12:32:12.003000 audit: BPF prog-id=34 op=LOAD Jan 19 12:32:12.003000 audit: BPF prog-id=28 op=UNLOAD Jan 19 12:32:12.003000 audit: BPF prog-id=29 op=UNLOAD Jan 19 12:32:12.003000 audit: BPF prog-id=35 op=LOAD Jan 19 12:32:12.004000 audit: BPF prog-id=18 op=UNLOAD Jan 19 12:32:12.004000 audit: BPF prog-id=36 op=LOAD Jan 19 12:32:12.004000 audit: BPF prog-id=37 op=LOAD Jan 19 12:32:12.004000 audit: BPF prog-id=19 op=UNLOAD Jan 19 12:32:12.004000 audit: BPF prog-id=20 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=38 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=15 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=39 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=40 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=16 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=17 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=41 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=22 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=42 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=43 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=23 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=24 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=44 op=LOAD Jan 19 12:32:12.005000 audit: BPF prog-id=25 op=UNLOAD Jan 19 12:32:12.005000 audit: BPF prog-id=45 op=LOAD Jan 19 12:32:12.006000 audit: BPF prog-id=46 op=LOAD Jan 19 12:32:12.006000 audit: BPF prog-id=26 op=UNLOAD Jan 19 12:32:12.006000 audit: BPF prog-id=27 op=UNLOAD Jan 19 12:32:12.008506 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jan 19 12:32:12.014000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.020530 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jan 19 12:32:12.036954 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jan 19 12:32:12.043399 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jan 19 12:32:12.050797 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jan 19 12:32:12.058490 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jan 19 12:32:12.066983 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 19 12:32:12.070674 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 19 12:32:12.078309 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 19 12:32:12.078000 audit[1894]: SYSTEM_BOOT pid=1894 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.092405 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 19 12:32:12.099099 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 19 12:32:12.099350 systemd[1]: systemd-confext.service - Merge System Configuration Images into /etc/ was skipped because no trigger condition checks were met. Jan 19 12:32:12.099487 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). Jan 19 12:32:12.101790 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 19 12:32:12.102084 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 19 12:32:12.108000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.108000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.108925 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 19 12:32:12.109113 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 19 12:32:12.115000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.115000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.116082 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 19 12:32:12.116305 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 19 12:32:12.121000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.121000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.128529 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 19 12:32:12.131507 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 19 12:32:12.137488 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 19 12:32:12.150254 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 19 12:32:12.156005 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 19 12:32:12.156798 systemd[1]: systemd-confext.service - Merge System Configuration Images into /etc/ was skipped because no trigger condition checks were met. Jan 19 12:32:12.156985 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). Jan 19 12:32:12.158143 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jan 19 12:32:12.163000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.164455 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jan 19 12:32:12.169000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.170579 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 19 12:32:12.170761 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 19 12:32:12.176873 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 19 12:32:12.176000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.176000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.177053 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 19 12:32:12.182000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.182000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.183046 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 19 12:32:12.183202 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 19 12:32:12.187000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.187000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.194162 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jan 19 12:32:12.195319 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jan 19 12:32:12.208511 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jan 19 12:32:12.215527 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jan 19 12:32:12.222190 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jan 19 12:32:12.229472 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jan 19 12:32:12.229642 systemd[1]: systemd-confext.service - Merge System Configuration Images into /etc/ was skipped because no trigger condition checks were met. Jan 19 12:32:12.229721 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). Jan 19 12:32:12.229827 systemd[1]: Reached target time-set.target - System Time Set. Jan 19 12:32:12.235518 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jan 19 12:32:12.237773 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jan 19 12:32:12.242000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.242000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.243595 systemd[1]: modprobe@drm.service: Deactivated successfully. Jan 19 12:32:12.245300 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jan 19 12:32:12.250000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.250000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.250999 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jan 19 12:32:12.251162 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jan 19 12:32:12.257000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.257000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.258889 systemd[1]: modprobe@loop.service: Deactivated successfully. Jan 19 12:32:12.260300 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jan 19 12:32:12.265000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.265000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.268376 systemd[1]: Finished ensure-sysext.service. Jan 19 12:32:12.272000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ensure-sysext comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jan 19 12:32:12.274574 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jan 19 12:32:12.274648 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jan 19 12:32:12.413000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jan 19 12:32:12.413000 audit[1936]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffdcafda40 a2=420 a3=0 items=0 ppid=1889 pid=1936 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/bin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Jan 19 12:32:12.413000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jan 19 12:32:12.414174 augenrules[1936]: No rules Jan 19 12:32:12.415345 systemd[1]: audit-rules.service: Deactivated successfully. Jan 19 12:32:12.415763 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jan 19 12:32:12.920405 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jan 19 12:32:12.926163 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). Jan 19 12:32:13.032441 systemd-networkd[1673]: eth0: Gained IPv6LL Jan 19 12:32:13.034879 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jan 19 12:32:13.042687 systemd[1]: Reached target network-online.target - Network is Online. Jan 19 12:32:17.385596 ldconfig[1891]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jan 19 12:32:17.401395 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jan 19 12:32:17.410076 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jan 19 12:32:17.424573 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jan 19 12:32:17.429512 systemd[1]: Reached target sysinit.target - System Initialization. Jan 19 12:32:17.434224 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jan 19 12:32:17.439537 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jan 19 12:32:17.444923 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jan 19 12:32:17.449859 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jan 19 12:32:17.455359 systemd[1]: Started systemd-sysupdate-reboot.timer - Reboot Automatically After System Update. Jan 19 12:32:17.460601 systemd[1]: Started systemd-sysupdate.timer - Automatic System Update. Jan 19 12:32:17.465471 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jan 19 12:32:17.470805 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jan 19 12:32:17.470830 systemd[1]: Reached target paths.target - Path Units. Jan 19 12:32:17.474702 systemd[1]: Reached target timers.target - Timer Units. Jan 19 12:32:17.479702 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jan 19 12:32:17.486335 systemd[1]: Starting docker.socket - Docker Socket for the API... Jan 19 12:32:17.491967 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jan 19 12:32:17.497752 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jan 19 12:32:17.503186 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jan 19 12:32:17.509789 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jan 19 12:32:17.514892 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jan 19 12:32:17.520817 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jan 19 12:32:17.525602 systemd[1]: Reached target sockets.target - Socket Units. Jan 19 12:32:17.529995 systemd[1]: Reached target basic.target - Basic System. Jan 19 12:32:17.534016 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jan 19 12:32:17.534036 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jan 19 12:32:17.536378 systemd[1]: Starting chronyd.service - NTP client/server... Jan 19 12:32:17.549415 systemd[1]: Starting containerd.service - containerd container runtime... Jan 19 12:32:17.555082 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jan 19 12:32:17.563500 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jan 19 12:32:17.573378 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jan 19 12:32:17.577482 chronyd[1949]: chronyd version 4.8 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Jan 19 12:32:17.580240 chronyd[1949]: Timezone right/UTC failed leap second check, ignoring Jan 19 12:32:17.580719 chronyd[1949]: Loaded seccomp filter (level 2) Jan 19 12:32:17.591348 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jan 19 12:32:17.599298 jq[1957]: false Jan 19 12:32:17.598797 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jan 19 12:32:17.604540 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). Jan 19 12:32:17.605850 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jan 19 12:32:17.610777 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jan 19 12:32:17.617881 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jan 19 12:32:17.624905 KVP[1959]: KVP starting; pid is:1959 Jan 19 12:32:17.625451 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jan 19 12:32:17.632675 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jan 19 12:32:17.641761 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jan 19 12:32:17.653721 kernel: hv_utils: KVP IC version 4.0 Jan 19 12:32:17.652285 systemd[1]: Starting systemd-logind.service - User Login Management... Jan 19 12:32:17.653932 KVP[1959]: KVP LIC Version: 3.1 Jan 19 12:32:17.658682 extend-filesystems[1958]: Found /dev/sda6 Jan 19 12:32:17.660222 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jan 19 12:32:17.660723 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jan 19 12:32:17.664389 systemd[1]: Starting update-engine.service - Update Engine... Jan 19 12:32:17.673439 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jan 19 12:32:17.679335 systemd[1]: Started chronyd.service - NTP client/server. Jan 19 12:32:17.680025 extend-filesystems[1958]: Found /dev/sda9 Jan 19 12:32:17.689595 extend-filesystems[1958]: Checking size of /dev/sda9 Jan 19 12:32:17.686713 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jan 19 12:32:17.698994 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jan 19 12:32:17.703166 jq[1980]: true Jan 19 12:32:17.708081 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jan 19 12:32:17.708510 systemd[1]: motdgen.service: Deactivated successfully. Jan 19 12:32:17.708702 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jan 19 12:32:17.716926 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jan 19 12:32:17.718196 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jan 19 12:32:17.738137 extend-filesystems[1958]: Resized partition /dev/sda9 Jan 19 12:32:17.753703 jq[1993]: true Jan 19 12:32:17.758310 update_engine[1977]: I20260119 12:32:17.756779 1977 main.cc:92] Flatcar Update Engine starting Jan 19 12:32:17.763389 extend-filesystems[2002]: resize2fs 1.47.3 (8-Jul-2025) Jan 19 12:32:17.777197 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jan 19 12:32:17.815206 systemd-logind[1970]: New seat seat0. Jan 19 12:32:17.817122 systemd-logind[1970]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Jan 19 12:32:17.818861 systemd[1]: Started systemd-logind.service - User Login Management. Jan 19 12:32:17.838534 kernel: EXT4-fs (sda9): resizing filesystem from 6359552 to 6376955 blocks Jan 19 12:32:17.838647 kernel: EXT4-fs (sda9): resized filesystem to 6376955 Jan 19 12:32:17.888454 extend-filesystems[2002]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Jan 19 12:32:17.888454 extend-filesystems[2002]: old_desc_blocks = 4, new_desc_blocks = 4 Jan 19 12:32:17.888454 extend-filesystems[2002]: The filesystem on /dev/sda9 is now 6376955 (4k) blocks long. Jan 19 12:32:17.940335 extend-filesystems[1958]: Resized filesystem in /dev/sda9 Jan 19 12:32:17.950564 bash[2044]: Updated "/home/core/.ssh/authorized_keys" Jan 19 12:32:17.897447 systemd[1]: extend-filesystems.service: Deactivated successfully. Jan 19 12:32:17.897732 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jan 19 12:32:17.944016 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jan 19 12:32:17.960126 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jan 19 12:32:17.963936 dbus-daemon[1952]: [system] SELinux support is enabled Jan 19 12:32:17.964209 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jan 19 12:32:17.973359 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). Jan 19 12:32:17.973391 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jan 19 12:32:17.980470 update_engine[1977]: I20260119 12:32:17.980415 1977 update_check_scheduler.cc:74] Next update check in 10m25s Jan 19 12:32:17.981610 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). Jan 19 12:32:17.981633 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jan 19 12:32:17.993833 dbus-daemon[1952]: [system] Successfully activated service 'org.freedesktop.systemd1' Jan 19 12:32:17.994614 systemd[1]: Started update-engine.service - Update Engine. Jan 19 12:32:18.005988 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jan 19 12:32:18.037380 sshd_keygen[1979]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jan 19 12:32:18.060802 coreos-metadata[1951]: Jan 19 12:32:18.060 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jan 19 12:32:18.063767 coreos-metadata[1951]: Jan 19 12:32:18.063 INFO Fetch successful Jan 19 12:32:18.063767 coreos-metadata[1951]: Jan 19 12:32:18.063 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jan 19 12:32:18.070339 coreos-metadata[1951]: Jan 19 12:32:18.069 INFO Fetch successful Jan 19 12:32:18.070339 coreos-metadata[1951]: Jan 19 12:32:18.070 INFO Fetching http://168.63.129.16/machine/6c02b117-54e3-4f82-933b-8ef2776cee94/ed9bd259%2Da79b%2D492d%2Da364%2Db9ba5fb2f84a.%5Fci%2D9999.9.100%2D4df6ab753d?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jan 19 12:32:18.070335 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jan 19 12:32:18.070900 coreos-metadata[1951]: Jan 19 12:32:18.070 INFO Fetch successful Jan 19 12:32:18.070900 coreos-metadata[1951]: Jan 19 12:32:18.070 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jan 19 12:32:18.078516 coreos-metadata[1951]: Jan 19 12:32:18.078 INFO Fetch successful Jan 19 12:32:18.078657 systemd[1]: Starting issuegen.service - Generate /run/issue... Jan 19 12:32:18.088604 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jan 19 12:32:18.097745 systemd[1]: issuegen.service: Deactivated successfully. Jan 19 12:32:18.099726 systemd[1]: Finished issuegen.service - Generate /run/issue. Jan 19 12:32:18.114564 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jan 19 12:32:18.132521 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jan 19 12:32:18.139978 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jan 19 12:32:18.146900 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jan 19 12:32:18.158511 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Jan 19 12:32:18.169392 systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 19 12:32:18.174722 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jan 19 12:32:18.177696 (-wrapper)[2131]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Jan 19 12:32:18.178648 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jan 19 12:32:18.186749 systemd[1]: Reached target getty.target - Login Prompts. Jan 19 12:32:18.241226 locksmithd[2102]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jan 19 12:32:18.254213 containerd[1994]: time="2026-01-19T12:32:18Z" 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 Jan 19 12:32:18.255164 containerd[1994]: time="2026-01-19T12:32:18.255123112Z" level=info msg="starting containerd" revision=fcd43222d6b07379a4be9786bda52438f0dd16a1 version=v2.1.5 Jan 19 12:32:18.260885 containerd[1994]: time="2026-01-19T12:32:18.260840328Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.904µs" Jan 19 12:32:18.260885 containerd[1994]: time="2026-01-19T12:32:18.260876200Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jan 19 12:32:18.260971 containerd[1994]: time="2026-01-19T12:32:18.260921240Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jan 19 12:32:18.260971 containerd[1994]: time="2026-01-19T12:32:18.260930120Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jan 19 12:32:18.261096 containerd[1994]: time="2026-01-19T12:32:18.261075240Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jan 19 12:32:18.261096 containerd[1994]: time="2026-01-19T12:32:18.261093320Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261147 containerd[1994]: time="2026-01-19T12:32:18.261133584Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261147 containerd[1994]: time="2026-01-19T12:32:18.261144600Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261364 containerd[1994]: time="2026-01-19T12:32:18.261343816Z" 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 Jan 19 12:32:18.261364 containerd[1994]: time="2026-01-19T12:32:18.261361480Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261389 containerd[1994]: time="2026-01-19T12:32:18.261369784Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261389 containerd[1994]: time="2026-01-19T12:32:18.261375544Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261538 containerd[1994]: time="2026-01-19T12:32:18.261520992Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261538 containerd[1994]: time="2026-01-19T12:32:18.261534848Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261606 containerd[1994]: time="2026-01-19T12:32:18.261592936Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261753 containerd[1994]: time="2026-01-19T12:32:18.261736344Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jan 19 12:32:18.261777 containerd[1994]: time="2026-01-19T12:32:18.261765080Z" 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 Jan 19 12:32:18.261795 containerd[1994]: time="2026-01-19T12:32:18.261778056Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jan 19 12:32:18.261810 containerd[1994]: time="2026-01-19T12:32:18.261803336Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jan 19 12:32:18.262293 containerd[1994]: time="2026-01-19T12:32:18.262096208Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jan 19 12:32:18.262293 containerd[1994]: time="2026-01-19T12:32:18.262181248Z" level=info msg="metadata content store policy set" policy=shared Jan 19 12:32:18.286101 containerd[1994]: time="2026-01-19T12:32:18.286042040Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jan 19 12:32:18.286218 containerd[1994]: time="2026-01-19T12:32:18.286124480Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Jan 19 12:32:18.286218 containerd[1994]: time="2026-01-19T12:32:18.286205144Z" level=info msg="skip loading plugin" error="could not find mkfs.erofs: exec: \"mkfs.erofs\": executable file not found in $PATH: skip plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Jan 19 12:32:18.286218 containerd[1994]: time="2026-01-19T12:32:18.286215184Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286225760Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286234464Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286245592Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286251840Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286271448Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286280240Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286289024Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286297248Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286306128Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jan 19 12:32:18.286330 containerd[1994]: time="2026-01-19T12:32:18.286318168Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286467080Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286483912Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286494912Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286502232Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286508816Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286515832Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286525544Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286532208Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286540416Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286547232Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jan 19 12:32:18.286549 containerd[1994]: time="2026-01-19T12:32:18.286553280Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jan 19 12:32:18.286839 containerd[1994]: time="2026-01-19T12:32:18.286577384Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jan 19 12:32:18.286839 containerd[1994]: time="2026-01-19T12:32:18.286610480Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jan 19 12:32:18.286839 containerd[1994]: time="2026-01-19T12:32:18.286620736Z" level=info msg="Start snapshots syncer" Jan 19 12:32:18.286839 containerd[1994]: time="2026-01-19T12:32:18.286646048Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jan 19 12:32:18.286897 containerd[1994]: time="2026-01-19T12:32:18.286858440Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Jan 19 12:32:18.287008 containerd[1994]: time="2026-01-19T12:32:18.286901912Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jan 19 12:32:18.287008 containerd[1994]: time="2026-01-19T12:32:18.286944536Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287061016Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287077936Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287085120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287091464Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287100432Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287107120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287113584Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287119896Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287131704Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287156080Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287169024Z" 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 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287174728Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287181584Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jan 19 12:32:18.287832 containerd[1994]: time="2026-01-19T12:32:18.287187072Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287196656Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287205248Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287218256Z" level=info msg="runtime interface created" Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287222080Z" level=info msg="created NRI interface" Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287227032Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287236704Z" level=info msg="Connect containerd service" Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287253200Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jan 19 12:32:18.288006 containerd[1994]: time="2026-01-19T12:32:18.287885928Z" 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" Jan 19 12:32:18.750723 containerd[1994]: time="2026-01-19T12:32:18.750660000Z" level=info msg="Start subscribing containerd event" Jan 19 12:32:18.750941 containerd[1994]: time="2026-01-19T12:32:18.750796256Z" level=info msg="Start recovering state" Jan 19 12:32:18.750941 containerd[1994]: time="2026-01-19T12:32:18.750844640Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jan 19 12:32:18.750941 containerd[1994]: time="2026-01-19T12:32:18.750892072Z" level=info msg=serving... address=/run/containerd/containerd.sock Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751037360Z" level=info msg="Start event monitor" Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751057784Z" level=info msg="Start cni network conf syncer for default" Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751075656Z" level=info msg="Start streaming server" Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751084304Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751089280Z" level=info msg="runtime interface starting up..." Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751093592Z" level=info msg="starting plugins..." Jan 19 12:32:18.751146 containerd[1994]: time="2026-01-19T12:32:18.751105560Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jan 19 12:32:18.756191 containerd[1994]: time="2026-01-19T12:32:18.751425072Z" level=info msg="containerd successfully booted in 0.497554s" Jan 19 12:32:18.751624 systemd[1]: Started containerd.service - containerd container runtime. Jan 19 12:32:18.758827 systemd[1]: Starting docker.service - Docker Application Container Engine... Jan 19 12:32:18.773591 (dockerd)[2166]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jan 19 12:32:19.736140 waagent[2127]: 2026-01-19T12:32:19.736058Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jan 19 12:32:19.744720 waagent[2127]: 2026-01-19T12:32:19.740973Z INFO Daemon Daemon OS: flatcar 9999.9.100 Jan 19 12:32:19.745054 waagent[2127]: 2026-01-19T12:32:19.745017Z INFO Daemon Daemon Python: 3.11.13 Jan 19 12:32:19.750362 waagent[2127]: 2026-01-19T12:32:19.750314Z INFO Daemon Daemon Run daemon Jan 19 12:32:19.753935 waagent[2127]: 2026-01-19T12:32:19.753897Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='9999.9.100' Jan 19 12:32:19.761167 waagent[2127]: 2026-01-19T12:32:19.761111Z INFO Daemon Daemon Using waagent for provisioning Jan 19 12:32:19.765562 waagent[2127]: 2026-01-19T12:32:19.765516Z INFO Daemon Daemon Activate resource disk Jan 19 12:32:19.769340 waagent[2127]: 2026-01-19T12:32:19.769291Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jan 19 12:32:19.775942 dockerd[2166]: time="2026-01-19T12:32:19.769594544Z" level=info msg="Starting up" Jan 19 12:32:19.776636 dockerd[2166]: time="2026-01-19T12:32:19.776594088Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Jan 19 12:32:19.779120 waagent[2127]: 2026-01-19T12:32:19.779062Z INFO Daemon Daemon Found device: None Jan 19 12:32:19.783101 waagent[2127]: 2026-01-19T12:32:19.783050Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Jan 19 12:32:19.789915 waagent[2127]: 2026-01-19T12:32:19.789856Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Jan 19 12:32:19.799529 dockerd[2166]: time="2026-01-19T12:32:19.799475200Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Jan 19 12:32:19.799820 waagent[2127]: 2026-01-19T12:32:19.799778Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 19 12:32:19.804494 waagent[2127]: 2026-01-19T12:32:19.804451Z INFO Daemon Daemon Running default provisioning handler Jan 19 12:32:19.814236 waagent[2127]: 2026-01-19T12:32:19.814185Z 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. Jan 19 12:32:19.825853 waagent[2127]: 2026-01-19T12:32:19.825797Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jan 19 12:32:19.839931 waagent[2127]: 2026-01-19T12:32:19.839866Z INFO Daemon Daemon cloud-init is enabled: False Jan 19 12:32:19.844045 waagent[2127]: 2026-01-19T12:32:19.844003Z INFO Daemon Daemon Copying ovf-env.xml Jan 19 12:32:19.906899 dockerd[2166]: time="2026-01-19T12:32:19.906681496Z" level=info msg="Loading containers: start." Jan 19 12:32:19.924551 waagent[2127]: 2026-01-19T12:32:19.924473Z INFO Daemon Daemon Successfully mounted dvd Jan 19 12:32:19.938557 kernel: Initializing XFRM netlink socket Jan 19 12:32:19.958399 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jan 19 12:32:19.961318 waagent[2127]: 2026-01-19T12:32:19.960449Z INFO Daemon Daemon Detect protocol endpoint Jan 19 12:32:19.964404 waagent[2127]: 2026-01-19T12:32:19.964354Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jan 19 12:32:19.969203 waagent[2127]: 2026-01-19T12:32:19.969157Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jan 19 12:32:19.974772 waagent[2127]: 2026-01-19T12:32:19.974712Z INFO Daemon Daemon Test for route to 168.63.129.16 Jan 19 12:32:19.979207 waagent[2127]: 2026-01-19T12:32:19.979158Z INFO Daemon Daemon Route to 168.63.129.16 exists Jan 19 12:32:19.983572 waagent[2127]: 2026-01-19T12:32:19.983524Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jan 19 12:32:19.996375 waagent[2127]: 2026-01-19T12:32:19.996291Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jan 19 12:32:20.001534 waagent[2127]: 2026-01-19T12:32:20.001508Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jan 19 12:32:20.005618 waagent[2127]: 2026-01-19T12:32:20.005587Z INFO Daemon Daemon Server preferred version:2015-04-05 Jan 19 12:32:20.090281 waagent[2127]: 2026-01-19T12:32:20.087728Z INFO Daemon Daemon Initializing goal state during protocol detection Jan 19 12:32:20.094232 waagent[2127]: 2026-01-19T12:32:20.093491Z INFO Daemon Daemon Forcing an update of the goal state. Jan 19 12:32:20.101206 waagent[2127]: 2026-01-19T12:32:20.101157Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 19 12:32:20.119401 waagent[2127]: 2026-01-19T12:32:20.119361Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Jan 19 12:32:20.125409 waagent[2127]: 2026-01-19T12:32:20.124698Z INFO Daemon Jan 19 12:32:20.127094 waagent[2127]: 2026-01-19T12:32:20.127056Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 7efdc8bd-150c-415c-9f9f-d351a204e0fa eTag: 2760982678866706999 source: Fabric] Jan 19 12:32:20.136341 waagent[2127]: 2026-01-19T12:32:20.136291Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jan 19 12:32:20.141942 waagent[2127]: 2026-01-19T12:32:20.141907Z INFO Daemon Jan 19 12:32:20.144582 waagent[2127]: 2026-01-19T12:32:20.144546Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jan 19 12:32:20.153504 waagent[2127]: 2026-01-19T12:32:20.153466Z INFO Daemon Daemon Downloading artifacts profile blob Jan 19 12:32:20.218295 waagent[2127]: 2026-01-19T12:32:20.217664Z INFO Daemon Downloaded certificate {'thumbprint': '1CB6E32E7635ED7BA23A8E5D6DB0289D37270B94', 'hasPrivateKey': True} Jan 19 12:32:20.226635 waagent[2127]: 2026-01-19T12:32:20.226585Z INFO Daemon Fetch goal state completed Jan 19 12:32:20.236653 waagent[2127]: 2026-01-19T12:32:20.236598Z INFO Daemon Daemon Starting provisioning Jan 19 12:32:20.240354 waagent[2127]: 2026-01-19T12:32:20.240317Z INFO Daemon Daemon Handle ovf-env.xml. Jan 19 12:32:20.245230 waagent[2127]: 2026-01-19T12:32:20.245170Z INFO Daemon Daemon Set hostname [ci-9999.9.100-4df6ab753d] Jan 19 12:32:20.251508 waagent[2127]: 2026-01-19T12:32:20.251450Z INFO Daemon Daemon Publish hostname [ci-9999.9.100-4df6ab753d] Jan 19 12:32:20.256248 waagent[2127]: 2026-01-19T12:32:20.256201Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jan 19 12:32:20.265499 waagent[2127]: 2026-01-19T12:32:20.261703Z INFO Daemon Daemon Primary interface is [eth0] Jan 19 12:32:20.272616 systemd-networkd[1673]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Jan 19 12:32:20.272909 systemd-networkd[1673]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Jan 19 12:32:20.273065 systemd-networkd[1673]: eth0: DHCP lease lost Jan 19 12:32:20.286171 waagent[2127]: 2026-01-19T12:32:20.286097Z INFO Daemon Daemon Create user account if not exists Jan 19 12:32:20.290714 waagent[2127]: 2026-01-19T12:32:20.290654Z INFO Daemon Daemon User core already exists, skip useradd Jan 19 12:32:20.295533 waagent[2127]: 2026-01-19T12:32:20.295472Z INFO Daemon Daemon Configure sudoer Jan 19 12:32:20.300367 systemd-networkd[1673]: eth0: DHCPv4 address 10.200.20.31/24, gateway 10.200.20.1 acquired from 168.63.129.16 Jan 19 12:32:20.306321 waagent[2127]: 2026-01-19T12:32:20.303311Z INFO Daemon Daemon Configure sshd Jan 19 12:32:20.311601 waagent[2127]: 2026-01-19T12:32:20.311534Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jan 19 12:32:20.322300 waagent[2127]: 2026-01-19T12:32:20.322205Z INFO Daemon Daemon Deploy ssh public key. Jan 19 12:32:20.598523 systemd-networkd[1673]: docker0: Link UP Jan 19 12:32:20.616745 dockerd[2166]: time="2026-01-19T12:32:20.616697176Z" level=info msg="Loading containers: done." Jan 19 12:32:20.643732 dockerd[2166]: time="2026-01-19T12:32:20.643672920Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jan 19 12:32:20.643903 dockerd[2166]: time="2026-01-19T12:32:20.643782016Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Jan 19 12:32:20.643903 dockerd[2166]: time="2026-01-19T12:32:20.643895040Z" level=info msg="Initializing buildkit" Jan 19 12:32:20.703902 dockerd[2166]: time="2026-01-19T12:32:20.703853408Z" level=info msg="Completed buildkit initialization" Jan 19 12:32:20.706960 dockerd[2166]: time="2026-01-19T12:32:20.706924304Z" level=info msg="Daemon has completed initialization" Jan 19 12:32:20.707022 dockerd[2166]: time="2026-01-19T12:32:20.706972200Z" level=info msg="API listen on /run/docker.sock" Jan 19 12:32:20.707470 systemd[1]: Started docker.service - Docker Application Container Engine. Jan 19 12:32:20.712437 etcd-wrapper[2140]: Error response from daemon: No such container: etcd-member Jan 19 12:32:20.724929 etcd-wrapper[2383]: Error response from daemon: No such container: etcd-member Jan 19 12:32:20.865499 etcd-wrapper[2402]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally Jan 19 12:32:21.418805 waagent[2127]: 2026-01-19T12:32:21.418758Z INFO Daemon Daemon Provisioning complete Jan 19 12:32:21.430655 waagent[2127]: 2026-01-19T12:32:21.430615Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jan 19 12:32:21.435749 waagent[2127]: 2026-01-19T12:32:21.435706Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jan 19 12:32:21.443628 waagent[2127]: 2026-01-19T12:32:21.443589Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jan 19 12:32:21.548296 waagent[2409]: 2026-01-19T12:32:21.547967Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jan 19 12:32:21.548296 waagent[2409]: 2026-01-19T12:32:21.548107Z INFO ExtHandler ExtHandler OS: flatcar 9999.9.100 Jan 19 12:32:21.548296 waagent[2409]: 2026-01-19T12:32:21.548147Z INFO ExtHandler ExtHandler Python: 3.11.13 Jan 19 12:32:21.548296 waagent[2409]: 2026-01-19T12:32:21.548183Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Jan 19 12:32:21.570288 waagent[2409]: 2026-01-19T12:32:21.568453Z INFO ExtHandler ExtHandler Distro: flatcar-9999.9.100; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jan 19 12:32:21.570288 waagent[2409]: 2026-01-19T12:32:21.568656Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 19 12:32:21.570288 waagent[2409]: 2026-01-19T12:32:21.568706Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 19 12:32:21.574287 waagent[2409]: 2026-01-19T12:32:21.574208Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jan 19 12:32:21.579174 waagent[2409]: 2026-01-19T12:32:21.579139Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Jan 19 12:32:21.579721 waagent[2409]: 2026-01-19T12:32:21.579689Z INFO ExtHandler Jan 19 12:32:21.579944 waagent[2409]: 2026-01-19T12:32:21.579914Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: a1b584f3-e11a-4113-b995-1f8f92a7747e eTag: 2760982678866706999 source: Fabric] Jan 19 12:32:21.580307 waagent[2409]: 2026-01-19T12:32:21.580254Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 19 12:32:21.580846 waagent[2409]: 2026-01-19T12:32:21.580812Z INFO ExtHandler Jan 19 12:32:21.580972 waagent[2409]: 2026-01-19T12:32:21.580948Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jan 19 12:32:21.583972 waagent[2409]: 2026-01-19T12:32:21.583940Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 19 12:32:21.642239 waagent[2409]: 2026-01-19T12:32:21.642169Z INFO ExtHandler Downloaded certificate {'thumbprint': '1CB6E32E7635ED7BA23A8E5D6DB0289D37270B94', 'hasPrivateKey': True} Jan 19 12:32:21.642859 waagent[2409]: 2026-01-19T12:32:21.642823Z INFO ExtHandler Fetch goal state completed Jan 19 12:32:21.653517 waagent[2409]: 2026-01-19T12:32:21.653475Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.5.4 30 Sep 2025 (Library: OpenSSL 3.5.4 30 Sep 2025) Jan 19 12:32:21.657176 waagent[2409]: 2026-01-19T12:32:21.657134Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2409 Jan 19 12:32:21.657434 waagent[2409]: 2026-01-19T12:32:21.657400Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jan 19 12:32:21.657799 waagent[2409]: 2026-01-19T12:32:21.657766Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jan 19 12:32:21.659063 waagent[2409]: 2026-01-19T12:32:21.659021Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '9999.9.100', '', 'Flatcar Container Linux by Kinvolk'] Jan 19 12:32:21.659532 waagent[2409]: 2026-01-19T12:32:21.659495Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '9999.9.100', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jan 19 12:32:21.659754 waagent[2409]: 2026-01-19T12:32:21.659723Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jan 19 12:32:21.660303 waagent[2409]: 2026-01-19T12:32:21.660239Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jan 19 12:32:21.661697 waagent[2409]: 2026-01-19T12:32:21.661674Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jan 19 12:32:21.661919 waagent[2409]: 2026-01-19T12:32:21.661890Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jan 19 12:32:21.666438 waagent[2409]: 2026-01-19T12:32:21.666416Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jan 19 12:32:21.671235 systemd[1]: Reload requested from client PID 2424 ('systemctl') (unit waagent.service)... Jan 19 12:32:21.671251 systemd[1]: Reloading... Jan 19 12:32:21.753379 zram_generator::config[2473]: No configuration found. Jan 19 12:32:21.906208 systemd[1]: Reloading finished in 234 ms. Jan 19 12:32:21.932100 waagent[2409]: 2026-01-19T12:32:21.931977Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jan 19 12:32:21.932186 waagent[2409]: 2026-01-19T12:32:21.932120Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jan 19 12:32:21.935592 waagent[2409]: 2026-01-19T12:32:21.935533Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jan 19 12:32:21.935826 waagent[2409]: 2026-01-19T12:32:21.935793Z 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] Jan 19 12:32:21.936435 waagent[2409]: 2026-01-19T12:32:21.936382Z INFO ExtHandler ExtHandler Starting env monitor service. Jan 19 12:32:21.936554 waagent[2409]: 2026-01-19T12:32:21.936498Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 19 12:32:21.936677 waagent[2409]: 2026-01-19T12:32:21.936652Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 19 12:32:21.936856 waagent[2409]: 2026-01-19T12:32:21.936826Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jan 19 12:32:21.937183 waagent[2409]: 2026-01-19T12:32:21.937140Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jan 19 12:32:21.937362 waagent[2409]: 2026-01-19T12:32:21.937327Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jan 19 12:32:21.937362 waagent[2409]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jan 19 12:32:21.937362 waagent[2409]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Jan 19 12:32:21.937362 waagent[2409]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jan 19 12:32:21.937362 waagent[2409]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jan 19 12:32:21.937362 waagent[2409]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 19 12:32:21.937362 waagent[2409]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jan 19 12:32:21.937362 waagent[2409]: docker0 000011AC 00000000 0001 0 0 0 0000FFFF 0 0 0 Jan 19 12:32:21.937845 waagent[2409]: 2026-01-19T12:32:21.937812Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jan 19 12:32:21.937896 waagent[2409]: 2026-01-19T12:32:21.937867Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jan 19 12:32:21.937984 waagent[2409]: 2026-01-19T12:32:21.937955Z INFO EnvHandler ExtHandler Configure routes Jan 19 12:32:21.938019 waagent[2409]: 2026-01-19T12:32:21.938003Z INFO EnvHandler ExtHandler Gateway:None Jan 19 12:32:21.938046 waagent[2409]: 2026-01-19T12:32:21.938032Z INFO EnvHandler ExtHandler Routes:None Jan 19 12:32:21.938362 waagent[2409]: 2026-01-19T12:32:21.938335Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jan 19 12:32:21.938500 waagent[2409]: 2026-01-19T12:32:21.938447Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jan 19 12:32:21.938847 waagent[2409]: 2026-01-19T12:32:21.938810Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jan 19 12:32:21.938900 waagent[2409]: 2026-01-19T12:32:21.938889Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jan 19 12:32:21.939320 waagent[2409]: 2026-01-19T12:32:21.939226Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jan 19 12:32:21.944592 waagent[2409]: 2026-01-19T12:32:21.944549Z INFO ExtHandler ExtHandler Jan 19 12:32:21.944775 waagent[2409]: 2026-01-19T12:32:21.944746Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: c773a9db-22f9-4cf5-86b6-dbcb4aade7b5 correlation c60af9dd-32fd-4911-8750-7c18ebcf197e created: 2026-01-19T12:31:29.667969Z] Jan 19 12:32:21.945168 waagent[2409]: 2026-01-19T12:32:21.945131Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 19 12:32:21.945707 waagent[2409]: 2026-01-19T12:32:21.945671Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Jan 19 12:32:21.971210 waagent[2409]: 2026-01-19T12:32:21.971147Z 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 Jan 19 12:32:21.971210 waagent[2409]: Try `iptables -h' or 'iptables --help' for more information.) Jan 19 12:32:21.971637 waagent[2409]: 2026-01-19T12:32:21.971601Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: ABB372FA-3DCD-481E-B97D-C7131BF08F8D;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jan 19 12:32:21.989470 waagent[2409]: 2026-01-19T12:32:21.989389Z INFO MonitorHandler ExtHandler Network interfaces: Jan 19 12:32:21.989470 waagent[2409]: Executing ['ip', '-a', '-o', 'link']: Jan 19 12:32:21.989470 waagent[2409]: 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 Jan 19 12:32:21.989470 waagent[2409]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:bf:e1:9e brd ff:ff:ff:ff:ff:ff\ altname enx7ced8dbfe19e Jan 19 12:32:21.989470 waagent[2409]: 3: enP554s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:bf:e1:9e brd ff:ff:ff:ff:ff:ff\ altname enP554p0s2 Jan 19 12:32:21.989470 waagent[2409]: 4: docker0: mtu 1500 qdisc noqueue state DOWN mode DEFAULT group default \ link/ether 36:21:b0:2b:d8:cd brd ff:ff:ff:ff:ff:ff Jan 19 12:32:21.989470 waagent[2409]: Executing ['ip', '-4', '-a', '-o', 'address']: Jan 19 12:32:21.989470 waagent[2409]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jan 19 12:32:21.989470 waagent[2409]: 2: eth0 inet 10.200.20.31/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Jan 19 12:32:21.989470 waagent[2409]: 4: docker0 inet 172.17.0.1/16 brd 172.17.255.255 scope global docker0\ valid_lft forever preferred_lft forever Jan 19 12:32:21.989470 waagent[2409]: Executing ['ip', '-6', '-a', '-o', 'address']: Jan 19 12:32:21.989470 waagent[2409]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jan 19 12:32:21.989470 waagent[2409]: 2: eth0 inet6 fe80::7eed:8dff:febf:e19e/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jan 19 12:32:22.148270 etcd-wrapper[2402]: v3.5.22: Pulling from coreos/etcd Jan 19 12:32:22.418228 waagent[2409]: 2026-01-19T12:32:22.418156Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jan 19 12:32:22.418228 waagent[2409]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 19 12:32:22.418228 waagent[2409]: pkts bytes target prot opt in out source destination Jan 19 12:32:22.418228 waagent[2409]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 19 12:32:22.418228 waagent[2409]: pkts bytes target prot opt in out source destination Jan 19 12:32:22.418228 waagent[2409]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jan 19 12:32:22.418228 waagent[2409]: pkts bytes target prot opt in out source destination Jan 19 12:32:22.418228 waagent[2409]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 19 12:32:22.418228 waagent[2409]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 19 12:32:22.418228 waagent[2409]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 19 12:32:22.420745 waagent[2409]: 2026-01-19T12:32:22.420698Z INFO EnvHandler ExtHandler Current Firewall rules: Jan 19 12:32:22.420745 waagent[2409]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jan 19 12:32:22.420745 waagent[2409]: pkts bytes target prot opt in out source destination Jan 19 12:32:22.420745 waagent[2409]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jan 19 12:32:22.420745 waagent[2409]: pkts bytes target prot opt in out source destination Jan 19 12:32:22.420745 waagent[2409]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jan 19 12:32:22.420745 waagent[2409]: pkts bytes target prot opt in out source destination Jan 19 12:32:22.420745 waagent[2409]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jan 19 12:32:22.420745 waagent[2409]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jan 19 12:32:22.420745 waagent[2409]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jan 19 12:32:22.420945 waagent[2409]: 2026-01-19T12:32:22.420917Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jan 19 12:32:22.571213 etcd-wrapper[2402]: 6c170d3e2c27: Pulling fs layer Jan 19 12:32:22.571213 etcd-wrapper[2402]: 2ae710cd8bfe: Pulling fs layer Jan 19 12:32:22.571213 etcd-wrapper[2402]: d462aa345367: Pulling fs layer Jan 19 12:32:22.571213 etcd-wrapper[2402]: 0f8b424aa0b9: Pulling fs layer Jan 19 12:32:22.571688 etcd-wrapper[2402]: d557676654e5: Pulling fs layer Jan 19 12:32:22.571688 etcd-wrapper[2402]: c8022d07192e: Pulling fs layer Jan 19 12:32:22.571688 etcd-wrapper[2402]: d858cbc252ad: Pulling fs layer Jan 19 12:32:22.571688 etcd-wrapper[2402]: 1069fc2daed1: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: b40161cd83fc: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: 5318d93a3a65: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: 307c1adadb60: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: 43e901b84b4f: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: e248ce7059b2: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: d9c3ebce7174: Pulling fs layer Jan 19 12:32:22.571751 etcd-wrapper[2402]: 0f8b424aa0b9: Waiting Jan 19 12:32:22.571751 etcd-wrapper[2402]: d557676654e5: Waiting Jan 19 12:32:22.571751 etcd-wrapper[2402]: c8022d07192e: Waiting Jan 19 12:32:22.571751 etcd-wrapper[2402]: d858cbc252ad: Waiting Jan 19 12:32:22.571751 etcd-wrapper[2402]: 1069fc2daed1: Waiting Jan 19 12:32:22.571751 etcd-wrapper[2402]: b40161cd83fc: Waiting Jan 19 12:32:22.571914 etcd-wrapper[2402]: 5318d93a3a65: Waiting Jan 19 12:32:22.571914 etcd-wrapper[2402]: 307c1adadb60: Waiting Jan 19 12:32:22.571914 etcd-wrapper[2402]: 43e901b84b4f: Waiting Jan 19 12:32:22.571914 etcd-wrapper[2402]: e248ce7059b2: Waiting Jan 19 12:32:22.571914 etcd-wrapper[2402]: d9c3ebce7174: Waiting Jan 19 12:32:22.847149 etcd-wrapper[2402]: 2ae710cd8bfe: Verifying Checksum Jan 19 12:32:22.847149 etcd-wrapper[2402]: 2ae710cd8bfe: Download complete Jan 19 12:32:22.858534 etcd-wrapper[2402]: 6c170d3e2c27: Download complete Jan 19 12:32:22.876463 etcd-wrapper[2402]: d462aa345367: Verifying Checksum Jan 19 12:32:22.876565 etcd-wrapper[2402]: d462aa345367: Download complete Jan 19 12:32:22.915830 etcd-wrapper[2402]: 6c170d3e2c27: Pull complete Jan 19 12:32:22.963401 systemd[1]: var-lib-docker-overlay2-1a4beef25de19c557c98e252e07c2bbdcdcbde90b9845b3eb2613c7e6e2e50f9-merged.mount: Deactivated successfully. Jan 19 12:32:23.016235 etcd-wrapper[2402]: 2ae710cd8bfe: Pull complete Jan 19 12:32:23.085146 etcd-wrapper[2402]: 0f8b424aa0b9: Verifying Checksum Jan 19 12:32:23.085146 etcd-wrapper[2402]: 0f8b424aa0b9: Download complete Jan 19 12:32:23.105707 etcd-wrapper[2402]: c8022d07192e: Verifying Checksum Jan 19 12:32:23.105707 etcd-wrapper[2402]: c8022d07192e: Download complete Jan 19 12:32:23.126085 etcd-wrapper[2402]: d557676654e5: Verifying Checksum Jan 19 12:32:23.126204 etcd-wrapper[2402]: d557676654e5: Download complete Jan 19 12:32:23.337001 systemd[1]: var-lib-docker-overlay2-e7a12ba09a7dc4cdf3e06f70183e3b7ec6cd28955c7584b80c0ec16f8d3c4436-merged.mount: Deactivated successfully. Jan 19 12:32:23.375509 etcd-wrapper[2402]: b40161cd83fc: Verifying Checksum Jan 19 12:32:23.375509 etcd-wrapper[2402]: b40161cd83fc: Download complete Jan 19 12:32:23.401621 etcd-wrapper[2402]: d858cbc252ad: Verifying Checksum Jan 19 12:32:23.401621 etcd-wrapper[2402]: d858cbc252ad: Download complete Jan 19 12:32:23.511778 etcd-wrapper[2402]: 1069fc2daed1: Verifying Checksum Jan 19 12:32:23.512105 etcd-wrapper[2402]: 1069fc2daed1: Download complete Jan 19 12:32:23.640325 etcd-wrapper[2402]: 5318d93a3a65: Verifying Checksum Jan 19 12:32:23.640516 etcd-wrapper[2402]: 5318d93a3a65: Download complete Jan 19 12:32:23.677805 etcd-wrapper[2402]: 307c1adadb60: Verifying Checksum Jan 19 12:32:23.677805 etcd-wrapper[2402]: 307c1adadb60: Download complete Jan 19 12:32:23.903605 etcd-wrapper[2402]: 43e901b84b4f: Verifying Checksum Jan 19 12:32:23.903605 etcd-wrapper[2402]: 43e901b84b4f: Download complete Jan 19 12:32:23.983107 login[2136]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:23.983107 login[2135]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:23.991479 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jan 19 12:32:23.992663 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jan 19 12:32:24.001516 systemd-logind[1970]: New session 1 of user core. Jan 19 12:32:24.006874 systemd-logind[1970]: New session 2 of user core. Jan 19 12:32:24.047857 etcd-wrapper[2402]: d9c3ebce7174: Verifying Checksum Jan 19 12:32:24.048036 etcd-wrapper[2402]: d9c3ebce7174: Download complete Jan 19 12:32:24.081006 etcd-wrapper[2402]: e248ce7059b2: Verifying Checksum Jan 19 12:32:24.081169 etcd-wrapper[2402]: e248ce7059b2: Download complete Jan 19 12:32:24.241238 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jan 19 12:32:24.243930 systemd[1]: Starting user@500.service - User Manager for UID 500... Jan 19 12:32:24.258997 (systemd)[2574]: pam_unix(systemd-user:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:24.261516 systemd-logind[1970]: New session 3 of user core. Jan 19 12:32:25.350540 systemd[2574]: Queued start job for default target default.target. Jan 19 12:32:25.372211 systemd[2574]: Created slice app.slice - User Application Slice. Jan 19 12:32:25.372242 systemd[2574]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of User's Temporary Directories. Jan 19 12:32:25.372253 systemd[2574]: Reached target paths.target - Paths. Jan 19 12:32:25.372335 systemd[2574]: Reached target timers.target - Timers. Jan 19 12:32:25.373495 systemd[2574]: Starting dbus.socket - D-Bus User Message Bus Socket... Jan 19 12:32:25.374090 systemd[2574]: Starting systemd-tmpfiles-setup.service - Create User Files and Directories... Jan 19 12:32:25.383353 systemd[2574]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jan 19 12:32:25.383457 systemd[2574]: Reached target sockets.target - Sockets. Jan 19 12:32:25.384639 systemd[2574]: Finished systemd-tmpfiles-setup.service - Create User Files and Directories. Jan 19 12:32:25.384703 systemd[2574]: Reached target basic.target - Basic System. Jan 19 12:32:25.384749 systemd[2574]: Reached target default.target - Main User Target. Jan 19 12:32:25.384773 systemd[2574]: Startup finished in 1.118s. Jan 19 12:32:25.385219 systemd[1]: Started user@500.service - User Manager for UID 500. Jan 19 12:32:25.391108 systemd[1]: Started session-1.scope - Session 1 of User core. Jan 19 12:32:25.391800 systemd[1]: Started session-2.scope - Session 2 of User core. Jan 19 12:32:27.909152 etcd-wrapper[2402]: d462aa345367: Pull complete Jan 19 12:32:28.290940 systemd[1]: var-lib-docker-overlay2-9f93a02bb419ad31b182f1066035a43d93a1ca80c3ef250c8132242ce4685c02-merged.mount: Deactivated successfully. Jan 19 12:32:30.323820 etcd-wrapper[2402]: 0f8b424aa0b9: Pull complete Jan 19 12:32:30.686428 systemd[1]: var-lib-docker-overlay2-233c77d5a5f747232736211be0100ab257b7fa2a62a87a2d2662542461d5c840-merged.mount: Deactivated successfully. Jan 19 12:32:31.890648 etcd-wrapper[2402]: d557676654e5: Pull complete Jan 19 12:32:32.533132 systemd[1]: var-lib-docker-overlay2-246a7315279140ac5df4136fe0950065350ec93f27e2b8ad6b1a3b4cfb89ce86-merged.mount: Deactivated successfully. Jan 19 12:32:33.681042 etcd-wrapper[2402]: c8022d07192e: Pull complete Jan 19 12:32:34.279033 systemd[1]: var-lib-docker-overlay2-1432db5d0d639660e0779a2040f966dc4330c860a7a0d8e9432afa858ceb6e20-merged.mount: Deactivated successfully. Jan 19 12:32:35.775987 etcd-wrapper[2402]: d858cbc252ad: Pull complete Jan 19 12:32:35.979480 systemd[1]: var-lib-docker-overlay2-98079b1052a7e959232f3d79f00c0f3d906aa48d4fcff1386068c72ecdbd842e-merged.mount: Deactivated successfully. Jan 19 12:32:37.782627 etcd-wrapper[2402]: 1069fc2daed1: Pull complete Jan 19 12:32:38.180901 systemd[1]: var-lib-docker-overlay2-55354a55912dbd8a5548d632e1258a3c71f71105bca968985fa6b51529b773a5-merged.mount: Deactivated successfully. Jan 19 12:32:39.386331 etcd-wrapper[2402]: b40161cd83fc: Pull complete Jan 19 12:32:40.031252 systemd[1]: var-lib-docker-overlay2-1cd5916f0639ee455cec3de828a1f0bfb5ba8394bfc63cfe661ce90fc12d65fb-merged.mount: Deactivated successfully. Jan 19 12:32:41.367050 chronyd[1949]: Selected source PHC0 Jan 19 12:32:41.368224 etcd-wrapper[2402]: 5318d93a3a65: Pull complete Jan 19 12:32:41.984357 systemd[1]: var-lib-docker-overlay2-5c8ff79ff26b82cb262bbb85f62528a0cf51133a1b6f1c7729d63a9388dbb7ff-merged.mount: Deactivated successfully. Jan 19 12:32:43.283978 etcd-wrapper[2402]: 307c1adadb60: Pull complete Jan 19 12:32:43.416598 systemd[1]: var-lib-docker-overlay2-0c267020ff73f6ee50d49eeb9b74ca13103b45d31e161351afec1b1148a1fdd2-merged.mount: Deactivated successfully. Jan 19 12:32:43.517178 etcd-wrapper[2402]: 43e901b84b4f: Pull complete Jan 19 12:32:43.632355 systemd[1]: var-lib-docker-overlay2-df9c0dc9969b129682a4e3f1647dadc10e1c3eb0c830edbb2f8d6b9003381237-merged.mount: Deactivated successfully. Jan 19 12:32:43.773738 etcd-wrapper[2402]: e248ce7059b2: Pull complete Jan 19 12:32:43.938081 etcd-wrapper[2402]: d9c3ebce7174: Pull complete Jan 19 12:32:43.966922 etcd-wrapper[2402]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6 Jan 19 12:32:43.981302 etcd-wrapper[2402]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22 Jan 19 12:32:44.107313 containerd[1994]: time="2026-01-19T12:32:44.107247830Z" level=info msg="connecting to shim 2da7ce6443a738865b607cf81e21533369c26acd3c7ae8ad0048de71c0c84fa3" address="unix:///run/containerd/s/d4353a1f283aeeaedc5fec238c07d27b704809e2e5b3b51a4eb45658fafc259a" namespace=moby protocol=ttrpc version=3 Jan 19 12:32:44.128426 systemd[1]: Started docker-2da7ce6443a738865b607cf81e21533369c26acd3c7ae8ad0048de71c0c84fa3.scope - libcontainer container 2da7ce6443a738865b607cf81e21533369c26acd3c7ae8ad0048de71c0c84fa3. Jan 19 12:32:44.205628 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.205163Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Jan 19 12:32:44.205887 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.205362Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"ec56a0cd5c6c48a58bbfeaf070e35031"} Jan 19 12:32:44.206074 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.205919Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Jan 19 12:32:44.206074 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.205942Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"} Jan 19 12:32:44.206074 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.205948Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Jan 19 12:32:44.208572 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.205952Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Jan 19 12:32:44.208572 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.206391Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Jan 19 12:32:44.208572 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.206453Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.200.20.31:2379"]} Jan 19 12:32:44.208572 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.206574Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Jan 19 12:32:44.208572 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.206593Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Jan 19 12:32:44.209846 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.209146Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Jan 19 12:32:44.209846 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.209321Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.22","git-sha":"7862c85","go-version":"go1.23.11","go-os":"linux","go-arch":"arm64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"ec56a0cd5c6c48a58bbfeaf070e35031","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.200.20.31:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"ec56a0cd5c6c48a58bbfeaf070e35031=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Jan 19 12:32:44.215335 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.214702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.132472ms"} Jan 19 12:32:44.224867 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.224636Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Jan 19 12:32:44.225128 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.224991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Jan 19 12:32:44.225362 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.225237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Jan 19 12:32:44.225565 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.225453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Jan 19 12:32:44.225565 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.225477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Jan 19 12:32:44.225796 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.225690Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jan 19 12:32:44.239453 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:32:44.239224Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Jan 19 12:32:44.244014 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.243643Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Jan 19 12:32:44.244174 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.243816Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Jan 19 12:32:44.248013 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.247844Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Jan 19 12:32:44.252138 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.252002Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.22","cluster-version":"to_be_decided"} Jan 19 12:32:44.252605 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.252291Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Jan 19 12:32:44.252605 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.252329Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Jan 19 12:32:44.252605 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.252335Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Jan 19 12:32:44.254117 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.252900Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Jan 19 12:32:44.254117 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.253823Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.200.20.31:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Jan 19 12:32:44.254407 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.254275Z","caller":"etcdserver/server.go:759","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Jan 19 12:32:44.254830 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.254701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jan 19 12:32:44.255089 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.254968Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"],"added-peer-is-learner":false} Jan 19 12:32:44.255353 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.255225Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Jan 19 12:32:44.255353 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.255281Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Jan 19 12:32:44.316370 systemd[1]: var-lib-docker-overlay2-b652906db482d33252b4bada08cbde4237d4dd14a62ad0f18cf14837671ddf25-merged.mount: Deactivated successfully. Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826540Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826670Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Jan 19 12:32:44.826994 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.826675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Jan 19 12:32:44.829468 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.829277Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Jan 19 12:32:44.831344 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.831106Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:ec56a0cd5c6c48a58bbfeaf070e35031 ClientURLs:[http://10.200.20.31:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Jan 19 12:32:44.831476 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.831332Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Jan 19 12:32:44.831596 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.831491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Jan 19 12:32:44.831817 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.831614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Jan 19 12:32:44.832115 systemd[1]: Started etcd-member.service - etcd (System Application Container). Jan 19 12:32:44.832401 systemd[1]: Reached target multi-user.target - Multi-User System. Jan 19 12:32:44.832589 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.831897Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Jan 19 12:32:44.832866 systemd[1]: Startup finished in 2.754s (kernel) + 10.555s (initrd) + 38.489s (userspace) = 51.799s. Jan 19 12:32:44.833057 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.832754Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Jan 19 12:32:44.833888 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.833519Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Jan 19 12:32:44.833888 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.833600Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Jan 19 12:32:44.833888 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:32:44.833631Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} Jan 19 12:32:45.755316 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jan 19 12:32:45.756733 systemd[1]: Started sshd@0-10.200.20.31:22-10.200.16.10:52492.service - OpenSSH per-connection server daemon (10.200.16.10:52492). Jan 19 12:32:46.283465 sshd[2714]: Accepted publickey for core from 10.200.16.10 port 52492 ssh2: RSA SHA256:YfHuiiYdHzU81Tqx2aDxx30pW2WazR1LBTwMbRuqYcY Jan 19 12:32:46.285016 sshd-session[2714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:46.289337 systemd-logind[1970]: New session 4 of user core. Jan 19 12:32:46.297431 systemd[1]: Started session-4.scope - Session 4 of User core. Jan 19 12:32:46.578083 systemd[1]: Started sshd@1-10.200.20.31:22-10.200.16.10:52496.service - OpenSSH per-connection server daemon (10.200.16.10:52496). Jan 19 12:32:46.968379 sshd[2721]: Accepted publickey for core from 10.200.16.10 port 52496 ssh2: RSA SHA256:YfHuiiYdHzU81Tqx2aDxx30pW2WazR1LBTwMbRuqYcY Jan 19 12:32:46.969523 sshd-session[2721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:46.973443 systemd-logind[1970]: New session 5 of user core. Jan 19 12:32:46.983402 systemd[1]: Started session-5.scope - Session 5 of User core. Jan 19 12:32:47.183278 sshd[2725]: Connection closed by 10.200.16.10 port 52496 Jan 19 12:32:47.182867 sshd-session[2721]: pam_unix(sshd:session): session closed for user core Jan 19 12:32:47.185689 systemd-logind[1970]: Session 5 logged out. Waiting for processes to exit. Jan 19 12:32:47.186177 systemd[1]: sshd@1-10.200.20.31:22-10.200.16.10:52496.service: Deactivated successfully. Jan 19 12:32:47.187684 systemd[1]: session-5.scope: Deactivated successfully. Jan 19 12:32:47.189620 systemd-logind[1970]: Removed session 5. Jan 19 12:32:47.274910 systemd[1]: Started sshd@2-10.200.20.31:22-10.200.16.10:52498.service - OpenSSH per-connection server daemon (10.200.16.10:52498). Jan 19 12:32:47.672733 sshd[2731]: Accepted publickey for core from 10.200.16.10 port 52498 ssh2: RSA SHA256:YfHuiiYdHzU81Tqx2aDxx30pW2WazR1LBTwMbRuqYcY Jan 19 12:32:47.673879 sshd-session[2731]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:47.678301 systemd-logind[1970]: New session 6 of user core. Jan 19 12:32:47.684405 systemd[1]: Started session-6.scope - Session 6 of User core. Jan 19 12:32:47.884863 sshd[2735]: Connection closed by 10.200.16.10 port 52498 Jan 19 12:32:47.885424 sshd-session[2731]: pam_unix(sshd:session): session closed for user core Jan 19 12:32:47.888786 systemd[1]: sshd@2-10.200.20.31:22-10.200.16.10:52498.service: Deactivated successfully. Jan 19 12:32:47.890314 systemd[1]: session-6.scope: Deactivated successfully. Jan 19 12:32:47.890971 systemd-logind[1970]: Session 6 logged out. Waiting for processes to exit. Jan 19 12:32:47.892203 systemd-logind[1970]: Removed session 6. Jan 19 12:32:47.981295 systemd[1]: Started sshd@3-10.200.20.31:22-10.200.16.10:52500.service - OpenSSH per-connection server daemon (10.200.16.10:52500). Jan 19 12:32:48.399048 sshd[2741]: Accepted publickey for core from 10.200.16.10 port 52500 ssh2: RSA SHA256:YfHuiiYdHzU81Tqx2aDxx30pW2WazR1LBTwMbRuqYcY Jan 19 12:32:48.399862 sshd-session[2741]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:48.403625 systemd-logind[1970]: New session 7 of user core. Jan 19 12:32:48.414555 systemd[1]: Started session-7.scope - Session 7 of User core. Jan 19 12:32:48.632977 sshd[2745]: Connection closed by 10.200.16.10 port 52500 Jan 19 12:32:48.632357 sshd-session[2741]: pam_unix(sshd:session): session closed for user core Jan 19 12:32:48.635962 systemd[1]: sshd@3-10.200.20.31:22-10.200.16.10:52500.service: Deactivated successfully. Jan 19 12:32:48.637763 systemd[1]: session-7.scope: Deactivated successfully. Jan 19 12:32:48.639145 systemd-logind[1970]: Session 7 logged out. Waiting for processes to exit. Jan 19 12:32:48.640545 systemd-logind[1970]: Removed session 7. Jan 19 12:32:48.714276 systemd[1]: Started sshd@4-10.200.20.31:22-10.200.16.10:52502.service - OpenSSH per-connection server daemon (10.200.16.10:52502). Jan 19 12:32:49.103928 sshd[2751]: Accepted publickey for core from 10.200.16.10 port 52502 ssh2: RSA SHA256:YfHuiiYdHzU81Tqx2aDxx30pW2WazR1LBTwMbRuqYcY Jan 19 12:32:49.104730 sshd-session[2751]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:49.108411 systemd-logind[1970]: New session 8 of user core. Jan 19 12:32:49.115425 systemd[1]: Started session-8.scope - Session 8 of User core. Jan 19 12:32:49.317549 sshd[2755]: Connection closed by 10.200.16.10 port 52502 Jan 19 12:32:49.317454 sshd-session[2751]: pam_unix(sshd:session): session closed for user core Jan 19 12:32:49.323337 systemd[1]: sshd@4-10.200.20.31:22-10.200.16.10:52502.service: Deactivated successfully. Jan 19 12:32:49.325227 systemd[1]: session-8.scope: Deactivated successfully. Jan 19 12:32:49.326378 systemd-logind[1970]: Session 8 logged out. Waiting for processes to exit. Jan 19 12:32:49.327953 systemd-logind[1970]: Removed session 8. Jan 19 12:32:49.407017 systemd[1]: Started sshd@5-10.200.20.31:22-10.200.16.10:52516.service - OpenSSH per-connection server daemon (10.200.16.10:52516). Jan 19 12:32:49.801727 sshd[2763]: Accepted publickey for core from 10.200.16.10 port 52516 ssh2: RSA SHA256:YfHuiiYdHzU81Tqx2aDxx30pW2WazR1LBTwMbRuqYcY Jan 19 12:32:49.802680 sshd-session[2763]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jan 19 12:32:49.807389 systemd-logind[1970]: New session 9 of user core. Jan 19 12:32:49.813412 systemd[1]: Started session-9.scope - Session 9 of User core. Jan 19 12:32:50.429288 sshd[2767]: Connection closed by 10.200.16.10 port 52516 Jan 19 12:32:50.429826 sshd-session[2763]: pam_unix(sshd:session): session closed for user core Jan 19 12:32:50.433929 systemd[1]: sshd@5-10.200.20.31:22-10.200.16.10:52516.service: Deactivated successfully. Jan 19 12:32:50.435381 systemd[1]: session-9.scope: Deactivated successfully. Jan 19 12:32:50.436548 systemd-logind[1970]: Session 9 logged out. Waiting for processes to exit. Jan 19 12:32:50.437484 systemd-logind[1970]: Removed session 9. Jan 19 12:32:59.478375 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Jan 19 12:33:03.038101 update_engine[1977]: I20260119 12:33:03.037567 1977 update_attempter.cc:509] Updating boot flags... Jan 19 12:33:40.161151 waagent[2409]: 2026-01-19T12:33:40.160438Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Jan 19 12:33:40.169477 waagent[2409]: 2026-01-19T12:33:40.169442Z INFO ExtHandler Jan 19 12:33:40.169725 waagent[2409]: 2026-01-19T12:33:40.169698Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: cdf05c6f-b25c-4e14-a4cf-0e3201be589a eTag: 6990075351368733796 source: Fabric] Jan 19 12:33:40.170143 waagent[2409]: 2026-01-19T12:33:40.170112Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jan 19 12:33:40.170820 waagent[2409]: 2026-01-19T12:33:40.170784Z INFO ExtHandler Jan 19 12:33:40.170967 waagent[2409]: 2026-01-19T12:33:40.170943Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Jan 19 12:33:40.230166 waagent[2409]: 2026-01-19T12:33:40.230068Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jan 19 12:33:40.283297 waagent[2409]: 2026-01-19T12:33:40.282691Z INFO ExtHandler Downloaded certificate {'thumbprint': '1CB6E32E7635ED7BA23A8E5D6DB0289D37270B94', 'hasPrivateKey': True} Jan 19 12:33:40.283297 waagent[2409]: 2026-01-19T12:33:40.283141Z INFO ExtHandler Fetch goal state completed Jan 19 12:33:40.283597 waagent[2409]: 2026-01-19T12:33:40.283555Z INFO ExtHandler ExtHandler Jan 19 12:33:40.283654 waagent[2409]: 2026-01-19T12:33:40.283632Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 204bf1f8-c779-4ea2-8a52-41c27a47cbde correlation c60af9dd-32fd-4911-8750-7c18ebcf197e created: 2026-01-19T12:33:33.184639Z] Jan 19 12:33:40.283898 waagent[2409]: 2026-01-19T12:33:40.283870Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jan 19 12:33:40.284349 waagent[2409]: 2026-01-19T12:33:40.284320Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 0 ms] Jan 19 12:35:09.713118 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:09.712474Z","caller":"traceutil/trace.go:171","msg":"trace[1788189848] linearizableReadLoop","detail":"{readStateIndex:19; appliedIndex:17; }","duration":"102.482545ms","start":"2026-01-19T12:35:09.609975Z","end":"2026-01-19T12:35:09.712458Z","steps":["trace[1788189848] 'read index received' (duration: 55.505435ms)","trace[1788189848] 'applied index is now lower than readState.Index' (duration: 46.976014ms)"],"step_count":2} Jan 19 12:35:09.713118 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:09.712555Z","caller":"traceutil/trace.go:171","msg":"trace[1836782929] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"106.522896ms","start":"2026-01-19T12:35:09.606027Z","end":"2026-01-19T12:35:09.712550Z","steps":["trace[1836782929] 'process raft request' (duration: 106.410516ms)"],"step_count":1} Jan 19 12:35:09.713118 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:35:09.712620Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.629575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests\" limit:1 ","response":"range_response_count:0 size:4"} Jan 19 12:35:09.713118 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:09.712490Z","caller":"traceutil/trace.go:171","msg":"trace[1894739674] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"106.477775ms","start":"2026-01-19T12:35:09.605998Z","end":"2026-01-19T12:35:09.712476Z","steps":["trace[1894739674] 'process raft request' (duration: 59.476864ms)","trace[1894739674] 'compare' (duration: 46.877354ms)"],"step_count":2} Jan 19 12:35:09.713762 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:09.712636Z","caller":"traceutil/trace.go:171","msg":"trace[617137265] range","detail":"{range_begin:/registry/certificatesigningrequests; range_end:; response_count:0; response_revision:15; }","duration":"102.65848ms","start":"2026-01-19T12:35:09.609973Z","end":"2026-01-19T12:35:09.712632Z","steps":["trace[617137265] 'agreement among raft nodes before linearized reading' (duration: 102.616111ms)"],"step_count":1} Jan 19 12:35:09.715061 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:09.714593Z","caller":"traceutil/trace.go:171","msg":"trace[1352477512] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"101.723309ms","start":"2026-01-19T12:35:09.612857Z","end":"2026-01-19T12:35:09.714581Z","steps":["trace[1352477512] 'process raft request' (duration: 101.489653ms)"],"step_count":1} Jan 19 12:35:09.719986 etcd-wrapper[2402]: {"level":"warn","ts":"2026-01-19T12:35:09.719780Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.272767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" limit:1 ","response":"range_response_count:0 size:4"} Jan 19 12:35:09.719986 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:09.719824Z","caller":"traceutil/trace.go:171","msg":"trace[1967609021] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:21; }","duration":"106.320977ms","start":"2026-01-19T12:35:09.613495Z","end":"2026-01-19T12:35:09.719816Z","steps":["trace[1967609021] 'agreement among raft nodes before linearized reading' (duration: 106.268559ms)"],"step_count":1} Jan 19 12:35:10.013681 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:10.012997Z","caller":"traceutil/trace.go:171","msg":"trace[1021314179] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"106.18409ms","start":"2026-01-19T12:35:09.906795Z","end":"2026-01-19T12:35:10.012979Z","steps":["trace[1021314179] 'process raft request' (duration: 59.891753ms)","trace[1021314179] 'compare' (duration: 46.162132ms)"],"step_count":2} Jan 19 12:35:10.013681 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:10.013033Z","caller":"traceutil/trace.go:171","msg":"trace[107820196] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"104.860577ms","start":"2026-01-19T12:35:09.908164Z","end":"2026-01-19T12:35:10.013025Z","steps":["trace[107820196] 'process raft request' (duration: 104.760245ms)"],"step_count":1} Jan 19 12:35:10.320889 etcd-wrapper[2402]: {"level":"info","ts":"2026-01-19T12:35:10.320183Z","caller":"traceutil/trace.go:171","msg":"trace[1426410676] transaction","detail":"{read_only:false; number_of_response:1; response_revision:64; }","duration":"105.853232ms","start":"2026-01-19T12:35:10.214311Z","end":"2026-01-19T12:35:10.320164Z","steps":["trace[1426410676] 'process raft request' (duration: 47.987027ms)","trace[1426410676] 'compare' (duration: 57.804018ms)"],"step_count":2}