Nov 4 04:14:20.926322 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Nov 4 04:14:20.926343 kernel: Linux version 6.12.54-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 Tue Nov 4 03:00:17 -00 2025 Nov 4 04:14:20.926349 kernel: KASLR enabled Nov 4 04:14:20.926353 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Nov 4 04:14:20.926358 kernel: printk: legacy bootconsole [pl11] enabled Nov 4 04:14:20.926362 kernel: efi: EFI v2.7 by EDK II Nov 4 04:14:20.926368 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e89c018 RNG=0x3f979998 MEMRESERVE=0x3db83598 Nov 4 04:14:20.926372 kernel: random: crng init done Nov 4 04:14:20.926376 kernel: secureboot: Secure boot disabled Nov 4 04:14:20.926380 kernel: ACPI: Early table checksum verification disabled Nov 4 04:14:20.926384 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Nov 4 04:14:20.926389 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926393 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926398 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Nov 4 04:14:20.926403 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926408 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926413 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926418 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926423 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926427 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926432 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Nov 4 04:14:20.926436 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 04:14:20.926440 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Nov 4 04:14:20.926445 kernel: ACPI: Use ACPI SPCR as default console: No Nov 4 04:14:20.926449 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 4 04:14:20.926454 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Nov 4 04:14:20.926458 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Nov 4 04:14:20.926463 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Nov 4 04:14:20.926468 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Nov 4 04:14:20.926472 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Nov 4 04:14:20.926476 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Nov 4 04:14:20.926481 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Nov 4 04:14:20.926485 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Nov 4 04:14:20.926490 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Nov 4 04:14:20.926494 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Nov 4 04:14:20.926498 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Nov 4 04:14:20.926503 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Nov 4 04:14:20.926507 kernel: NODE_DATA(0) allocated [mem 0x1bf7ffa00-0x1bf806fff] Nov 4 04:14:20.926512 kernel: Zone ranges: Nov 4 04:14:20.926517 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Nov 4 04:14:20.926523 kernel: DMA32 empty Nov 4 04:14:20.926528 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Nov 4 04:14:20.926533 kernel: Device empty Nov 4 04:14:20.926538 kernel: Movable zone start for each node Nov 4 04:14:20.926543 kernel: Early memory node ranges Nov 4 04:14:20.926548 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Nov 4 04:14:20.926552 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Nov 4 04:14:20.926557 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Nov 4 04:14:20.926562 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Nov 4 04:14:20.926566 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Nov 4 04:14:20.926571 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Nov 4 04:14:20.926575 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Nov 4 04:14:20.926581 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Nov 4 04:14:20.926586 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Nov 4 04:14:20.926590 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Nov 4 04:14:20.926595 kernel: psci: probing for conduit method from ACPI. Nov 4 04:14:20.926600 kernel: psci: PSCIv1.3 detected in firmware. Nov 4 04:14:20.926604 kernel: psci: Using standard PSCI v0.2 function IDs Nov 4 04:14:20.926609 kernel: psci: MIGRATE_INFO_TYPE not supported. Nov 4 04:14:20.926613 kernel: psci: SMC Calling Convention v1.4 Nov 4 04:14:20.926618 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Nov 4 04:14:20.926623 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Nov 4 04:14:20.926627 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 4 04:14:20.926632 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 4 04:14:20.926638 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 4 04:14:20.926642 kernel: Detected PIPT I-cache on CPU0 Nov 4 04:14:20.926647 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Nov 4 04:14:20.926652 kernel: CPU features: detected: GIC system register CPU interface Nov 4 04:14:20.926656 kernel: CPU features: detected: Spectre-v4 Nov 4 04:14:20.926661 kernel: CPU features: detected: Spectre-BHB Nov 4 04:14:20.926666 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 4 04:14:20.926670 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 4 04:14:20.926675 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Nov 4 04:14:20.926680 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 4 04:14:20.926685 kernel: alternatives: applying boot alternatives Nov 4 04:14:20.926691 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=184500f7d2eb6ab997bc068a700bccfd199e25e814087e9e73479b28edc9aa9c Nov 4 04:14:20.926696 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 4 04:14:20.926701 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 4 04:14:20.926705 kernel: Fallback order for Node 0: 0 Nov 4 04:14:20.926710 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Nov 4 04:14:20.926714 kernel: Policy zone: Normal Nov 4 04:14:20.926719 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 4 04:14:20.926724 kernel: software IO TLB: area num 2. Nov 4 04:14:20.926728 kernel: software IO TLB: mapped [mem 0x00000000373b0000-0x000000003b3b0000] (64MB) Nov 4 04:14:20.926733 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 4 04:14:20.926738 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 4 04:14:20.926744 kernel: rcu: RCU event tracing is enabled. Nov 4 04:14:20.926749 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 4 04:14:20.926753 kernel: Trampoline variant of Tasks RCU enabled. Nov 4 04:14:20.926758 kernel: Tracing variant of Tasks RCU enabled. Nov 4 04:14:20.926763 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 4 04:14:20.926768 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 4 04:14:20.926772 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 04:14:20.926777 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 04:14:20.926782 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 4 04:14:20.926786 kernel: GICv3: 960 SPIs implemented Nov 4 04:14:20.926792 kernel: GICv3: 0 Extended SPIs implemented Nov 4 04:14:20.926797 kernel: Root IRQ handler: gic_handle_irq Nov 4 04:14:20.926801 kernel: GICv3: GICv3 features: 16 PPIs, RSS Nov 4 04:14:20.926806 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Nov 4 04:14:20.926810 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Nov 4 04:14:20.926815 kernel: ITS: No ITS available, not enabling LPIs Nov 4 04:14:20.926820 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 4 04:14:20.926825 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Nov 4 04:14:20.926830 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 4 04:14:20.926834 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Nov 4 04:14:20.926839 kernel: Console: colour dummy device 80x25 Nov 4 04:14:20.926845 kernel: printk: legacy console [tty1] enabled Nov 4 04:14:20.926850 kernel: ACPI: Core revision 20240827 Nov 4 04:14:20.926855 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Nov 4 04:14:20.926861 kernel: pid_max: default: 32768 minimum: 301 Nov 4 04:14:20.926865 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 4 04:14:20.926870 kernel: landlock: Up and running. Nov 4 04:14:20.926875 kernel: SELinux: Initializing. Nov 4 04:14:20.926881 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 4 04:14:20.926886 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 4 04:14:20.926891 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Nov 4 04:14:20.926896 kernel: Hyper-V: Host Build 10.0.26102.1109-1-0 Nov 4 04:14:20.926904 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 4 04:14:20.926910 kernel: rcu: Hierarchical SRCU implementation. Nov 4 04:14:20.926915 kernel: rcu: Max phase no-delay instances is 400. Nov 4 04:14:20.926920 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 4 04:14:20.926925 kernel: Remapping and enabling EFI services. Nov 4 04:14:20.926931 kernel: smp: Bringing up secondary CPUs ... Nov 4 04:14:20.926936 kernel: Detected PIPT I-cache on CPU1 Nov 4 04:14:20.930754 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Nov 4 04:14:20.930763 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Nov 4 04:14:20.930770 kernel: smp: Brought up 1 node, 2 CPUs Nov 4 04:14:20.930776 kernel: SMP: Total of 2 processors activated. Nov 4 04:14:20.930781 kernel: CPU: All CPU(s) started at EL1 Nov 4 04:14:20.930786 kernel: CPU features: detected: 32-bit EL0 Support Nov 4 04:14:20.930792 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Nov 4 04:14:20.930797 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 4 04:14:20.930803 kernel: CPU features: detected: Common not Private translations Nov 4 04:14:20.930809 kernel: CPU features: detected: CRC32 instructions Nov 4 04:14:20.930814 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Nov 4 04:14:20.930819 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 4 04:14:20.930825 kernel: CPU features: detected: LSE atomic instructions Nov 4 04:14:20.930830 kernel: CPU features: detected: Privileged Access Never Nov 4 04:14:20.930835 kernel: CPU features: detected: Speculation barrier (SB) Nov 4 04:14:20.930840 kernel: CPU features: detected: TLB range maintenance instructions Nov 4 04:14:20.930846 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 4 04:14:20.930851 kernel: CPU features: detected: Scalable Vector Extension Nov 4 04:14:20.930857 kernel: alternatives: applying system-wide alternatives Nov 4 04:14:20.930862 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Nov 4 04:14:20.930867 kernel: SVE: maximum available vector length 16 bytes per vector Nov 4 04:14:20.930873 kernel: SVE: default vector length 16 bytes per vector Nov 4 04:14:20.930878 kernel: Memory: 3980156K/4194160K available (11136K kernel code, 2456K rwdata, 9084K rodata, 12288K init, 1038K bss, 192816K reserved, 16384K cma-reserved) Nov 4 04:14:20.930884 kernel: devtmpfs: initialized Nov 4 04:14:20.930890 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 4 04:14:20.930895 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 4 04:14:20.930900 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 4 04:14:20.930905 kernel: 0 pages in range for non-PLT usage Nov 4 04:14:20.930910 kernel: 515232 pages in range for PLT usage Nov 4 04:14:20.930916 kernel: pinctrl core: initialized pinctrl subsystem Nov 4 04:14:20.930921 kernel: SMBIOS 3.1.0 present. Nov 4 04:14:20.930927 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Nov 4 04:14:20.930932 kernel: DMI: Memory slots populated: 2/2 Nov 4 04:14:20.930937 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 4 04:14:20.930949 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 4 04:14:20.930954 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 4 04:14:20.930960 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 4 04:14:20.930965 kernel: audit: initializing netlink subsys (disabled) Nov 4 04:14:20.930971 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Nov 4 04:14:20.930976 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 4 04:14:20.930982 kernel: cpuidle: using governor menu Nov 4 04:14:20.930987 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 4 04:14:20.930992 kernel: ASID allocator initialised with 32768 entries Nov 4 04:14:20.930997 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 4 04:14:20.931002 kernel: Serial: AMBA PL011 UART driver Nov 4 04:14:20.931009 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 4 04:14:20.931014 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 4 04:14:20.931019 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 4 04:14:20.931024 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 4 04:14:20.931029 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 4 04:14:20.931035 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 4 04:14:20.931040 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 4 04:14:20.931046 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 4 04:14:20.931051 kernel: ACPI: Added _OSI(Module Device) Nov 4 04:14:20.931057 kernel: ACPI: Added _OSI(Processor Device) Nov 4 04:14:20.931062 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 4 04:14:20.931067 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 4 04:14:20.931072 kernel: ACPI: Interpreter enabled Nov 4 04:14:20.931077 kernel: ACPI: Using GIC for interrupt routing Nov 4 04:14:20.931084 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Nov 4 04:14:20.931089 kernel: printk: legacy console [ttyAMA0] enabled Nov 4 04:14:20.931094 kernel: printk: legacy bootconsole [pl11] disabled Nov 4 04:14:20.931099 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Nov 4 04:14:20.931104 kernel: ACPI: CPU0 has been hot-added Nov 4 04:14:20.931109 kernel: ACPI: CPU1 has been hot-added Nov 4 04:14:20.931115 kernel: iommu: Default domain type: Translated Nov 4 04:14:20.931120 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 4 04:14:20.931126 kernel: efivars: Registered efivars operations Nov 4 04:14:20.931131 kernel: vgaarb: loaded Nov 4 04:14:20.931136 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 4 04:14:20.931141 kernel: VFS: Disk quotas dquot_6.6.0 Nov 4 04:14:20.931146 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 4 04:14:20.931151 kernel: pnp: PnP ACPI init Nov 4 04:14:20.931157 kernel: pnp: PnP ACPI: found 0 devices Nov 4 04:14:20.931163 kernel: NET: Registered PF_INET protocol family Nov 4 04:14:20.931168 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 4 04:14:20.931173 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 4 04:14:20.931178 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 4 04:14:20.931184 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 4 04:14:20.931189 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 4 04:14:20.931195 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 4 04:14:20.931200 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 4 04:14:20.931205 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 4 04:14:20.931211 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 4 04:14:20.931216 kernel: PCI: CLS 0 bytes, default 64 Nov 4 04:14:20.931221 kernel: kvm [1]: HYP mode not available Nov 4 04:14:20.931226 kernel: Initialise system trusted keyrings Nov 4 04:14:20.931231 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 4 04:14:20.931238 kernel: Key type asymmetric registered Nov 4 04:14:20.931243 kernel: Asymmetric key parser 'x509' registered Nov 4 04:14:20.931248 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 4 04:14:20.931253 kernel: io scheduler mq-deadline registered Nov 4 04:14:20.931258 kernel: io scheduler kyber registered Nov 4 04:14:20.931263 kernel: io scheduler bfq registered Nov 4 04:14:20.931268 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 4 04:14:20.931274 kernel: thunder_xcv, ver 1.0 Nov 4 04:14:20.931280 kernel: thunder_bgx, ver 1.0 Nov 4 04:14:20.931289 kernel: nicpf, ver 1.0 Nov 4 04:14:20.931294 kernel: nicvf, ver 1.0 Nov 4 04:14:20.931451 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 4 04:14:20.931522 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-04T04:14:17 UTC (1762229657) Nov 4 04:14:20.931532 kernel: efifb: probing for efifb Nov 4 04:14:20.931537 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 4 04:14:20.931542 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 4 04:14:20.931547 kernel: efifb: scrolling: redraw Nov 4 04:14:20.931553 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 4 04:14:20.931558 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 04:14:20.931563 kernel: fb0: EFI VGA frame buffer device Nov 4 04:14:20.931569 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Nov 4 04:14:20.931575 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 4 04:14:20.931580 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Nov 4 04:14:20.931585 kernel: watchdog: NMI not fully supported Nov 4 04:14:20.931590 kernel: watchdog: Hard watchdog permanently disabled Nov 4 04:14:20.931596 kernel: NET: Registered PF_INET6 protocol family Nov 4 04:14:20.931601 kernel: Segment Routing with IPv6 Nov 4 04:14:20.931607 kernel: In-situ OAM (IOAM) with IPv6 Nov 4 04:14:20.931612 kernel: NET: Registered PF_PACKET protocol family Nov 4 04:14:20.931617 kernel: Key type dns_resolver registered Nov 4 04:14:20.931623 kernel: registered taskstats version 1 Nov 4 04:14:20.931628 kernel: Loading compiled-in X.509 certificates Nov 4 04:14:20.931633 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: 7aed512aae99c1c97b09aaf8f37cb7318f15f6e6' Nov 4 04:14:20.931638 kernel: Demotion targets for Node 0: null Nov 4 04:14:20.931645 kernel: Key type .fscrypt registered Nov 4 04:14:20.931650 kernel: Key type fscrypt-provisioning registered Nov 4 04:14:20.931655 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 4 04:14:20.931660 kernel: ima: Allocated hash algorithm: sha1 Nov 4 04:14:20.931666 kernel: ima: No architecture policies found Nov 4 04:14:20.931671 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 4 04:14:20.931676 kernel: clk: Disabling unused clocks Nov 4 04:14:20.931681 kernel: PM: genpd: Disabling unused power domains Nov 4 04:14:20.931687 kernel: Freeing unused kernel memory: 12288K Nov 4 04:14:20.931693 kernel: Run /init as init process Nov 4 04:14:20.931698 kernel: with arguments: Nov 4 04:14:20.931703 kernel: /init Nov 4 04:14:20.931708 kernel: with environment: Nov 4 04:14:20.931713 kernel: HOME=/ Nov 4 04:14:20.931718 kernel: TERM=linux Nov 4 04:14:20.931725 kernel: hv_vmbus: Vmbus version:5.3 Nov 4 04:14:20.931730 kernel: hv_vmbus: registering driver hid_hyperv Nov 4 04:14:20.931735 kernel: SCSI subsystem initialized Nov 4 04:14:20.931740 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 4 04:14:20.931825 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 4 04:14:20.931833 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 4 04:14:20.931840 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Nov 4 04:14:20.931845 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 4 04:14:20.931851 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 4 04:14:20.931856 kernel: PTP clock support registered Nov 4 04:14:20.931861 kernel: hv_utils: Registering HyperV Utility Driver Nov 4 04:14:20.931866 kernel: hv_vmbus: registering driver hv_utils Nov 4 04:14:20.931872 kernel: hv_utils: Heartbeat IC version 3.0 Nov 4 04:14:20.931878 kernel: hv_utils: Shutdown IC version 3.2 Nov 4 04:14:20.931883 kernel: hv_utils: TimeSync IC version 4.0 Nov 4 04:14:20.931889 kernel: hv_vmbus: registering driver hv_storvsc Nov 4 04:14:20.932003 kernel: scsi host0: storvsc_host_t Nov 4 04:14:20.932082 kernel: scsi host1: storvsc_host_t Nov 4 04:14:20.932170 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 4 04:14:20.932259 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Nov 4 04:14:20.932332 kernel: sd 0:0:0:0: [sda] 71737344 512-byte logical blocks: (36.7 GB/34.2 GiB) Nov 4 04:14:20.932405 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 4 04:14:20.932476 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 4 04:14:20.932549 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 4 04:14:20.932620 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 4 04:14:20.932703 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#125 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:14:20.932772 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#68 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 04:14:20.932779 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 4 04:14:20.932850 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 4 04:14:20.932922 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 4 04:14:20.932930 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 4 04:14:20.933023 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 4 04:14:20.933031 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 4 04:14:20.933036 kernel: device-mapper: uevent: version 1.0.3 Nov 4 04:14:20.933042 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 4 04:14:20.933047 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 4 04:14:20.933053 kernel: raid6: neonx8 gen() 18553 MB/s Nov 4 04:14:20.933060 kernel: raid6: neonx4 gen() 18580 MB/s Nov 4 04:14:20.933065 kernel: raid6: neonx2 gen() 17093 MB/s Nov 4 04:14:20.933070 kernel: raid6: neonx1 gen() 15008 MB/s Nov 4 04:14:20.933076 kernel: raid6: int64x8 gen() 10552 MB/s Nov 4 04:14:20.933081 kernel: raid6: int64x4 gen() 10624 MB/s Nov 4 04:14:20.933086 kernel: raid6: int64x2 gen() 9004 MB/s Nov 4 04:14:20.933091 kernel: raid6: int64x1 gen() 7041 MB/s Nov 4 04:14:20.933098 kernel: raid6: using algorithm neonx4 gen() 18580 MB/s Nov 4 04:14:20.933103 kernel: raid6: .... xor() 15136 MB/s, rmw enabled Nov 4 04:14:20.933108 kernel: raid6: using neon recovery algorithm Nov 4 04:14:20.933114 kernel: xor: measuring software checksum speed Nov 4 04:14:20.933119 kernel: 8regs : 28607 MB/sec Nov 4 04:14:20.933124 kernel: 32regs : 28793 MB/sec Nov 4 04:14:20.933129 kernel: arm64_neon : 37269 MB/sec Nov 4 04:14:20.933135 kernel: xor: using function: arm64_neon (37269 MB/sec) Nov 4 04:14:20.933141 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 4 04:14:20.933146 kernel: BTRFS: device fsid 6c40df93-4adb-43f9-9606-1e6831c4440e devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (360) Nov 4 04:14:20.933152 kernel: BTRFS info (device dm-0): first mount of filesystem 6c40df93-4adb-43f9-9606-1e6831c4440e Nov 4 04:14:20.933158 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 4 04:14:20.933163 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 4 04:14:20.933169 kernel: BTRFS info (device dm-0): enabling free space tree Nov 4 04:14:20.933174 kernel: loop: module loaded Nov 4 04:14:20.933180 kernel: loop0: detected capacity change from 0 to 91480 Nov 4 04:14:20.933186 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 4 04:14:20.933192 systemd[1]: Successfully made /usr/ read-only. Nov 4 04:14:20.933200 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 4 04:14:20.933206 systemd[1]: Detected virtualization microsoft. Nov 4 04:14:20.933213 systemd[1]: Detected architecture arm64. Nov 4 04:14:20.933218 systemd[1]: Running in initrd. Nov 4 04:14:20.933224 systemd[1]: No hostname configured, using default hostname. Nov 4 04:14:20.933229 systemd[1]: Hostname set to . Nov 4 04:14:20.933235 systemd[1]: Initializing machine ID from random generator. Nov 4 04:14:20.933241 systemd[1]: Queued start job for default target initrd.target. Nov 4 04:14:20.933246 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 04:14:20.933253 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:14:20.933259 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:14:20.933265 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 4 04:14:20.933271 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 04:14:20.933277 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 4 04:14:20.933283 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 4 04:14:20.933290 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:14:20.933296 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:14:20.933302 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 4 04:14:20.933308 systemd[1]: Reached target paths.target - Path Units. Nov 4 04:14:20.933313 systemd[1]: Reached target slices.target - Slice Units. Nov 4 04:14:20.933319 systemd[1]: Reached target swap.target - Swaps. Nov 4 04:14:20.933325 systemd[1]: Reached target timers.target - Timer Units. Nov 4 04:14:20.933331 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 04:14:20.933337 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 04:14:20.933343 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 4 04:14:20.933348 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 4 04:14:20.933354 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:14:20.933360 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 04:14:20.933372 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:14:20.933379 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 04:14:20.933385 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 4 04:14:20.933391 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 4 04:14:20.933397 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 04:14:20.933404 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 4 04:14:20.933410 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Nov 4 04:14:20.933416 systemd[1]: Starting systemd-fsck-usr.service... Nov 4 04:14:20.933422 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 04:14:20.933428 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 04:14:20.933434 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:14:20.933441 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 4 04:14:20.933464 systemd-journald[496]: Collecting audit messages is disabled. Nov 4 04:14:20.933480 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:14:20.933486 systemd[1]: Finished systemd-fsck-usr.service. Nov 4 04:14:20.933493 systemd-journald[496]: Journal started Nov 4 04:14:20.933508 systemd-journald[496]: Runtime Journal (/run/log/journal/8209c2ab6d194b1c916473d5009e9766) is 8M, max 78.3M, 70.3M free. Nov 4 04:14:20.946434 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 04:14:20.948557 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 4 04:14:20.961332 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 04:14:20.984977 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Nov 4 04:14:21.000670 systemd-modules-load[499]: Inserted module 'br_netfilter' Nov 4 04:14:21.004641 kernel: Bridge firewalling registered Nov 4 04:14:21.004750 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 04:14:21.012123 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 04:14:21.021387 systemd-tmpfiles[507]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 4 04:14:21.027686 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 04:14:21.043870 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:14:21.053760 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:14:21.064120 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 4 04:14:21.084134 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 04:14:21.097028 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:14:21.105890 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 04:14:21.137964 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:14:21.156707 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 04:14:21.200089 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 4 04:14:21.253153 systemd-resolved[524]: Positive Trust Anchors: Nov 4 04:14:21.256591 systemd-resolved[524]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 04:14:21.273195 dracut-cmdline[537]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=184500f7d2eb6ab997bc068a700bccfd199e25e814087e9e73479b28edc9aa9c Nov 4 04:14:21.256596 systemd-resolved[524]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 04:14:21.256619 systemd-resolved[524]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 4 04:14:21.305452 systemd-resolved[524]: Defaulting to hostname 'linux'. Nov 4 04:14:21.308398 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 04:14:21.314874 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:14:21.458968 kernel: Loading iSCSI transport class v2.0-870. Nov 4 04:14:21.505985 kernel: iscsi: registered transport (tcp) Nov 4 04:14:21.532223 kernel: iscsi: registered transport (qla4xxx) Nov 4 04:14:21.532250 kernel: QLogic iSCSI HBA Driver Nov 4 04:14:21.586366 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 04:14:21.612841 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:14:21.619724 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 04:14:21.668538 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 4 04:14:21.674818 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 4 04:14:21.704100 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 4 04:14:21.725120 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 4 04:14:21.736314 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:14:21.815114 systemd-udevd[756]: Using default interface naming scheme 'v257'. Nov 4 04:14:21.822730 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:14:21.834827 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 4 04:14:21.860008 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 04:14:21.872870 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 04:14:21.883315 dracut-pre-trigger[877]: rd.md=0: removing MD RAID activation Nov 4 04:14:21.911285 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 04:14:21.915061 systemd-networkd[883]: lo: Link UP Nov 4 04:14:21.915065 systemd-networkd[883]: lo: Gained carrier Nov 4 04:14:21.924332 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 04:14:21.931869 systemd[1]: Reached target network.target - Network. Nov 4 04:14:21.940033 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 04:14:21.998477 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:14:22.006383 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 4 04:14:22.087990 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#278 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 4 04:14:22.120160 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:14:22.132985 kernel: hv_vmbus: registering driver hv_netvsc Nov 4 04:14:22.125396 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:14:22.133646 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:14:22.142831 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:14:22.172858 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:14:22.220678 kernel: hv_netvsc 000d3a6e-4f7f-000d-3a6e-4f7f000d3a6e eth0: VF slot 1 added Nov 4 04:14:22.226063 systemd-networkd[883]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:14:22.226073 systemd-networkd[883]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:14:22.226689 systemd-networkd[883]: eth0: Link UP Nov 4 04:14:22.226759 systemd-networkd[883]: eth0: Gained carrier Nov 4 04:14:22.226769 systemd-networkd[883]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:14:22.259960 kernel: hv_vmbus: registering driver hv_pci Nov 4 04:14:22.265026 systemd-networkd[883]: eth0: DHCPv4 address 10.200.20.11/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 4 04:14:22.275735 kernel: hv_pci 5f682dde-02fd-4d08-910a-c7cd27bde30d: PCI VMBus probing: Using version 0x10004 Nov 4 04:14:22.275969 kernel: hv_pci 5f682dde-02fd-4d08-910a-c7cd27bde30d: PCI host bridge to bus 02fd:00 Nov 4 04:14:22.280666 kernel: pci_bus 02fd:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Nov 4 04:14:22.284717 kernel: pci_bus 02fd:00: No busn resource found for root bus, will use [bus 00-ff] Nov 4 04:14:22.291240 kernel: pci 02fd:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Nov 4 04:14:22.295989 kernel: pci 02fd:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 4 04:14:22.300016 kernel: pci 02fd:00:02.0: enabling Extended Tags Nov 4 04:14:22.315035 kernel: pci 02fd:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 02fd:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Nov 4 04:14:22.324896 kernel: pci_bus 02fd:00: busn_res: [bus 00-ff] end is updated to 00 Nov 4 04:14:22.325170 kernel: pci 02fd:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Nov 4 04:14:22.488866 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 4 04:14:22.507020 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 4 04:14:22.521316 kernel: mlx5_core 02fd:00:02.0: enabling device (0000 -> 0002) Nov 4 04:14:22.521581 kernel: mlx5_core 02fd:00:02.0: PTM is not supported by PCIe Nov 4 04:14:22.521674 kernel: mlx5_core 02fd:00:02.0: firmware version: 16.30.5006 Nov 4 04:14:22.580060 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 4 04:14:22.606740 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 04:14:22.637779 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 4 04:14:22.729146 kernel: hv_netvsc 000d3a6e-4f7f-000d-3a6e-4f7f000d3a6e eth0: VF registering: eth1 Nov 4 04:14:22.729387 kernel: mlx5_core 02fd:00:02.0 eth1: joined to eth0 Nov 4 04:14:22.740016 kernel: mlx5_core 02fd:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Nov 4 04:14:22.783419 kernel: mlx5_core 02fd:00:02.0 enP765s1: renamed from eth1 Nov 4 04:14:22.784684 systemd-networkd[883]: eth1: Interface name change detected, renamed to enP765s1. Nov 4 04:14:22.827509 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 4 04:14:22.832048 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 04:14:22.840262 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:14:22.849871 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 04:14:22.859548 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 4 04:14:22.890037 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 4 04:14:22.911012 kernel: mlx5_core 02fd:00:02.0 enP765s1: Link up Nov 4 04:14:22.944978 kernel: hv_netvsc 000d3a6e-4f7f-000d-3a6e-4f7f000d3a6e eth0: Data path switched to VF: enP765s1 Nov 4 04:14:22.944722 systemd-networkd[883]: enP765s1: Link UP Nov 4 04:14:23.234530 systemd-networkd[883]: enP765s1: Gained carrier Nov 4 04:14:23.626658 systemd-networkd[883]: eth0: Gained IPv6LL Nov 4 04:14:23.704770 disk-uuid[988]: Warning: The kernel is still using the old partition table. Nov 4 04:14:23.704770 disk-uuid[988]: The new table will be used at the next reboot or after you Nov 4 04:14:23.704770 disk-uuid[988]: run partprobe(8) or kpartx(8) Nov 4 04:14:23.704770 disk-uuid[988]: The operation has completed successfully. Nov 4 04:14:23.720312 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 4 04:14:23.720413 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 4 04:14:23.726080 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 4 04:14:23.776964 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1149) Nov 4 04:14:23.786803 kernel: BTRFS info (device sda6): first mount of filesystem 4270646e-f1e7-4973-b114-3d717a76cfde Nov 4 04:14:23.786866 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 4 04:14:23.841998 kernel: BTRFS info (device sda6): turning on async discard Nov 4 04:14:23.842025 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 04:14:23.850972 kernel: BTRFS info (device sda6): last unmount of filesystem 4270646e-f1e7-4973-b114-3d717a76cfde Nov 4 04:14:23.854048 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 4 04:14:23.859323 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 4 04:14:25.225003 ignition[1168]: Ignition 2.22.0 Nov 4 04:14:25.225014 ignition[1168]: Stage: fetch-offline Nov 4 04:14:25.227216 ignition[1168]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:25.230352 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 04:14:25.227239 ignition[1168]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:25.240087 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 4 04:14:25.227328 ignition[1168]: parsed url from cmdline: "" Nov 4 04:14:25.227330 ignition[1168]: no config URL provided Nov 4 04:14:25.227334 ignition[1168]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 04:14:25.227341 ignition[1168]: no config at "/usr/lib/ignition/user.ign" Nov 4 04:14:25.227345 ignition[1168]: failed to fetch config: resource requires networking Nov 4 04:14:25.227491 ignition[1168]: Ignition finished successfully Nov 4 04:14:25.277783 ignition[1175]: Ignition 2.22.0 Nov 4 04:14:25.277797 ignition[1175]: Stage: fetch Nov 4 04:14:25.278029 ignition[1175]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:25.278037 ignition[1175]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:25.278125 ignition[1175]: parsed url from cmdline: "" Nov 4 04:14:25.278127 ignition[1175]: no config URL provided Nov 4 04:14:25.278131 ignition[1175]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 04:14:25.278135 ignition[1175]: no config at "/usr/lib/ignition/user.ign" Nov 4 04:14:25.278151 ignition[1175]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 4 04:14:25.391687 ignition[1175]: GET result: OK Nov 4 04:14:25.391732 ignition[1175]: config has been read from IMDS userdata Nov 4 04:14:25.391739 ignition[1175]: parsing config with SHA512: 125761a3b0d2cb6f4b45dd3108fec50db01fdb693a6073a123f1cfe06f2fc08979e4c88e2c443bf7ff7345fb8373462d3de5030aa852ee38ebae1c18b622d42c Nov 4 04:14:25.397775 unknown[1175]: fetched base config from "system" Nov 4 04:14:25.397907 ignition[1175]: fetch: fetch complete Nov 4 04:14:25.397781 unknown[1175]: fetched base config from "system" Nov 4 04:14:25.397911 ignition[1175]: fetch: fetch passed Nov 4 04:14:25.397784 unknown[1175]: fetched user config from "azure" Nov 4 04:14:25.397976 ignition[1175]: Ignition finished successfully Nov 4 04:14:25.399830 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 4 04:14:25.407115 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 4 04:14:25.444448 ignition[1181]: Ignition 2.22.0 Nov 4 04:14:25.444461 ignition[1181]: Stage: kargs Nov 4 04:14:25.444672 ignition[1181]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:25.450310 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 4 04:14:25.444680 ignition[1181]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:25.459254 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 4 04:14:25.447686 ignition[1181]: kargs: kargs passed Nov 4 04:14:25.447745 ignition[1181]: Ignition finished successfully Nov 4 04:14:25.491837 ignition[1187]: Ignition 2.22.0 Nov 4 04:14:25.491852 ignition[1187]: Stage: disks Nov 4 04:14:25.495618 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 4 04:14:25.492090 ignition[1187]: no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:25.505613 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 4 04:14:25.492097 ignition[1187]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:25.510154 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 4 04:14:25.492552 ignition[1187]: disks: disks passed Nov 4 04:14:25.518387 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 04:14:25.492600 ignition[1187]: Ignition finished successfully Nov 4 04:14:25.526359 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 04:14:25.534641 systemd[1]: Reached target basic.target - Basic System. Nov 4 04:14:25.543479 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 4 04:14:25.710813 systemd-fsck[1196]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks Nov 4 04:14:25.719055 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 4 04:14:25.727018 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 4 04:14:27.312129 kernel: EXT4-fs (sda9): mounted filesystem 7cfda2e2-a28a-4bc0-b163-12cbeed348dc r/w with ordered data mode. Quota mode: none. Nov 4 04:14:27.312709 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 4 04:14:27.316542 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 4 04:14:27.360401 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 04:14:27.376908 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 4 04:14:27.382258 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 4 04:14:27.390879 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 4 04:14:27.390924 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 04:14:27.416694 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 4 04:14:27.423838 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 4 04:14:27.442967 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1210) Nov 4 04:14:27.443011 kernel: BTRFS info (device sda6): first mount of filesystem 4270646e-f1e7-4973-b114-3d717a76cfde Nov 4 04:14:27.452716 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 4 04:14:27.462701 kernel: BTRFS info (device sda6): turning on async discard Nov 4 04:14:27.462757 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 04:14:27.464031 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 04:14:28.138481 coreos-metadata[1212]: Nov 04 04:14:28.138 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 04:14:28.144410 coreos-metadata[1212]: Nov 04 04:14:28.141 INFO Fetch successful Nov 4 04:14:28.144410 coreos-metadata[1212]: Nov 04 04:14:28.141 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 4 04:14:28.156114 coreos-metadata[1212]: Nov 04 04:14:28.149 INFO Fetch successful Nov 4 04:14:28.162944 coreos-metadata[1212]: Nov 04 04:14:28.162 INFO wrote hostname ci-4508.0.0-n-6c20075e56 to /sysroot/etc/hostname Nov 4 04:14:28.169792 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 04:14:28.684291 initrd-setup-root[1240]: cut: /sysroot/etc/passwd: No such file or directory Nov 4 04:14:28.752686 initrd-setup-root[1247]: cut: /sysroot/etc/group: No such file or directory Nov 4 04:14:28.772286 initrd-setup-root[1254]: cut: /sysroot/etc/shadow: No such file or directory Nov 4 04:14:28.777527 initrd-setup-root[1261]: cut: /sysroot/etc/gshadow: No such file or directory Nov 4 04:14:30.191591 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 4 04:14:30.197715 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 4 04:14:30.213787 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 4 04:14:30.243775 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 4 04:14:30.253989 kernel: BTRFS info (device sda6): last unmount of filesystem 4270646e-f1e7-4973-b114-3d717a76cfde Nov 4 04:14:30.264976 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 4 04:14:30.281442 ignition[1330]: INFO : Ignition 2.22.0 Nov 4 04:14:30.281442 ignition[1330]: INFO : Stage: mount Nov 4 04:14:30.293035 ignition[1330]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:30.293035 ignition[1330]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:30.293035 ignition[1330]: INFO : mount: mount passed Nov 4 04:14:30.293035 ignition[1330]: INFO : Ignition finished successfully Nov 4 04:14:30.285287 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 4 04:14:30.290318 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 4 04:14:30.325428 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 04:14:30.349089 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1341) Nov 4 04:14:30.358086 kernel: BTRFS info (device sda6): first mount of filesystem 4270646e-f1e7-4973-b114-3d717a76cfde Nov 4 04:14:30.358103 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 4 04:14:30.367495 kernel: BTRFS info (device sda6): turning on async discard Nov 4 04:14:30.367538 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 04:14:30.369079 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 04:14:30.402974 ignition[1358]: INFO : Ignition 2.22.0 Nov 4 04:14:30.402974 ignition[1358]: INFO : Stage: files Nov 4 04:14:30.402974 ignition[1358]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:30.413733 ignition[1358]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:30.413733 ignition[1358]: DEBUG : files: compiled without relabeling support, skipping Nov 4 04:14:30.439344 ignition[1358]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 4 04:14:30.439344 ignition[1358]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 4 04:14:30.560558 ignition[1358]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 4 04:14:30.566167 ignition[1358]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 4 04:14:30.566167 ignition[1358]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 4 04:14:30.560989 unknown[1358]: wrote ssh authorized keys file for user: core Nov 4 04:14:30.623826 ignition[1358]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 04:14:30.631190 ignition[1358]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 04:14:30.631190 ignition[1358]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 4 04:14:30.631190 ignition[1358]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 4 04:14:30.631190 ignition[1358]: INFO : files: files passed Nov 4 04:14:30.631190 ignition[1358]: INFO : Ignition finished successfully Nov 4 04:14:30.626195 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 4 04:14:30.636177 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 4 04:14:30.668600 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 4 04:14:30.679211 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 4 04:14:30.679312 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 4 04:14:30.734059 initrd-setup-root-after-ignition[1389]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:14:30.734059 initrd-setup-root-after-ignition[1389]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:14:30.748211 initrd-setup-root-after-ignition[1393]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 04:14:30.751002 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 04:14:30.760477 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 4 04:14:30.771549 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 4 04:14:30.819580 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 4 04:14:30.821175 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 4 04:14:30.829360 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 4 04:14:30.838864 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 4 04:14:30.846753 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 4 04:14:30.847714 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 4 04:14:30.882006 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 04:14:30.888391 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 4 04:14:30.925758 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 04:14:30.925917 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:14:30.935462 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:14:30.944130 systemd[1]: Stopped target timers.target - Timer Units. Nov 4 04:14:30.951481 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 4 04:14:30.951652 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 04:14:30.962553 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 4 04:14:30.970484 systemd[1]: Stopped target basic.target - Basic System. Nov 4 04:14:30.977860 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 4 04:14:30.985059 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 04:14:30.994105 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 4 04:14:31.002517 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 4 04:14:31.011232 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 4 04:14:31.019836 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 04:14:31.028377 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 4 04:14:31.036839 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 4 04:14:31.044352 systemd[1]: Stopped target swap.target - Swaps. Nov 4 04:14:31.051062 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 4 04:14:31.051242 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 4 04:14:31.062696 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:14:31.071308 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:14:31.080023 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 4 04:14:31.084098 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:14:31.089557 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 4 04:14:31.089713 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 4 04:14:31.102483 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 4 04:14:31.102646 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 04:14:31.111006 systemd[1]: ignition-files.service: Deactivated successfully. Nov 4 04:14:31.111133 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 4 04:14:31.119233 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 4 04:14:31.119370 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 04:14:31.130054 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 4 04:14:31.142828 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 4 04:14:31.143068 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:14:31.154068 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 4 04:14:31.204307 ignition[1413]: INFO : Ignition 2.22.0 Nov 4 04:14:31.204307 ignition[1413]: INFO : Stage: umount Nov 4 04:14:31.204307 ignition[1413]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 04:14:31.204307 ignition[1413]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 04:14:31.204307 ignition[1413]: INFO : umount: umount passed Nov 4 04:14:31.204307 ignition[1413]: INFO : Ignition finished successfully Nov 4 04:14:31.164042 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 4 04:14:31.164278 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:14:31.175738 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 4 04:14:31.175920 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:14:31.195273 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 4 04:14:31.195388 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 04:14:31.204483 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 4 04:14:31.204598 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 4 04:14:31.212626 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 4 04:14:31.212918 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 4 04:14:31.218833 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 4 04:14:31.218902 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 4 04:14:31.227361 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 4 04:14:31.227418 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 4 04:14:31.236252 systemd[1]: Stopped target network.target - Network. Nov 4 04:14:31.243067 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 4 04:14:31.243137 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 04:14:31.251014 systemd[1]: Stopped target paths.target - Path Units. Nov 4 04:14:31.258540 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 4 04:14:31.267360 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:14:31.277146 systemd[1]: Stopped target slices.target - Slice Units. Nov 4 04:14:31.293049 systemd[1]: Stopped target sockets.target - Socket Units. Nov 4 04:14:31.299913 systemd[1]: iscsid.socket: Deactivated successfully. Nov 4 04:14:31.299966 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 04:14:31.308204 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 4 04:14:31.308236 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 04:14:31.316401 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 4 04:14:31.316468 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 4 04:14:31.324355 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 4 04:14:31.324392 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 4 04:14:31.332109 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 4 04:14:31.339905 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 4 04:14:31.349712 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 4 04:14:31.350352 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 4 04:14:31.350455 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 4 04:14:31.362816 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 4 04:14:31.362906 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 4 04:14:31.380757 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 4 04:14:31.380876 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 4 04:14:31.394800 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 4 04:14:31.395169 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 4 04:14:31.403237 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 4 04:14:31.408476 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 4 04:14:31.559733 kernel: hv_netvsc 000d3a6e-4f7f-000d-3a6e-4f7f000d3a6e eth0: Data path switched from VF: enP765s1 Nov 4 04:14:31.408528 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:14:31.416968 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 4 04:14:31.417040 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 4 04:14:31.425453 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 4 04:14:31.434722 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 4 04:14:31.434815 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 04:14:31.442653 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 4 04:14:31.442715 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:14:31.450956 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 4 04:14:31.451001 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 4 04:14:31.459105 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:14:31.486400 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 4 04:14:31.486547 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:14:31.497966 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 4 04:14:31.498069 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 4 04:14:31.505854 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 4 04:14:31.505894 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:14:31.514977 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 4 04:14:31.515038 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 4 04:14:31.528571 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 4 04:14:31.528648 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 4 04:14:31.546428 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 4 04:14:31.546504 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 04:14:31.560606 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 4 04:14:31.581267 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 4 04:14:31.581365 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:14:31.586894 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 4 04:14:31.586939 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:14:31.599028 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 04:14:31.599094 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:14:31.608284 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 4 04:14:31.610041 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 4 04:14:31.622285 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 4 04:14:31.622450 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 4 04:14:31.629704 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 4 04:14:31.635639 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 4 04:14:31.736754 systemd[1]: Switching root. Nov 4 04:14:31.827626 systemd-journald[496]: Journal stopped Nov 4 04:14:39.423989 systemd-journald[496]: Received SIGTERM from PID 1 (systemd). Nov 4 04:14:39.424013 kernel: SELinux: policy capability network_peer_controls=1 Nov 4 04:14:39.424021 kernel: SELinux: policy capability open_perms=1 Nov 4 04:14:39.424029 kernel: SELinux: policy capability extended_socket_class=1 Nov 4 04:14:39.424036 kernel: SELinux: policy capability always_check_network=0 Nov 4 04:14:39.424042 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 4 04:14:39.424048 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 4 04:14:39.424054 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 4 04:14:39.424060 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 4 04:14:39.424067 kernel: SELinux: policy capability userspace_initial_context=0 Nov 4 04:14:39.424073 systemd[1]: Successfully loaded SELinux policy in 236.779ms. Nov 4 04:14:39.424079 kernel: audit: type=1403 audit(1762229672.779:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 4 04:14:39.424086 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.728ms. Nov 4 04:14:39.424093 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Nov 4 04:14:39.424101 systemd[1]: Detected virtualization microsoft. Nov 4 04:14:39.424108 systemd[1]: Detected architecture arm64. Nov 4 04:14:39.424114 systemd[1]: Detected first boot. Nov 4 04:14:39.424121 systemd[1]: Hostname set to . Nov 4 04:14:39.424127 systemd[1]: Initializing machine ID from random generator. Nov 4 04:14:39.424135 zram_generator::config[1455]: No configuration found. Nov 4 04:14:39.424141 kernel: NET: Registered PF_VSOCK protocol family Nov 4 04:14:39.424147 systemd[1]: Populated /etc with preset unit settings. Nov 4 04:14:39.424154 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 4 04:14:39.424160 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 4 04:14:39.424167 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 4 04:14:39.424176 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 4 04:14:39.424182 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 4 04:14:39.424189 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 4 04:14:39.424196 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 4 04:14:39.424202 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 4 04:14:39.424209 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 4 04:14:39.424216 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 4 04:14:39.424223 systemd[1]: Created slice user.slice - User and Session Slice. Nov 4 04:14:39.424229 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 04:14:39.424236 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 04:14:39.424242 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 4 04:14:39.424249 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 4 04:14:39.424257 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 4 04:14:39.424263 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 04:14:39.424270 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 4 04:14:39.424278 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 04:14:39.424285 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 04:14:39.424291 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 4 04:14:39.424299 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 4 04:14:39.424306 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 4 04:14:39.424312 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 4 04:14:39.424320 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 04:14:39.424326 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 04:14:39.424333 systemd[1]: Reached target slices.target - Slice Units. Nov 4 04:14:39.424339 systemd[1]: Reached target swap.target - Swaps. Nov 4 04:14:39.424347 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 4 04:14:39.424354 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 4 04:14:39.424360 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 4 04:14:39.424367 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 04:14:39.424375 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 04:14:39.424382 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 04:14:39.424388 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 4 04:14:39.424395 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 4 04:14:39.424402 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 4 04:14:39.424408 systemd[1]: Mounting media.mount - External Media Directory... Nov 4 04:14:39.424416 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 4 04:14:39.424423 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 4 04:14:39.424429 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 4 04:14:39.424436 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Nov 4 04:14:39.424443 systemd[1]: Reached target machines.target - Containers. Nov 4 04:14:39.424449 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 4 04:14:39.424456 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:14:39.424465 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 04:14:39.424471 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 4 04:14:39.424478 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:14:39.424484 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 04:14:39.424491 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:14:39.424498 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 4 04:14:39.424504 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 04:14:39.424512 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 4 04:14:39.424519 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 4 04:14:39.424525 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 4 04:14:39.424532 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 4 04:14:39.424538 systemd[1]: Stopped systemd-fsck-usr.service. Nov 4 04:14:39.424545 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 4 04:14:39.424553 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 04:14:39.424560 kernel: fuse: init (API version 7.41) Nov 4 04:14:39.424566 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 04:14:39.424573 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 04:14:39.424579 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 4 04:14:39.424586 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 4 04:14:39.424593 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 04:14:39.424600 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 4 04:14:39.424608 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 4 04:14:39.424614 systemd[1]: Mounted media.mount - External Media Directory. Nov 4 04:14:39.424621 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 4 04:14:39.424642 systemd-journald[1536]: Collecting audit messages is disabled. Nov 4 04:14:39.424658 systemd-journald[1536]: Journal started Nov 4 04:14:39.424674 systemd-journald[1536]: Runtime Journal (/run/log/journal/25d143c9ca08494c958a1e6e60513516) is 8M, max 78.3M, 70.3M free. Nov 4 04:14:38.567299 systemd[1]: Queued start job for default target multi-user.target. Nov 4 04:14:38.574531 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 4 04:14:38.575052 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 4 04:14:38.575382 systemd[1]: systemd-journald.service: Consumed 2.014s CPU time. Nov 4 04:14:39.434979 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 04:14:39.435754 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 4 04:14:39.440444 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 4 04:14:39.445004 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 4 04:14:39.451079 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 04:14:39.457539 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 4 04:14:39.457693 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 4 04:14:39.464063 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:14:39.464212 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:14:39.469269 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:14:39.469683 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:14:39.475451 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 4 04:14:39.475610 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 4 04:14:39.480183 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 04:14:39.480328 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 04:14:39.485040 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 04:14:39.493095 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 04:14:39.501958 kernel: ACPI: bus type drm_connector registered Nov 4 04:14:39.502263 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 4 04:14:39.507923 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 04:14:39.508098 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 04:14:39.520147 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 04:14:39.536754 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 4 04:14:39.542901 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 4 04:14:39.553866 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 4 04:14:39.558595 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 4 04:14:39.558638 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 04:14:39.563573 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 4 04:14:39.569501 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:14:39.598149 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 4 04:14:39.613864 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 4 04:14:39.618593 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:14:39.619638 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 4 04:14:39.623930 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 04:14:39.624977 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 04:14:39.629998 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 4 04:14:39.637216 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 4 04:14:39.643591 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 4 04:14:39.651105 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 04:14:39.656327 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 4 04:14:39.662317 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 4 04:14:39.687102 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 4 04:14:39.688987 systemd-journald[1536]: Time spent on flushing to /var/log/journal/25d143c9ca08494c958a1e6e60513516 is 8.657ms for 888 entries. Nov 4 04:14:39.688987 systemd-journald[1536]: System Journal (/var/log/journal/25d143c9ca08494c958a1e6e60513516) is 8M, max 2.6G, 2.6G free. Nov 4 04:14:39.741157 kernel: loop1: detected capacity change from 0 to 27736 Nov 4 04:14:39.741218 systemd-journald[1536]: Received client request to flush runtime journal. Nov 4 04:14:39.702197 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 4 04:14:39.708570 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 4 04:14:39.742697 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 4 04:14:39.770666 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 4 04:14:39.773338 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 4 04:14:39.790180 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 04:14:40.408870 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 4 04:14:40.418134 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 04:14:40.423512 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 04:14:40.509150 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 4 04:14:40.523564 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 4 04:14:40.557579 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 4 04:14:40.566795 systemd-tmpfiles[1611]: ACLs are not supported, ignoring. Nov 4 04:14:40.566812 systemd-tmpfiles[1611]: ACLs are not supported, ignoring. Nov 4 04:14:40.573970 kernel: loop2: detected capacity change from 0 to 100192 Nov 4 04:14:40.574234 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 04:14:40.583267 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 04:14:40.610519 systemd-udevd[1621]: Using default interface naming scheme 'v257'. Nov 4 04:14:40.652734 systemd-resolved[1610]: Positive Trust Anchors: Nov 4 04:14:40.652756 systemd-resolved[1610]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 04:14:40.652759 systemd-resolved[1610]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 04:14:40.652783 systemd-resolved[1610]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Nov 4 04:14:40.831219 systemd-resolved[1610]: Using system hostname 'ci-4508.0.0-n-6c20075e56'. Nov 4 04:14:40.832542 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 04:14:40.837271 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 04:14:41.005968 kernel: loop3: detected capacity change from 0 to 109736 Nov 4 04:14:41.411402 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 04:14:41.420525 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 04:14:41.432998 kernel: loop4: detected capacity change from 0 to 27736 Nov 4 04:14:41.451024 kernel: loop5: detected capacity change from 0 to 100192 Nov 4 04:14:41.467968 kernel: loop6: detected capacity change from 0 to 109736 Nov 4 04:14:41.476808 (sd-merge)[1632]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Nov 4 04:14:41.479895 (sd-merge)[1632]: Merged extensions into '/usr'. Nov 4 04:14:41.485971 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 4 04:14:41.500201 systemd[1]: Starting ensure-sysext.service... Nov 4 04:14:41.507871 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 04:14:41.540263 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 4 04:14:41.558345 systemd[1]: Reload requested from client PID 1653 ('systemctl') (unit ensure-sysext.service)... Nov 4 04:14:41.558362 systemd[1]: Reloading... Nov 4 04:14:41.580965 kernel: mousedev: PS/2 mouse device common for all mice Nov 4 04:14:41.585262 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 4 04:14:41.587010 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 4 04:14:41.587263 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 4 04:14:41.587415 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 4 04:14:41.587858 systemd-tmpfiles[1654]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 4 04:14:41.589037 systemd-tmpfiles[1654]: ACLs are not supported, ignoring. Nov 4 04:14:41.589465 systemd-tmpfiles[1654]: ACLs are not supported, ignoring. Nov 4 04:14:41.612963 kernel: hv_vmbus: registering driver hv_balloon Nov 4 04:14:41.613064 kernel: hv_vmbus: registering driver hyperv_fb Nov 4 04:14:41.644043 zram_generator::config[1712]: No configuration found. Nov 4 04:14:41.644144 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#113 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 4 04:14:41.673997 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 4 04:14:41.674125 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 4 04:14:41.674141 kernel: hv_balloon: Memory hot add disabled on ARM64 Nov 4 04:14:41.674169 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 4 04:14:41.680514 systemd-networkd[1629]: lo: Link UP Nov 4 04:14:41.680526 systemd-networkd[1629]: lo: Gained carrier Nov 4 04:14:41.684494 systemd-networkd[1629]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:14:41.684502 systemd-networkd[1629]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:14:41.685642 kernel: Console: switching to colour dummy device 80x25 Nov 4 04:14:41.690159 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 04:14:41.730627 systemd-tmpfiles[1654]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 04:14:41.730805 systemd-tmpfiles[1654]: Skipping /boot Nov 4 04:14:41.738734 systemd-tmpfiles[1654]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 04:14:41.738746 systemd-tmpfiles[1654]: Skipping /boot Nov 4 04:14:41.746999 kernel: mlx5_core 02fd:00:02.0 enP765s1: Link up Nov 4 04:14:41.770958 kernel: hv_netvsc 000d3a6e-4f7f-000d-3a6e-4f7f000d3a6e eth0: Data path switched to VF: enP765s1 Nov 4 04:14:41.773250 systemd-networkd[1629]: enP765s1: Link UP Nov 4 04:14:41.773764 systemd-networkd[1629]: eth0: Link UP Nov 4 04:14:41.773861 systemd-networkd[1629]: eth0: Gained carrier Nov 4 04:14:41.774044 systemd-networkd[1629]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:14:41.805115 systemd-networkd[1629]: enP765s1: Gained carrier Nov 4 04:14:41.807204 systemd-networkd[1629]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:14:41.831007 systemd-networkd[1629]: eth0: DHCPv4 address 10.200.20.11/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 4 04:14:41.894357 systemd[1]: Reloading finished in 335 ms. Nov 4 04:14:41.912323 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 04:14:41.936765 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 04:14:41.976839 systemd[1]: Finished ensure-sysext.service. Nov 4 04:14:41.992593 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 04:14:41.999744 systemd[1]: Reached target network.target - Network. Nov 4 04:14:42.005294 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 04:14:42.015926 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 4 04:14:42.021488 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 04:14:42.023414 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 4 04:14:42.029219 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 04:14:42.037970 kernel: MACsec IEEE 802.1AE Nov 4 04:14:42.038437 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 04:14:42.046195 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 04:14:42.054136 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 04:14:42.058537 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 04:14:42.060334 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 4 04:14:42.065333 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Nov 4 04:14:42.066779 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 4 04:14:42.076390 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 4 04:14:42.083021 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 4 04:14:42.087851 systemd[1]: Reached target time-set.target - System Time Set. Nov 4 04:14:42.096099 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 4 04:14:42.103147 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 04:14:42.109615 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 04:14:42.113803 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 04:14:42.121725 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 04:14:42.121933 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 04:14:42.127193 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 04:14:42.127348 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 04:14:42.133630 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 04:14:42.133996 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 04:14:42.143168 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 04:14:42.143487 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 04:14:42.149017 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 4 04:14:42.183903 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 4 04:14:42.205197 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 4 04:14:42.354181 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 4 04:14:42.440663 augenrules[1878]: No rules Nov 4 04:14:42.442096 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 04:14:42.442341 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 04:14:43.274119 systemd-networkd[1629]: eth0: Gained IPv6LL Nov 4 04:14:43.277797 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 4 04:14:43.283836 systemd[1]: Reached target network-online.target - Network is Online. Nov 4 04:14:43.351132 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 04:14:44.520022 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 4 04:14:44.525330 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Nov 4 04:14:51.474655 ldconfig[1835]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 04:14:51.485482 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 4 04:14:51.492869 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 4 04:14:51.519010 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 4 04:14:51.523751 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 04:14:51.528858 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 4 04:14:51.533682 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 4 04:14:51.538758 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 4 04:14:51.543601 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 4 04:14:51.548691 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 4 04:14:51.553840 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 4 04:14:51.553864 systemd[1]: Reached target paths.target - Path Units. Nov 4 04:14:51.557916 systemd[1]: Reached target timers.target - Timer Units. Nov 4 04:14:51.575022 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 4 04:14:51.580931 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 4 04:14:51.586885 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 4 04:14:51.593136 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 4 04:14:51.598595 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 4 04:14:51.606290 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 4 04:14:51.610673 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 4 04:14:51.615890 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 4 04:14:51.620568 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 04:14:51.624180 systemd[1]: Reached target basic.target - Basic System. Nov 4 04:14:51.627731 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 4 04:14:51.627760 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 4 04:14:51.687023 systemd[1]: Starting chronyd.service - NTP client/server... Nov 4 04:14:51.699102 systemd[1]: Starting containerd.service - containerd container runtime... Nov 4 04:14:51.704425 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 4 04:14:51.710956 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 4 04:14:51.717469 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 4 04:14:51.727096 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 4 04:14:51.734474 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 4 04:14:51.735977 chronyd[1894]: chronyd version 4.8 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 4 04:14:51.739020 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Nov 4 04:14:51.740707 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 4 04:14:51.744924 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Nov 4 04:14:51.745936 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 4 04:14:51.750868 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 4 04:14:51.755399 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 4 04:14:51.758813 jq[1901]: false Nov 4 04:14:51.761580 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 4 04:14:51.764424 KVP[1904]: KVP starting; pid is:1904 Nov 4 04:14:51.769638 KVP[1904]: KVP LIC Version: 3.1 Nov 4 04:14:51.770061 kernel: hv_utils: KVP IC version 4.0 Nov 4 04:14:51.771744 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 4 04:14:51.775784 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 4 04:14:51.778641 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 4 04:14:51.779240 systemd[1]: Starting update-engine.service - Update Engine... Nov 4 04:14:51.786507 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 4 04:14:51.794885 chronyd[1894]: Timezone right/UTC failed leap second check, ignoring Nov 4 04:14:51.795621 chronyd[1894]: Loaded seccomp filter (level 2) Nov 4 04:14:51.797366 systemd[1]: Started chronyd.service - NTP client/server. Nov 4 04:14:51.799875 jq[1916]: true Nov 4 04:14:51.803765 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 4 04:14:51.811459 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 4 04:14:51.816020 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 4 04:14:51.816415 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 4 04:14:51.816560 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 4 04:14:51.836826 jq[1927]: true Nov 4 04:14:51.872175 extend-filesystems[1903]: Found /dev/sda6 Nov 4 04:14:51.877564 update_engine[1910]: I20251104 04:14:51.875336 1910 main.cc:92] Flatcar Update Engine starting Nov 4 04:14:51.879034 systemd[1]: motdgen.service: Deactivated successfully. Nov 4 04:14:51.879372 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 4 04:14:51.900695 systemd-logind[1909]: New seat seat0. Nov 4 04:14:51.903818 systemd-logind[1909]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 4 04:14:51.909405 extend-filesystems[1903]: Found /dev/sda9 Nov 4 04:14:51.904809 systemd[1]: Started systemd-logind.service - User Login Management. Nov 4 04:14:51.918334 extend-filesystems[1903]: Checking size of /dev/sda9 Nov 4 04:14:51.922053 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 4 04:14:51.936430 bash[1951]: Updated "/home/core/.ssh/authorized_keys" Nov 4 04:14:51.938009 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 4 04:14:51.943981 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 4 04:14:51.964311 extend-filesystems[1903]: Resized partition /dev/sda9 Nov 4 04:14:52.012999 extend-filesystems[1993]: resize2fs 1.47.3 (8-Jul-2025) Nov 4 04:14:52.021146 sshd_keygen[1926]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 4 04:14:52.032050 kernel: EXT4-fs (sda9): resizing filesystem from 7359488 to 7376891 blocks Nov 4 04:14:52.046966 kernel: EXT4-fs (sda9): resized filesystem to 7376891 Nov 4 04:14:52.059734 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 4 04:14:52.082022 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 4 04:14:52.091009 extend-filesystems[1993]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 4 04:14:52.091009 extend-filesystems[1993]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 4 04:14:52.091009 extend-filesystems[1993]: The filesystem on /dev/sda9 is now 7376891 (4k) blocks long. Nov 4 04:14:52.112368 extend-filesystems[1903]: Resized filesystem in /dev/sda9 Nov 4 04:14:52.102883 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 4 04:14:52.109384 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 4 04:14:52.109590 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 4 04:14:52.120139 systemd[1]: issuegen.service: Deactivated successfully. Nov 4 04:14:52.120324 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 4 04:14:52.132226 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 4 04:14:52.141839 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 4 04:14:52.178234 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 4 04:14:52.185227 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 4 04:14:52.193190 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 4 04:14:52.198395 systemd[1]: Reached target getty.target - Login Prompts. Nov 4 04:14:52.256706 dbus-daemon[1897]: [system] SELinux support is enabled Nov 4 04:14:52.256998 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 4 04:14:52.259717 update_engine[1910]: I20251104 04:14:52.259564 1910 update_check_scheduler.cc:74] Next update check in 9m43s Nov 4 04:14:52.278204 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Nov 4 04:14:52.278471 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 4 04:14:52.284618 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Nov 4 04:14:52.284757 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 4 04:14:52.291164 systemd[1]: Started update-engine.service - Update Engine. Nov 4 04:14:52.293869 dbus-daemon[1897]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 4 04:14:52.300992 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 4 04:14:52.310581 coreos-metadata[1896]: Nov 04 04:14:52.310 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 04:14:52.314118 coreos-metadata[1896]: Nov 04 04:14:52.314 INFO Fetch successful Nov 4 04:14:52.314271 coreos-metadata[1896]: Nov 04 04:14:52.314 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 4 04:14:52.320001 coreos-metadata[1896]: Nov 04 04:14:52.319 INFO Fetch successful Nov 4 04:14:52.320001 coreos-metadata[1896]: Nov 04 04:14:52.319 INFO Fetching http://168.63.129.16/machine/5cb0dfe4-a0bb-4bab-a9b5-e8c4c3d7b6b5/da64379f%2Dc2dd%2D4671%2D91f8%2D25531824584a.%5Fci%2D4508.0.0%2Dn%2D6c20075e56?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 4 04:14:52.322787 coreos-metadata[1896]: Nov 04 04:14:52.322 INFO Fetch successful Nov 4 04:14:52.322787 coreos-metadata[1896]: Nov 04 04:14:52.322 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 4 04:14:52.331188 coreos-metadata[1896]: Nov 04 04:14:52.331 INFO Fetch successful Nov 4 04:14:52.358061 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 4 04:14:52.363682 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 4 04:14:52.405815 containerd[1953]: time="2025-11-04T04:14:52Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Nov 4 04:14:52.406968 containerd[1953]: time="2025-11-04T04:14:52.406543792Z" level=info msg="starting containerd" revision=75cb2b7193e4e490e9fbdc236c0e811ccaba3376 version=v2.1.4 Nov 4 04:14:52.416742 containerd[1953]: time="2025-11-04T04:14:52.416689720Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="9.816µs" Nov 4 04:14:52.416742 containerd[1953]: time="2025-11-04T04:14:52.416730032Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 4 04:14:52.416884 containerd[1953]: time="2025-11-04T04:14:52.416774192Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 4 04:14:52.416884 containerd[1953]: time="2025-11-04T04:14:52.416782056Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 4 04:14:52.416973 containerd[1953]: time="2025-11-04T04:14:52.416936584Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 4 04:14:52.416973 containerd[1953]: time="2025-11-04T04:14:52.416971448Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417029 containerd[1953]: time="2025-11-04T04:14:52.417016256Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417029 containerd[1953]: time="2025-11-04T04:14:52.417027128Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417241 containerd[1953]: time="2025-11-04T04:14:52.417223880Z" level=info msg="skip loading plugin" error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417241 containerd[1953]: time="2025-11-04T04:14:52.417239048Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417268 containerd[1953]: time="2025-11-04T04:14:52.417247616Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417268 containerd[1953]: time="2025-11-04T04:14:52.417252616Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417391 containerd[1953]: time="2025-11-04T04:14:52.417376040Z" level=info msg="skip loading plugin" error="EROFS unsupported, please `modprobe erofs`: skip plugin" id=io.containerd.snapshotter.v1.erofs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417391 containerd[1953]: time="2025-11-04T04:14:52.417390104Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417456 containerd[1953]: time="2025-11-04T04:14:52.417444744Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417590 containerd[1953]: time="2025-11-04T04:14:52.417575200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417607 containerd[1953]: time="2025-11-04T04:14:52.417599128Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 04:14:52.417619 containerd[1953]: time="2025-11-04T04:14:52.417607208Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 4 04:14:52.417643 containerd[1953]: time="2025-11-04T04:14:52.417636992Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 4 04:14:52.417795 containerd[1953]: time="2025-11-04T04:14:52.417781792Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 4 04:14:52.417866 containerd[1953]: time="2025-11-04T04:14:52.417848672Z" level=info msg="metadata content store policy set" policy=shared Nov 4 04:14:52.433766 containerd[1953]: time="2025-11-04T04:14:52.433700760Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 4 04:14:52.433766 containerd[1953]: time="2025-11-04T04:14:52.433784264Z" level=info msg="loading plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 4 04:14:52.495029 locksmithd[2058]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962192824Z" level=info msg="skip loading plugin" error="could not find mkfs.erofs: exec: \"mkfs.erofs\": executable file not found in $PATH: skip plugin" id=io.containerd.differ.v1.erofs type=io.containerd.differ.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962241464Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962260992Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962271288Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962283448Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962289856Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962298192Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962306760Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962315344Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962322040Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962327984Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962336400Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 4 04:14:52.962666 containerd[1953]: time="2025-11-04T04:14:52.962512512Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962529520Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962543352Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962557464Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962567072Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962573680Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962583328Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962589856Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962597048Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962603400Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962609728Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962652176Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962686576Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962696368Z" level=info msg="Start snapshots syncer" Nov 4 04:14:52.963089 containerd[1953]: time="2025-11-04T04:14:52.962728768Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 4 04:14:52.963292 containerd[1953]: time="2025-11-04T04:14:52.962905304Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"cgroupWritable\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"\",\"binDirs\":[\"/opt/cni/bin\"],\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogLineSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 4 04:14:52.963292 containerd[1953]: time="2025-11-04T04:14:52.962961424Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963016136Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963118336Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963135048Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963142104Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963148752Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963156728Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963162832Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963169112Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963175472Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963182312Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963206960Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963217272Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 04:14:52.963368 containerd[1953]: time="2025-11-04T04:14:52.963222568Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963228184Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963232768Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963238968Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963245176Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963259600Z" level=info msg="runtime interface created" Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963265424Z" level=info msg="created NRI interface" Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963273672Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963282072Z" level=info msg="Connect containerd service" Nov 4 04:14:52.963522 containerd[1953]: time="2025-11-04T04:14:52.963296864Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 4 04:14:52.964296 containerd[1953]: time="2025-11-04T04:14:52.963999480Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Nov 4 04:14:53.372717 containerd[1953]: time="2025-11-04T04:14:53.372606824Z" level=info msg="Start subscribing containerd event" Nov 4 04:14:53.372717 containerd[1953]: time="2025-11-04T04:14:53.372677704Z" level=info msg="Start recovering state" Nov 4 04:14:53.372887 containerd[1953]: time="2025-11-04T04:14:53.372782304Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 4 04:14:53.372887 containerd[1953]: time="2025-11-04T04:14:53.372824144Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373012752Z" level=info msg="Start event monitor" Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373034024Z" level=info msg="Start cni network conf syncer for default" Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373040536Z" level=info msg="Start streaming server" Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373047288Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373052800Z" level=info msg="runtime interface starting up..." Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373061248Z" level=info msg="starting plugins..." Nov 4 04:14:53.373172 containerd[1953]: time="2025-11-04T04:14:53.373073656Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 4 04:14:53.373363 containerd[1953]: time="2025-11-04T04:14:53.373351216Z" level=info msg="containerd successfully booted in 0.967875s" Nov 4 04:14:53.374118 systemd[1]: Started containerd.service - containerd container runtime. Nov 4 04:14:53.379260 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 4 04:14:53.389040 systemd[1]: Startup finished in 3.225s (kernel) + 12.893s (initrd) + 20.844s (userspace) = 36.963s. Nov 4 04:14:54.301996 login[2055]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 4 04:14:54.302215 login[2054]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:14:54.308038 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 4 04:14:54.308997 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 4 04:14:54.314939 systemd-logind[1909]: New session 1 of user core. Nov 4 04:14:54.338581 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 4 04:14:54.341232 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 4 04:14:54.377323 (systemd)[2093]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 4 04:14:54.379908 systemd-logind[1909]: New session c1 of user core. Nov 4 04:14:54.653195 systemd[2093]: Queued start job for default target default.target. Nov 4 04:14:54.661865 systemd[2093]: Created slice app.slice - User Application Slice. Nov 4 04:14:54.661894 systemd[2093]: Reached target paths.target - Paths. Nov 4 04:14:54.661928 systemd[2093]: Reached target timers.target - Timers. Nov 4 04:14:54.663046 systemd[2093]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 04:14:54.672061 systemd[2093]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 04:14:54.672131 systemd[2093]: Reached target sockets.target - Sockets. Nov 4 04:14:54.672181 systemd[2093]: Reached target basic.target - Basic System. Nov 4 04:14:54.672203 systemd[2093]: Reached target default.target - Main User Target. Nov 4 04:14:54.672225 systemd[2093]: Startup finished in 287ms. Nov 4 04:14:54.672341 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 4 04:14:54.675217 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 4 04:14:54.872101 waagent[2052]: 2025-11-04T04:14:54.867966Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 4 04:14:54.872455 waagent[2052]: 2025-11-04T04:14:54.872250Z INFO Daemon Daemon OS: flatcar 4508.0.0 Nov 4 04:14:54.875644 waagent[2052]: 2025-11-04T04:14:54.875596Z INFO Daemon Daemon Python: 3.11.13 Nov 4 04:14:54.878994 waagent[2052]: 2025-11-04T04:14:54.878931Z INFO Daemon Daemon Run daemon Nov 4 04:14:54.882151 waagent[2052]: 2025-11-04T04:14:54.882099Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4508.0.0' Nov 4 04:14:54.888302 waagent[2052]: 2025-11-04T04:14:54.888254Z INFO Daemon Daemon Using waagent for provisioning Nov 4 04:14:54.892036 waagent[2052]: 2025-11-04T04:14:54.891993Z INFO Daemon Daemon Activate resource disk Nov 4 04:14:54.895418 waagent[2052]: 2025-11-04T04:14:54.895384Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 4 04:14:54.903326 waagent[2052]: 2025-11-04T04:14:54.903277Z INFO Daemon Daemon Found device: None Nov 4 04:14:54.906427 waagent[2052]: 2025-11-04T04:14:54.906356Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Nov 4 04:14:54.912332 waagent[2052]: 2025-11-04T04:14:54.912295Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Nov 4 04:14:54.920172 waagent[2052]: 2025-11-04T04:14:54.920130Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 04:14:54.924383 waagent[2052]: 2025-11-04T04:14:54.924348Z INFO Daemon Daemon Running default provisioning handler Nov 4 04:14:54.934184 waagent[2052]: 2025-11-04T04:14:54.934123Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Nov 4 04:14:54.943959 waagent[2052]: 2025-11-04T04:14:54.943892Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 4 04:14:54.950983 waagent[2052]: 2025-11-04T04:14:54.950924Z INFO Daemon Daemon cloud-init is enabled: False Nov 4 04:14:54.954588 waagent[2052]: 2025-11-04T04:14:54.954553Z INFO Daemon Daemon Copying ovf-env.xml Nov 4 04:14:55.185574 waagent[2052]: 2025-11-04T04:14:55.185358Z INFO Daemon Daemon Successfully mounted dvd Nov 4 04:14:55.209596 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 4 04:14:55.212037 waagent[2052]: 2025-11-04T04:14:55.211880Z INFO Daemon Daemon Detect protocol endpoint Nov 4 04:14:55.215612 waagent[2052]: 2025-11-04T04:14:55.215556Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 04:14:55.219645 waagent[2052]: 2025-11-04T04:14:55.219600Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 4 04:14:55.224470 waagent[2052]: 2025-11-04T04:14:55.224433Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 4 04:14:55.228247 waagent[2052]: 2025-11-04T04:14:55.228209Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 4 04:14:55.231763 waagent[2052]: 2025-11-04T04:14:55.231730Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 4 04:14:55.273496 waagent[2052]: 2025-11-04T04:14:55.273448Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 4 04:14:55.278237 waagent[2052]: 2025-11-04T04:14:55.278213Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 4 04:14:55.281946 waagent[2052]: 2025-11-04T04:14:55.281914Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 4 04:14:55.303232 login[2055]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:14:55.307001 systemd-logind[1909]: New session 2 of user core. Nov 4 04:14:55.317133 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 4 04:14:55.448053 waagent[2052]: 2025-11-04T04:14:55.447670Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 4 04:14:55.452559 waagent[2052]: 2025-11-04T04:14:55.452500Z INFO Daemon Daemon Forcing an update of the goal state. Nov 4 04:14:55.460351 waagent[2052]: 2025-11-04T04:14:55.460298Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 04:14:55.477706 waagent[2052]: 2025-11-04T04:14:55.477667Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 4 04:14:55.482255 waagent[2052]: 2025-11-04T04:14:55.482215Z INFO Daemon Nov 4 04:14:55.484530 waagent[2052]: 2025-11-04T04:14:55.484493Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 022ffee2-a0d7-429a-a7f1-9dfdc8c907d5 eTag: 8036241266938887814 source: Fabric] Nov 4 04:14:55.493302 waagent[2052]: 2025-11-04T04:14:55.493265Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 4 04:14:55.498169 waagent[2052]: 2025-11-04T04:14:55.498133Z INFO Daemon Nov 4 04:14:55.500246 waagent[2052]: 2025-11-04T04:14:55.500214Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 4 04:14:55.509593 waagent[2052]: 2025-11-04T04:14:55.509563Z INFO Daemon Daemon Downloading artifacts profile blob Nov 4 04:14:55.572920 waagent[2052]: 2025-11-04T04:14:55.572830Z INFO Daemon Downloaded certificate {'thumbprint': 'FF1755183C967011DEF6931487E2D267F874E376', 'hasPrivateKey': True} Nov 4 04:14:55.580136 waagent[2052]: 2025-11-04T04:14:55.580087Z INFO Daemon Fetch goal state completed Nov 4 04:14:55.590924 waagent[2052]: 2025-11-04T04:14:55.590886Z INFO Daemon Daemon Starting provisioning Nov 4 04:14:55.594652 waagent[2052]: 2025-11-04T04:14:55.594609Z INFO Daemon Daemon Handle ovf-env.xml. Nov 4 04:14:55.597979 waagent[2052]: 2025-11-04T04:14:55.597935Z INFO Daemon Daemon Set hostname [ci-4508.0.0-n-6c20075e56] Nov 4 04:14:55.626822 waagent[2052]: 2025-11-04T04:14:55.626755Z INFO Daemon Daemon Publish hostname [ci-4508.0.0-n-6c20075e56] Nov 4 04:14:55.631283 waagent[2052]: 2025-11-04T04:14:55.631228Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 4 04:14:55.635936 waagent[2052]: 2025-11-04T04:14:55.635893Z INFO Daemon Daemon Primary interface is [eth0] Nov 4 04:14:55.657623 systemd-networkd[1629]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 04:14:55.657631 systemd-networkd[1629]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 4 04:14:55.657717 systemd-networkd[1629]: eth0: DHCP lease lost Nov 4 04:14:55.670874 waagent[2052]: 2025-11-04T04:14:55.670793Z INFO Daemon Daemon Create user account if not exists Nov 4 04:14:55.675000 waagent[2052]: 2025-11-04T04:14:55.674907Z INFO Daemon Daemon User core already exists, skip useradd Nov 4 04:14:55.678978 waagent[2052]: 2025-11-04T04:14:55.678893Z INFO Daemon Daemon Configure sudoer Nov 4 04:14:55.679038 systemd-networkd[1629]: eth0: DHCPv4 address 10.200.20.11/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 4 04:14:55.693116 waagent[2052]: 2025-11-04T04:14:55.693029Z INFO Daemon Daemon Configure sshd Nov 4 04:14:55.699932 waagent[2052]: 2025-11-04T04:14:55.699820Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Nov 4 04:14:55.708958 waagent[2052]: 2025-11-04T04:14:55.708901Z INFO Daemon Daemon Deploy ssh public key. Nov 4 04:14:56.829782 waagent[2052]: 2025-11-04T04:14:56.829718Z INFO Daemon Daemon Provisioning complete Nov 4 04:14:56.842725 waagent[2052]: 2025-11-04T04:14:56.842680Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 4 04:14:56.847629 waagent[2052]: 2025-11-04T04:14:56.847579Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 4 04:14:56.917362 waagent[2052]: 2025-11-04T04:14:56.917193Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 4 04:14:57.024794 waagent[2143]: 2025-11-04T04:14:57.024711Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 4 04:14:57.025999 waagent[2143]: 2025-11-04T04:14:57.025299Z INFO ExtHandler ExtHandler OS: flatcar 4508.0.0 Nov 4 04:14:57.025999 waagent[2143]: 2025-11-04T04:14:57.025362Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 4 04:14:57.025999 waagent[2143]: 2025-11-04T04:14:57.025404Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Nov 4 04:14:57.081995 waagent[2143]: 2025-11-04T04:14:57.081831Z INFO ExtHandler ExtHandler Distro: flatcar-4508.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 4 04:14:57.082093 waagent[2143]: 2025-11-04T04:14:57.082073Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:14:57.082243 waagent[2143]: 2025-11-04T04:14:57.082122Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:14:57.088970 waagent[2143]: 2025-11-04T04:14:57.088896Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 04:14:57.094532 waagent[2143]: 2025-11-04T04:14:57.094493Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 4 04:14:57.095042 waagent[2143]: 2025-11-04T04:14:57.095007Z INFO ExtHandler Nov 4 04:14:57.095103 waagent[2143]: 2025-11-04T04:14:57.095083Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: dc37f5ac-0d90-40b1-b2f8-43cba8e08407 eTag: 8036241266938887814 source: Fabric] Nov 4 04:14:57.095338 waagent[2143]: 2025-11-04T04:14:57.095311Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 4 04:14:57.095760 waagent[2143]: 2025-11-04T04:14:57.095730Z INFO ExtHandler Nov 4 04:14:57.095793 waagent[2143]: 2025-11-04T04:14:57.095783Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 4 04:14:57.099650 waagent[2143]: 2025-11-04T04:14:57.099619Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 4 04:14:57.158745 waagent[2143]: 2025-11-04T04:14:57.158657Z INFO ExtHandler Downloaded certificate {'thumbprint': 'FF1755183C967011DEF6931487E2D267F874E376', 'hasPrivateKey': True} Nov 4 04:14:57.159210 waagent[2143]: 2025-11-04T04:14:57.159173Z INFO ExtHandler Fetch goal state completed Nov 4 04:14:57.171791 waagent[2143]: 2025-11-04T04:14:57.171724Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.3 30 Sep 2025 (Library: OpenSSL 3.4.3 30 Sep 2025) Nov 4 04:14:57.175583 waagent[2143]: 2025-11-04T04:14:57.175520Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2143 Nov 4 04:14:57.175700 waagent[2143]: 2025-11-04T04:14:57.175676Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 4 04:14:57.176018 waagent[2143]: 2025-11-04T04:14:57.175988Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 4 04:14:57.177219 waagent[2143]: 2025-11-04T04:14:57.177178Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4508.0.0', '', 'Flatcar Container Linux by Kinvolk'] Nov 4 04:14:57.177567 waagent[2143]: 2025-11-04T04:14:57.177535Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4508.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 4 04:14:57.177688 waagent[2143]: 2025-11-04T04:14:57.177665Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 4 04:14:57.178154 waagent[2143]: 2025-11-04T04:14:57.178124Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 4 04:14:57.246606 waagent[2143]: 2025-11-04T04:14:57.246560Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 4 04:14:57.246817 waagent[2143]: 2025-11-04T04:14:57.246787Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 4 04:14:57.251643 waagent[2143]: 2025-11-04T04:14:57.251609Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 4 04:14:57.266081 systemd[1]: Reload requested from client PID 2158 ('systemctl') (unit waagent.service)... Nov 4 04:14:57.266361 systemd[1]: Reloading... Nov 4 04:14:57.353069 zram_generator::config[2212]: No configuration found. Nov 4 04:14:57.483814 systemd[1]: Reloading finished in 217 ms. Nov 4 04:14:57.511192 waagent[2143]: 2025-11-04T04:14:57.510191Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 4 04:14:57.511192 waagent[2143]: 2025-11-04T04:14:57.510357Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 4 04:14:58.170964 waagent[2143]: 2025-11-04T04:14:58.170110Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 4 04:14:58.170964 waagent[2143]: 2025-11-04T04:14:58.170445Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Nov 4 04:14:58.171329 waagent[2143]: 2025-11-04T04:14:58.171180Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:14:58.171329 waagent[2143]: 2025-11-04T04:14:58.171264Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:14:58.171464 waagent[2143]: 2025-11-04T04:14:58.171428Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 4 04:14:58.171557 waagent[2143]: 2025-11-04T04:14:58.171512Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 4 04:14:58.171748 waagent[2143]: 2025-11-04T04:14:58.171714Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 4 04:14:58.171748 waagent[2143]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 4 04:14:58.171748 waagent[2143]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Nov 4 04:14:58.171748 waagent[2143]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 4 04:14:58.171748 waagent[2143]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:14:58.171748 waagent[2143]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:14:58.171748 waagent[2143]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 04:14:58.172207 waagent[2143]: 2025-11-04T04:14:58.172166Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 4 04:14:58.172769 waagent[2143]: 2025-11-04T04:14:58.172731Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 4 04:14:58.172818 waagent[2143]: 2025-11-04T04:14:58.172791Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 04:14:58.172873 waagent[2143]: 2025-11-04T04:14:58.172849Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 04:14:58.172958 waagent[2143]: 2025-11-04T04:14:58.172911Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 4 04:14:58.173123 waagent[2143]: 2025-11-04T04:14:58.173086Z INFO EnvHandler ExtHandler Configure routes Nov 4 04:14:58.173300 waagent[2143]: 2025-11-04T04:14:58.173270Z INFO EnvHandler ExtHandler Gateway:None Nov 4 04:14:58.173353 waagent[2143]: 2025-11-04T04:14:58.173322Z INFO EnvHandler ExtHandler Routes:None Nov 4 04:14:58.173802 waagent[2143]: 2025-11-04T04:14:58.173701Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 4 04:14:58.173802 waagent[2143]: 2025-11-04T04:14:58.173744Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 4 04:14:58.174057 waagent[2143]: 2025-11-04T04:14:58.174028Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 4 04:14:58.180329 waagent[2143]: 2025-11-04T04:14:58.180271Z INFO ExtHandler ExtHandler Nov 4 04:14:58.180547 waagent[2143]: 2025-11-04T04:14:58.180510Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 9f2a8217-82b2-4040-bc44-ebf87ed578a3 correlation b1da7310-2f44-43b0-90a8-fb45a68f38f0 created: 2025-11-04T04:13:44.453468Z] Nov 4 04:14:58.181040 waagent[2143]: 2025-11-04T04:14:58.180993Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 4 04:14:58.181658 waagent[2143]: 2025-11-04T04:14:58.181605Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Nov 4 04:14:58.215633 waagent[2143]: 2025-11-04T04:14:58.215556Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Nov 4 04:14:58.215633 waagent[2143]: Try `iptables -h' or 'iptables --help' for more information.) Nov 4 04:14:58.216097 waagent[2143]: 2025-11-04T04:14:58.216057Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 374B4467-67BC-4D8E-9001-1B31566F943D;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 4 04:14:58.266451 waagent[2143]: 2025-11-04T04:14:58.266369Z INFO MonitorHandler ExtHandler Network interfaces: Nov 4 04:14:58.266451 waagent[2143]: Executing ['ip', '-a', '-o', 'link']: Nov 4 04:14:58.266451 waagent[2143]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Nov 4 04:14:58.266451 waagent[2143]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6e:4f:7f brd ff:ff:ff:ff:ff:ff\ altname enx000d3a6e4f7f Nov 4 04:14:58.266451 waagent[2143]: 3: enP765s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6e:4f:7f brd ff:ff:ff:ff:ff:ff\ altname enP765p0s2 Nov 4 04:14:58.266451 waagent[2143]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 4 04:14:58.266451 waagent[2143]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 4 04:14:58.266451 waagent[2143]: 2: eth0 inet 10.200.20.11/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 4 04:14:58.266451 waagent[2143]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 4 04:14:58.266451 waagent[2143]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 4 04:14:58.266451 waagent[2143]: 2: eth0 inet6 fe80::20d:3aff:fe6e:4f7f/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 4 04:14:58.307926 waagent[2143]: 2025-11-04T04:14:58.307859Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 4 04:14:58.307926 waagent[2143]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:14:58.307926 waagent[2143]: pkts bytes target prot opt in out source destination Nov 4 04:14:58.307926 waagent[2143]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:14:58.307926 waagent[2143]: pkts bytes target prot opt in out source destination Nov 4 04:14:58.307926 waagent[2143]: Chain OUTPUT (policy ACCEPT 4 packets, 406 bytes) Nov 4 04:14:58.307926 waagent[2143]: pkts bytes target prot opt in out source destination Nov 4 04:14:58.307926 waagent[2143]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 04:14:58.307926 waagent[2143]: 5 646 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 04:14:58.307926 waagent[2143]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 04:14:58.311318 waagent[2143]: 2025-11-04T04:14:58.311252Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 4 04:14:58.311318 waagent[2143]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:14:58.311318 waagent[2143]: pkts bytes target prot opt in out source destination Nov 4 04:14:58.311318 waagent[2143]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 04:14:58.311318 waagent[2143]: pkts bytes target prot opt in out source destination Nov 4 04:14:58.311318 waagent[2143]: Chain OUTPUT (policy ACCEPT 4 packets, 406 bytes) Nov 4 04:14:58.311318 waagent[2143]: pkts bytes target prot opt in out source destination Nov 4 04:14:58.311318 waagent[2143]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 04:14:58.311318 waagent[2143]: 9 1062 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 04:14:58.311318 waagent[2143]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 04:14:58.311543 waagent[2143]: 2025-11-04T04:14:58.311522Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 4 04:15:15.583378 chronyd[1894]: Selected source PHC0 Nov 4 04:15:29.781171 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Nov 4 04:15:30.974316 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 4 04:15:30.975742 systemd[1]: Started sshd@0-10.200.20.11:22-10.200.16.10:56624.service - OpenSSH per-connection server daemon (10.200.16.10:56624). Nov 4 04:15:31.572637 sshd[2286]: Accepted publickey for core from 10.200.16.10 port 56624 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:31.573791 sshd-session[2286]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:31.577756 systemd-logind[1909]: New session 3 of user core. Nov 4 04:15:31.585102 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 4 04:15:31.858586 systemd[1]: Started sshd@1-10.200.20.11:22-10.200.16.10:56640.service - OpenSSH per-connection server daemon (10.200.16.10:56640). Nov 4 04:15:32.232076 sshd[2292]: Accepted publickey for core from 10.200.16.10 port 56640 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:32.233120 sshd-session[2292]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:32.237232 systemd-logind[1909]: New session 4 of user core. Nov 4 04:15:32.245290 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 4 04:15:32.439523 sshd[2295]: Connection closed by 10.200.16.10 port 56640 Nov 4 04:15:32.440187 sshd-session[2292]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:32.443986 systemd[1]: sshd@1-10.200.20.11:22-10.200.16.10:56640.service: Deactivated successfully. Nov 4 04:15:32.445802 systemd[1]: session-4.scope: Deactivated successfully. Nov 4 04:15:32.446803 systemd-logind[1909]: Session 4 logged out. Waiting for processes to exit. Nov 4 04:15:32.448290 systemd-logind[1909]: Removed session 4. Nov 4 04:15:32.526038 systemd[1]: Started sshd@2-10.200.20.11:22-10.200.16.10:56652.service - OpenSSH per-connection server daemon (10.200.16.10:56652). Nov 4 04:15:32.915573 sshd[2301]: Accepted publickey for core from 10.200.16.10 port 56652 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:32.916771 sshd-session[2301]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:32.920904 systemd-logind[1909]: New session 5 of user core. Nov 4 04:15:32.931143 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 4 04:15:33.130552 sshd[2304]: Connection closed by 10.200.16.10 port 56652 Nov 4 04:15:33.131215 sshd-session[2301]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:33.134808 systemd[1]: sshd@2-10.200.20.11:22-10.200.16.10:56652.service: Deactivated successfully. Nov 4 04:15:33.136337 systemd[1]: session-5.scope: Deactivated successfully. Nov 4 04:15:33.137040 systemd-logind[1909]: Session 5 logged out. Waiting for processes to exit. Nov 4 04:15:33.138252 systemd-logind[1909]: Removed session 5. Nov 4 04:15:33.224934 systemd[1]: Started sshd@3-10.200.20.11:22-10.200.16.10:56660.service - OpenSSH per-connection server daemon (10.200.16.10:56660). Nov 4 04:15:33.614385 sshd[2310]: Accepted publickey for core from 10.200.16.10 port 56660 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:33.615605 sshd-session[2310]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:33.619803 systemd-logind[1909]: New session 6 of user core. Nov 4 04:15:33.627149 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 4 04:15:33.832670 sshd[2313]: Connection closed by 10.200.16.10 port 56660 Nov 4 04:15:33.833365 sshd-session[2310]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:33.836871 systemd[1]: sshd@3-10.200.20.11:22-10.200.16.10:56660.service: Deactivated successfully. Nov 4 04:15:33.838609 systemd[1]: session-6.scope: Deactivated successfully. Nov 4 04:15:33.839522 systemd-logind[1909]: Session 6 logged out. Waiting for processes to exit. Nov 4 04:15:33.840835 systemd-logind[1909]: Removed session 6. Nov 4 04:15:33.919201 systemd[1]: Started sshd@4-10.200.20.11:22-10.200.16.10:56670.service - OpenSSH per-connection server daemon (10.200.16.10:56670). Nov 4 04:15:34.307119 sshd[2319]: Accepted publickey for core from 10.200.16.10 port 56670 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:34.308735 sshd-session[2319]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:34.312808 systemd-logind[1909]: New session 7 of user core. Nov 4 04:15:34.327118 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 4 04:15:34.607236 sudo[2323]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 4 04:15:34.607467 sudo[2323]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:15:34.632441 sudo[2323]: pam_unix(sudo:session): session closed for user root Nov 4 04:15:34.704078 sshd[2322]: Connection closed by 10.200.16.10 port 56670 Nov 4 04:15:34.704868 sshd-session[2319]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:34.709471 systemd[1]: sshd@4-10.200.20.11:22-10.200.16.10:56670.service: Deactivated successfully. Nov 4 04:15:34.711426 systemd[1]: session-7.scope: Deactivated successfully. Nov 4 04:15:34.712255 systemd-logind[1909]: Session 7 logged out. Waiting for processes to exit. Nov 4 04:15:34.713506 systemd-logind[1909]: Removed session 7. Nov 4 04:15:34.789222 systemd[1]: Started sshd@5-10.200.20.11:22-10.200.16.10:56680.service - OpenSSH per-connection server daemon (10.200.16.10:56680). Nov 4 04:15:35.182732 sshd[2329]: Accepted publickey for core from 10.200.16.10 port 56680 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:35.184026 sshd-session[2329]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:35.188081 systemd-logind[1909]: New session 8 of user core. Nov 4 04:15:35.199150 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 4 04:15:35.329360 sudo[2334]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 4 04:15:35.329595 sudo[2334]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:15:35.337303 sudo[2334]: pam_unix(sudo:session): session closed for user root Nov 4 04:15:35.342757 sudo[2333]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 4 04:15:35.343168 sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:15:35.351003 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 04:15:35.384026 augenrules[2356]: No rules Nov 4 04:15:35.385471 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 04:15:35.385852 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 04:15:35.387165 sudo[2333]: pam_unix(sudo:session): session closed for user root Nov 4 04:15:35.458228 sshd[2332]: Connection closed by 10.200.16.10 port 56680 Nov 4 04:15:35.458517 sshd-session[2329]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:35.462690 systemd-logind[1909]: Session 8 logged out. Waiting for processes to exit. Nov 4 04:15:35.463479 systemd[1]: sshd@5-10.200.20.11:22-10.200.16.10:56680.service: Deactivated successfully. Nov 4 04:15:35.465923 systemd[1]: session-8.scope: Deactivated successfully. Nov 4 04:15:35.467613 systemd-logind[1909]: Removed session 8. Nov 4 04:15:35.539804 systemd[1]: Started sshd@6-10.200.20.11:22-10.200.16.10:56696.service - OpenSSH per-connection server daemon (10.200.16.10:56696). Nov 4 04:15:35.924742 sshd[2365]: Accepted publickey for core from 10.200.16.10 port 56696 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:35.925925 sshd-session[2365]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:35.930056 systemd-logind[1909]: New session 9 of user core. Nov 4 04:15:35.938140 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 4 04:15:36.070686 sudo[2369]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . Nov 4 04:15:36.071322 sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:15:36.074834 sudo[2369]: pam_unix(sudo:session): session closed for user root Nov 4 04:15:36.145670 sshd[2368]: Connection closed by 10.200.16.10 port 56696 Nov 4 04:15:36.146599 sshd-session[2365]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:36.151179 systemd[1]: sshd@6-10.200.20.11:22-10.200.16.10:56696.service: Deactivated successfully. Nov 4 04:15:36.153074 systemd[1]: session-9.scope: Deactivated successfully. Nov 4 04:15:36.153795 systemd-logind[1909]: Session 9 logged out. Waiting for processes to exit. Nov 4 04:15:36.154902 systemd-logind[1909]: Removed session 9. Nov 4 04:15:36.234082 systemd[1]: Started sshd@7-10.200.20.11:22-10.200.16.10:56698.service - OpenSSH per-connection server daemon (10.200.16.10:56698). Nov 4 04:15:36.635625 sshd[2375]: Accepted publickey for core from 10.200.16.10 port 56698 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:36.636867 sshd-session[2375]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:36.641017 systemd-logind[1909]: New session 10 of user core. Nov 4 04:15:36.649384 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 4 04:15:36.785473 sudo[2379]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet Nov 4 04:15:36.785700 sudo[2379]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 04:15:37.426688 update_engine[1910]: I20251104 04:15:37.426613 1910 update_attempter.cc:509] Updating boot flags... Nov 4 04:15:39.172315 sudo[2379]: pam_unix(sudo:session): session closed for user root Nov 4 04:15:39.245958 sshd[2378]: Connection closed by 10.200.16.10 port 56698 Nov 4 04:15:39.245242 sshd-session[2375]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:39.248510 systemd[1]: sshd@7-10.200.20.11:22-10.200.16.10:56698.service: Deactivated successfully. Nov 4 04:15:39.250189 systemd[1]: session-10.scope: Deactivated successfully. Nov 4 04:15:39.252579 systemd-logind[1909]: Session 10 logged out. Waiting for processes to exit. Nov 4 04:15:39.254276 systemd-logind[1909]: Removed session 10. Nov 4 04:15:39.331006 systemd[1]: Started sshd@8-10.200.20.11:22-10.200.16.10:56702.service - OpenSSH per-connection server daemon (10.200.16.10:56702). Nov 4 04:15:39.724988 sshd[2449]: Accepted publickey for core from 10.200.16.10 port 56702 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:39.726225 sshd-session[2449]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:39.730295 systemd-logind[1909]: New session 11 of user core. Nov 4 04:15:39.740323 systemd[1]: Started session-11.scope - Session 11 of User core. Nov 4 04:15:39.991769 sshd[2452]: Connection closed by 10.200.16.10 port 56702 Nov 4 04:15:39.992361 sshd-session[2449]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:39.996072 systemd[1]: sshd@8-10.200.20.11:22-10.200.16.10:56702.service: Deactivated successfully. Nov 4 04:15:39.997566 systemd[1]: session-11.scope: Deactivated successfully. Nov 4 04:15:39.998531 systemd-logind[1909]: Session 11 logged out. Waiting for processes to exit. Nov 4 04:15:39.999883 systemd-logind[1909]: Removed session 11. Nov 4 04:15:40.075214 systemd[1]: Started sshd@9-10.200.20.11:22-10.200.16.10:43022.service - OpenSSH per-connection server daemon (10.200.16.10:43022). Nov 4 04:15:40.467199 sshd[2464]: Accepted publickey for core from 10.200.16.10 port 43022 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:40.469518 sshd-session[2464]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:40.473930 systemd-logind[1909]: New session 12 of user core. Nov 4 04:15:40.481119 systemd[1]: Started session-12.scope - Session 12 of User core. Nov 4 04:15:41.045250 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 4 04:15:41.055444 (dockerd)[2482]: 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 Nov 4 04:15:42.327973 dockerd[2482]: time="2025-11-04T04:15:42.327677183Z" level=info msg="Starting up" Nov 4 04:15:42.329310 dockerd[2482]: time="2025-11-04T04:15:42.329270472Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 4 04:15:42.342202 dockerd[2482]: time="2025-11-04T04:15:42.342072956Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 4 04:15:42.647191 systemd[1]: var-lib-docker-metacopy\x2dcheck3191471580-merged.mount: Deactivated successfully. Nov 4 04:15:42.665507 dockerd[2482]: time="2025-11-04T04:15:42.665462406Z" level=info msg="Loading containers: start." Nov 4 04:15:42.907976 kernel: Initializing XFRM netlink socket Nov 4 04:15:43.475521 systemd-networkd[1629]: docker0: Link UP Nov 4 04:15:43.492283 dockerd[2482]: time="2025-11-04T04:15:43.492233791Z" level=info msg="Loading containers: done." Nov 4 04:15:43.504496 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4098688062-merged.mount: Deactivated successfully. Nov 4 04:15:43.521549 dockerd[2482]: time="2025-11-04T04:15:43.521501442Z" 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 Nov 4 04:15:43.521708 dockerd[2482]: time="2025-11-04T04:15:43.521623294Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 4 04:15:43.521760 dockerd[2482]: time="2025-11-04T04:15:43.521739274Z" level=info msg="Initializing buildkit" Nov 4 04:15:43.574532 dockerd[2482]: time="2025-11-04T04:15:43.574481643Z" level=info msg="Completed buildkit initialization" Nov 4 04:15:43.581249 dockerd[2482]: time="2025-11-04T04:15:43.580389300Z" level=info msg="Daemon has completed initialization" Nov 4 04:15:43.581249 dockerd[2482]: time="2025-11-04T04:15:43.580457542Z" level=info msg="API listen on /run/docker.sock" Nov 4 04:15:43.580957 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 4 04:15:44.665576 systemd[1]: var-lib-docker-overlay2-ee90081478eb17f7f33351c9c0a7104f9779253b4c278de204970fa7540a58bf\x2dinit-merged.mount: Deactivated successfully. Nov 4 04:15:44.775348 containerd[1953]: time="2025-11-04T04:15:44.774850388Z" level=info msg="connecting to shim 86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b" address="unix:///run/containerd/s/bb97edde4aeb11428d69972f7559a1934079f2a909f9a47f16ba0f00be70de91" namespace=moby protocol=ttrpc version=3 Nov 4 04:15:44.797128 systemd[1]: Started docker-86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b.scope - libcontainer container 86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b. Nov 4 04:15:44.896822 kernel: docker0: port 1(vethdc4422c) entered blocking state Nov 4 04:15:44.896984 kernel: docker0: port 1(vethdc4422c) entered disabled state Nov 4 04:15:44.897004 kernel: vethdc4422c: entered allmulticast mode Nov 4 04:15:44.901767 kernel: vethdc4422c: entered promiscuous mode Nov 4 04:15:44.902605 systemd-networkd[1629]: vethdc4422c: Link UP Nov 4 04:15:44.931962 kernel: eth0: renamed from veth23108dd Nov 4 04:15:44.938697 kernel: docker0: port 1(vethdc4422c) entered blocking state Nov 4 04:15:44.939160 kernel: docker0: port 1(vethdc4422c) entered forwarding state Nov 4 04:15:44.938845 systemd-networkd[1629]: vethdc4422c: Gained carrier Nov 4 04:15:44.939511 systemd-networkd[1629]: docker0: Gained carrier Nov 4 04:15:46.122315 systemd-networkd[1629]: vethdc4422c: Gained IPv6LL Nov 4 04:15:46.506403 systemd-networkd[1629]: docker0: Gained IPv6LL Nov 4 04:15:48.240828 systemd[1]: docker-86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b.scope: Deactivated successfully. Nov 4 04:15:48.254218 containerd[1953]: time="2025-11-04T04:15:48.254139560Z" level=info msg="shim disconnected" id=86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b namespace=moby Nov 4 04:15:48.254218 containerd[1953]: time="2025-11-04T04:15:48.254176482Z" level=info msg="cleaning up after shim disconnected" id=86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b namespace=moby Nov 4 04:15:48.254547 containerd[1953]: time="2025-11-04T04:15:48.254287023Z" level=info msg="cleaning up dead shim" id=86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b namespace=moby Nov 4 04:15:48.255374 dockerd[2482]: time="2025-11-04T04:15:48.254872127Z" level=info msg="ignoring event" container=86b9d46d5d8a73d65c76f839759c413556d0d78c567d43506f6dd8784914878b module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 4 04:15:48.281194 systemd-networkd[1629]: vethdc4422c: Lost carrier Nov 4 04:15:48.284174 kernel: docker0: port 1(vethdc4422c) entered disabled state Nov 4 04:15:48.284247 kernel: veth23108dd: renamed from eth0 Nov 4 04:15:48.300805 systemd-networkd[1629]: vethdc4422c: Link DOWN Nov 4 04:15:48.304192 kernel: docker0: port 1(vethdc4422c) entered disabled state Nov 4 04:15:48.304251 kernel: vethdc4422c (unregistering): left allmulticast mode Nov 4 04:15:48.310667 kernel: vethdc4422c (unregistering): left promiscuous mode Nov 4 04:15:48.310767 kernel: docker0: port 1(vethdc4422c) entered disabled state Nov 4 04:15:48.329076 systemd[1]: run-docker-netns-af054edf830d.mount: Deactivated successfully. Nov 4 04:15:48.335363 systemd[1]: var-lib-docker-overlay2-ee90081478eb17f7f33351c9c0a7104f9779253b4c278de204970fa7540a58bf-merged.mount: Deactivated successfully. Nov 4 04:15:48.458253 sshd[2467]: Connection closed by 10.200.16.10 port 43022 Nov 4 04:15:48.457711 sshd-session[2464]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:48.461418 systemd-logind[1909]: Session 12 logged out. Waiting for processes to exit. Nov 4 04:15:48.462095 systemd[1]: sshd@9-10.200.20.11:22-10.200.16.10:43022.service: Deactivated successfully. Nov 4 04:15:48.464324 systemd[1]: session-12.scope: Deactivated successfully. Nov 4 04:15:48.465813 systemd-logind[1909]: Removed session 12. Nov 4 04:15:48.532868 systemd[1]: Started sshd@10-10.200.20.11:22-10.200.16.10:43028.service - OpenSSH per-connection server daemon (10.200.16.10:43028). Nov 4 04:15:48.891236 sshd[2761]: Accepted publickey for core from 10.200.16.10 port 43028 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:48.892499 sshd-session[2761]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:48.896437 systemd-logind[1909]: New session 13 of user core. Nov 4 04:15:48.907343 systemd[1]: Started session-13.scope - Session 13 of User core. Nov 4 04:15:49.072342 systemd[1]: var-lib-docker-overlay2-b69b39098d878692924a4f81722355c5069fbd74eb209267dd4e989fae1c0f8b\x2dinit-merged.mount: Deactivated successfully. Nov 4 04:15:49.165278 containerd[1953]: time="2025-11-04T04:15:49.165112561Z" level=info msg="connecting to shim 39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607" address="unix:///run/containerd/s/41b67801692e8e902e7feb42e1731a6e94862b7bb2496caa1afce15a320b3fde" namespace=moby protocol=ttrpc version=3 Nov 4 04:15:49.184143 systemd[1]: Started docker-39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607.scope - libcontainer container 39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607. Nov 4 04:15:49.206609 systemd-networkd[1629]: docker0: Lost carrier Nov 4 04:15:49.213988 kernel: docker0: port 1(veth68325a1) entered blocking state Nov 4 04:15:49.214093 kernel: docker0: port 1(veth68325a1) entered disabled state Nov 4 04:15:49.214113 kernel: veth68325a1: entered allmulticast mode Nov 4 04:15:49.219704 kernel: veth68325a1: entered promiscuous mode Nov 4 04:15:49.220408 systemd-networkd[1629]: veth68325a1: Link UP Nov 4 04:15:49.243965 kernel: eth0: renamed from vethacb1b25 Nov 4 04:15:49.251159 kernel: docker0: port 1(veth68325a1) entered blocking state Nov 4 04:15:49.251530 kernel: docker0: port 1(veth68325a1) entered forwarding state Nov 4 04:15:49.251416 systemd-networkd[1629]: veth68325a1: Gained carrier Nov 4 04:15:49.252134 systemd-networkd[1629]: docker0: Gained carrier Nov 4 04:15:49.281546 systemd[1]: docker-39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607.scope: Deactivated successfully. Nov 4 04:15:49.307369 dockerd[2482]: time="2025-11-04T04:15:49.307321401Z" level=info msg="ignoring event" container=39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Nov 4 04:15:49.308683 containerd[1953]: time="2025-11-04T04:15:49.308646553Z" level=info msg="shim disconnected" id=39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607 namespace=moby Nov 4 04:15:49.308683 containerd[1953]: time="2025-11-04T04:15:49.308682947Z" level=info msg="cleaning up after shim disconnected" id=39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607 namespace=moby Nov 4 04:15:49.308683 containerd[1953]: time="2025-11-04T04:15:49.308690163Z" level=info msg="cleaning up dead shim" id=39a765ab73b8a47c6411d7adfed61da24f898e071731a68997778014fcc44607 namespace=moby Nov 4 04:15:49.337199 kernel: vethacb1b25: renamed from eth0 Nov 4 04:15:49.337322 kernel: docker0: port 1(veth68325a1) entered disabled state Nov 4 04:15:49.337318 systemd-networkd[1629]: veth68325a1: Lost carrier Nov 4 04:15:49.367185 systemd-networkd[1629]: veth68325a1: Link DOWN Nov 4 04:15:49.373850 kernel: docker0: port 1(veth68325a1) entered disabled state Nov 4 04:15:49.373975 kernel: veth68325a1 (unregistering): left allmulticast mode Nov 4 04:15:49.374008 kernel: veth68325a1 (unregistering): left promiscuous mode Nov 4 04:15:49.380879 kernel: docker0: port 1(veth68325a1) entered disabled state Nov 4 04:15:49.394746 systemd[1]: run-docker-netns-4b1a8614581c.mount: Deactivated successfully. Nov 4 04:15:49.398007 systemd[1]: var-lib-docker-overlay2-b69b39098d878692924a4f81722355c5069fbd74eb209267dd4e989fae1c0f8b-merged.mount: Deactivated successfully. Nov 4 04:15:49.482398 sshd[2764]: Connection closed by 10.200.16.10 port 43028 Nov 4 04:15:49.483297 sshd-session[2761]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:49.487055 systemd[1]: sshd@10-10.200.20.11:22-10.200.16.10:43028.service: Deactivated successfully. Nov 4 04:15:49.488692 systemd[1]: session-13.scope: Deactivated successfully. Nov 4 04:15:49.489448 systemd-logind[1909]: Session 13 logged out. Waiting for processes to exit. Nov 4 04:15:49.490490 systemd-logind[1909]: Removed session 13. Nov 4 04:15:49.560937 systemd[1]: Started sshd@11-10.200.20.11:22-10.200.16.10:43030.service - OpenSSH per-connection server daemon (10.200.16.10:43030). Nov 4 04:15:49.914602 sshd[2855]: Accepted publickey for core from 10.200.16.10 port 43030 ssh2: RSA SHA256:CEHBb7xwRdV/tnOR/9kEYJyWKeQZju3S3xayLyJ38vw Nov 4 04:15:49.915743 sshd-session[2855]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 04:15:49.919991 systemd-logind[1909]: New session 14 of user core. Nov 4 04:15:49.927118 systemd[1]: Started session-14.scope - Session 14 of User core. Nov 4 04:15:50.188038 ntpdate[2866]: ntpdate 4.2.8p18@1.4062-o Tue Nov 4 02:33:17 UTC 2025 (1) Nov 4 04:15:50.346287 systemd-networkd[1629]: docker0: Lost carrier Nov 4 04:15:56.955698 ntpdate[2866]: adjust time server 51.81.226.229 offset +0.005345 sec Nov 4 04:15:57.021028 sshd[2858]: Connection closed by 10.200.16.10 port 43030 Nov 4 04:15:57.021687 sshd-session[2855]: pam_unix(sshd:session): session closed for user core Nov 4 04:15:57.025256 systemd[1]: sshd@11-10.200.20.11:22-10.200.16.10:43030.service: Deactivated successfully. Nov 4 04:15:57.027001 systemd[1]: session-14.scope: Deactivated successfully. Nov 4 04:15:57.027706 systemd-logind[1909]: Session 14 logged out. Waiting for processes to exit. Nov 4 04:15:57.028717 systemd-logind[1909]: Removed session 14.