Nov 4 23:16:31.831598 kernel: Booting Linux on physical CPU 0x0000000000 [0x410fd490] Nov 4 23:16:31.831616 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 22:00:29 -00 2025 Nov 4 23:16:31.831622 kernel: KASLR enabled Nov 4 23:16:31.831626 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Nov 4 23:16:31.831631 kernel: printk: legacy bootconsole [pl11] enabled Nov 4 23:16:31.831635 kernel: efi: EFI v2.7 by EDK II Nov 4 23:16:31.831641 kernel: efi: ACPI 2.0=0x3f979018 SMBIOS=0x3f8a0000 SMBIOS 3.0=0x3f880000 MEMATTR=0x3e3f9018 RNG=0x3f979998 MEMRESERVE=0x3db83598 Nov 4 23:16:31.831645 kernel: random: crng init done Nov 4 23:16:31.831649 kernel: secureboot: Secure boot disabled Nov 4 23:16:31.831653 kernel: ACPI: Early table checksum verification disabled Nov 4 23:16:31.831657 kernel: ACPI: RSDP 0x000000003F979018 000024 (v02 VRTUAL) Nov 4 23:16:31.831661 kernel: ACPI: XSDT 0x000000003F979F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831666 kernel: ACPI: FACP 0x000000003F979C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831671 kernel: ACPI: DSDT 0x000000003F95A018 01E046 (v02 MSFTVM DSDT01 00000001 INTL 20230628) Nov 4 23:16:31.831676 kernel: ACPI: DBG2 0x000000003F979B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831681 kernel: ACPI: GTDT 0x000000003F979D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831685 kernel: ACPI: OEM0 0x000000003F979098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831690 kernel: ACPI: SPCR 0x000000003F979A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831695 kernel: ACPI: APIC 0x000000003F979818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831699 kernel: ACPI: SRAT 0x000000003F979198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831704 kernel: ACPI: PPTT 0x000000003F979418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Nov 4 23:16:31.831708 kernel: ACPI: BGRT 0x000000003F979E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Nov 4 23:16:31.831712 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Nov 4 23:16:31.831717 kernel: ACPI: Use ACPI SPCR as default console: No Nov 4 23:16:31.831721 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Nov 4 23:16:31.831726 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] hotplug Nov 4 23:16:31.831730 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] hotplug Nov 4 23:16:31.831735 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] hotplug Nov 4 23:16:31.831740 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] hotplug Nov 4 23:16:31.831744 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] hotplug Nov 4 23:16:31.831749 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] hotplug Nov 4 23:16:31.831753 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] hotplug Nov 4 23:16:31.831757 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] hotplug Nov 4 23:16:31.831762 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] hotplug Nov 4 23:16:31.831766 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] hotplug Nov 4 23:16:31.831770 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] hotplug Nov 4 23:16:31.831775 kernel: NUMA: Node 0 [mem 0x00000000-0x3fffffff] + [mem 0x100000000-0x1bfffffff] -> [mem 0x00000000-0x1bfffffff] Nov 4 23:16:31.831779 kernel: NODE_DATA(0) allocated [mem 0x1bf7ffa00-0x1bf806fff] Nov 4 23:16:31.831785 kernel: Zone ranges: Nov 4 23:16:31.831789 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Nov 4 23:16:31.831796 kernel: DMA32 empty Nov 4 23:16:31.831800 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Nov 4 23:16:31.831805 kernel: Device empty Nov 4 23:16:31.831810 kernel: Movable zone start for each node Nov 4 23:16:31.831815 kernel: Early memory node ranges Nov 4 23:16:31.831820 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Nov 4 23:16:31.831825 kernel: node 0: [mem 0x0000000000824000-0x000000003f38ffff] Nov 4 23:16:31.831829 kernel: node 0: [mem 0x000000003f390000-0x000000003f93ffff] Nov 4 23:16:31.831834 kernel: node 0: [mem 0x000000003f940000-0x000000003f9effff] Nov 4 23:16:31.831839 kernel: node 0: [mem 0x000000003f9f0000-0x000000003fdeffff] Nov 4 23:16:31.831843 kernel: node 0: [mem 0x000000003fdf0000-0x000000003fffffff] Nov 4 23:16:31.831848 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Nov 4 23:16:31.831854 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Nov 4 23:16:31.831858 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Nov 4 23:16:31.831863 kernel: cma: Reserved 16 MiB at 0x000000003ca00000 on node -1 Nov 4 23:16:31.831868 kernel: psci: probing for conduit method from ACPI. Nov 4 23:16:31.831872 kernel: psci: PSCIv1.3 detected in firmware. Nov 4 23:16:31.831877 kernel: psci: Using standard PSCI v0.2 function IDs Nov 4 23:16:31.831881 kernel: psci: MIGRATE_INFO_TYPE not supported. Nov 4 23:16:31.831886 kernel: psci: SMC Calling Convention v1.4 Nov 4 23:16:31.831891 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Nov 4 23:16:31.831895 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Nov 4 23:16:31.831900 kernel: percpu: Embedded 33 pages/cpu s98200 r8192 d28776 u135168 Nov 4 23:16:31.831905 kernel: pcpu-alloc: s98200 r8192 d28776 u135168 alloc=33*4096 Nov 4 23:16:31.831910 kernel: pcpu-alloc: [0] 0 [0] 1 Nov 4 23:16:31.831915 kernel: Detected PIPT I-cache on CPU0 Nov 4 23:16:31.831920 kernel: CPU features: detected: Address authentication (architected QARMA5 algorithm) Nov 4 23:16:31.831924 kernel: CPU features: detected: GIC system register CPU interface Nov 4 23:16:31.831929 kernel: CPU features: detected: Spectre-v4 Nov 4 23:16:31.831934 kernel: CPU features: detected: Spectre-BHB Nov 4 23:16:31.831938 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 4 23:16:31.831943 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 4 23:16:31.831948 kernel: CPU features: detected: ARM erratum 2067961 or 2054223 Nov 4 23:16:31.831952 kernel: CPU features: detected: SSBS not fully self-synchronizing Nov 4 23:16:31.831958 kernel: alternatives: applying boot alternatives Nov 4 23:16:31.831963 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=f5fad2f14ba33eafa636a02c130b28d1e2865e3fd8b50e1d7ad0f6eb8ba09fec Nov 4 23:16:31.831968 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 4 23:16:31.831973 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 4 23:16:31.831978 kernel: Fallback order for Node 0: 0 Nov 4 23:16:31.831982 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1048540 Nov 4 23:16:31.831987 kernel: Policy zone: Normal Nov 4 23:16:31.831992 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 4 23:16:31.831996 kernel: software IO TLB: area num 2. Nov 4 23:16:31.832001 kernel: software IO TLB: mapped [mem 0x00000000372f0000-0x000000003b2f0000] (64MB) Nov 4 23:16:31.832006 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Nov 4 23:16:31.832011 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 4 23:16:31.832016 kernel: rcu: RCU event tracing is enabled. Nov 4 23:16:31.832021 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Nov 4 23:16:31.832026 kernel: Trampoline variant of Tasks RCU enabled. Nov 4 23:16:31.832031 kernel: Tracing variant of Tasks RCU enabled. Nov 4 23:16:31.832035 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 4 23:16:31.832040 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Nov 4 23:16:31.832045 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 23:16:31.832050 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Nov 4 23:16:31.832054 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 4 23:16:31.832059 kernel: GICv3: 960 SPIs implemented Nov 4 23:16:31.832064 kernel: GICv3: 0 Extended SPIs implemented Nov 4 23:16:31.832069 kernel: Root IRQ handler: gic_handle_irq Nov 4 23:16:31.832074 kernel: GICv3: GICv3 features: 16 PPIs, RSS Nov 4 23:16:31.832078 kernel: GICv3: GICD_CTRL.DS=0, SCR_EL3.FIQ=0 Nov 4 23:16:31.832083 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Nov 4 23:16:31.832088 kernel: ITS: No ITS available, not enabling LPIs Nov 4 23:16:31.832093 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Nov 4 23:16:31.832097 kernel: arch_timer: cp15 timer(s) running at 1000.00MHz (virt). Nov 4 23:16:31.832102 kernel: clocksource: arch_sys_counter: mask: 0x1fffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Nov 4 23:16:31.832107 kernel: sched_clock: 61 bits at 1000MHz, resolution 1ns, wraps every 4398046511103ns Nov 4 23:16:31.832112 kernel: Console: colour dummy device 80x25 Nov 4 23:16:31.832118 kernel: printk: legacy console [tty1] enabled Nov 4 23:16:31.832123 kernel: ACPI: Core revision 20240827 Nov 4 23:16:31.832128 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 2000.00 BogoMIPS (lpj=1000000) Nov 4 23:16:31.832133 kernel: pid_max: default: 32768 minimum: 301 Nov 4 23:16:31.832138 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Nov 4 23:16:31.832143 kernel: landlock: Up and running. Nov 4 23:16:31.832148 kernel: SELinux: Initializing. Nov 4 23:16:31.832153 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 4 23:16:31.832158 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 4 23:16:31.832163 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0xa0000e, misc 0x31e1 Nov 4 23:16:31.832168 kernel: Hyper-V: Host Build 10.0.26102.1109-1-0 Nov 4 23:16:31.832177 kernel: Hyper-V: enabling crash_kexec_post_notifiers Nov 4 23:16:31.832183 kernel: rcu: Hierarchical SRCU implementation. Nov 4 23:16:31.832188 kernel: rcu: Max phase no-delay instances is 400. Nov 4 23:16:31.832193 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Nov 4 23:16:31.832198 kernel: Remapping and enabling EFI services. Nov 4 23:16:31.832204 kernel: smp: Bringing up secondary CPUs ... Nov 4 23:16:31.832209 kernel: Detected PIPT I-cache on CPU1 Nov 4 23:16:31.832214 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Nov 4 23:16:31.832219 kernel: CPU1: Booted secondary processor 0x0000000001 [0x410fd490] Nov 4 23:16:31.832225 kernel: smp: Brought up 1 node, 2 CPUs Nov 4 23:16:31.832230 kernel: SMP: Total of 2 processors activated. Nov 4 23:16:31.832236 kernel: CPU: All CPU(s) started at EL1 Nov 4 23:16:31.832241 kernel: CPU features: detected: 32-bit EL0 Support Nov 4 23:16:31.832246 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Nov 4 23:16:31.832252 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 4 23:16:31.832257 kernel: CPU features: detected: Common not Private translations Nov 4 23:16:31.832263 kernel: CPU features: detected: CRC32 instructions Nov 4 23:16:31.832268 kernel: CPU features: detected: Generic authentication (architected QARMA5 algorithm) Nov 4 23:16:31.832273 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Nov 4 23:16:31.832278 kernel: CPU features: detected: LSE atomic instructions Nov 4 23:16:31.832283 kernel: CPU features: detected: Privileged Access Never Nov 4 23:16:31.832288 kernel: CPU features: detected: Speculation barrier (SB) Nov 4 23:16:31.832294 kernel: CPU features: detected: TLB range maintenance instructions Nov 4 23:16:31.832300 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 4 23:16:31.832305 kernel: CPU features: detected: Scalable Vector Extension Nov 4 23:16:31.832310 kernel: alternatives: applying system-wide alternatives Nov 4 23:16:31.832315 kernel: CPU features: detected: Hardware dirty bit management on CPU0-1 Nov 4 23:16:31.832320 kernel: SVE: maximum available vector length 16 bytes per vector Nov 4 23:16:31.832325 kernel: SVE: default vector length 16 bytes per vector Nov 4 23:16:31.832331 kernel: Memory: 3979388K/4194160K available (11136K kernel code, 2456K rwdata, 9084K rodata, 13056K init, 1038K bss, 193584K reserved, 16384K cma-reserved) Nov 4 23:16:31.832337 kernel: devtmpfs: initialized Nov 4 23:16:31.832342 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 4 23:16:31.832347 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Nov 4 23:16:31.832353 kernel: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Nov 4 23:16:31.832358 kernel: 0 pages in range for non-PLT usage Nov 4 23:16:31.832363 kernel: 515040 pages in range for PLT usage Nov 4 23:16:31.832368 kernel: pinctrl core: initialized pinctrl subsystem Nov 4 23:16:31.832374 kernel: SMBIOS 3.1.0 present. Nov 4 23:16:31.832379 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 06/10/2025 Nov 4 23:16:31.832384 kernel: DMI: Memory slots populated: 2/2 Nov 4 23:16:31.832389 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Nov 4 23:16:31.832394 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 4 23:16:31.832400 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 4 23:16:31.832405 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 4 23:16:31.832410 kernel: audit: initializing netlink subsys (disabled) Nov 4 23:16:31.832416 kernel: audit: type=2000 audit(0.059:1): state=initialized audit_enabled=0 res=1 Nov 4 23:16:31.832422 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 4 23:16:31.832427 kernel: cpuidle: using governor menu Nov 4 23:16:31.832432 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 4 23:16:31.832437 kernel: ASID allocator initialised with 32768 entries Nov 4 23:16:31.832442 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 4 23:16:31.832447 kernel: Serial: AMBA PL011 UART driver Nov 4 23:16:31.832453 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Nov 4 23:16:31.832458 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Nov 4 23:16:31.832463 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Nov 4 23:16:31.832469 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Nov 4 23:16:31.832474 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Nov 4 23:16:31.832479 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Nov 4 23:16:31.832484 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Nov 4 23:16:31.832490 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Nov 4 23:16:31.832495 kernel: ACPI: Added _OSI(Module Device) Nov 4 23:16:31.832500 kernel: ACPI: Added _OSI(Processor Device) Nov 4 23:16:31.832506 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 4 23:16:31.832511 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 4 23:16:31.832516 kernel: ACPI: Interpreter enabled Nov 4 23:16:31.832521 kernel: ACPI: Using GIC for interrupt routing Nov 4 23:16:31.832535 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Nov 4 23:16:31.832540 kernel: printk: legacy console [ttyAMA0] enabled Nov 4 23:16:31.832545 kernel: printk: legacy bootconsole [pl11] disabled Nov 4 23:16:31.832551 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Nov 4 23:16:31.832556 kernel: ACPI: CPU0 has been hot-added Nov 4 23:16:31.832561 kernel: ACPI: CPU1 has been hot-added Nov 4 23:16:31.832566 kernel: iommu: Default domain type: Translated Nov 4 23:16:31.832572 kernel: iommu: DMA domain TLB invalidation policy: strict mode Nov 4 23:16:31.832577 kernel: efivars: Registered efivars operations Nov 4 23:16:31.832582 kernel: vgaarb: loaded Nov 4 23:16:31.832588 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 4 23:16:31.832593 kernel: VFS: Disk quotas dquot_6.6.0 Nov 4 23:16:31.832598 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 4 23:16:31.832603 kernel: pnp: PnP ACPI init Nov 4 23:16:31.832609 kernel: pnp: PnP ACPI: found 0 devices Nov 4 23:16:31.832614 kernel: NET: Registered PF_INET protocol family Nov 4 23:16:31.832619 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 4 23:16:31.832624 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 4 23:16:31.832630 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Nov 4 23:16:31.832635 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 4 23:16:31.832640 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Nov 4 23:16:31.832646 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 4 23:16:31.832651 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 4 23:16:31.832656 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 4 23:16:31.832661 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Nov 4 23:16:31.832667 kernel: PCI: CLS 0 bytes, default 64 Nov 4 23:16:31.832672 kernel: kvm [1]: HYP mode not available Nov 4 23:16:31.832677 kernel: Initialise system trusted keyrings Nov 4 23:16:31.832682 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 4 23:16:31.832688 kernel: Key type asymmetric registered Nov 4 23:16:31.832693 kernel: Asymmetric key parser 'x509' registered Nov 4 23:16:31.832698 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Nov 4 23:16:31.832703 kernel: io scheduler mq-deadline registered Nov 4 23:16:31.832708 kernel: io scheduler kyber registered Nov 4 23:16:31.832713 kernel: io scheduler bfq registered Nov 4 23:16:31.832719 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 4 23:16:31.832725 kernel: thunder_xcv, ver 1.0 Nov 4 23:16:31.832730 kernel: thunder_bgx, ver 1.0 Nov 4 23:16:31.832735 kernel: nicpf, ver 1.0 Nov 4 23:16:31.832740 kernel: nicvf, ver 1.0 Nov 4 23:16:31.832856 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 4 23:16:31.832923 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-04T23:16:28 UTC (1762298188) Nov 4 23:16:31.832931 kernel: efifb: probing for efifb Nov 4 23:16:31.832936 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Nov 4 23:16:31.832942 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Nov 4 23:16:31.832947 kernel: efifb: scrolling: redraw Nov 4 23:16:31.832952 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Nov 4 23:16:31.832957 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 23:16:31.832962 kernel: fb0: EFI VGA frame buffer device Nov 4 23:16:31.832968 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Nov 4 23:16:31.832974 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 4 23:16:31.832979 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 (0,8000003f) counters available Nov 4 23:16:31.832984 kernel: watchdog: NMI not fully supported Nov 4 23:16:31.832989 kernel: watchdog: Hard watchdog permanently disabled Nov 4 23:16:31.832995 kernel: NET: Registered PF_INET6 protocol family Nov 4 23:16:31.833000 kernel: Segment Routing with IPv6 Nov 4 23:16:31.833006 kernel: In-situ OAM (IOAM) with IPv6 Nov 4 23:16:31.833011 kernel: NET: Registered PF_PACKET protocol family Nov 4 23:16:31.833016 kernel: Key type dns_resolver registered Nov 4 23:16:31.833021 kernel: registered taskstats version 1 Nov 4 23:16:31.833026 kernel: Loading compiled-in X.509 certificates Nov 4 23:16:31.833034 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.54-flatcar: b9a783989f22a1b93da669ffd1529a2a9e0c2420' Nov 4 23:16:31.833039 kernel: Demotion targets for Node 0: null Nov 4 23:16:31.833045 kernel: Key type .fscrypt registered Nov 4 23:16:31.833050 kernel: Key type fscrypt-provisioning registered Nov 4 23:16:31.833055 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 4 23:16:31.833061 kernel: ima: Allocated hash algorithm: sha1 Nov 4 23:16:31.833066 kernel: ima: No architecture policies found Nov 4 23:16:31.833071 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Nov 4 23:16:31.833076 kernel: clk: Disabling unused clocks Nov 4 23:16:31.833081 kernel: PM: genpd: Disabling unused power domains Nov 4 23:16:31.833087 kernel: Freeing unused kernel memory: 13056K Nov 4 23:16:31.833092 kernel: Run /init as init process Nov 4 23:16:31.833097 kernel: with arguments: Nov 4 23:16:31.833103 kernel: /init Nov 4 23:16:31.833108 kernel: with environment: Nov 4 23:16:31.833112 kernel: HOME=/ Nov 4 23:16:31.833118 kernel: TERM=linux Nov 4 23:16:31.833123 kernel: hv_vmbus: Vmbus version:5.3 Nov 4 23:16:31.833129 kernel: hv_vmbus: registering driver hid_hyperv Nov 4 23:16:31.833134 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Nov 4 23:16:31.833216 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Nov 4 23:16:31.833224 kernel: SCSI subsystem initialized Nov 4 23:16:31.833229 kernel: hv_vmbus: registering driver hyperv_keyboard Nov 4 23:16:31.833236 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 23:16:31.833241 kernel: pps_core: LinuxPPS API ver. 1 registered Nov 4 23:16:31.833246 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Nov 4 23:16:31.833251 kernel: PTP clock support registered Nov 4 23:16:31.833257 kernel: hv_utils: Registering HyperV Utility Driver Nov 4 23:16:31.833262 kernel: hv_vmbus: registering driver hv_utils Nov 4 23:16:31.833267 kernel: hv_utils: Heartbeat IC version 3.0 Nov 4 23:16:31.833273 kernel: hv_utils: Shutdown IC version 3.2 Nov 4 23:16:31.833278 kernel: hv_utils: TimeSync IC version 4.0 Nov 4 23:16:31.833284 kernel: hv_vmbus: registering driver hv_storvsc Nov 4 23:16:31.833374 kernel: scsi host0: storvsc_host_t Nov 4 23:16:31.833457 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Nov 4 23:16:31.833543 kernel: scsi host1: storvsc_host_t Nov 4 23:16:31.833627 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 5 Nov 4 23:16:31.833700 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Nov 4 23:16:31.833774 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Nov 4 23:16:31.833854 kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 4 23:16:31.833951 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Nov 4 23:16:31.834026 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Nov 4 23:16:31.834106 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#61 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:16:31.834173 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#4 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Nov 4 23:16:31.834180 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Nov 4 23:16:31.834251 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 4 23:16:31.834323 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Nov 4 23:16:31.834331 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Nov 4 23:16:31.834402 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Nov 4 23:16:31.834409 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Nov 4 23:16:31.834414 kernel: device-mapper: uevent: version 1.0.3 Nov 4 23:16:31.834419 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Nov 4 23:16:31.834424 kernel: device-mapper: verity: sha256 using shash "sha256-ce" Nov 4 23:16:31.834430 kernel: raid6: neonx8 gen() 18549 MB/s Nov 4 23:16:31.834436 kernel: raid6: neonx4 gen() 18572 MB/s Nov 4 23:16:31.834441 kernel: raid6: neonx2 gen() 17104 MB/s Nov 4 23:16:31.834446 kernel: raid6: neonx1 gen() 15024 MB/s Nov 4 23:16:31.834452 kernel: raid6: int64x8 gen() 10536 MB/s Nov 4 23:16:31.834457 kernel: raid6: int64x4 gen() 10623 MB/s Nov 4 23:16:31.834462 kernel: raid6: int64x2 gen() 8998 MB/s Nov 4 23:16:31.834467 kernel: raid6: int64x1 gen() 7028 MB/s Nov 4 23:16:31.834472 kernel: raid6: using algorithm neonx4 gen() 18572 MB/s Nov 4 23:16:31.834478 kernel: raid6: .... xor() 15157 MB/s, rmw enabled Nov 4 23:16:31.834483 kernel: raid6: using neon recovery algorithm Nov 4 23:16:31.834489 kernel: xor: measuring software checksum speed Nov 4 23:16:31.834494 kernel: 8regs : 28629 MB/sec Nov 4 23:16:31.834499 kernel: 32regs : 28828 MB/sec Nov 4 23:16:31.834504 kernel: arm64_neon : 37694 MB/sec Nov 4 23:16:31.834509 kernel: xor: using function: arm64_neon (37694 MB/sec) Nov 4 23:16:31.834515 kernel: Btrfs loaded, zoned=no, fsverity=no Nov 4 23:16:31.834520 kernel: BTRFS: device fsid 3945a319-ac24-4f41-92d0-9c29c05b835d devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (403) Nov 4 23:16:31.834526 kernel: BTRFS info (device dm-0): first mount of filesystem 3945a319-ac24-4f41-92d0-9c29c05b835d Nov 4 23:16:31.834538 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Nov 4 23:16:31.834543 kernel: BTRFS info (device dm-0): disabling log replay at mount time Nov 4 23:16:31.834549 kernel: BTRFS info (device dm-0): enabling free space tree Nov 4 23:16:31.834554 kernel: loop: module loaded Nov 4 23:16:31.834560 kernel: loop0: detected capacity change from 0 to 91464 Nov 4 23:16:31.834565 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 4 23:16:31.834572 systemd[1]: Successfully made /usr/ read-only. Nov 4 23:16:31.834579 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 23:16:31.834585 systemd[1]: Detected virtualization microsoft. Nov 4 23:16:31.834592 systemd[1]: Detected architecture arm64. Nov 4 23:16:31.834597 systemd[1]: Running in initrd. Nov 4 23:16:31.834603 systemd[1]: No hostname configured, using default hostname. Nov 4 23:16:31.834608 systemd[1]: Hostname set to . Nov 4 23:16:31.834614 systemd[1]: Initializing machine ID from random generator. Nov 4 23:16:31.834619 systemd[1]: Queued start job for default target initrd.target. Nov 4 23:16:31.834625 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 23:16:31.834631 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 23:16:31.834637 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 23:16:31.834643 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Nov 4 23:16:31.834649 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 23:16:31.834655 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Nov 4 23:16:31.834661 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Nov 4 23:16:31.834667 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 23:16:31.834673 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 23:16:31.834679 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Nov 4 23:16:31.834684 systemd[1]: Reached target paths.target - Path Units. Nov 4 23:16:31.834690 systemd[1]: Reached target slices.target - Slice Units. Nov 4 23:16:31.834695 systemd[1]: Reached target swap.target - Swaps. Nov 4 23:16:31.834702 systemd[1]: Reached target timers.target - Timer Units. Nov 4 23:16:31.834707 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 23:16:31.834713 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 23:16:31.834719 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Nov 4 23:16:31.834724 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Nov 4 23:16:31.834730 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 23:16:31.834736 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 23:16:31.834746 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 23:16:31.834753 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 23:16:31.834759 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Nov 4 23:16:31.834765 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Nov 4 23:16:31.834770 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 23:16:31.834777 systemd[1]: Finished network-cleanup.service - Network Cleanup. Nov 4 23:16:31.834783 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 23:16:31.834789 systemd[1]: Starting systemd-fsck-usr.service... Nov 4 23:16:31.834795 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 23:16:31.834800 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 23:16:31.834806 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:16:31.834813 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Nov 4 23:16:31.834819 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 23:16:31.834825 systemd[1]: Finished systemd-fsck-usr.service. Nov 4 23:16:31.834842 systemd-journald[540]: Collecting audit messages is disabled. Nov 4 23:16:31.834857 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 23:16:31.834863 systemd-journald[540]: Journal started Nov 4 23:16:31.834878 systemd-journald[540]: Runtime Journal (/run/log/journal/c0b626001fa04f459142b07dfe0303b8) is 8M, max 78.3M, 70.3M free. Nov 4 23:16:31.844834 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 23:16:31.850215 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Nov 4 23:16:31.867544 kernel: Bridge firewalling registered Nov 4 23:16:31.867667 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 23:16:31.867808 systemd-modules-load[543]: Inserted module 'br_netfilter' Nov 4 23:16:31.880550 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 23:16:31.910918 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 23:16:31.929934 systemd-tmpfiles[554]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Nov 4 23:16:31.938606 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Nov 4 23:16:31.949426 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:31.958058 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 23:16:31.967512 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 23:16:31.973324 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Nov 4 23:16:31.989997 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 23:16:31.996647 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 23:16:32.017654 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 23:16:32.029592 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 23:16:32.086655 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Nov 4 23:16:32.142243 systemd-resolved[567]: Positive Trust Anchors: Nov 4 23:16:32.142252 systemd-resolved[567]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 23:16:32.142254 systemd-resolved[567]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 23:16:32.142281 systemd-resolved[567]: 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 23:16:32.186657 dracut-cmdline[582]: 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=f5fad2f14ba33eafa636a02c130b28d1e2865e3fd8b50e1d7ad0f6eb8ba09fec Nov 4 23:16:32.188954 systemd-resolved[567]: Defaulting to hostname 'linux'. Nov 4 23:16:32.209729 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 23:16:32.214549 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 23:16:32.338552 kernel: Loading iSCSI transport class v2.0-870. Nov 4 23:16:32.382550 kernel: iscsi: registered transport (tcp) Nov 4 23:16:32.412410 kernel: iscsi: registered transport (qla4xxx) Nov 4 23:16:32.412451 kernel: QLogic iSCSI HBA Driver Nov 4 23:16:32.468481 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 23:16:32.487128 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 23:16:32.492996 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 23:16:32.534068 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Nov 4 23:16:32.539562 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Nov 4 23:16:32.557075 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Nov 4 23:16:32.578276 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Nov 4 23:16:32.588096 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 23:16:32.659051 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 23:16:32.674620 systemd-udevd[799]: Using default interface naming scheme 'v257'. Nov 4 23:16:32.679731 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 23:16:32.691440 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Nov 4 23:16:32.712351 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 23:16:32.725251 dracut-pre-trigger[923]: rd.md=0: removing MD RAID activation Nov 4 23:16:32.746930 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 23:16:32.753185 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 23:16:32.760524 systemd-networkd[924]: lo: Link UP Nov 4 23:16:32.760540 systemd-networkd[924]: lo: Gained carrier Nov 4 23:16:32.768497 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 23:16:32.772848 systemd[1]: Reached target network.target - Network. Nov 4 23:16:32.806762 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 23:16:32.818397 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Nov 4 23:16:32.883584 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#5 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 4 23:16:32.922571 kernel: hv_vmbus: registering driver hv_netvsc Nov 4 23:16:32.937759 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:16:32.937822 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:32.942581 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:16:32.952643 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:16:32.980626 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:32.994554 kernel: hv_netvsc 000d3af5-67a8-000d-3af5-67a8000d3af5 eth0: VF slot 1 added Nov 4 23:16:32.998285 systemd-networkd[924]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:16:32.998292 systemd-networkd[924]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 23:16:33.007014 systemd-networkd[924]: eth0: Link UP Nov 4 23:16:33.007125 systemd-networkd[924]: eth0: Gained carrier Nov 4 23:16:33.024592 kernel: hv_vmbus: registering driver hv_pci Nov 4 23:16:33.024612 kernel: hv_pci aec9f152-8422-403f-a575-affe9a29de65: PCI VMBus probing: Using version 0x10004 Nov 4 23:16:33.007133 systemd-networkd[924]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:16:33.038565 systemd-networkd[924]: eth0: DHCPv4 address 10.200.20.38/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 4 23:16:33.054654 kernel: hv_pci aec9f152-8422-403f-a575-affe9a29de65: PCI host bridge to bus 8422:00 Nov 4 23:16:33.054802 kernel: pci_bus 8422:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Nov 4 23:16:33.054908 kernel: pci_bus 8422:00: No busn resource found for root bus, will use [bus 00-ff] Nov 4 23:16:33.065840 kernel: pci 8422:00:02.0: [15b3:101a] type 00 class 0x020000 PCIe Endpoint Nov 4 23:16:33.071578 kernel: pci 8422:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref] Nov 4 23:16:33.075565 kernel: pci 8422:00:02.0: enabling Extended Tags Nov 4 23:16:33.088537 kernel: pci 8422:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 8422:00:02.0 (capable of 252.048 Gb/s with 16.0 GT/s PCIe x16 link) Nov 4 23:16:33.097430 kernel: pci_bus 8422:00: busn_res: [bus 00-ff] end is updated to 00 Nov 4 23:16:33.097615 kernel: pci 8422:00:02.0: BAR 0 [mem 0xfc0000000-0xfc00fffff 64bit pref]: assigned Nov 4 23:16:33.327719 kernel: mlx5_core 8422:00:02.0: enabling device (0000 -> 0002) Nov 4 23:16:33.335734 kernel: mlx5_core 8422:00:02.0: PTM is not supported by PCIe Nov 4 23:16:33.335871 kernel: mlx5_core 8422:00:02.0: firmware version: 16.30.5006 Nov 4 23:16:33.379744 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Nov 4 23:16:33.390405 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Nov 4 23:16:33.444342 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Nov 4 23:16:33.478447 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 23:16:33.524908 kernel: hv_netvsc 000d3af5-67a8-000d-3af5-67a8000d3af5 eth0: VF registering: eth1 Nov 4 23:16:33.525073 kernel: mlx5_core 8422:00:02.0 eth1: joined to eth0 Nov 4 23:16:33.531588 kernel: mlx5_core 8422:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Nov 4 23:16:33.539210 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Nov 4 23:16:33.553744 systemd-networkd[924]: eth1: Interface name change detected, renamed to enP33826s1. Nov 4 23:16:33.559144 kernel: mlx5_core 8422:00:02.0 enP33826s1: renamed from eth1 Nov 4 23:16:33.656576 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Nov 4 23:16:33.663131 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 23:16:33.676816 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 23:16:33.681447 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 23:16:33.705124 kernel: mlx5_core 8422:00:02.0 enP33826s1: Link up Nov 4 23:16:33.694902 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Nov 4 23:16:33.727362 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Nov 4 23:16:33.747591 kernel: hv_netvsc 000d3af5-67a8-000d-3af5-67a8000d3af5 eth0: Data path switched to VF: enP33826s1 Nov 4 23:16:33.738936 systemd-networkd[924]: enP33826s1: Link UP Nov 4 23:16:34.010810 systemd-networkd[924]: enP33826s1: Gained carrier Nov 4 23:16:34.628612 systemd-networkd[924]: eth0: Gained IPv6LL Nov 4 23:16:34.666977 disk-uuid[1034]: Warning: The kernel is still using the old partition table. Nov 4 23:16:34.666977 disk-uuid[1034]: The new table will be used at the next reboot or after you Nov 4 23:16:34.666977 disk-uuid[1034]: run partprobe(8) or kpartx(8) Nov 4 23:16:34.666977 disk-uuid[1034]: The operation has completed successfully. Nov 4 23:16:34.681005 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 4 23:16:34.681089 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Nov 4 23:16:34.691296 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Nov 4 23:16:34.748544 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1195) Nov 4 23:16:34.758433 kernel: BTRFS info (device sda6): first mount of filesystem 66d8fdd7-bb77-4ef3-9186-de6bf719805a Nov 4 23:16:34.758459 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 4 23:16:34.814075 kernel: BTRFS info (device sda6): turning on async discard Nov 4 23:16:34.814105 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 23:16:34.823569 kernel: BTRFS info (device sda6): last unmount of filesystem 66d8fdd7-bb77-4ef3-9186-de6bf719805a Nov 4 23:16:34.823870 systemd[1]: Finished ignition-setup.service - Ignition (setup). Nov 4 23:16:34.830370 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Nov 4 23:16:35.903874 ignition[1214]: Ignition 2.22.0 Nov 4 23:16:35.903888 ignition[1214]: Stage: fetch-offline Nov 4 23:16:35.906959 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 23:16:35.903995 ignition[1214]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:35.918393 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Nov 4 23:16:35.904011 ignition[1214]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:35.904091 ignition[1214]: parsed url from cmdline: "" Nov 4 23:16:35.904093 ignition[1214]: no config URL provided Nov 4 23:16:35.904096 ignition[1214]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 23:16:35.904104 ignition[1214]: no config at "/usr/lib/ignition/user.ign" Nov 4 23:16:35.904107 ignition[1214]: failed to fetch config: resource requires networking Nov 4 23:16:35.904217 ignition[1214]: Ignition finished successfully Nov 4 23:16:35.950204 ignition[1220]: Ignition 2.22.0 Nov 4 23:16:35.950209 ignition[1220]: Stage: fetch Nov 4 23:16:35.950384 ignition[1220]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:35.950391 ignition[1220]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:35.950460 ignition[1220]: parsed url from cmdline: "" Nov 4 23:16:35.950463 ignition[1220]: no config URL provided Nov 4 23:16:35.950467 ignition[1220]: reading system config file "/usr/lib/ignition/user.ign" Nov 4 23:16:35.950471 ignition[1220]: no config at "/usr/lib/ignition/user.ign" Nov 4 23:16:35.950484 ignition[1220]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Nov 4 23:16:36.023748 ignition[1220]: GET result: OK Nov 4 23:16:36.023782 ignition[1220]: config has been read from IMDS userdata Nov 4 23:16:36.023794 ignition[1220]: parsing config with SHA512: 3e76b387a628860470d32d3ee9a785a86ebd03fedaa91d58f0b631491d4783b8f50e504170ae14477b13f9c27494d4c98dccc943480aa9127fe78f5af97a5bfe Nov 4 23:16:36.027272 unknown[1220]: fetched base config from "system" Nov 4 23:16:36.027440 ignition[1220]: fetch: fetch complete Nov 4 23:16:36.027277 unknown[1220]: fetched base config from "system" Nov 4 23:16:36.027448 ignition[1220]: fetch: fetch passed Nov 4 23:16:36.027280 unknown[1220]: fetched user config from "azure" Nov 4 23:16:36.027492 ignition[1220]: Ignition finished successfully Nov 4 23:16:36.031028 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Nov 4 23:16:36.038704 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Nov 4 23:16:36.073918 ignition[1227]: Ignition 2.22.0 Nov 4 23:16:36.073932 ignition[1227]: Stage: kargs Nov 4 23:16:36.074079 ignition[1227]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:36.080774 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Nov 4 23:16:36.074088 ignition[1227]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:36.088865 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Nov 4 23:16:36.074438 ignition[1227]: kargs: kargs passed Nov 4 23:16:36.074476 ignition[1227]: Ignition finished successfully Nov 4 23:16:36.126629 ignition[1233]: Ignition 2.22.0 Nov 4 23:16:36.126641 ignition[1233]: Stage: disks Nov 4 23:16:36.126793 ignition[1233]: no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:36.130394 systemd[1]: Finished ignition-disks.service - Ignition (disks). Nov 4 23:16:36.126799 ignition[1233]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:36.136436 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Nov 4 23:16:36.127195 ignition[1233]: disks: disks passed Nov 4 23:16:36.144040 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Nov 4 23:16:36.127230 ignition[1233]: Ignition finished successfully Nov 4 23:16:36.152720 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 23:16:36.161507 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 23:16:36.170220 systemd[1]: Reached target basic.target - Basic System. Nov 4 23:16:36.177106 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Nov 4 23:16:36.328616 systemd-fsck[1241]: ROOT: clean, 15/6361680 files, 408771/6359552 blocks Nov 4 23:16:36.339709 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Nov 4 23:16:36.345682 systemd[1]: Mounting sysroot.mount - /sysroot... Nov 4 23:16:38.171857 kernel: EXT4-fs (sda9): mounted filesystem 7d7dd2f0-d1ff-4725-86c9-b3137e768051 r/w with ordered data mode. Quota mode: none. Nov 4 23:16:38.173330 systemd[1]: Mounted sysroot.mount - /sysroot. Nov 4 23:16:38.179907 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Nov 4 23:16:38.227884 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 23:16:38.245818 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Nov 4 23:16:38.250650 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Nov 4 23:16:38.258677 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Nov 4 23:16:38.258706 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 23:16:38.278484 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Nov 4 23:16:38.286745 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Nov 4 23:16:38.305541 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1255) Nov 4 23:16:38.314625 kernel: BTRFS info (device sda6): first mount of filesystem 66d8fdd7-bb77-4ef3-9186-de6bf719805a Nov 4 23:16:38.314655 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 4 23:16:38.323277 kernel: BTRFS info (device sda6): turning on async discard Nov 4 23:16:38.323295 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 23:16:38.324340 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 23:16:38.877046 coreos-metadata[1257]: Nov 04 23:16:38.876 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 23:16:38.883733 coreos-metadata[1257]: Nov 04 23:16:38.883 INFO Fetch successful Nov 4 23:16:38.887804 coreos-metadata[1257]: Nov 04 23:16:38.883 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Nov 4 23:16:38.896106 coreos-metadata[1257]: Nov 04 23:16:38.895 INFO Fetch successful Nov 4 23:16:38.921722 coreos-metadata[1257]: Nov 04 23:16:38.921 INFO wrote hostname ci-4487.0.0-n-2641de53ce to /sysroot/etc/hostname Nov 4 23:16:38.928428 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 23:16:39.168828 initrd-setup-root[1286]: cut: /sysroot/etc/passwd: No such file or directory Nov 4 23:16:39.217466 initrd-setup-root[1293]: cut: /sysroot/etc/group: No such file or directory Nov 4 23:16:39.235401 initrd-setup-root[1300]: cut: /sysroot/etc/shadow: No such file or directory Nov 4 23:16:39.240596 initrd-setup-root[1307]: cut: /sysroot/etc/gshadow: No such file or directory Nov 4 23:16:40.359269 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Nov 4 23:16:40.364385 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Nov 4 23:16:40.369656 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Nov 4 23:16:40.432016 systemd[1]: sysroot-oem.mount: Deactivated successfully. Nov 4 23:16:40.470786 kernel: BTRFS info (device sda6): last unmount of filesystem 66d8fdd7-bb77-4ef3-9186-de6bf719805a Nov 4 23:16:40.479513 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Nov 4 23:16:40.491154 ignition[1377]: INFO : Ignition 2.22.0 Nov 4 23:16:40.491154 ignition[1377]: INFO : Stage: mount Nov 4 23:16:40.499580 ignition[1377]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:40.499580 ignition[1377]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:40.499580 ignition[1377]: INFO : mount: mount passed Nov 4 23:16:40.499580 ignition[1377]: INFO : Ignition finished successfully Nov 4 23:16:40.495735 systemd[1]: Finished ignition-mount.service - Ignition (mount). Nov 4 23:16:40.503733 systemd[1]: Starting ignition-files.service - Ignition (files)... Nov 4 23:16:40.528624 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Nov 4 23:16:40.555558 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1386) Nov 4 23:16:40.560290 kernel: BTRFS info (device sda6): first mount of filesystem 66d8fdd7-bb77-4ef3-9186-de6bf719805a Nov 4 23:16:40.564897 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Nov 4 23:16:40.573652 kernel: BTRFS info (device sda6): turning on async discard Nov 4 23:16:40.573664 kernel: BTRFS info (device sda6): enabling free space tree Nov 4 23:16:40.575096 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Nov 4 23:16:40.605436 ignition[1403]: INFO : Ignition 2.22.0 Nov 4 23:16:40.605436 ignition[1403]: INFO : Stage: files Nov 4 23:16:40.605436 ignition[1403]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:40.605436 ignition[1403]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:40.605436 ignition[1403]: DEBUG : files: compiled without relabeling support, skipping Nov 4 23:16:40.634247 ignition[1403]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Nov 4 23:16:40.634247 ignition[1403]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Nov 4 23:16:40.715430 ignition[1403]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Nov 4 23:16:40.720733 ignition[1403]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Nov 4 23:16:40.720733 ignition[1403]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Nov 4 23:16:40.715821 unknown[1403]: wrote ssh authorized keys file for user: core Nov 4 23:16:40.781488 ignition[1403]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 23:16:40.788521 ignition[1403]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Nov 4 23:16:40.788521 ignition[1403]: INFO : files: op(4): [started] processing unit "etcd-member.service" Nov 4 23:16:40.826114 ignition[1403]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Nov 4 23:16:40.842410 ignition[1403]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Nov 4 23:16:40.842410 ignition[1403]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Nov 4 23:16:40.857168 ignition[1403]: INFO : files: op(6): [started] setting preset to enabled for "etcd-member.service" Nov 4 23:16:40.857168 ignition[1403]: INFO : files: op(6): [finished] setting preset to enabled for "etcd-member.service" Nov 4 23:16:40.857168 ignition[1403]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Nov 4 23:16:40.857168 ignition[1403]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Nov 4 23:16:40.857168 ignition[1403]: INFO : files: files passed Nov 4 23:16:40.857168 ignition[1403]: INFO : Ignition finished successfully Nov 4 23:16:40.852835 systemd[1]: Finished ignition-files.service - Ignition (files). Nov 4 23:16:40.862393 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Nov 4 23:16:40.894006 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Nov 4 23:16:40.907112 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 4 23:16:40.917606 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Nov 4 23:16:40.946158 initrd-setup-root-after-ignition[1434]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 23:16:40.946158 initrd-setup-root-after-ignition[1434]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Nov 4 23:16:40.962794 initrd-setup-root-after-ignition[1438]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Nov 4 23:16:40.949387 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 23:16:40.957062 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Nov 4 23:16:40.967652 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Nov 4 23:16:41.009821 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 4 23:16:41.009919 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Nov 4 23:16:41.019676 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Nov 4 23:16:41.027868 systemd[1]: Reached target initrd.target - Initrd Default Target. Nov 4 23:16:41.035521 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Nov 4 23:16:41.036106 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Nov 4 23:16:41.065712 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 23:16:41.072637 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Nov 4 23:16:41.098048 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr. Nov 4 23:16:41.098183 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Nov 4 23:16:41.107319 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 23:16:41.116362 systemd[1]: Stopped target timers.target - Timer Units. Nov 4 23:16:41.124249 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 4 23:16:41.124334 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Nov 4 23:16:41.135716 systemd[1]: Stopped target initrd.target - Initrd Default Target. Nov 4 23:16:41.139947 systemd[1]: Stopped target basic.target - Basic System. Nov 4 23:16:41.148069 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Nov 4 23:16:41.156209 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Nov 4 23:16:41.164180 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Nov 4 23:16:41.172303 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Nov 4 23:16:41.180794 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Nov 4 23:16:41.188727 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Nov 4 23:16:41.197526 systemd[1]: Stopped target sysinit.target - System Initialization. Nov 4 23:16:41.204942 systemd[1]: Stopped target local-fs.target - Local File Systems. Nov 4 23:16:41.213682 systemd[1]: Stopped target swap.target - Swaps. Nov 4 23:16:41.220642 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 4 23:16:41.220729 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Nov 4 23:16:41.231019 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Nov 4 23:16:41.235203 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 23:16:41.243443 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Nov 4 23:16:41.247256 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 23:16:41.252263 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 4 23:16:41.252339 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Nov 4 23:16:41.265017 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Nov 4 23:16:41.265098 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Nov 4 23:16:41.270175 systemd[1]: ignition-files.service: Deactivated successfully. Nov 4 23:16:41.270238 systemd[1]: Stopped ignition-files.service - Ignition (files). Nov 4 23:16:41.339660 ignition[1458]: INFO : Ignition 2.22.0 Nov 4 23:16:41.339660 ignition[1458]: INFO : Stage: umount Nov 4 23:16:41.339660 ignition[1458]: INFO : no configs at "/usr/lib/ignition/base.d" Nov 4 23:16:41.339660 ignition[1458]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Nov 4 23:16:41.339660 ignition[1458]: INFO : umount: umount passed Nov 4 23:16:41.339660 ignition[1458]: INFO : Ignition finished successfully Nov 4 23:16:41.277883 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Nov 4 23:16:41.277951 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Nov 4 23:16:41.288961 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Nov 4 23:16:41.302017 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 4 23:16:41.302132 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 23:16:41.312118 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Nov 4 23:16:41.328016 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 4 23:16:41.328134 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 23:16:41.335661 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 4 23:16:41.335742 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 23:16:41.344641 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 4 23:16:41.344715 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Nov 4 23:16:41.354985 systemd[1]: ignition-mount.service: Deactivated successfully. Nov 4 23:16:41.356549 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Nov 4 23:16:41.364897 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 4 23:16:41.366546 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Nov 4 23:16:41.372349 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 4 23:16:41.372386 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Nov 4 23:16:41.379522 systemd[1]: ignition-kargs.service: Deactivated successfully. Nov 4 23:16:41.379613 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Nov 4 23:16:41.386633 systemd[1]: ignition-fetch.service: Deactivated successfully. Nov 4 23:16:41.386662 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Nov 4 23:16:41.395217 systemd[1]: Stopped target network.target - Network. Nov 4 23:16:41.403335 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Nov 4 23:16:41.403373 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Nov 4 23:16:41.412181 systemd[1]: Stopped target paths.target - Path Units. Nov 4 23:16:41.419830 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 4 23:16:41.424102 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 23:16:41.429383 systemd[1]: Stopped target slices.target - Slice Units. Nov 4 23:16:41.436842 systemd[1]: Stopped target sockets.target - Socket Units. Nov 4 23:16:41.440782 systemd[1]: iscsid.socket: Deactivated successfully. Nov 4 23:16:41.440831 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Nov 4 23:16:41.448456 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 4 23:16:41.448484 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Nov 4 23:16:41.456941 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 4 23:16:41.675151 kernel: hv_netvsc 000d3af5-67a8-000d-3af5-67a8000d3af5 eth0: Data path switched from VF: enP33826s1 Nov 4 23:16:41.456977 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Nov 4 23:16:41.464793 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Nov 4 23:16:41.464819 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Nov 4 23:16:41.473278 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Nov 4 23:16:41.480876 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Nov 4 23:16:41.494017 systemd[1]: sysroot-boot.mount: Deactivated successfully. Nov 4 23:16:41.494447 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 4 23:16:41.495551 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Nov 4 23:16:41.506809 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 4 23:16:41.506875 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Nov 4 23:16:41.520355 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 4 23:16:41.520438 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Nov 4 23:16:41.528213 systemd[1]: Stopped target network-pre.target - Preparation for Network. Nov 4 23:16:41.536296 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 4 23:16:41.536327 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Nov 4 23:16:41.544144 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 4 23:16:41.544190 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Nov 4 23:16:41.552452 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Nov 4 23:16:41.564256 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Nov 4 23:16:41.564322 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Nov 4 23:16:41.573860 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 4 23:16:41.573900 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Nov 4 23:16:41.578958 systemd[1]: systemd-modules-load.service: Deactivated successfully. Nov 4 23:16:41.578986 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Nov 4 23:16:41.589789 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 23:16:41.616831 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 4 23:16:41.616956 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 23:16:41.622761 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 4 23:16:41.622791 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Nov 4 23:16:41.631419 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 4 23:16:41.631441 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 23:16:41.639572 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 4 23:16:41.639608 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Nov 4 23:16:41.651348 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 4 23:16:41.651381 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Nov 4 23:16:41.666030 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 4 23:16:41.666080 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Nov 4 23:16:41.678668 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Nov 4 23:16:41.867580 systemd-journald[540]: Received SIGTERM from PID 1 (systemd). Nov 4 23:16:41.685985 systemd[1]: systemd-network-generator.service: Deactivated successfully. Nov 4 23:16:41.686044 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 23:16:41.694360 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 4 23:16:41.694400 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 23:16:41.702976 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:16:41.703018 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:41.713069 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 4 23:16:41.713143 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Nov 4 23:16:41.753212 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 4 23:16:41.753308 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Nov 4 23:16:41.761551 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Nov 4 23:16:41.770676 systemd[1]: Starting initrd-switch-root.service - Switch Root... Nov 4 23:16:41.793307 systemd[1]: Switching root. Nov 4 23:16:41.919632 systemd-journald[540]: Journal stopped Nov 4 23:16:48.938238 kernel: SELinux: policy capability network_peer_controls=1 Nov 4 23:16:48.938257 kernel: SELinux: policy capability open_perms=1 Nov 4 23:16:48.938265 kernel: SELinux: policy capability extended_socket_class=1 Nov 4 23:16:48.938270 kernel: SELinux: policy capability always_check_network=0 Nov 4 23:16:48.938277 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 4 23:16:48.938283 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 4 23:16:48.938290 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 4 23:16:48.938296 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Nov 4 23:16:48.938301 kernel: SELinux: policy capability userspace_initial_context=0 Nov 4 23:16:48.938307 kernel: audit: type=1403 audit(1762298202.743:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 4 23:16:48.938315 systemd[1]: Successfully loaded SELinux policy in 247.416ms. Nov 4 23:16:48.938322 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 4.442ms. Nov 4 23:16:48.938329 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 23:16:48.938335 systemd[1]: Detected virtualization microsoft. Nov 4 23:16:48.938343 systemd[1]: Detected architecture arm64. Nov 4 23:16:48.938350 systemd[1]: Detected first boot. Nov 4 23:16:48.938356 systemd[1]: Hostname set to . Nov 4 23:16:48.938363 systemd[1]: Initializing machine ID from random generator. Nov 4 23:16:48.938369 zram_generator::config[1501]: No configuration found. Nov 4 23:16:48.938377 kernel: NET: Registered PF_VSOCK protocol family Nov 4 23:16:48.938383 systemd[1]: Populated /etc with preset unit settings. Nov 4 23:16:48.938390 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 4 23:16:48.938396 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Nov 4 23:16:48.938402 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 4 23:16:48.938410 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Nov 4 23:16:48.938417 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Nov 4 23:16:48.938426 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Nov 4 23:16:48.938433 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Nov 4 23:16:48.938439 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Nov 4 23:16:48.938446 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Nov 4 23:16:48.938452 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Nov 4 23:16:48.938460 systemd[1]: Created slice user.slice - User and Session Slice. Nov 4 23:16:48.938466 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Nov 4 23:16:48.938473 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Nov 4 23:16:48.938480 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Nov 4 23:16:48.938487 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Nov 4 23:16:48.938493 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Nov 4 23:16:48.938500 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Nov 4 23:16:48.938507 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Nov 4 23:16:48.938514 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Nov 4 23:16:48.938522 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Nov 4 23:16:48.938549 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Nov 4 23:16:48.938556 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Nov 4 23:16:48.938563 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Nov 4 23:16:48.938571 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Nov 4 23:16:48.938577 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Nov 4 23:16:48.938584 systemd[1]: Reached target remote-fs.target - Remote File Systems. Nov 4 23:16:48.938592 systemd[1]: Reached target slices.target - Slice Units. Nov 4 23:16:48.938598 systemd[1]: Reached target swap.target - Swaps. Nov 4 23:16:48.938605 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Nov 4 23:16:48.938611 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Nov 4 23:16:48.938619 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Nov 4 23:16:48.938626 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Nov 4 23:16:48.938633 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Nov 4 23:16:48.938641 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Nov 4 23:16:48.938647 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Nov 4 23:16:48.938654 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Nov 4 23:16:48.938661 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Nov 4 23:16:48.938667 systemd[1]: Mounting media.mount - External Media Directory... Nov 4 23:16:48.938674 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Nov 4 23:16:48.938681 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Nov 4 23:16:48.938688 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Nov 4 23:16:48.938696 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 23:16:48.938703 systemd[1]: Reached target machines.target - Containers. Nov 4 23:16:48.938709 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Nov 4 23:16:48.938716 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:16:48.938723 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Nov 4 23:16:48.938731 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Nov 4 23:16:48.938738 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:16:48.938745 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 23:16:48.938752 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:16:48.938759 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Nov 4 23:16:48.938765 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:16:48.938772 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Nov 4 23:16:48.938780 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 4 23:16:48.938787 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Nov 4 23:16:48.938793 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 4 23:16:48.938800 systemd[1]: Stopped systemd-fsck-usr.service. Nov 4 23:16:48.938807 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 23:16:48.938814 systemd[1]: Starting systemd-journald.service - Journal Service... Nov 4 23:16:48.938820 kernel: fuse: init (API version 7.41) Nov 4 23:16:48.938827 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Nov 4 23:16:48.938834 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Nov 4 23:16:48.938841 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Nov 4 23:16:48.938847 kernel: ACPI: bus type drm_connector registered Nov 4 23:16:48.938853 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Nov 4 23:16:48.938860 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Nov 4 23:16:48.938879 systemd-journald[1591]: Collecting audit messages is disabled. Nov 4 23:16:48.938896 systemd-journald[1591]: Journal started Nov 4 23:16:48.938912 systemd-journald[1591]: Runtime Journal (/run/log/journal/5abd21bef77a4e9ab176d1263203a780) is 8M, max 78.3M, 70.3M free. Nov 4 23:16:48.183547 systemd[1]: Queued start job for default target multi-user.target. Nov 4 23:16:48.189930 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Nov 4 23:16:48.190333 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 4 23:16:48.190597 systemd[1]: systemd-journald.service: Consumed 2.022s CPU time. Nov 4 23:16:48.949266 systemd[1]: Started systemd-journald.service - Journal Service. Nov 4 23:16:48.950088 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Nov 4 23:16:48.954811 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Nov 4 23:16:48.959944 systemd[1]: Mounted media.mount - External Media Directory. Nov 4 23:16:48.964211 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Nov 4 23:16:48.969235 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Nov 4 23:16:48.974203 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Nov 4 23:16:48.978572 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Nov 4 23:16:48.983610 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Nov 4 23:16:48.988844 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 4 23:16:48.988980 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Nov 4 23:16:48.993926 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:16:48.994040 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:16:48.998756 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 23:16:48.998869 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 23:16:49.003330 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:16:49.003445 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:16:49.008785 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 4 23:16:49.008889 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Nov 4 23:16:49.013419 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:16:49.015564 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:16:49.020043 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Nov 4 23:16:49.025002 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Nov 4 23:16:49.030758 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Nov 4 23:16:49.035885 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Nov 4 23:16:49.041764 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Nov 4 23:16:49.055186 systemd[1]: Reached target network-pre.target - Preparation for Network. Nov 4 23:16:49.059891 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket. Nov 4 23:16:49.065567 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Nov 4 23:16:49.075120 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Nov 4 23:16:49.079499 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Nov 4 23:16:49.079523 systemd[1]: Reached target local-fs.target - Local File Systems. Nov 4 23:16:49.084194 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Nov 4 23:16:49.089264 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:16:49.090055 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Nov 4 23:16:49.100021 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Nov 4 23:16:49.104596 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 23:16:49.105202 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Nov 4 23:16:49.109403 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 23:16:49.110020 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Nov 4 23:16:49.115819 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Nov 4 23:16:49.122025 systemd[1]: Starting systemd-sysusers.service - Create System Users... Nov 4 23:16:49.127289 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Nov 4 23:16:49.132449 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Nov 4 23:16:49.148557 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Nov 4 23:16:49.154082 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Nov 4 23:16:49.160737 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Nov 4 23:16:49.177299 systemd-journald[1591]: Time spent on flushing to /var/log/journal/5abd21bef77a4e9ab176d1263203a780 is 8.408ms for 896 entries. Nov 4 23:16:49.177299 systemd-journald[1591]: System Journal (/var/log/journal/5abd21bef77a4e9ab176d1263203a780) is 8M, max 2.2G, 2.2G free. Nov 4 23:16:49.219655 systemd-journald[1591]: Received client request to flush runtime journal. Nov 4 23:16:49.219710 kernel: loop1: detected capacity change from 0 to 27760 Nov 4 23:16:49.220855 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Nov 4 23:16:49.250174 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 4 23:16:49.252570 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Nov 4 23:16:49.276906 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Nov 4 23:16:49.770854 systemd[1]: Finished systemd-sysusers.service - Create System Users. Nov 4 23:16:49.777868 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Nov 4 23:16:49.783343 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Nov 4 23:16:49.802548 kernel: loop2: detected capacity change from 0 to 100624 Nov 4 23:16:49.836343 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Nov 4 23:16:49.881790 systemd[1]: Started systemd-userdbd.service - User Database Manager. Nov 4 23:16:49.969481 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Nov 4 23:16:49.979493 systemd-resolved[1655]: Positive Trust Anchors: Nov 4 23:16:49.979505 systemd-resolved[1655]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 4 23:16:49.979508 systemd-resolved[1655]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16 Nov 4 23:16:49.979526 systemd-resolved[1655]: 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 23:16:49.982607 systemd-tmpfiles[1656]: ACLs are not supported, ignoring. Nov 4 23:16:49.982620 systemd-tmpfiles[1656]: ACLs are not supported, ignoring. Nov 4 23:16:49.984982 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Nov 4 23:16:49.991921 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Nov 4 23:16:50.013980 systemd-udevd[1668]: Using default interface naming scheme 'v257'. Nov 4 23:16:50.075091 systemd-resolved[1655]: Using system hostname 'ci-4487.0.0-n-2641de53ce'. Nov 4 23:16:50.076062 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Nov 4 23:16:50.081348 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Nov 4 23:16:50.444557 kernel: loop3: detected capacity change from 0 to 119344 Nov 4 23:16:50.662705 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Nov 4 23:16:50.672432 systemd[1]: Starting systemd-networkd.service - Network Configuration... Nov 4 23:16:50.715526 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Nov 4 23:16:50.778546 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#247 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001 Nov 4 23:16:50.830548 kernel: mousedev: PS/2 mouse device common for all mice Nov 4 23:16:50.830602 kernel: hv_vmbus: registering driver hv_balloon Nov 4 23:16:50.844757 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Nov 4 23:16:50.844814 kernel: hv_balloon: Memory hot add disabled on ARM64 Nov 4 23:16:50.880544 kernel: hv_vmbus: registering driver hyperv_fb Nov 4 23:16:50.877667 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:16:50.886560 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Nov 4 23:16:50.895005 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Nov 4 23:16:50.895055 kernel: Console: switching to colour dummy device 80x25 Nov 4 23:16:50.895617 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:16:50.895980 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:50.900524 systemd-networkd[1680]: lo: Link UP Nov 4 23:16:50.905331 kernel: Console: switching to colour frame buffer device 128x48 Nov 4 23:16:50.904436 systemd-networkd[1680]: lo: Gained carrier Nov 4 23:16:50.906447 systemd-networkd[1680]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:16:50.906646 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:16:50.906759 systemd-networkd[1680]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Nov 4 23:16:50.912122 systemd[1]: Started systemd-networkd.service - Network Configuration. Nov 4 23:16:50.921148 systemd[1]: Reached target network.target - Network. Nov 4 23:16:50.926640 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Nov 4 23:16:50.933900 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Nov 4 23:16:50.941964 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 4 23:16:50.947662 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:50.954918 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Nov 4 23:16:50.977560 kernel: mlx5_core 8422:00:02.0 enP33826s1: Link up Nov 4 23:16:50.986547 kernel: loop4: detected capacity change from 0 to 27760 Nov 4 23:16:51.002593 kernel: loop5: detected capacity change from 0 to 100624 Nov 4 23:16:51.015557 kernel: loop6: detected capacity change from 0 to 119344 Nov 4 23:16:51.023317 (sd-merge)[1741]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'. Nov 4 23:16:51.025576 (sd-merge)[1741]: Merged extensions into '/usr'. Nov 4 23:16:51.028482 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Nov 4 23:16:51.043290 kernel: hv_netvsc 000d3af5-67a8-000d-3af5-67a8000d3af5 eth0: Data path switched to VF: enP33826s1 Nov 4 23:16:51.039370 systemd-networkd[1680]: enP33826s1: Link UP Nov 4 23:16:51.039724 systemd-networkd[1680]: eth0: Link UP Nov 4 23:16:51.039728 systemd-networkd[1680]: eth0: Gained carrier Nov 4 23:16:51.039745 systemd-networkd[1680]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:16:51.040182 systemd[1]: Starting ensure-sysext.service... Nov 4 23:16:51.046666 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Nov 4 23:16:51.053822 systemd-networkd[1680]: enP33826s1: Gained carrier Nov 4 23:16:51.056599 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Nov 4 23:16:51.065599 systemd-networkd[1680]: eth0: DHCPv4 address 10.200.20.38/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 4 23:16:51.066563 systemd[1]: Reload requested from client PID 1744 ('systemctl') (unit ensure-sysext.service)... Nov 4 23:16:51.066579 systemd[1]: Reloading... Nov 4 23:16:51.110846 systemd-tmpfiles[1745]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Nov 4 23:16:51.112309 systemd-tmpfiles[1745]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Nov 4 23:16:51.112867 systemd-tmpfiles[1745]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Nov 4 23:16:51.113004 systemd-tmpfiles[1745]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Nov 4 23:16:51.113412 systemd-tmpfiles[1745]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Nov 4 23:16:51.115911 systemd-tmpfiles[1745]: ACLs are not supported, ignoring. Nov 4 23:16:51.116072 systemd-tmpfiles[1745]: ACLs are not supported, ignoring. Nov 4 23:16:51.161556 zram_generator::config[1835]: No configuration found. Nov 4 23:16:51.203555 kernel: MACsec IEEE 802.1AE Nov 4 23:16:51.251726 systemd-tmpfiles[1745]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 23:16:51.251735 systemd-tmpfiles[1745]: Skipping /boot Nov 4 23:16:51.257435 systemd-tmpfiles[1745]: Detected autofs mount point /boot during canonicalization of boot. Nov 4 23:16:51.258267 systemd-tmpfiles[1745]: Skipping /boot Nov 4 23:16:51.305237 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Nov 4 23:16:51.310221 systemd[1]: Reloading finished in 243 ms. Nov 4 23:16:51.351597 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Nov 4 23:16:51.376391 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 23:16:51.382296 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Nov 4 23:16:51.386876 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:16:51.388704 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Nov 4 23:16:51.393942 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:16:51.401459 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:16:51.417710 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:16:51.421978 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:16:51.423737 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Nov 4 23:16:51.428591 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 23:16:51.431469 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Nov 4 23:16:51.444994 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Nov 4 23:16:51.452294 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:16:51.453453 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:16:51.458414 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:16:51.458714 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:16:51.463891 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:16:51.464014 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:16:51.476805 systemd[1]: Finished ensure-sysext.service. Nov 4 23:16:51.481393 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Nov 4 23:16:51.488722 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Nov 4 23:16:51.490501 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Nov 4 23:16:51.499388 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Nov 4 23:16:51.505673 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Nov 4 23:16:51.516766 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Nov 4 23:16:51.521549 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Nov 4 23:16:51.521680 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 23:16:51.521773 systemd[1]: Reached target time-set.target - System Time Set. Nov 4 23:16:51.527515 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Nov 4 23:16:51.533211 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Nov 4 23:16:51.533352 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Nov 4 23:16:51.538725 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 4 23:16:51.538843 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Nov 4 23:16:51.544104 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Nov 4 23:16:51.544225 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Nov 4 23:16:51.549686 systemd[1]: modprobe@loop.service: Deactivated successfully. Nov 4 23:16:51.549791 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Nov 4 23:16:51.556195 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Nov 4 23:16:51.556253 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Nov 4 23:16:51.737009 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Nov 4 23:16:51.908037 augenrules[1936]: No rules Nov 4 23:16:51.909089 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 23:16:51.909263 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 23:16:52.418650 systemd-networkd[1680]: eth0: Gained IPv6LL Nov 4 23:16:52.420798 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Nov 4 23:16:52.427912 systemd[1]: Reached target network-online.target - Network is Online. Nov 4 23:16:52.463242 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Nov 4 23:16:53.903755 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Nov 4 23:16:53.909495 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 23:16:59.759203 ldconfig[1894]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Nov 4 23:16:59.769844 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Nov 4 23:16:59.776121 systemd[1]: Starting systemd-update-done.service - Update is Completed... Nov 4 23:16:59.804904 systemd[1]: Finished systemd-update-done.service - Update is Completed. Nov 4 23:16:59.809387 systemd[1]: Reached target sysinit.target - System Initialization. Nov 4 23:16:59.813515 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Nov 4 23:16:59.818361 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 23:16:59.823225 systemd[1]: Started logrotate.timer - Daily rotation of log files. Nov 4 23:16:59.827394 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Nov 4 23:16:59.832363 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Nov 4 23:16:59.837573 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Nov 4 23:16:59.837597 systemd[1]: Reached target paths.target - Path Units. Nov 4 23:16:59.841075 systemd[1]: Reached target timers.target - Timer Units. Nov 4 23:16:59.868907 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Nov 4 23:16:59.874105 systemd[1]: Starting docker.socket - Docker Socket for the API... Nov 4 23:16:59.879077 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Nov 4 23:16:59.884189 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Nov 4 23:16:59.888957 systemd[1]: Reached target ssh-access.target - SSH Access Available. Nov 4 23:16:59.894309 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Nov 4 23:16:59.913413 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Nov 4 23:16:59.918652 systemd[1]: Listening on docker.socket - Docker Socket for the API. Nov 4 23:16:59.922763 systemd[1]: Reached target sockets.target - Socket Units. Nov 4 23:16:59.926382 systemd[1]: Reached target basic.target - Basic System. Nov 4 23:16:59.930040 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Nov 4 23:16:59.930064 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Nov 4 23:16:59.944883 systemd[1]: Starting chronyd.service - NTP client/server... Nov 4 23:16:59.955626 systemd[1]: Starting containerd.service - containerd container runtime... Nov 4 23:16:59.960391 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Nov 4 23:16:59.966650 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Nov 4 23:16:59.972520 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Nov 4 23:16:59.984101 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Nov 4 23:16:59.989385 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Nov 4 23:16:59.993723 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 23:16:59.994637 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Nov 4 23:16:59.999030 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 23:16:59.999906 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Nov 4 23:17:00.005310 jq[1960]: false Nov 4 23:17:00.005638 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Nov 4 23:17:00.012514 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Nov 4 23:17:00.020893 KVP[1962]: KVP starting; pid is:1962 Nov 4 23:17:00.021359 chronyd[1952]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG) Nov 4 23:17:00.024556 KVP[1962]: KVP LIC Version: 3.1 Nov 4 23:17:00.029284 kernel: hv_utils: KVP IC version 4.0 Nov 4 23:17:00.025682 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Nov 4 23:17:00.034756 systemd[1]: Starting systemd-logind.service - User Login Management... Nov 4 23:17:00.039088 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Nov 4 23:17:00.039464 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 4 23:17:00.040724 systemd[1]: Starting update-engine.service - Update Engine... Nov 4 23:17:00.047502 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Nov 4 23:17:00.053424 chronyd[1952]: Timezone right/UTC failed leap second check, ignoring Nov 4 23:17:00.053915 chronyd[1952]: Loaded seccomp filter (level 2) Nov 4 23:17:00.054985 jq[1977]: true Nov 4 23:17:00.055942 systemd[1]: Started chronyd.service - NTP client/server. Nov 4 23:17:00.062702 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Nov 4 23:17:00.068437 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Nov 4 23:17:00.068784 extend-filesystems[1961]: Found /dev/sda6 Nov 4 23:17:00.073661 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Nov 4 23:17:00.073936 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 4 23:17:00.074752 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Nov 4 23:17:00.086151 systemd[1]: motdgen.service: Deactivated successfully. Nov 4 23:17:00.086298 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Nov 4 23:17:00.096582 (ntainerd)[1994]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Nov 4 23:17:00.098396 jq[1993]: true Nov 4 23:17:00.109784 extend-filesystems[1961]: Found /dev/sda9 Nov 4 23:17:00.113700 extend-filesystems[1961]: Checking size of /dev/sda9 Nov 4 23:17:00.117151 update_engine[1976]: I20251104 23:17:00.114203 1976 main.cc:92] Flatcar Update Engine starting Nov 4 23:17:00.127596 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Nov 4 23:17:00.135605 systemd-logind[1973]: New seat seat0. Nov 4 23:17:00.137121 systemd-logind[1973]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Nov 4 23:17:00.137279 systemd[1]: Started systemd-logind.service - User Login Management. Nov 4 23:17:00.153855 extend-filesystems[1961]: Resized partition /dev/sda9 Nov 4 23:17:00.183494 extend-filesystems[2034]: resize2fs 1.47.3 (8-Jul-2025) Nov 4 23:17:00.202869 kernel: EXT4-fs (sda9): resizing filesystem from 6359552 to 6376955 blocks Nov 4 23:17:00.202925 kernel: EXT4-fs (sda9): resized filesystem to 6376955 Nov 4 23:17:00.246653 extend-filesystems[2034]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Nov 4 23:17:00.246653 extend-filesystems[2034]: old_desc_blocks = 4, new_desc_blocks = 4 Nov 4 23:17:00.246653 extend-filesystems[2034]: The filesystem on /dev/sda9 is now 6376955 (4k) blocks long. Nov 4 23:17:00.252919 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 4 23:17:00.303749 bash[2023]: Updated "/home/core/.ssh/authorized_keys" Nov 4 23:17:00.303821 extend-filesystems[1961]: Resized filesystem in /dev/sda9 Nov 4 23:17:00.254715 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Nov 4 23:17:00.273909 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Nov 4 23:17:00.313075 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Nov 4 23:17:00.501324 dbus-daemon[1955]: [system] SELinux support is enabled Nov 4 23:17:00.501595 systemd[1]: Started dbus.service - D-Bus System Message Bus. Nov 4 23:17:00.509442 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 23:17:00.510196 update_engine[1976]: I20251104 23:17:00.509996 1976 update_check_scheduler.cc:74] Next update check in 3m10s Nov 4 23:17:00.510297 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Nov 4 23:17:00.518823 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 23:17:00.518839 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Nov 4 23:17:00.525350 dbus-daemon[1955]: [system] Successfully activated service 'org.freedesktop.systemd1' Nov 4 23:17:00.525684 systemd[1]: Started update-engine.service - Update Engine. Nov 4 23:17:00.534719 systemd[1]: Started locksmithd.service - Cluster reboot manager. Nov 4 23:17:00.570993 coreos-metadata[1954]: Nov 04 23:17:00.570 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Nov 4 23:17:00.573895 coreos-metadata[1954]: Nov 04 23:17:00.573 INFO Fetch successful Nov 4 23:17:00.574032 coreos-metadata[1954]: Nov 04 23:17:00.574 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Nov 4 23:17:00.578705 coreos-metadata[1954]: Nov 04 23:17:00.578 INFO Fetch successful Nov 4 23:17:00.579006 coreos-metadata[1954]: Nov 04 23:17:00.578 INFO Fetching http://168.63.129.16/machine/3f1707b7-f207-4861-b42c-025da9747e25/aa9bbb14%2D3b31%2D489e%2Dad5c%2D0893021bea9a.%5Fci%2D4487.0.0%2Dn%2D2641de53ce?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Nov 4 23:17:00.580574 coreos-metadata[1954]: Nov 04 23:17:00.580 INFO Fetch successful Nov 4 23:17:00.580774 coreos-metadata[1954]: Nov 04 23:17:00.580 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Nov 4 23:17:00.587622 coreos-metadata[1954]: Nov 04 23:17:00.587 INFO Fetch successful Nov 4 23:17:00.610687 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Nov 4 23:17:00.619432 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Nov 4 23:17:00.626780 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Nov 4 23:17:00.632178 (-wrapper)[2098]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Nov 4 23:17:00.638666 sshd_keygen[1979]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Nov 4 23:17:00.652013 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Nov 4 23:17:00.659457 systemd[1]: Starting issuegen.service - Generate /run/issue... Nov 4 23:17:00.665499 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Nov 4 23:17:00.679178 systemd[1]: issuegen.service: Deactivated successfully. Nov 4 23:17:00.679352 systemd[1]: Finished issuegen.service - Generate /run/issue. Nov 4 23:17:00.687728 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Nov 4 23:17:00.695692 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Nov 4 23:17:00.715602 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Nov 4 23:17:00.725785 systemd[1]: Started getty@tty1.service - Getty on tty1. Nov 4 23:17:00.734761 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Nov 4 23:17:00.740134 systemd[1]: Reached target getty.target - Login Prompts. Nov 4 23:17:00.744281 locksmithd[2091]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 4 23:17:00.959643 containerd[1994]: time="2025-11-04T23:17:00Z" 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 23:17:00.960516 containerd[1994]: time="2025-11-04T23:17:00.960486628Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968693060Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="7.72µs" Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968717164Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968730740Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968850956Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968861036Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968877284Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968911340Z" 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 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.968918276Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.969159900Z" 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 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.969176036Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Nov 4 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.969188756Z" 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 23:17:00.969216 containerd[1994]: time="2025-11-04T23:17:00.969197892Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Nov 4 23:17:00.969510 containerd[1994]: time="2025-11-04T23:17:00.969493868Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Nov 4 23:17:00.970134 containerd[1994]: time="2025-11-04T23:17:00.970115316Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Nov 4 23:17:00.970216 containerd[1994]: time="2025-11-04T23:17:00.970202796Z" 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 23:17:00.970263 containerd[1994]: time="2025-11-04T23:17:00.970253956Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Nov 4 23:17:00.970421 containerd[1994]: time="2025-11-04T23:17:00.970404732Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Nov 4 23:17:00.970660 containerd[1994]: time="2025-11-04T23:17:00.970634364Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Nov 4 23:17:00.970757 containerd[1994]: time="2025-11-04T23:17:00.970740324Z" level=info msg="metadata content store policy set" policy=shared Nov 4 23:17:00.999758 containerd[1994]: time="2025-11-04T23:17:00.999725516Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Nov 4 23:17:00.999807 containerd[1994]: time="2025-11-04T23:17:00.999782700Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Nov 4 23:17:00.999807 containerd[1994]: time="2025-11-04T23:17:00.999793772Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Nov 4 23:17:00.999807 containerd[1994]: time="2025-11-04T23:17:00.999804636Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Nov 4 23:17:00.999841 containerd[1994]: time="2025-11-04T23:17:00.999813172Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Nov 4 23:17:00.999841 containerd[1994]: time="2025-11-04T23:17:00.999819804Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Nov 4 23:17:00.999841 containerd[1994]: time="2025-11-04T23:17:00.999827332Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Nov 4 23:17:00.999841 containerd[1994]: time="2025-11-04T23:17:00.999839868Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Nov 4 23:17:00.999904 containerd[1994]: time="2025-11-04T23:17:00.999847068Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Nov 4 23:17:00.999904 containerd[1994]: time="2025-11-04T23:17:00.999853252Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Nov 4 23:17:00.999904 containerd[1994]: time="2025-11-04T23:17:00.999859420Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Nov 4 23:17:00.999904 containerd[1994]: time="2025-11-04T23:17:00.999867252Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Nov 4 23:17:00.999997 containerd[1994]: time="2025-11-04T23:17:00.999977972Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Nov 4 23:17:00.999997 containerd[1994]: time="2025-11-04T23:17:00.999996668Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Nov 4 23:17:01.000036 containerd[1994]: time="2025-11-04T23:17:01.000006300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Nov 4 23:17:01.000036 containerd[1994]: time="2025-11-04T23:17:01.000013692Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Nov 4 23:17:01.000036 containerd[1994]: time="2025-11-04T23:17:01.000021444Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Nov 4 23:17:01.000036 containerd[1994]: time="2025-11-04T23:17:01.000032476Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Nov 4 23:17:01.000086 containerd[1994]: time="2025-11-04T23:17:01.000039740Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Nov 4 23:17:01.000086 containerd[1994]: time="2025-11-04T23:17:01.000046932Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Nov 4 23:17:01.000086 containerd[1994]: time="2025-11-04T23:17:01.000054324Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Nov 4 23:17:01.000086 containerd[1994]: time="2025-11-04T23:17:01.000061356Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Nov 4 23:17:01.000086 containerd[1994]: time="2025-11-04T23:17:01.000067716Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Nov 4 23:17:01.000157 containerd[1994]: time="2025-11-04T23:17:01.000116420Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Nov 4 23:17:01.000157 containerd[1994]: time="2025-11-04T23:17:01.000126452Z" level=info msg="Start snapshots syncer" Nov 4 23:17:01.000157 containerd[1994]: time="2025-11-04T23:17:01.000147932Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Nov 4 23:17:01.000750 containerd[1994]: time="2025-11-04T23:17:01.000712756Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"containerdRootDir\":\"/var/lib/containerd\",\"containerdEndpoint\":\"/run/containerd/containerd.sock\",\"rootDir\":\"/var/lib/containerd/io.containerd.grpc.v1.cri\",\"stateDir\":\"/run/containerd/io.containerd.grpc.v1.cri\"}" Nov 4 23:17:01.000903 containerd[1994]: time="2025-11-04T23:17:01.000761180Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Nov 4 23:17:01.000903 containerd[1994]: time="2025-11-04T23:17:01.000817756Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Nov 4 23:17:01.000937 containerd[1994]: time="2025-11-04T23:17:01.000926260Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Nov 4 23:17:01.000950 containerd[1994]: time="2025-11-04T23:17:01.000942220Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Nov 4 23:17:01.000970 containerd[1994]: time="2025-11-04T23:17:01.000949564Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Nov 4 23:17:01.000970 containerd[1994]: time="2025-11-04T23:17:01.000957452Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Nov 4 23:17:01.000970 containerd[1994]: time="2025-11-04T23:17:01.000965596Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Nov 4 23:17:01.001010 containerd[1994]: time="2025-11-04T23:17:01.000973964Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Nov 4 23:17:01.001010 containerd[1994]: time="2025-11-04T23:17:01.000980932Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Nov 4 23:17:01.001010 containerd[1994]: time="2025-11-04T23:17:01.000999004Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Nov 4 23:17:01.001010 containerd[1994]: time="2025-11-04T23:17:01.001007004Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001016860Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001039964Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001049124Z" 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 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001054620Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001060244Z" 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 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001065228Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001071092Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001079348Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001091740Z" level=info msg="runtime interface created" Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001094908Z" level=info msg="created NRI interface" Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001099916Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001107316Z" level=info msg="Connect containerd service" Nov 4 23:17:01.001124 containerd[1994]: time="2025-11-04T23:17:01.001125180Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Nov 4 23:17:01.001783 containerd[1994]: time="2025-11-04T23:17:01.001734244Z" 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 23:17:01.656503 containerd[1994]: time="2025-11-04T23:17:01.656438884Z" level=info msg="Start subscribing containerd event" Nov 4 23:17:01.656627 containerd[1994]: time="2025-11-04T23:17:01.656589556Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Nov 4 23:17:01.656666 containerd[1994]: time="2025-11-04T23:17:01.656627924Z" level=info msg=serving... address=/run/containerd/containerd.sock Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656710108Z" level=info msg="Start recovering state" Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656804364Z" level=info msg="Start event monitor" Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656815420Z" level=info msg="Start cni network conf syncer for default" Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656821956Z" level=info msg="Start streaming server" Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656829076Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656834244Z" level=info msg="runtime interface starting up..." Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656844364Z" level=info msg="starting plugins..." Nov 4 23:17:01.656919 containerd[1994]: time="2025-11-04T23:17:01.656856620Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Nov 4 23:17:01.657116 containerd[1994]: time="2025-11-04T23:17:01.657103204Z" level=info msg="containerd successfully booted in 0.697826s" Nov 4 23:17:01.657710 systemd[1]: Started containerd.service - containerd container runtime. Nov 4 23:17:01.664739 systemd[1]: Starting docker.service - Docker Application Container Engine... Nov 4 23:17:01.683769 (dockerd)[2154]: 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 23:17:02.583694 dockerd[2154]: time="2025-11-04T23:17:02.583635700Z" level=info msg="Starting up" Nov 4 23:17:02.586075 dockerd[2154]: time="2025-11-04T23:17:02.586045924Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Nov 4 23:17:02.593177 dockerd[2154]: time="2025-11-04T23:17:02.593149780Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Nov 4 23:17:02.627803 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport394818993-merged.mount: Deactivated successfully. Nov 4 23:17:02.686477 systemd[1]: var-lib-docker-metacopy\x2dcheck211597476-merged.mount: Deactivated successfully. Nov 4 23:17:02.702448 dockerd[2154]: time="2025-11-04T23:17:02.702392612Z" level=info msg="Loading containers: start." Nov 4 23:17:02.769009 waagent[2126]: 2025-11-04T23:17:02.768949Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Nov 4 23:17:02.775337 kernel: Initializing XFRM netlink socket Nov 4 23:17:02.777163 waagent[2126]: 2025-11-04T23:17:02.775626Z INFO Daemon Daemon OS: flatcar 4487.0.0 Nov 4 23:17:02.779133 waagent[2126]: 2025-11-04T23:17:02.779092Z INFO Daemon Daemon Python: 3.11.13 Nov 4 23:17:02.784409 waagent[2126]: 2025-11-04T23:17:02.782304Z INFO Daemon Daemon Run daemon Nov 4 23:17:02.785349 waagent[2126]: 2025-11-04T23:17:02.785316Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.0' Nov 4 23:17:02.791944 waagent[2126]: 2025-11-04T23:17:02.791851Z INFO Daemon Daemon Using waagent for provisioning Nov 4 23:17:02.795538 waagent[2126]: 2025-11-04T23:17:02.795502Z INFO Daemon Daemon Activate resource disk Nov 4 23:17:02.798775 waagent[2126]: 2025-11-04T23:17:02.798743Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Nov 4 23:17:02.806631 waagent[2126]: 2025-11-04T23:17:02.806591Z INFO Daemon Daemon Found device: None Nov 4 23:17:02.809790 waagent[2126]: 2025-11-04T23:17:02.809757Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Nov 4 23:17:02.815849 waagent[2126]: 2025-11-04T23:17:02.815822Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Nov 4 23:17:02.824507 waagent[2126]: 2025-11-04T23:17:02.824465Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 23:17:02.828505 waagent[2126]: 2025-11-04T23:17:02.828474Z INFO Daemon Daemon Running default provisioning handler Nov 4 23:17:02.837851 waagent[2126]: 2025-11-04T23:17:02.837769Z 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 23:17:02.847119 waagent[2126]: 2025-11-04T23:17:02.847085Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Nov 4 23:17:02.854024 waagent[2126]: 2025-11-04T23:17:02.853989Z INFO Daemon Daemon cloud-init is enabled: False Nov 4 23:17:02.857815 waagent[2126]: 2025-11-04T23:17:02.857782Z INFO Daemon Daemon Copying ovf-env.xml Nov 4 23:17:02.977637 waagent[2126]: 2025-11-04T23:17:02.977592Z INFO Daemon Daemon Successfully mounted dvd Nov 4 23:17:03.009180 waagent[2126]: 2025-11-04T23:17:03.009136Z INFO Daemon Daemon Detect protocol endpoint Nov 4 23:17:03.012894 waagent[2126]: 2025-11-04T23:17:03.012858Z INFO Daemon Daemon Clean protocol and wireserver endpoint Nov 4 23:17:03.016851 waagent[2126]: 2025-11-04T23:17:03.016816Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Nov 4 23:17:03.021404 waagent[2126]: 2025-11-04T23:17:03.021376Z INFO Daemon Daemon Test for route to 168.63.129.16 Nov 4 23:17:03.025098 waagent[2126]: 2025-11-04T23:17:03.025069Z INFO Daemon Daemon Route to 168.63.129.16 exists Nov 4 23:17:03.032226 waagent[2126]: 2025-11-04T23:17:03.028698Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Nov 4 23:17:03.120831 waagent[2126]: 2025-11-04T23:17:03.120749Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Nov 4 23:17:03.125610 waagent[2126]: 2025-11-04T23:17:03.125590Z INFO Daemon Daemon Wire protocol version:2012-11-30 Nov 4 23:17:03.129197 waagent[2126]: 2025-11-04T23:17:03.129173Z INFO Daemon Daemon Server preferred version:2015-04-05 Nov 4 23:17:03.272716 waagent[2126]: 2025-11-04T23:17:03.272649Z INFO Daemon Daemon Initializing goal state during protocol detection Nov 4 23:17:03.277499 waagent[2126]: 2025-11-04T23:17:03.277462Z INFO Daemon Daemon Forcing an update of the goal state. Nov 4 23:17:03.284658 waagent[2126]: 2025-11-04T23:17:03.284620Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 23:17:03.302061 waagent[2126]: 2025-11-04T23:17:03.302027Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177 Nov 4 23:17:03.306250 waagent[2126]: 2025-11-04T23:17:03.306217Z INFO Daemon Nov 4 23:17:03.308349 waagent[2126]: 2025-11-04T23:17:03.308318Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: baef2bf9-cb20-44bd-a29e-7b3da5bf1b43 eTag: 14751652089655410018 source: Fabric] Nov 4 23:17:03.316703 waagent[2126]: 2025-11-04T23:17:03.316671Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Nov 4 23:17:03.321547 waagent[2126]: 2025-11-04T23:17:03.321461Z INFO Daemon Nov 4 23:17:03.323473 waagent[2126]: 2025-11-04T23:17:03.323444Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Nov 4 23:17:03.331913 waagent[2126]: 2025-11-04T23:17:03.331887Z INFO Daemon Daemon Downloading artifacts profile blob Nov 4 23:17:03.393761 waagent[2126]: 2025-11-04T23:17:03.393674Z INFO Daemon Downloaded certificate {'thumbprint': '8D09FBD2304062ADE0B97110C63C3BE6402FEEC6', 'hasPrivateKey': True} Nov 4 23:17:03.400991 waagent[2126]: 2025-11-04T23:17:03.400781Z INFO Daemon Fetch goal state completed Nov 4 23:17:03.410072 waagent[2126]: 2025-11-04T23:17:03.410042Z INFO Daemon Daemon Starting provisioning Nov 4 23:17:03.413573 waagent[2126]: 2025-11-04T23:17:03.413541Z INFO Daemon Daemon Handle ovf-env.xml. Nov 4 23:17:03.417213 waagent[2126]: 2025-11-04T23:17:03.417185Z INFO Daemon Daemon Set hostname [ci-4487.0.0-n-2641de53ce] Nov 4 23:17:03.449321 waagent[2126]: 2025-11-04T23:17:03.449281Z INFO Daemon Daemon Publish hostname [ci-4487.0.0-n-2641de53ce] Nov 4 23:17:03.453604 waagent[2126]: 2025-11-04T23:17:03.453571Z INFO Daemon Daemon Examine /proc/net/route for primary interface Nov 4 23:17:03.458165 waagent[2126]: 2025-11-04T23:17:03.458135Z INFO Daemon Daemon Primary interface is [eth0] Nov 4 23:17:03.466990 systemd-networkd[1680]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network Nov 4 23:17:03.466998 systemd-networkd[1680]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network. Nov 4 23:17:03.467069 systemd-networkd[1680]: eth0: DHCP lease lost Nov 4 23:17:03.481213 waagent[2126]: 2025-11-04T23:17:03.477271Z INFO Daemon Daemon Create user account if not exists Nov 4 23:17:03.481413 waagent[2126]: 2025-11-04T23:17:03.481366Z INFO Daemon Daemon User core already exists, skip useradd Nov 4 23:17:03.485262 waagent[2126]: 2025-11-04T23:17:03.485228Z INFO Daemon Daemon Configure sudoer Nov 4 23:17:03.491573 systemd-networkd[1680]: eth0: DHCPv4 address 10.200.20.38/24, gateway 10.200.20.1 acquired from 168.63.129.16 Nov 4 23:17:03.491975 waagent[2126]: 2025-11-04T23:17:03.491765Z INFO Daemon Daemon Configure sshd Nov 4 23:17:03.498478 waagent[2126]: 2025-11-04T23:17:03.498440Z 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 23:17:03.507370 waagent[2126]: 2025-11-04T23:17:03.507338Z INFO Daemon Daemon Deploy ssh public key. Nov 4 23:17:03.625504 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Nov 4 23:17:03.715729 systemd-networkd[1680]: docker0: Link UP Nov 4 23:17:03.733702 dockerd[2154]: time="2025-11-04T23:17:03.733633844Z" level=info msg="Loading containers: done." Nov 4 23:17:03.743624 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3276492579-merged.mount: Deactivated successfully. Nov 4 23:17:03.761021 dockerd[2154]: time="2025-11-04T23:17:03.760960844Z" 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 23:17:03.761021 dockerd[2154]: time="2025-11-04T23:17:03.761021524Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Nov 4 23:17:03.761152 dockerd[2154]: time="2025-11-04T23:17:03.761088828Z" level=info msg="Initializing buildkit" Nov 4 23:17:03.807673 dockerd[2154]: time="2025-11-04T23:17:03.807646636Z" level=info msg="Completed buildkit initialization" Nov 4 23:17:03.813233 dockerd[2154]: time="2025-11-04T23:17:03.813151084Z" level=info msg="Daemon has completed initialization" Nov 4 23:17:03.814404 dockerd[2154]: time="2025-11-04T23:17:03.813426532Z" level=info msg="API listen on /run/docker.sock" Nov 4 23:17:03.813485 systemd[1]: Started docker.service - Docker Application Container Engine. Nov 4 23:17:03.818018 etcd-wrapper[2116]: Error response from daemon: No such container: etcd-member Nov 4 23:17:03.826894 etcd-wrapper[2371]: Error response from daemon: No such container: etcd-member Nov 4 23:17:03.912834 etcd-wrapper[2390]: Unable to find image 'quay.io/coreos/etcd:v3.5.22' locally Nov 4 23:17:04.799400 waagent[2126]: 2025-11-04T23:17:04.799347Z INFO Daemon Daemon Provisioning complete Nov 4 23:17:04.819249 waagent[2126]: 2025-11-04T23:17:04.819212Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Nov 4 23:17:04.823801 waagent[2126]: 2025-11-04T23:17:04.823766Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Nov 4 23:17:04.830652 waagent[2126]: 2025-11-04T23:17:04.830622Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Nov 4 23:17:04.925564 waagent[2397]: 2025-11-04T23:17:04.925201Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Nov 4 23:17:04.925564 waagent[2397]: 2025-11-04T23:17:04.925310Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.0 Nov 4 23:17:04.925564 waagent[2397]: 2025-11-04T23:17:04.925349Z INFO ExtHandler ExtHandler Python: 3.11.13 Nov 4 23:17:04.925564 waagent[2397]: 2025-11-04T23:17:04.925384Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Nov 4 23:17:04.986566 waagent[2397]: 2025-11-04T23:17:04.986167Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Nov 4 23:17:04.986566 waagent[2397]: 2025-11-04T23:17:04.986332Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 23:17:04.986566 waagent[2397]: 2025-11-04T23:17:04.986375Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 23:17:04.992051 waagent[2397]: 2025-11-04T23:17:04.992005Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Nov 4 23:17:04.996785 waagent[2397]: 2025-11-04T23:17:04.996755Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177 Nov 4 23:17:04.997132 waagent[2397]: 2025-11-04T23:17:04.997102Z INFO ExtHandler Nov 4 23:17:04.997185 waagent[2397]: 2025-11-04T23:17:04.997166Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: da0cbde1-5004-401f-8082-525f8dba485d eTag: 14751652089655410018 source: Fabric] Nov 4 23:17:04.997394 waagent[2397]: 2025-11-04T23:17:04.997369Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Nov 4 23:17:04.997801 waagent[2397]: 2025-11-04T23:17:04.997771Z INFO ExtHandler Nov 4 23:17:04.997840 waagent[2397]: 2025-11-04T23:17:04.997824Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Nov 4 23:17:05.005790 waagent[2397]: 2025-11-04T23:17:05.005762Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Nov 4 23:17:05.061400 waagent[2397]: 2025-11-04T23:17:05.061306Z INFO ExtHandler Downloaded certificate {'thumbprint': '8D09FBD2304062ADE0B97110C63C3BE6402FEEC6', 'hasPrivateKey': True} Nov 4 23:17:05.061704 waagent[2397]: 2025-11-04T23:17:05.061671Z INFO ExtHandler Fetch goal state completed Nov 4 23:17:05.071426 waagent[2397]: 2025-11-04T23:17:05.071382Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025) Nov 4 23:17:05.075000 waagent[2397]: 2025-11-04T23:17:05.074959Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2397 Nov 4 23:17:05.075096 waagent[2397]: 2025-11-04T23:17:05.075070Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Nov 4 23:17:05.075320 waagent[2397]: 2025-11-04T23:17:05.075295Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Nov 4 23:17:05.076376 waagent[2397]: 2025-11-04T23:17:05.076344Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] Nov 4 23:17:05.076702 waagent[2397]: 2025-11-04T23:17:05.076673Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Nov 4 23:17:05.076805 waagent[2397]: 2025-11-04T23:17:05.076784Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Nov 4 23:17:05.077194 waagent[2397]: 2025-11-04T23:17:05.077167Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Nov 4 23:17:05.078330 waagent[2397]: 2025-11-04T23:17:05.078305Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Nov 4 23:17:05.078443 waagent[2397]: 2025-11-04T23:17:05.078417Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Nov 4 23:17:05.083049 waagent[2397]: 2025-11-04T23:17:05.083019Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Nov 4 23:17:05.087436 systemd[1]: Reload requested from client PID 2412 ('systemctl') (unit waagent.service)... Nov 4 23:17:05.087673 systemd[1]: Reloading... Nov 4 23:17:05.150553 zram_generator::config[2450]: No configuration found. Nov 4 23:17:05.159871 etcd-wrapper[2390]: v3.5.22: Pulling from coreos/etcd Nov 4 23:17:05.302390 systemd[1]: Reloading finished in 214 ms. Nov 4 23:17:05.321952 waagent[2397]: 2025-11-04T23:17:05.321711Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Nov 4 23:17:05.321952 waagent[2397]: 2025-11-04T23:17:05.321842Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Nov 4 23:17:05.324728 waagent[2397]: 2025-11-04T23:17:05.324650Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Nov 4 23:17:05.325031 waagent[2397]: 2025-11-04T23:17:05.325000Z 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 23:17:05.325892 waagent[2397]: 2025-11-04T23:17:05.325840Z INFO ExtHandler ExtHandler Starting env monitor service. Nov 4 23:17:05.325980 waagent[2397]: 2025-11-04T23:17:05.325947Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 23:17:05.326036 waagent[2397]: 2025-11-04T23:17:05.326014Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 23:17:05.326254 waagent[2397]: 2025-11-04T23:17:05.326223Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Nov 4 23:17:05.326560 waagent[2397]: 2025-11-04T23:17:05.326499Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Nov 4 23:17:05.326684 waagent[2397]: 2025-11-04T23:17:05.326634Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Nov 4 23:17:05.326684 waagent[2397]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Nov 4 23:17:05.326684 waagent[2397]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Nov 4 23:17:05.326684 waagent[2397]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Nov 4 23:17:05.326684 waagent[2397]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Nov 4 23:17:05.326684 waagent[2397]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 23:17:05.326684 waagent[2397]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Nov 4 23:17:05.326684 waagent[2397]: docker0 000011AC 00000000 0001 0 0 0 0000FFFF 0 0 0 Nov 4 23:17:05.326812 waagent[2397]: 2025-11-04T23:17:05.326720Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Nov 4 23:17:05.326812 waagent[2397]: 2025-11-04T23:17:05.326767Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Nov 4 23:17:05.326872 waagent[2397]: 2025-11-04T23:17:05.326849Z INFO EnvHandler ExtHandler Configure routes Nov 4 23:17:05.326910 waagent[2397]: 2025-11-04T23:17:05.326894Z INFO EnvHandler ExtHandler Gateway:None Nov 4 23:17:05.326988 waagent[2397]: 2025-11-04T23:17:05.326923Z INFO EnvHandler ExtHandler Routes:None Nov 4 23:17:05.327233 waagent[2397]: 2025-11-04T23:17:05.327185Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Nov 4 23:17:05.327330 waagent[2397]: 2025-11-04T23:17:05.327289Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Nov 4 23:17:05.327818 waagent[2397]: 2025-11-04T23:17:05.327782Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Nov 4 23:17:05.327874 waagent[2397]: 2025-11-04T23:17:05.327846Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Nov 4 23:17:05.327954 waagent[2397]: 2025-11-04T23:17:05.327913Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Nov 4 23:17:05.333229 waagent[2397]: 2025-11-04T23:17:05.333199Z INFO ExtHandler ExtHandler Nov 4 23:17:05.333359 waagent[2397]: 2025-11-04T23:17:05.333336Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 5f08e7de-3d68-47cc-b11a-c4265cca5c2a correlation 952f8ca5-99ca-444b-875b-bd3ee2e82b07 created: 2025-11-04T23:15:58.753624Z] Nov 4 23:17:05.333709 waagent[2397]: 2025-11-04T23:17:05.333676Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Nov 4 23:17:05.334184 waagent[2397]: 2025-11-04T23:17:05.334156Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms] Nov 4 23:17:05.377906 waagent[2397]: 2025-11-04T23:17:05.377857Z 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 23:17:05.377906 waagent[2397]: Try `iptables -h' or 'iptables --help' for more information.) Nov 4 23:17:05.378205 waagent[2397]: 2025-11-04T23:17:05.378171Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 07530176-8192-4E09-9B6F-B8ADEF6B8766;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Nov 4 23:17:05.459852 etcd-wrapper[2390]: 6c170d3e2c27: Pulling fs layer Nov 4 23:17:05.459987 etcd-wrapper[2390]: 2ae710cd8bfe: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: d462aa345367: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: 0f8b424aa0b9: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: d557676654e5: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: c8022d07192e: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: d858cbc252ad: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: 1069fc2daed1: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: b40161cd83fc: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: 5318d93a3a65: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: 307c1adadb60: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: 43e901b84b4f: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: e248ce7059b2: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: d9c3ebce7174: Pulling fs layer Nov 4 23:17:05.460710 etcd-wrapper[2390]: 0f8b424aa0b9: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: d557676654e5: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: c8022d07192e: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: d858cbc252ad: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: 1069fc2daed1: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: b40161cd83fc: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: 5318d93a3a65: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: 307c1adadb60: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: 43e901b84b4f: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: e248ce7059b2: Waiting Nov 4 23:17:05.460710 etcd-wrapper[2390]: d9c3ebce7174: Waiting Nov 4 23:17:05.471494 waagent[2397]: 2025-11-04T23:17:05.471435Z INFO MonitorHandler ExtHandler Network interfaces: Nov 4 23:17:05.471494 waagent[2397]: Executing ['ip', '-a', '-o', 'link']: Nov 4 23:17:05.471494 waagent[2397]: 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 23:17:05.471494 waagent[2397]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f5:67:a8 brd ff:ff:ff:ff:ff:ff\ altname enx000d3af567a8 Nov 4 23:17:05.471494 waagent[2397]: 3: enP33826s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f5:67:a8 brd ff:ff:ff:ff:ff:ff\ altname enP33826p0s2 Nov 4 23:17:05.471494 waagent[2397]: 4: docker0: mtu 1500 qdisc noqueue state DOWN mode DEFAULT group default \ link/ether b2:ee:de:81:7a:55 brd ff:ff:ff:ff:ff:ff Nov 4 23:17:05.471494 waagent[2397]: Executing ['ip', '-4', '-a', '-o', 'address']: Nov 4 23:17:05.471494 waagent[2397]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Nov 4 23:17:05.471494 waagent[2397]: 2: eth0 inet 10.200.20.38/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Nov 4 23:17:05.471494 waagent[2397]: 4: docker0 inet 172.17.0.1/16 brd 172.17.255.255 scope global docker0\ valid_lft forever preferred_lft forever Nov 4 23:17:05.471494 waagent[2397]: Executing ['ip', '-6', '-a', '-o', 'address']: Nov 4 23:17:05.471494 waagent[2397]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Nov 4 23:17:05.471494 waagent[2397]: 2: eth0 inet6 fe80::20d:3aff:fef5:67a8/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Nov 4 23:17:05.515183 waagent[2397]: 2025-11-04T23:17:05.515136Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Nov 4 23:17:05.515183 waagent[2397]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:17:05.515183 waagent[2397]: pkts bytes target prot opt in out source destination Nov 4 23:17:05.515183 waagent[2397]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:17:05.515183 waagent[2397]: pkts bytes target prot opt in out source destination Nov 4 23:17:05.515183 waagent[2397]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:17:05.515183 waagent[2397]: pkts bytes target prot opt in out source destination Nov 4 23:17:05.515183 waagent[2397]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 23:17:05.515183 waagent[2397]: 4 416 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 23:17:05.515183 waagent[2397]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 23:17:05.518624 waagent[2397]: 2025-11-04T23:17:05.518583Z INFO EnvHandler ExtHandler Current Firewall rules: Nov 4 23:17:05.518624 waagent[2397]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:17:05.518624 waagent[2397]: pkts bytes target prot opt in out source destination Nov 4 23:17:05.518624 waagent[2397]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:17:05.518624 waagent[2397]: pkts bytes target prot opt in out source destination Nov 4 23:17:05.518624 waagent[2397]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Nov 4 23:17:05.518624 waagent[2397]: pkts bytes target prot opt in out source destination Nov 4 23:17:05.518624 waagent[2397]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Nov 4 23:17:05.518624 waagent[2397]: 12 980 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Nov 4 23:17:05.518624 waagent[2397]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Nov 4 23:17:05.518803 waagent[2397]: 2025-11-04T23:17:05.518777Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Nov 4 23:17:05.728636 etcd-wrapper[2390]: 2ae710cd8bfe: Verifying Checksum Nov 4 23:17:05.728636 etcd-wrapper[2390]: 2ae710cd8bfe: Download complete Nov 4 23:17:05.762445 etcd-wrapper[2390]: d462aa345367: Verifying Checksum Nov 4 23:17:05.762445 etcd-wrapper[2390]: d462aa345367: Download complete Nov 4 23:17:05.779711 etcd-wrapper[2390]: 6c170d3e2c27: Verifying Checksum Nov 4 23:17:05.779711 etcd-wrapper[2390]: 6c170d3e2c27: Download complete Nov 4 23:17:05.854703 etcd-wrapper[2390]: 6c170d3e2c27: Pull complete Nov 4 23:17:05.890139 systemd[1]: var-lib-docker-overlay2-958b1dfe8c0717efb062a40b3d42fc29117d6c000812bebb902f2d2db27d3462-merged.mount: Deactivated successfully. Nov 4 23:17:05.939058 etcd-wrapper[2390]: 2ae710cd8bfe: Pull complete Nov 4 23:17:05.991473 etcd-wrapper[2390]: 0f8b424aa0b9: Verifying Checksum Nov 4 23:17:05.991473 etcd-wrapper[2390]: 0f8b424aa0b9: Download complete Nov 4 23:17:06.036823 etcd-wrapper[2390]: d557676654e5: Verifying Checksum Nov 4 23:17:06.036823 etcd-wrapper[2390]: d557676654e5: Download complete Nov 4 23:17:06.038476 etcd-wrapper[2390]: c8022d07192e: Verifying Checksum Nov 4 23:17:06.038476 etcd-wrapper[2390]: c8022d07192e: Download complete Nov 4 23:17:06.231664 etcd-wrapper[2390]: d858cbc252ad: Verifying Checksum Nov 4 23:17:06.231664 etcd-wrapper[2390]: d858cbc252ad: Download complete Nov 4 23:17:06.303010 etcd-wrapper[2390]: b40161cd83fc: Verifying Checksum Nov 4 23:17:06.303010 etcd-wrapper[2390]: b40161cd83fc: Download complete Nov 4 23:17:06.328158 etcd-wrapper[2390]: 1069fc2daed1: Verifying Checksum Nov 4 23:17:06.328158 etcd-wrapper[2390]: 1069fc2daed1: Download complete Nov 4 23:17:06.431960 etcd-wrapper[2390]: d462aa345367: Pull complete Nov 4 23:17:06.440331 login[2129]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Nov 4 23:17:06.467707 login[2134]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:06.470338 etcd-wrapper[2390]: 5318d93a3a65: Verifying Checksum Nov 4 23:17:06.470338 etcd-wrapper[2390]: 5318d93a3a65: Download complete Nov 4 23:17:06.478166 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Nov 4 23:17:06.479997 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Nov 4 23:17:06.482602 systemd-logind[1973]: New session 2 of user core. Nov 4 23:17:06.502823 etcd-wrapper[2390]: 0f8b424aa0b9: Pull complete Nov 4 23:17:06.507209 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Nov 4 23:17:06.509214 systemd[1]: Starting user@500.service - User Manager for UID 500... Nov 4 23:17:06.530465 (systemd)[2563]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 4 23:17:06.532220 systemd-logind[1973]: New session c1 of user core. Nov 4 23:17:06.539361 etcd-wrapper[2390]: 307c1adadb60: Verifying Checksum Nov 4 23:17:06.539947 etcd-wrapper[2390]: 307c1adadb60: Download complete Nov 4 23:17:06.608033 etcd-wrapper[2390]: d557676654e5: Pull complete Nov 4 23:17:06.672263 etcd-wrapper[2390]: c8022d07192e: Pull complete Nov 4 23:17:06.746682 systemd[1]: var-lib-docker-overlay2-dee5558daba56e671a5365433b694cf63cec88f905dea7b16e87cf44a80a1adb-merged.mount: Deactivated successfully. Nov 4 23:17:06.748142 etcd-wrapper[2390]: 43e901b84b4f: Verifying Checksum Nov 4 23:17:06.748142 etcd-wrapper[2390]: 43e901b84b4f: Download complete Nov 4 23:17:06.761512 etcd-wrapper[2390]: d858cbc252ad: Pull complete Nov 4 23:17:06.791196 systemd[1]: var-lib-docker-overlay2-049044a47fdc9c4898872fef83cc1bcca6be2e177e008c3e8c47a9faf0332947-merged.mount: Deactivated successfully. Nov 4 23:17:06.794445 systemd[2563]: Queued start job for default target default.target. Nov 4 23:17:06.799839 systemd[2563]: Created slice app.slice - User Application Slice. Nov 4 23:17:06.799856 systemd[2563]: Reached target paths.target - Paths. Nov 4 23:17:06.799891 systemd[2563]: Reached target timers.target - Timers. Nov 4 23:17:06.800826 systemd[2563]: Starting dbus.socket - D-Bus User Message Bus Socket... Nov 4 23:17:06.809681 systemd[2563]: Listening on dbus.socket - D-Bus User Message Bus Socket. Nov 4 23:17:06.809734 systemd[2563]: Reached target sockets.target - Sockets. Nov 4 23:17:06.809765 systemd[2563]: Reached target basic.target - Basic System. Nov 4 23:17:06.809787 systemd[2563]: Reached target default.target - Main User Target. Nov 4 23:17:06.809805 systemd[2563]: Startup finished in 272ms. Nov 4 23:17:06.810071 systemd[1]: Started user@500.service - User Manager for UID 500. Nov 4 23:17:06.817816 systemd[1]: Started session-2.scope - Session 2 of User core. Nov 4 23:17:06.852450 etcd-wrapper[2390]: 1069fc2daed1: Pull complete Nov 4 23:17:06.883741 systemd[1]: var-lib-docker-overlay2-7f110f17956b9545df33046f6f41f8225e43841b3fec9a86a1bbf1157a721938-merged.mount: Deactivated successfully. Nov 4 23:17:06.922271 etcd-wrapper[2390]: b40161cd83fc: Pull complete Nov 4 23:17:06.984119 etcd-wrapper[2390]: 5318d93a3a65: Pull complete Nov 4 23:17:06.986558 etcd-wrapper[2390]: d9c3ebce7174: Verifying Checksum Nov 4 23:17:06.986620 etcd-wrapper[2390]: d9c3ebce7174: Download complete Nov 4 23:17:07.009723 etcd-wrapper[2390]: e248ce7059b2: Verifying Checksum Nov 4 23:17:07.009723 etcd-wrapper[2390]: e248ce7059b2: Download complete Nov 4 23:17:07.058983 etcd-wrapper[2390]: 307c1adadb60: Pull complete Nov 4 23:17:07.286244 etcd-wrapper[2390]: 43e901b84b4f: Pull complete Nov 4 23:17:07.440761 login[2129]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:07.444853 systemd-logind[1973]: New session 1 of user core. Nov 4 23:17:07.448618 systemd[1]: Started session-1.scope - Session 1 of User core. Nov 4 23:17:07.452782 etcd-wrapper[2390]: e248ce7059b2: Pull complete Nov 4 23:17:07.601877 etcd-wrapper[2390]: d9c3ebce7174: Pull complete Nov 4 23:17:07.626994 etcd-wrapper[2390]: Digest: sha256:51efcfc4f3a863c072ebe745942f790878c2a268e713a6d140c4f2593271adc6 Nov 4 23:17:07.642820 etcd-wrapper[2390]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.22 Nov 4 23:17:07.752171 containerd[1994]: time="2025-11-04T23:17:07.752123684Z" level=info msg="connecting to shim 2f248ae1913d8b6e887a3360fd1cde1835c1b0f02725b1fcb868b94a056f2b4b" address="unix:///run/containerd/s/0ffa45d71e1f7a73e4b09ba11fc6465143f9e499b9f27c06cc41671ea55ea39d" namespace=moby protocol=ttrpc version=3 Nov 4 23:17:07.780642 systemd[1]: Started docker-2f248ae1913d8b6e887a3360fd1cde1835c1b0f02725b1fcb868b94a056f2b4b.scope - libcontainer container 2f248ae1913d8b6e887a3360fd1cde1835c1b0f02725b1fcb868b94a056f2b4b. Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.872844Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.873182Z","caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"5abd21bef77a4e9ab176d1263203a780"} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.873285Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.873351Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.22"} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.873413Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.873480Z","caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.873582Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.873679Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.200.20.38:2379"]} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.873791Z","caller":"embed/config.go:689","msg":"Running http and grpc server on single port. This is not recommended for production."} Nov 4 23:17:07.874731 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.873875Z","caller":"embed/etcd.go:140","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Nov 4 23:17:07.875683 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.875333Z","caller":"embed/etcd.go:148","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Nov 4 23:17:07.875683 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.875434Z","caller":"embed/etcd.go:323","msg":"starting an etcd server","etcd-version":"3.5.22","git-sha":"7862c85","go-version":"go1.23.11","go-os":"linux","go-arch":"arm64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"5abd21bef77a4e9ab176d1263203a780","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.200.20.38:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"5abd21bef77a4e9ab176d1263203a780=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} Nov 4 23:17:07.880945 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.880501Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.526904ms"} Nov 4 23:17:07.889544 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.889406Z","caller":"etcdserver/raft.go:507","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Nov 4 23:17:07.889742 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.889625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Nov 4 23:17:07.889866 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.889663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Nov 4 23:17:07.889944 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.889803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Nov 4 23:17:07.890072 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.889815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Nov 4 23:17:07.890195 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.890023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 4 23:17:07.898792 etcd-wrapper[2390]: {"level":"warn","ts":"2025-11-04T23:17:07.898693Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} Nov 4 23:17:07.901898 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.901646Z","caller":"mvcc/kvstore.go:425","msg":"kvstore restored","current-rev":1} Nov 4 23:17:07.901898 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.901701Z","caller":"etcdserver/server.go:628","msg":"restore consistentIndex","index":0} Nov 4 23:17:07.904746 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.904624Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Nov 4 23:17:07.907352 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.907272Z","caller":"etcdserver/server.go:875","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.22","cluster-version":"to_be_decided"} Nov 4 23:17:07.907757 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.907670Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 4 23:17:07.908481 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.908184Z","caller":"etcdserver/server.go:759","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Nov 4 23:17:07.908481 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.908273Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} Nov 4 23:17:07.908481 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.908291Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} Nov 4 23:17:07.908481 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.908297Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} Nov 4 23:17:07.909145 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.909048Z","caller":"embed/etcd.go:292","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.200.20.38:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Nov 4 23:17:07.909357 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.909169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Nov 4 23:17:07.909493 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.909258Z","caller":"embed/etcd.go:633","msg":"serving peer traffic","address":"127.0.0.1:2380"} Nov 4 23:17:07.909634 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.909507Z","caller":"embed/etcd.go:603","msg":"cmux::serve","address":"127.0.0.1:2380"} Nov 4 23:17:07.909785 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:07.909573Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"],"added-peer-is-learner":false} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.790923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.790963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.791002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.791012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.791016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.791023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Nov 4 23:17:08.791343 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.791030Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Nov 4 23:17:08.793796 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.793638Z","caller":"etcdserver/server.go:2144","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:5abd21bef77a4e9ab176d1263203a780 ClientURLs:[http://10.200.20.38:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Nov 4 23:17:08.793977 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.793740Z","caller":"etcdserver/server.go:2697","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Nov 4 23:17:08.794378 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.794099Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} Nov 4 23:17:08.794627 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.794347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} Nov 4 23:17:08.794627 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.794488Z","caller":"embed/serve.go:124","msg":"ready to serve client requests"} Nov 4 23:17:08.794605 systemd[1]: Started etcd-member.service - etcd (System Application Container). Nov 4 23:17:08.795049 systemd[1]: Reached target multi-user.target - Multi-User System. Nov 4 23:17:08.795512 systemd[1]: Startup finished in 3.040s (kernel) + 11.839s (initrd) + 26.297s (userspace) = 41.176s. Nov 4 23:17:08.796166 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.795195Z","caller":"v3rpc/health.go:61","msg":"grpc service status changed","service":"","status":"SERVING"} Nov 4 23:17:08.796166 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.795422Z","caller":"embed/serve.go:210","msg":"serving client traffic insecurely; this is strongly discouraged!","traffic":"grpc+http","address":"[::]:2379"} Nov 4 23:17:08.796166 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.795782Z","caller":"membership/cluster.go:587","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Nov 4 23:17:08.796166 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.795927Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Nov 4 23:17:08.796166 etcd-wrapper[2390]: {"level":"info","ts":"2025-11-04T23:17:08.795973Z","caller":"etcdserver/server.go:2721","msg":"cluster version is updated","cluster-version":"3.5"} Nov 4 23:17:15.332442 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Nov 4 23:17:15.333717 systemd[1]: Started sshd@0-10.200.20.38:22-10.200.16.10:54722.service - OpenSSH per-connection server daemon (10.200.16.10:54722). Nov 4 23:17:16.020017 sshd[2697]: Accepted publickey for core from 10.200.16.10 port 54722 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:16.021076 sshd-session[2697]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:16.024756 systemd-logind[1973]: New session 3 of user core. Nov 4 23:17:16.031638 systemd[1]: Started session-3.scope - Session 3 of User core. Nov 4 23:17:16.457708 systemd[1]: Started sshd@1-10.200.20.38:22-10.200.16.10:54724.service - OpenSSH per-connection server daemon (10.200.16.10:54724). Nov 4 23:17:16.950930 sshd[2703]: Accepted publickey for core from 10.200.16.10 port 54724 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:16.951905 sshd-session[2703]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:16.955403 systemd-logind[1973]: New session 4 of user core. Nov 4 23:17:16.962644 systemd[1]: Started session-4.scope - Session 4 of User core. Nov 4 23:17:17.320577 sshd[2706]: Connection closed by 10.200.16.10 port 54724 Nov 4 23:17:17.321070 sshd-session[2703]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:17.324118 systemd[1]: sshd@1-10.200.20.38:22-10.200.16.10:54724.service: Deactivated successfully. Nov 4 23:17:17.325447 systemd[1]: session-4.scope: Deactivated successfully. Nov 4 23:17:17.326065 systemd-logind[1973]: Session 4 logged out. Waiting for processes to exit. Nov 4 23:17:17.327137 systemd-logind[1973]: Removed session 4. Nov 4 23:17:17.411923 systemd[1]: Started sshd@2-10.200.20.38:22-10.200.16.10:54738.service - OpenSSH per-connection server daemon (10.200.16.10:54738). Nov 4 23:17:17.902572 sshd[2712]: Accepted publickey for core from 10.200.16.10 port 54738 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:17.903842 sshd-session[2712]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:17.907161 systemd-logind[1973]: New session 5 of user core. Nov 4 23:17:17.917806 systemd[1]: Started session-5.scope - Session 5 of User core. Nov 4 23:17:18.251605 sshd[2715]: Connection closed by 10.200.16.10 port 54738 Nov 4 23:17:18.252207 sshd-session[2712]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:18.255163 systemd[1]: sshd@2-10.200.20.38:22-10.200.16.10:54738.service: Deactivated successfully. Nov 4 23:17:18.256451 systemd[1]: session-5.scope: Deactivated successfully. Nov 4 23:17:18.257483 systemd-logind[1973]: Session 5 logged out. Waiting for processes to exit. Nov 4 23:17:18.258509 systemd-logind[1973]: Removed session 5. Nov 4 23:17:18.350782 systemd[1]: Started sshd@3-10.200.20.38:22-10.200.16.10:54754.service - OpenSSH per-connection server daemon (10.200.16.10:54754). Nov 4 23:17:18.840838 sshd[2721]: Accepted publickey for core from 10.200.16.10 port 54754 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:18.841809 sshd-session[2721]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:18.845422 systemd-logind[1973]: New session 6 of user core. Nov 4 23:17:18.852642 systemd[1]: Started session-6.scope - Session 6 of User core. Nov 4 23:17:19.204600 sshd[2724]: Connection closed by 10.200.16.10 port 54754 Nov 4 23:17:19.205175 sshd-session[2721]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:19.208153 systemd[1]: sshd@3-10.200.20.38:22-10.200.16.10:54754.service: Deactivated successfully. Nov 4 23:17:19.209408 systemd[1]: session-6.scope: Deactivated successfully. Nov 4 23:17:19.210275 systemd-logind[1973]: Session 6 logged out. Waiting for processes to exit. Nov 4 23:17:19.211184 systemd-logind[1973]: Removed session 6. Nov 4 23:17:19.291827 systemd[1]: Started sshd@4-10.200.20.38:22-10.200.16.10:54770.service - OpenSSH per-connection server daemon (10.200.16.10:54770). Nov 4 23:17:19.782878 sshd[2730]: Accepted publickey for core from 10.200.16.10 port 54770 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:19.783849 sshd-session[2730]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:19.787261 systemd-logind[1973]: New session 7 of user core. Nov 4 23:17:19.795646 systemd[1]: Started session-7.scope - Session 7 of User core. Nov 4 23:17:20.236307 sudo[2734]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Nov 4 23:17:20.236514 sudo[2734]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 23:17:20.260797 sudo[2734]: pam_unix(sudo:session): session closed for user root Nov 4 23:17:20.338564 sshd[2733]: Connection closed by 10.200.16.10 port 54770 Nov 4 23:17:20.339299 sshd-session[2730]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:20.342739 systemd[1]: sshd@4-10.200.20.38:22-10.200.16.10:54770.service: Deactivated successfully. Nov 4 23:17:20.344270 systemd[1]: session-7.scope: Deactivated successfully. Nov 4 23:17:20.344940 systemd-logind[1973]: Session 7 logged out. Waiting for processes to exit. Nov 4 23:17:20.346025 systemd-logind[1973]: Removed session 7. Nov 4 23:17:20.424330 systemd[1]: Started sshd@5-10.200.20.38:22-10.200.16.10:39360.service - OpenSSH per-connection server daemon (10.200.16.10:39360). Nov 4 23:17:20.876898 sshd[2740]: Accepted publickey for core from 10.200.16.10 port 39360 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:20.877878 sshd-session[2740]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:20.881342 systemd-logind[1973]: New session 8 of user core. Nov 4 23:17:20.888644 systemd[1]: Started session-8.scope - Session 8 of User core. Nov 4 23:17:21.132469 sudo[2745]: 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 23:17:21.132701 sudo[2745]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 23:17:21.138792 sudo[2745]: pam_unix(sudo:session): session closed for user root Nov 4 23:17:21.142754 sudo[2744]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Nov 4 23:17:21.142931 sudo[2744]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Nov 4 23:17:21.150074 systemd[1]: Starting audit-rules.service - Load Audit Rules... Nov 4 23:17:21.176118 augenrules[2767]: No rules Nov 4 23:17:21.177210 systemd[1]: audit-rules.service: Deactivated successfully. Nov 4 23:17:21.177504 systemd[1]: Finished audit-rules.service - Load Audit Rules. Nov 4 23:17:21.180777 sudo[2744]: pam_unix(sudo:session): session closed for user root Nov 4 23:17:21.258488 sshd[2743]: Connection closed by 10.200.16.10 port 39360 Nov 4 23:17:21.258740 sshd-session[2740]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:21.261931 systemd-logind[1973]: Session 8 logged out. Waiting for processes to exit. Nov 4 23:17:21.262314 systemd[1]: sshd@5-10.200.20.38:22-10.200.16.10:39360.service: Deactivated successfully. Nov 4 23:17:21.263716 systemd[1]: session-8.scope: Deactivated successfully. Nov 4 23:17:21.264986 systemd-logind[1973]: Removed session 8. Nov 4 23:17:21.343623 systemd[1]: Started sshd@6-10.200.20.38:22-10.200.16.10:39372.service - OpenSSH per-connection server daemon (10.200.16.10:39372). Nov 4 23:17:21.799257 sshd[2776]: Accepted publickey for core from 10.200.16.10 port 39372 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:21.800208 sshd-session[2776]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:21.803693 systemd-logind[1973]: New session 9 of user core. Nov 4 23:17:21.810798 systemd[1]: Started session-9.scope - Session 9 of User core. Nov 4 23:17:22.132625 sshd[2779]: Connection closed by 10.200.16.10 port 39372 Nov 4 23:17:22.133126 sshd-session[2776]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:22.135810 systemd[1]: sshd@6-10.200.20.38:22-10.200.16.10:39372.service: Deactivated successfully. Nov 4 23:17:22.137497 systemd[1]: session-9.scope: Deactivated successfully. Nov 4 23:17:22.140049 systemd-logind[1973]: Session 9 logged out. Waiting for processes to exit. Nov 4 23:17:22.140927 systemd-logind[1973]: Removed session 9. Nov 4 23:17:22.222642 systemd[1]: Started sshd@7-10.200.20.38:22-10.200.16.10:39378.service - OpenSSH per-connection server daemon (10.200.16.10:39378). Nov 4 23:17:22.720973 sshd[2787]: Accepted publickey for core from 10.200.16.10 port 39378 ssh2: RSA SHA256:FPqWGE+TkwbKvxUtLH9JIrKCWhkC++HGKQZX/gresVM Nov 4 23:17:22.721976 sshd-session[2787]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Nov 4 23:17:22.725445 systemd-logind[1973]: New session 10 of user core. Nov 4 23:17:22.735637 systemd[1]: Started session-10.scope - Session 10 of User core. Nov 4 23:17:23.386205 sshd[2790]: Connection closed by 10.200.16.10 port 39378 Nov 4 23:17:23.386710 sshd-session[2787]: pam_unix(sshd:session): session closed for user core Nov 4 23:17:23.389662 systemd[1]: sshd@7-10.200.20.38:22-10.200.16.10:39378.service: Deactivated successfully. Nov 4 23:17:23.391172 systemd[1]: session-10.scope: Deactivated successfully. Nov 4 23:17:23.392272 systemd-logind[1973]: Session 10 logged out. Waiting for processes to exit. Nov 4 23:17:23.393138 systemd-logind[1973]: Removed session 10. Nov 4 23:17:23.857370 chronyd[1952]: Selected source PHC0 Nov 4 23:17:38.997271 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Nov 4 23:17:45.640981 update_engine[1976]: I20251104 23:17:45.640877 1976 update_attempter.cc:509] Updating boot flags... Nov 4 23:20:10.711396 update_engine[1976]: I20251104 23:20:10.711105 1976 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Nov 4 23:20:10.711396 update_engine[1976]: I20251104 23:20:10.711153 1976 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Nov 4 23:20:10.711396 update_engine[1976]: I20251104 23:20:10.711333 1976 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711624 1976 omaha_request_params.cc:62] Current group set to alpha Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711740 1976 update_attempter.cc:499] Already updated boot flags. Skipping. Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711748 1976 update_attempter.cc:643] Scheduling an action processor start. Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711764 1976 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711789 1976 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711828 1976 omaha_request_action.cc:271] Posting an Omaha request to disabled Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711833 1976 omaha_request_action.cc:272] Request: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: Nov 4 23:20:10.712582 update_engine[1976]: I20251104 23:20:10.711836 1976 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Nov 4 23:20:10.712830 locksmithd[2091]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_CHECKING_FOR_UPDATE" NewVersion=0.0.0 NewSize=0 Nov 4 23:20:10.712986 update_engine[1976]: I20251104 23:20:10.712862 1976 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Nov 4 23:20:10.713422 update_engine[1976]: I20251104 23:20:10.713393 1976 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Nov 4 23:20:11.017516 update_engine[1976]: E20251104 23:20:11.017462 1976 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Nov 4 23:20:11.017658 update_engine[1976]: I20251104 23:20:11.017581 1976 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Nov 4 23:20:21.629305 update_engine[1976]: I20251104 23:20:21.629160 1976 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Nov 4 23:20:21.629305 update_engine[1976]: I20251104 23:20:21.629258 1976 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Nov 4 23:20:21.629685 update_engine[1976]: I20251104 23:20:21.629601 1976 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Nov 4 23:20:21.700504 update_engine[1976]: E20251104 23:20:21.700470 1976 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Nov 4 23:20:21.700572 update_engine[1976]: I20251104 23:20:21.700557 1976 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Nov 4 23:20:31.628574 update_engine[1976]: I20251104 23:20:31.628489 1976 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Nov 4 23:20:31.628912 update_engine[1976]: I20251104 23:20:31.628600 1976 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Nov 4 23:20:31.628933 update_engine[1976]: I20251104 23:20:31.628921 1976 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Nov 4 23:20:31.725750 update_engine[1976]: E20251104 23:20:31.725706 1976 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Nov 4 23:20:31.725832 update_engine[1976]: I20251104 23:20:31.725795 1976 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Nov 4 23:20:41.621632 update_engine[1976]: I20251104 23:20:41.621567 1976 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Nov 4 23:20:41.622429 update_engine[1976]: I20251104 23:20:41.622092 1976 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Nov 4 23:20:41.622429 update_engine[1976]: I20251104 23:20:41.622390 1976 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Nov 4 23:20:41.634158 update_engine[1976]: E20251104 23:20:41.633643 1976 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633693 1976 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633699 1976 omaha_request_action.cc:617] Omaha request response: Nov 4 23:20:41.634158 update_engine[1976]: E20251104 23:20:41.633772 1976 omaha_request_action.cc:636] Omaha request network transfer failed. Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633787 1976 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633790 1976 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633793 1976 update_attempter.cc:306] Processing Done. Nov 4 23:20:41.634158 update_engine[1976]: E20251104 23:20:41.633805 1976 update_attempter.cc:619] Update failed. Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633808 1976 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633811 1976 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633816 1976 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633877 1976 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633893 1976 omaha_request_action.cc:271] Posting an Omaha request to disabled Nov 4 23:20:41.634158 update_engine[1976]: I20251104 23:20:41.633896 1976 omaha_request_action.cc:272] Request: Nov 4 23:20:41.634158 update_engine[1976]: Nov 4 23:20:41.634158 update_engine[1976]: Nov 4 23:20:41.634416 locksmithd[2091]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_REPORTING_ERROR_EVENT" NewVersion=0.0.0 NewSize=0 Nov 4 23:20:41.634632 update_engine[1976]: Nov 4 23:20:41.634632 update_engine[1976]: Nov 4 23:20:41.634632 update_engine[1976]: Nov 4 23:20:41.634632 update_engine[1976]: Nov 4 23:20:41.634632 update_engine[1976]: I20251104 23:20:41.633901 1976 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Nov 4 23:20:41.634632 update_engine[1976]: I20251104 23:20:41.633913 1976 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Nov 4 23:20:41.634632 update_engine[1976]: I20251104 23:20:41.634127 1976 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Nov 4 23:20:41.705817 update_engine[1976]: E20251104 23:20:41.705581 1976 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled (Domain name not found) Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705703 1976 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705713 1976 omaha_request_action.cc:617] Omaha request response: Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705719 1976 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705722 1976 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705725 1976 update_attempter.cc:306] Processing Done. Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705729 1976 update_attempter.cc:310] Error event sent. Nov 4 23:20:41.705817 update_engine[1976]: I20251104 23:20:41.705741 1976 update_check_scheduler.cc:74] Next update check in 44m14s Nov 4 23:20:41.706099 locksmithd[2091]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_IDLE" NewVersion=0.0.0 NewSize=0